{"level":"warn","ts":"2026-09-24T07:41:26.03789Z","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-24T07:41:26.038015Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.85:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.85:2380","--initial-cluster=instance=https://162.253.55.85:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.85:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.85: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-24T07:41:26.038101Z","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-24T07:41:26.038119Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.85:2380"]} {"level":"info","ts":"2026-09-24T07:41:26.038145Z","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-24T07:41:26.039018Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.85:2379"]} {"level":"info","ts":"2026-09-24T07:41:26.039125Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.85:2380"],"listen-peer-urls":["https://162.253.55.85:2380"],"advertise-client-urls":["https://162.253.55.85:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.85:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.85: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-24T07:41:26.043948Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.541707ms"} {"level":"info","ts":"2026-09-24T07:41:26.057704Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"427150911cb3975a","cluster-id":"f76f6804bd8187de"} {"level":"info","ts":"2026-09-24T07:41:26.057806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a switched to configuration voters=()"} {"level":"info","ts":"2026-09-24T07:41:26.057869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became follower at term 0"} {"level":"info","ts":"2026-09-24T07:41:26.057896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 427150911cb3975a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-24T07:41:26.057914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became follower at term 1"} {"level":"info","ts":"2026-09-24T07:41:26.057977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a switched to configuration voters=(4787696463053559642)"} {"level":"warn","ts":"2026-09-24T07:41:26.069848Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-24T07:41:26.072168Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-24T07:41:26.074848Z","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-24T07:41:26.079971Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"427150911cb3975a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-24T07:41:26.080227Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"427150911cb3975a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-24T07:41:26.08076Z","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-24T07:41:26.080997Z","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-24T07:41:26.081047Z","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-24T07:41:26.083777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a switched to configuration voters=(4787696463053559642)"} {"level":"info","ts":"2026-09-24T07:41:26.084095Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f76f6804bd8187de","local-member-id":"427150911cb3975a","added-peer-id":"427150911cb3975a","added-peer-peer-urls":["https://162.253.55.85:2380"]} {"level":"info","ts":"2026-09-24T07:41:26.085804Z","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-24T07:41:26.086176Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"427150911cb3975a","initial-advertise-peer-urls":["https://162.253.55.85:2380"],"listen-peer-urls":["https://162.253.55.85:2380"],"advertise-client-urls":["https://162.253.55.85:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.85:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-24T07:41:26.086235Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-24T07:41:26.08707Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.85:2380"} {"level":"info","ts":"2026-09-24T07:41:26.087153Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.85:2380"} {"level":"info","ts":"2026-09-24T07:41:26.559081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a is starting a new election at term 1"} {"level":"info","ts":"2026-09-24T07:41:26.559261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became pre-candidate at term 1"} {"level":"info","ts":"2026-09-24T07:41:26.559334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a received MsgPreVoteResp from 427150911cb3975a at term 1"} {"level":"info","ts":"2026-09-24T07:41:26.559386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became candidate at term 2"} {"level":"info","ts":"2026-09-24T07:41:26.559422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a received MsgVoteResp from 427150911cb3975a at term 2"} {"level":"info","ts":"2026-09-24T07:41:26.559474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became leader at term 2"} {"level":"info","ts":"2026-09-24T07:41:26.559515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 427150911cb3975a elected leader 427150911cb3975a at term 2"} {"level":"info","ts":"2026-09-24T07:41:26.560853Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-24T07:41:26.560849Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-24T07:41:26.560905Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-24T07:41:26.561658Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-24T07:41:26.561737Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-24T07:41:26.562348Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f76f6804bd8187de","local-member-id":"427150911cb3975a","cluster-version":"3.5"} {"level":"info","ts":"2026-09-24T07:41:26.562474Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-24T07:41:26.562514Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-24T07:41:26.56421Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-24T07:41:26.565846Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.85:2379"} {"level":"info","ts":"2026-09-24T07:41:26.570575Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"427150911cb3975a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.85:2379]}","request-path":"/0/members/427150911cb3975a/attributes","cluster-id":"f76f6804bd8187de","publish-timeout":"7s"} {"level":"info","ts":"2026-09-24T07:41:43.935991Z","caller":"traceutil/trace.go:171","msg":"trace[286114820] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"204.524989ms","start":"2026-09-24T07:41:43.731432Z","end":"2026-09-24T07:41:43.935957Z","steps":["trace[286114820] 'process raft request' (duration: 204.314723ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:42:13.109946Z","caller":"traceutil/trace.go:171","msg":"trace[1316622785] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"135.892867ms","start":"2026-09-24T07:42:12.974017Z","end":"2026-09-24T07:42:13.10991Z","steps":["trace[1316622785] 'process raft request' (duration: 135.6189ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:42:13.545102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.504315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-24T07:42:13.545248Z","caller":"traceutil/trace.go:171","msg":"trace[96185452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:626; }","duration":"195.70572ms","start":"2026-09-24T07:42:13.349512Z","end":"2026-09-24T07:42:13.545218Z","steps":["trace[96185452] 'range keys from in-memory index tree' (duration: 195.29618ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:42:14.033791Z","caller":"traceutil/trace.go:171","msg":"trace[1059033156] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"387.86504ms","start":"2026-09-24T07:42:13.645893Z","end":"2026-09-24T07:42:14.033758Z","steps":["trace[1059033156] 'process raft request' (duration: 387.706256ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:42:14.034341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T07:42:13.645861Z","time spent":"388.035695ms","remote":"127.0.0.1:52100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-24T07:42:30.26394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.504469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-24T07:42:30.264184Z","caller":"traceutil/trace.go:171","msg":"trace[1307932962] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:792; }","duration":"106.806717ms","start":"2026-09-24T07:42:30.157355Z","end":"2026-09-24T07:42:30.264162Z","steps":["trace[1307932962] 'range keys from in-memory index tree' (duration: 106.297123ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:42:30.401658Z","caller":"traceutil/trace.go:171","msg":"trace[313199725] linearizableReadLoop","detail":"{readStateIndex:822; appliedIndex:821; }","duration":"135.759264ms","start":"2026-09-24T07:42:30.265879Z","end":"2026-09-24T07:42:30.401638Z","steps":["trace[313199725] 'read index received' (duration: 135.652401ms)","trace[313199725] 'applied index is now lower than readState.Index' (duration: 105.773µs)"],"step_count":2} {"level":"warn","ts":"2026-09-24T07:42:30.40177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.884168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-24T07:42:30.401802Z","caller":"traceutil/trace.go:171","msg":"trace[469813515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:792; }","duration":"135.98145ms","start":"2026-09-24T07:42:30.26581Z","end":"2026-09-24T07:42:30.401792Z","steps":["trace[469813515] 'agreement among raft nodes before linearized reading' (duration: 135.910678ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:42:30.443883Z","caller":"traceutil/trace.go:171","msg":"trace[255734551] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"170.910501ms","start":"2026-09-24T07:42:30.272942Z","end":"2026-09-24T07:42:30.443852Z","steps":["trace[255734551] 'process raft request' (duration: 170.822418ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:42:30.443911Z","caller":"traceutil/trace.go:171","msg":"trace[1372200628] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"176.139896ms","start":"2026-09-24T07:42:30.267743Z","end":"2026-09-24T07:42:30.443883Z","steps":["trace[1372200628] 'process raft request' (duration: 175.837899ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:43:55.430982Z","caller":"traceutil/trace.go:171","msg":"trace[1562229336] linearizableReadLoop","detail":"{readStateIndex:1978; appliedIndex:1977; }","duration":"130.968689ms","start":"2026-09-24T07:43:55.29999Z","end":"2026-09-24T07:43:55.430958Z","steps":["trace[1562229336] 'read index received' (duration: 69.79115ms)","trace[1562229336] 'applied index is now lower than readState.Index' (duration: 61.175889ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T07:43:55.431115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.105832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-24T07:43:55.431144Z","caller":"traceutil/trace.go:171","msg":"trace[1264197815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1902; }","duration":"131.177774ms","start":"2026-09-24T07:43:55.299957Z","end":"2026-09-24T07:43:55.431135Z","steps":["trace[1264197815] 'agreement among raft nodes before linearized reading' (duration: 131.099032ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:43:55.819712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.261914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-015adc8d-bc9c-483a-b6ba-ae6a45fb2748\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-24T07:43:55.819792Z","caller":"traceutil/trace.go:171","msg":"trace[615099988] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-015adc8d-bc9c-483a-b6ba-ae6a45fb2748; range_end:; response_count:1; response_revision:1903; }","duration":"125.406597ms","start":"2026-09-24T07:43:55.694368Z","end":"2026-09-24T07:43:55.819775Z","steps":["trace[615099988] 'range keys from in-memory index tree' (duration: 125.071238ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:43:55.819888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.043432ms","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-24T07:43:55.819975Z","caller":"traceutil/trace.go:171","msg":"trace[112461780] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1903; }","duration":"143.209166ms","start":"2026-09-24T07:43:55.676747Z","end":"2026-09-24T07:43:55.819956Z","steps":["trace[112461780] 'range keys from in-memory index tree' (duration: 142.823486ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:44:12.150721Z","caller":"traceutil/trace.go:171","msg":"trace[1194772453] transaction","detail":"{read_only:false; response_revision:2041; number_of_response:1; }","duration":"178.322928ms","start":"2026-09-24T07:44:11.972331Z","end":"2026-09-24T07:44:12.150654Z","steps":["trace[1194772453] 'process raft request' (duration: 142.617615ms)","trace[1194772453] 'compare' (duration: 35.551079ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T07:44:56.38344Z","caller":"traceutil/trace.go:171","msg":"trace[1055036970] transaction","detail":"{read_only:false; response_revision:2280; number_of_response:1; }","duration":"147.706176ms","start":"2026-09-24T07:44:56.235704Z","end":"2026-09-24T07:44:56.38341Z","steps":["trace[1055036970] 'process raft request' (duration: 147.656465ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:44:56.383846Z","caller":"traceutil/trace.go:171","msg":"trace[535322966] transaction","detail":"{read_only:false; response_revision:2279; number_of_response:1; }","duration":"157.001107ms","start":"2026-09-24T07:44:56.226804Z","end":"2026-09-24T07:44:56.383805Z","steps":["trace[535322966] 'process raft request' (duration: 142.636166ms)","trace[535322966] 'compare' (duration: 13.773846ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T07:45:38.744369Z","caller":"traceutil/trace.go:171","msg":"trace[1614092251] transaction","detail":"{read_only:false; response_revision:2684; number_of_response:1; }","duration":"149.787948ms","start":"2026-09-24T07:45:38.594541Z","end":"2026-09-24T07:45:38.744329Z","steps":["trace[1614092251] 'process raft request' (duration: 149.572102ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:49:27.032186Z","caller":"traceutil/trace.go:171","msg":"trace[997678814] transaction","detail":"{read_only:false; response_revision:4114; number_of_response:1; }","duration":"180.894902ms","start":"2026-09-24T07:49:26.851257Z","end":"2026-09-24T07:49:27.032152Z","steps":["trace[997678814] 'process raft request' (duration: 180.642816ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:51:00.641953Z","caller":"traceutil/trace.go:171","msg":"trace[1401627912] linearizableReadLoop","detail":"{readStateIndex:5409; appliedIndex:5408; }","duration":"402.95547ms","start":"2026-09-24T07:51:00.238969Z","end":"2026-09-24T07:51:00.641924Z","steps":["trace[1401627912] 'read index received' (duration: 402.745645ms)","trace[1401627912] 'applied index is now lower than readState.Index' (duration: 208.515µs)"],"step_count":2} {"level":"info","ts":"2026-09-24T07:51:00.642023Z","caller":"traceutil/trace.go:171","msg":"trace[1790829754] transaction","detail":"{read_only:false; response_revision:5207; number_of_response:1; }","duration":"406.869223ms","start":"2026-09-24T07:51:00.235113Z","end":"2026-09-24T07:51:00.641982Z","steps":["trace[1790829754] 'process raft request' (duration: 406.637736ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:51:00.642142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.147996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-24T07:51:00.642184Z","caller":"traceutil/trace.go:171","msg":"trace[677190409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5207; }","duration":"403.227798ms","start":"2026-09-24T07:51:00.238945Z","end":"2026-09-24T07:51:00.642173Z","steps":["trace[677190409] 'agreement among raft nodes before linearized reading' (duration: 403.135955ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:51:00.642223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T07:51:00.238937Z","time spent":"403.27893ms","remote":"127.0.0.1:51882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-24T07:51:00.642584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T07:51:00.235087Z","time spent":"407.021187ms","remote":"127.0.0.1:32792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-24T07:51:00.642552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.850337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.85\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-24T07:51:00.642965Z","caller":"traceutil/trace.go:171","msg":"trace[2143389091] range","detail":"{range_begin:/registry/masterleases/162.253.55.85; range_end:; response_count:1; response_revision:5207; }","duration":"396.33375ms","start":"2026-09-24T07:51:00.246618Z","end":"2026-09-24T07:51:00.642952Z","steps":["trace[2143389091] 'agreement among raft nodes before linearized reading' (duration: 395.697773ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:51:00.643011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T07:51:00.246611Z","time spent":"396.388191ms","remote":"127.0.0.1:51904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.85\" "} {"level":"info","ts":"2026-09-24T07:51:04.359695Z","caller":"traceutil/trace.go:171","msg":"trace[757906478] transaction","detail":"{read_only:false; response_revision:5344; number_of_response:1; }","duration":"308.433665ms","start":"2026-09-24T07:51:04.051237Z","end":"2026-09-24T07:51:04.35967Z","steps":["trace[757906478] 'process raft request' (duration: 308.189479ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:51:04.35985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T07:51:04.051228Z","time spent":"308.542488ms","remote":"127.0.0.1:52056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4934,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-24T07:51:04.359955Z","caller":"traceutil/trace.go:171","msg":"trace[737197521] transaction","detail":"{read_only:false; response_revision:5345; number_of_response:1; }","duration":"308.353212ms","start":"2026-09-24T07:51:04.051561Z","end":"2026-09-24T07:51:04.359914Z","steps":["trace[737197521] 'process raft request' (duration: 308.058614ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:51:04.359984Z","caller":"traceutil/trace.go:171","msg":"trace[170895743] transaction","detail":"{read_only:false; response_revision:5346; number_of_response:1; }","duration":"305.831577ms","start":"2026-09-24T07:51:04.054127Z","end":"2026-09-24T07:51:04.359959Z","steps":["trace[170895743] 'process raft request' (duration: 305.725844ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:51:04.360099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T07:51:04.051553Z","time spent":"308.486696ms","remote":"127.0.0.1:51952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-24T07:51:04.360153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T07:51:04.054121Z","time spent":"305.95186ms","remote":"127.0.0.1:52344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5006,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-24T07:51:04.445549Z","caller":"traceutil/trace.go:171","msg":"trace[1195751866] transaction","detail":"{read_only:false; response_revision:5347; number_of_response:1; }","duration":"364.454714ms","start":"2026-09-24T07:51:04.081053Z","end":"2026-09-24T07:51:04.445508Z","steps":["trace[1195751866] 'process raft request' (duration: 347.695291ms)","trace[1195751866] 'compare' (duration: 16.508617ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T07:51:04.445612Z","caller":"traceutil/trace.go:171","msg":"trace[1599164055] linearizableReadLoop","detail":"{readStateIndex:5554; appliedIndex:5550; }","duration":"308.333832ms","start":"2026-09-24T07:51:04.137261Z","end":"2026-09-24T07:51:04.445594Z","steps":["trace[1599164055] 'read index received' (duration: 222.223934ms)","trace[1599164055] 'applied index is now lower than readState.Index' (duration: 86.107838ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T07:51:04.445597Z","caller":"traceutil/trace.go:171","msg":"trace[1719025482] transaction","detail":"{read_only:false; response_revision:5348; number_of_response:1; }","duration":"102.287518ms","start":"2026-09-24T07:51:04.343271Z","end":"2026-09-24T07:51:04.445559Z","steps":["trace[1719025482] 'process raft request' (duration: 102.145814ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:51:04.445761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T07:51:04.081031Z","time spent":"364.613938ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-24T07:51:04.445816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.552747ms","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-24T07:51:04.446128Z","caller":"traceutil/trace.go:171","msg":"trace[986743902] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5349; }","duration":"308.905626ms","start":"2026-09-24T07:51:04.13721Z","end":"2026-09-24T07:51:04.446116Z","steps":["trace[986743902] 'agreement among raft nodes before linearized reading' (duration: 308.485345ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:51:04.446168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T07:51:04.137193Z","time spent":"308.963437ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-24T07:51:04.445913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.047514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-47bba51d-1ce0-4272-a12f-0dfb92865ef4\" ","response":"range_response_count:1 size:3720"} {"level":"info","ts":"2026-09-24T07:51:04.446232Z","caller":"traceutil/trace.go:171","msg":"trace[90773834] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-47bba51d-1ce0-4272-a12f-0dfb92865ef4; range_end:; response_count:1; response_revision:5349; }","duration":"286.407623ms","start":"2026-09-24T07:51:04.159807Z","end":"2026-09-24T07:51:04.446215Z","steps":["trace[90773834] 'agreement among raft nodes before linearized reading' (duration: 285.942792ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:51:04.44602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.791881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-8xf58-tls\" ","response":"range_response_count:1 size:1966"} {"level":"info","ts":"2026-09-24T07:51:04.446401Z","caller":"traceutil/trace.go:171","msg":"trace[581325676] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-8xf58-tls; range_end:; response_count:1; response_revision:5349; }","duration":"268.164512ms","start":"2026-09-24T07:51:04.178177Z","end":"2026-09-24T07:51:04.446342Z","steps":["trace[581325676] 'agreement among raft nodes before linearized reading' (duration: 267.669318ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:51:04.446496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.934722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786877"} {"level":"info","ts":"2026-09-24T07:51:04.446617Z","caller":"traceutil/trace.go:171","msg":"trace[928841867] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5349; }","duration":"269.072105ms","start":"2026-09-24T07:51:04.177516Z","end":"2026-09-24T07:51:04.446588Z","steps":["trace[928841867] 'agreement among raft nodes before linearized reading' (duration: 268.335485ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:51:19.460441Z","caller":"traceutil/trace.go:171","msg":"trace[191206598] transaction","detail":"{read_only:false; response_revision:5623; number_of_response:1; }","duration":"122.602584ms","start":"2026-09-24T07:51:19.337795Z","end":"2026-09-24T07:51:19.460398Z","steps":["trace[191206598] 'process raft request' (duration: 69.265213ms)","trace[191206598] 'compare' (duration: 53.138876ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T07:51:19.46227Z","caller":"traceutil/trace.go:171","msg":"trace[1766200733] transaction","detail":"{read_only:false; response_revision:5624; number_of_response:1; }","duration":"124.216447ms","start":"2026-09-24T07:51:19.338016Z","end":"2026-09-24T07:51:19.462232Z","steps":["trace[1766200733] 'process raft request' (duration: 124.029242ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:51:19.462488Z","caller":"traceutil/trace.go:171","msg":"trace[700837527] transaction","detail":"{read_only:false; response_revision:5626; number_of_response:1; }","duration":"116.942437ms","start":"2026-09-24T07:51:19.345512Z","end":"2026-09-24T07:51:19.462455Z","steps":["trace[700837527] 'process raft request' (duration: 116.690941ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:51:19.462933Z","caller":"traceutil/trace.go:171","msg":"trace[2127405125] transaction","detail":"{read_only:false; response_revision:5625; number_of_response:1; }","duration":"124.038471ms","start":"2026-09-24T07:51:19.338311Z","end":"2026-09-24T07:51:19.462349Z","steps":["trace[2127405125] 'process raft request' (duration: 123.837886ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:51:26.601154Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2966} {"level":"info","ts":"2026-09-24T07:51:26.695109Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2966,"took":"91.220262ms","hash":3787148721,"current-db-size-bytes":57012224,"current-db-size":"57 MB","current-db-size-in-use-bytes":31789056,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-24T07:51:26.695183Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3787148721,"revision":2966,"compact-revision":-1} {"level":"info","ts":"2026-09-24T07:51:29.674566Z","caller":"traceutil/trace.go:171","msg":"trace[1759503577] transaction","detail":"{read_only:false; response_revision:5830; number_of_response:1; }","duration":"156.8783ms","start":"2026-09-24T07:51:29.517657Z","end":"2026-09-24T07:51:29.674535Z","steps":["trace[1759503577] 'process raft request' (duration: 146.542913ms)","trace[1759503577] 'compare' (duration: 10.195234ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T07:51:29.733499Z","caller":"traceutil/trace.go:171","msg":"trace[1151659318] linearizableReadLoop","detail":"{readStateIndex:6051; appliedIndex:6048; }","duration":"141.921513ms","start":"2026-09-24T07:51:29.591562Z","end":"2026-09-24T07:51:29.733483Z","steps":["trace[1151659318] 'read index received' (duration: 72.742123ms)","trace[1151659318] 'applied index is now lower than readState.Index' (duration: 69.17834ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T07:51:29.73356Z","caller":"traceutil/trace.go:171","msg":"trace[595697674] transaction","detail":"{read_only:false; response_revision:5831; number_of_response:1; }","duration":"214.116692ms","start":"2026-09-24T07:51:29.519396Z","end":"2026-09-24T07:51:29.733513Z","steps":["trace[595697674] 'process raft request' (duration: 213.952198ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:51:29.733624Z","caller":"traceutil/trace.go:171","msg":"trace[860560024] transaction","detail":"{read_only:false; response_revision:5832; number_of_response:1; }","duration":"178.044828ms","start":"2026-09-24T07:51:29.555525Z","end":"2026-09-24T07:51:29.73357Z","steps":["trace[860560024] 'process raft request' (duration: 177.916535ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:51:29.733724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.151688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c7c3f2fe-86a1-49c8-af67-5ca48b9f0c95\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-09-24T07:51:29.733764Z","caller":"traceutil/trace.go:171","msg":"trace[1029601548] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c7c3f2fe-86a1-49c8-af67-5ca48b9f0c95; range_end:; response_count:1; response_revision:5832; }","duration":"142.278232ms","start":"2026-09-24T07:51:29.591472Z","end":"2026-09-24T07:51:29.73375Z","steps":["trace[1029601548] 'agreement among raft nodes before linearized reading' (duration: 142.084677ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:51:29.873756Z","caller":"traceutil/trace.go:171","msg":"trace[1439826203] transaction","detail":"{read_only:false; response_revision:5833; number_of_response:1; }","duration":"129.385179ms","start":"2026-09-24T07:51:29.74432Z","end":"2026-09-24T07:51:29.873705Z","steps":["trace[1439826203] 'process raft request' (duration: 71.548952ms)","trace[1439826203] 'compare' (duration: 57.58878ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T07:51:40.664739Z","caller":"traceutil/trace.go:171","msg":"trace[1413399889] linearizableReadLoop","detail":"{readStateIndex:6158; appliedIndex:6157; }","duration":"106.801305ms","start":"2026-09-24T07:51:40.557911Z","end":"2026-09-24T07:51:40.664712Z","steps":["trace[1413399889] 'read index received' (duration: 106.60709ms)","trace[1413399889] 'applied index is now lower than readState.Index' (duration: 192.825µs)"],"step_count":2} {"level":"info","ts":"2026-09-24T07:51:40.664798Z","caller":"traceutil/trace.go:171","msg":"trace[257751323] transaction","detail":"{read_only:false; response_revision:5935; number_of_response:1; }","duration":"114.987036ms","start":"2026-09-24T07:51:40.549777Z","end":"2026-09-24T07:51:40.664764Z","steps":["trace[257751323] 'process raft request' (duration: 114.759321ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:51:40.664971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.035581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-24T07:51:40.665015Z","caller":"traceutil/trace.go:171","msg":"trace[160371578] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5935; }","duration":"107.137323ms","start":"2026-09-24T07:51:40.557864Z","end":"2026-09-24T07:51:40.665001Z","steps":["trace[160371578] 'agreement among raft nodes before linearized reading' (duration: 106.947779ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:56:26.608437Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5734} {"level":"info","ts":"2026-09-24T07:56:26.705984Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5734,"took":"95.71396ms","hash":394135525,"current-db-size-bytes":57012224,"current-db-size":"57 MB","current-db-size-in-use-bytes":26603520,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-24T07:56:26.706068Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":394135525,"revision":5734,"compact-revision":2966} {"level":"info","ts":"2026-09-24T07:57:55.042121Z","caller":"traceutil/trace.go:171","msg":"trace[1038300342] transaction","detail":"{read_only:false; response_revision:8721; number_of_response:1; }","duration":"152.306842ms","start":"2026-09-24T07:57:54.889789Z","end":"2026-09-24T07:57:55.042095Z","steps":["trace[1038300342] 'process raft request' (duration: 152.103276ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:57:55.609352Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002350.wal"} {"level":"warn","ts":"2026-09-24T07:58:07.021522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.183913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-q54t5\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-09-24T07:58:07.022098Z","caller":"traceutil/trace.go:171","msg":"trace[554133389] range","detail":"{range_begin:/registry/pods/openstack/keepalived-q54t5; range_end:; response_count:1; response_revision:8782; }","duration":"332.325127ms","start":"2026-09-24T07:58:06.689291Z","end":"2026-09-24T07:58:07.021616Z","steps":["trace[554133389] 'range keys from in-memory index tree' (duration: 331.894286ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:58:07.022195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T07:58:06.689231Z","time spent":"332.945103ms","remote":"127.0.0.1:52056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6470,"request content":"key:\"/registry/pods/openstack/keepalived-q54t5\" "} {"level":"info","ts":"2026-09-24T07:58:07.576739Z","caller":"traceutil/trace.go:171","msg":"trace[77954641] transaction","detail":"{read_only:false; response_revision:8786; number_of_response:1; }","duration":"174.221445ms","start":"2026-09-24T07:58:07.402477Z","end":"2026-09-24T07:58:07.576699Z","steps":["trace[77954641] 'process raft request' (duration: 173.986349ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:58:08.072806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.143345ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-24T07:58:08.072946Z","caller":"traceutil/trace.go:171","msg":"trace[1021257255] transaction","detail":"{read_only:false; response_revision:8788; number_of_response:1; }","duration":"150.944636ms","start":"2026-09-24T07:58:07.921978Z","end":"2026-09-24T07:58:08.072923Z","steps":["trace[1021257255] 'process raft request' (duration: 48.37173ms)","trace[1021257255] 'compare' (duration: 101.959971ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T07:58:38.350966Z","caller":"traceutil/trace.go:171","msg":"trace[105061966] linearizableReadLoop","detail":"{readStateIndex:9330; appliedIndex:9329; }","duration":"152.507755ms","start":"2026-09-24T07:58:38.198422Z","end":"2026-09-24T07:58:38.35093Z","steps":["trace[105061966] 'read index received' (duration: 152.170287ms)","trace[105061966] 'applied index is now lower than readState.Index' (duration: 335.848µs)"],"step_count":2} {"level":"info","ts":"2026-09-24T07:58:38.351003Z","caller":"traceutil/trace.go:171","msg":"trace[1215554697] transaction","detail":"{read_only:false; response_revision:9005; number_of_response:1; }","duration":"206.226161ms","start":"2026-09-24T07:58:38.144745Z","end":"2026-09-24T07:58:38.350972Z","steps":["trace[1215554697] 'process raft request' (duration: 205.972135ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:58:38.3512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.756291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-24T07:58:38.351304Z","caller":"traceutil/trace.go:171","msg":"trace[610486590] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:9005; }","duration":"152.916975ms","start":"2026-09-24T07:58:38.198368Z","end":"2026-09-24T07:58:38.351285Z","steps":["trace[610486590] 'agreement among raft nodes before linearized reading' (duration: 152.72416ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:58:38.351566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.934658ms","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-24T07:58:38.351775Z","caller":"traceutil/trace.go:171","msg":"trace[2095517073] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9005; }","duration":"117.231136ms","start":"2026-09-24T07:58:38.234502Z","end":"2026-09-24T07:58:38.351733Z","steps":["trace[2095517073] 'agreement among raft nodes before linearized reading' (duration: 116.712941ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:58:38.471291Z","caller":"traceutil/trace.go:171","msg":"trace[158149618] transaction","detail":"{read_only:false; response_revision:9006; number_of_response:1; }","duration":"113.54036ms","start":"2026-09-24T07:58:38.357729Z","end":"2026-09-24T07:58:38.47127Z","steps":["trace[158149618] 'process raft request' (duration: 113.413767ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:58:49.612404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.340812ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-24T07:58:49.612673Z","caller":"traceutil/trace.go:171","msg":"trace[1938652471] transaction","detail":"{read_only:false; response_revision:9067; number_of_response:1; }","duration":"107.070583ms","start":"2026-09-24T07:58:49.505577Z","end":"2026-09-24T07:58:49.612647Z","steps":["trace[1938652471] 'process raft request' (duration: 106.96161ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:58:49.612723Z","caller":"traceutil/trace.go:171","msg":"trace[925217766] transaction","detail":"{read_only:false; response_revision:9066; number_of_response:1; }","duration":"161.006624ms","start":"2026-09-24T07:58:49.451676Z","end":"2026-09-24T07:58:49.612683Z","steps":["trace[925217766] 'process raft request' (duration: 51.272092ms)","trace[925217766] 'compare' (duration: 109.158357ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T07:58:49.61277Z","caller":"traceutil/trace.go:171","msg":"trace[1248761936] linearizableReadLoop","detail":"{readStateIndex:9393; appliedIndex:9392; }","duration":"149.634501ms","start":"2026-09-24T07:58:49.463108Z","end":"2026-09-24T07:58:49.612742Z","steps":["trace[1248761936] 'read index received' (duration: 39.829558ms)","trace[1248761936] 'applied index is now lower than readState.Index' (duration: 109.802803ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T07:58:49.612989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.856328ms","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-24T07:58:49.613109Z","caller":"traceutil/trace.go:171","msg":"trace[1519272182] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:9067; }","duration":"150.052682ms","start":"2026-09-24T07:58:49.463036Z","end":"2026-09-24T07:58:49.613089Z","steps":["trace[1519272182] 'agreement among raft nodes before linearized reading' (duration: 149.838287ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:58:49.613119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.858901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-24T07:58:49.613158Z","caller":"traceutil/trace.go:171","msg":"trace[1030447347] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9067; }","duration":"110.919283ms","start":"2026-09-24T07:58:49.502228Z","end":"2026-09-24T07:58:49.613147Z","steps":["trace[1030447347] 'agreement among raft nodes before linearized reading' (duration: 110.749288ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T07:58:49.613389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.426993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-24T07:58:49.613707Z","caller":"traceutil/trace.go:171","msg":"trace[1528433484] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:9067; }","duration":"145.67086ms","start":"2026-09-24T07:58:49.4679Z","end":"2026-09-24T07:58:49.613571Z","steps":["trace[1528433484] 'agreement among raft nodes before linearized reading' (duration: 144.991902ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T07:59:53.510292Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"427150911cb3975a","local-member-applied-index":10006,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-24T07:59:53.520063Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10006} {"level":"info","ts":"2026-09-24T07:59:53.520192Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5006} {"level":"info","ts":"2026-09-24T08:00:22.403635Z","caller":"traceutil/trace.go:171","msg":"trace[1170549944] linearizableReadLoop","detail":"{readStateIndex:10299; appliedIndex:10298; }","duration":"169.946475ms","start":"2026-09-24T08:00:22.233664Z","end":"2026-09-24T08:00:22.40361Z","steps":["trace[1170549944] 'read index received' (duration: 137.962697ms)","trace[1170549944] 'applied index is now lower than readState.Index' (duration: 31.982388ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:00:22.403779Z","caller":"traceutil/trace.go:171","msg":"trace[138717927] transaction","detail":"{read_only:false; response_revision:9945; number_of_response:1; }","duration":"173.299481ms","start":"2026-09-24T08:00:22.230416Z","end":"2026-09-24T08:00:22.403716Z","steps":["trace[138717927] 'process raft request' (duration: 141.027956ms)","trace[138717927] 'compare' (duration: 32.07275ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:00:22.40453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.843617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-24T08:00:22.404631Z","caller":"traceutil/trace.go:171","msg":"trace[781351891] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:9945; }","duration":"170.96566ms","start":"2026-09-24T08:00:22.233643Z","end":"2026-09-24T08:00:22.404609Z","steps":["trace[781351891] 'agreement among raft nodes before linearized reading' (duration: 170.077817ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:01:26.616528Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8020} {"level":"info","ts":"2026-09-24T08:01:26.700421Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8020,"took":"78.719836ms","hash":3166032002,"current-db-size-bytes":57012224,"current-db-size":"57 MB","current-db-size-in-use-bytes":28237824,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-24T08:01:26.700508Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3166032002,"revision":8020,"compact-revision":5734} {"level":"info","ts":"2026-09-24T08:06:02.881105Z","caller":"traceutil/trace.go:171","msg":"trace[464272685] linearizableReadLoop","detail":"{readStateIndex:12817; appliedIndex:12816; }","duration":"112.359865ms","start":"2026-09-24T08:06:02.768721Z","end":"2026-09-24T08:06:02.881081Z","steps":["trace[464272685] 'read index received' (duration: 112.14971ms)","trace[464272685] 'applied index is now lower than readState.Index' (duration: 208.865µs)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:06:02.881193Z","caller":"traceutil/trace.go:171","msg":"trace[354722206] transaction","detail":"{read_only:false; response_revision:12384; number_of_response:1; }","duration":"148.52271ms","start":"2026-09-24T08:06:02.732622Z","end":"2026-09-24T08:06:02.881144Z","steps":["trace[354722206] 'process raft request' (duration: 148.293554ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:06:02.881676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.801016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-q54t5\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-09-24T08:06:02.881817Z","caller":"traceutil/trace.go:171","msg":"trace[1076153654] range","detail":"{range_begin:/registry/pods/openstack/keepalived-q54t5; range_end:; response_count:1; response_revision:12384; }","duration":"113.107924ms","start":"2026-09-24T08:06:02.768672Z","end":"2026-09-24T08:06:02.88178Z","steps":["trace[1076153654] 'agreement among raft nodes before linearized reading' (duration: 112.521149ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:06:03.230445Z","caller":"traceutil/trace.go:171","msg":"trace[1688246980] transaction","detail":"{read_only:false; response_revision:12387; number_of_response:1; }","duration":"137.570637ms","start":"2026-09-24T08:06:03.092826Z","end":"2026-09-24T08:06:03.230396Z","steps":["trace[1688246980] 'process raft request' (duration: 40.485127ms)","trace[1688246980] 'compare' (duration: 96.823432ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:06:04.015788Z","caller":"traceutil/trace.go:171","msg":"trace[1805335067] transaction","detail":"{read_only:false; response_revision:12389; number_of_response:1; }","duration":"126.388927ms","start":"2026-09-24T08:06:03.889371Z","end":"2026-09-24T08:06:04.01576Z","steps":["trace[1805335067] 'process raft request' (duration: 126.222583ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:06:26.624476Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10397} {"level":"info","ts":"2026-09-24T08:06:26.715822Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10397,"took":"88.683873ms","hash":2803843340,"current-db-size-bytes":57012224,"current-db-size":"57 MB","current-db-size-in-use-bytes":25477120,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-24T08:06:26.715896Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2803843340,"revision":10397,"compact-revision":8020} {"level":"info","ts":"2026-09-24T08:11:26.633135Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12559} {"level":"info","ts":"2026-09-24T08:11:26.727331Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12559,"took":"91.215228ms","hash":2008028476,"current-db-size-bytes":57012224,"current-db-size":"57 MB","current-db-size-in-use-bytes":27516928,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-24T08:11:26.727408Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2008028476,"revision":12559,"compact-revision":10397} {"level":"info","ts":"2026-09-24T08:13:09.728015Z","caller":"traceutil/trace.go:171","msg":"trace[325959341] transaction","detail":"{read_only:false; response_revision:15185; number_of_response:1; }","duration":"281.655522ms","start":"2026-09-24T08:13:09.446324Z","end":"2026-09-24T08:13:09.72798Z","steps":["trace[325959341] 'process raft request' (duration: 223.131479ms)","trace[325959341] 'compare' (duration: 58.220986ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:13:09.728278Z","caller":"traceutil/trace.go:171","msg":"trace[1281918606] linearizableReadLoop","detail":"{readStateIndex:15720; appliedIndex:15719; }","duration":"239.627775ms","start":"2026-09-24T08:13:09.488618Z","end":"2026-09-24T08:13:09.728245Z","steps":["trace[1281918606] 'read index received' (duration: 180.717612ms)","trace[1281918606] 'applied index is now lower than readState.Index' (duration: 58.907333ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:13:09.72865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.375483ms","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-24T08:13:09.728685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.027445ms","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-24T08:13:09.728712Z","caller":"traceutil/trace.go:171","msg":"trace[852544534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15186; }","duration":"238.503997ms","start":"2026-09-24T08:13:09.490191Z","end":"2026-09-24T08:13:09.728695Z","steps":["trace[852544534] 'agreement among raft nodes before linearized reading' (duration: 238.292981ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:13:09.728729Z","caller":"traceutil/trace.go:171","msg":"trace[1224152902] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15186; }","duration":"240.157308ms","start":"2026-09-24T08:13:09.488557Z","end":"2026-09-24T08:13:09.728714Z","steps":["trace[1224152902] 'agreement among raft nodes before linearized reading' (duration: 239.878301ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:13:09.72878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.833736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-24T08:13:09.728984Z","caller":"traceutil/trace.go:171","msg":"trace[1248145650] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:15186; }","duration":"217.081212ms","start":"2026-09-24T08:13:09.511833Z","end":"2026-09-24T08:13:09.728914Z","steps":["trace[1248145650] 'agreement among raft nodes before linearized reading' (duration: 216.712113ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:13:35.968297Z","caller":"traceutil/trace.go:171","msg":"trace[1583276983] transaction","detail":"{read_only:false; response_revision:15515; number_of_response:1; }","duration":"125.478195ms","start":"2026-09-24T08:13:35.842757Z","end":"2026-09-24T08:13:35.968235Z","steps":["trace[1583276983] 'process raft request' (duration: 125.30441ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:13:36.160371Z","caller":"traceutil/trace.go:171","msg":"trace[1733575033] linearizableReadLoop","detail":"{readStateIndex:16068; appliedIndex:16066; }","duration":"288.862938ms","start":"2026-09-24T08:13:35.871478Z","end":"2026-09-24T08:13:36.160341Z","steps":["trace[1733575033] 'read index received' (duration: 96.594228ms)","trace[1733575033] 'applied index is now lower than readState.Index' (duration: 192.26714ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:13:36.160761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.2569ms","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-24T08:13:36.160894Z","caller":"traceutil/trace.go:171","msg":"trace[1364587172] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15516; }","duration":"289.463654ms","start":"2026-09-24T08:13:35.871404Z","end":"2026-09-24T08:13:36.160868Z","steps":["trace[1364587172] 'agreement among raft nodes before linearized reading' (duration: 289.136146ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:13:36.161254Z","caller":"traceutil/trace.go:171","msg":"trace[906841371] transaction","detail":"{read_only:false; response_revision:15516; number_of_response:1; }","duration":"316.233526ms","start":"2026-09-24T08:13:35.845006Z","end":"2026-09-24T08:13:36.16124Z","steps":["trace[906841371] 'process raft request' (duration: 315.145607ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:13:36.161425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:13:35.84497Z","time spent":"316.359809ms","remote":"127.0.0.1:52056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3738,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-24T08:13:36.160911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.764255ms","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-24T08:13:36.161601Z","caller":"traceutil/trace.go:171","msg":"trace[1846875631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15516; }","duration":"287.522805ms","start":"2026-09-24T08:13:35.874063Z","end":"2026-09-24T08:13:36.161586Z","steps":["trace[1846875631] 'agreement among raft nodes before linearized reading' (duration: 286.669943ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:15:04.836346Z","caller":"traceutil/trace.go:171","msg":"trace[96084606] linearizableReadLoop","detail":"{readStateIndex:16917; appliedIndex:16916; }","duration":"130.395944ms","start":"2026-09-24T08:15:04.70592Z","end":"2026-09-24T08:15:04.836316Z","steps":["trace[96084606] 'read index received' (duration: 82.538326ms)","trace[96084606] 'applied index is now lower than readState.Index' (duration: 47.856638ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:15:04.836374Z","caller":"traceutil/trace.go:171","msg":"trace[1188989588] transaction","detail":"{read_only:false; response_revision:16344; number_of_response:1; }","duration":"136.465451ms","start":"2026-09-24T08:15:04.699872Z","end":"2026-09-24T08:15:04.836337Z","steps":["trace[1188989588] 'process raft request' (duration: 88.654004ms)","trace[1188989588] 'compare' (duration: 47.675554ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:15:04.836679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.734623ms","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-24T08:15:04.836745Z","caller":"traceutil/trace.go:171","msg":"trace[2119642262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16344; }","duration":"130.820885ms","start":"2026-09-24T08:15:04.705911Z","end":"2026-09-24T08:15:04.836732Z","steps":["trace[2119642262] 'agreement among raft nodes before linearized reading' (duration: 130.590719ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:16:26.641639Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14563} {"level":"info","ts":"2026-09-24T08:16:26.733836Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14563,"took":"88.133239ms","hash":1150896314,"current-db-size-bytes":57012224,"current-db-size":"57 MB","current-db-size-in-use-bytes":30650368,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-24T08:16:26.733903Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1150896314,"revision":14563,"compact-revision":12559} {"level":"info","ts":"2026-09-24T08:21:26.215083Z","caller":"traceutil/trace.go:171","msg":"trace[37581982] transaction","detail":"{read_only:false; response_revision:19113; number_of_response:1; }","duration":"137.636271ms","start":"2026-09-24T08:21:26.077407Z","end":"2026-09-24T08:21:26.215043Z","steps":["trace[37581982] 'process raft request' (duration: 107.331068ms)","trace[37581982] 'compare' (duration: 30.122368ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:21:26.648983Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16867} {"level":"info","ts":"2026-09-24T08:21:26.775744Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16867,"took":"123.71555ms","hash":3400927730,"current-db-size-bytes":57012224,"current-db-size":"57 MB","current-db-size-in-use-bytes":31985664,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-24T08:21:26.775848Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3400927730,"revision":16867,"compact-revision":14563} {"level":"warn","ts":"2026-09-24T08:21:32.581918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.421094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1985"} {"level":"info","ts":"2026-09-24T08:21:32.582063Z","caller":"traceutil/trace.go:171","msg":"trace[1407040029] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:19166; }","duration":"181.580318ms","start":"2026-09-24T08:21:32.400449Z","end":"2026-09-24T08:21:32.58203Z","steps":["trace[1407040029] 'range keys from in-memory index tree' (duration: 181.293031ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:21:32.827079Z","caller":"traceutil/trace.go:171","msg":"trace[1312045321] transaction","detail":"{read_only:false; response_revision:19168; number_of_response:1; }","duration":"180.772778ms","start":"2026-09-24T08:21:32.646263Z","end":"2026-09-24T08:21:32.827036Z","steps":["trace[1312045321] 'process raft request' (duration: 111.529536ms)","trace[1312045321] 'compare' (duration: 69.096289ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:21:32.881481Z","caller":"traceutil/trace.go:171","msg":"trace[1118834719] transaction","detail":"{read_only:false; response_revision:19169; number_of_response:1; }","duration":"219.234883ms","start":"2026-09-24T08:21:32.662215Z","end":"2026-09-24T08:21:32.881449Z","steps":["trace[1118834719] 'process raft request' (duration: 219.002047ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:21:32.881819Z","caller":"traceutil/trace.go:171","msg":"trace[65101606] transaction","detail":"{read_only:false; response_revision:19170; number_of_response:1; }","duration":"175.374647ms","start":"2026-09-24T08:21:32.706428Z","end":"2026-09-24T08:21:32.881803Z","steps":["trace[65101606] 'process raft request' (duration: 174.969917ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:21:32.882133Z","caller":"traceutil/trace.go:171","msg":"trace[1849199453] transaction","detail":"{read_only:false; response_revision:19171; number_of_response:1; }","duration":"130.568639ms","start":"2026-09-24T08:21:32.751532Z","end":"2026-09-24T08:21:32.882101Z","steps":["trace[1849199453] 'process raft request' (duration: 130.21995ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:21:32.882125Z","caller":"traceutil/trace.go:171","msg":"trace[476153929] linearizableReadLoop","detail":"{readStateIndex:19838; appliedIndex:19835; }","duration":"143.77812ms","start":"2026-09-24T08:21:32.738277Z","end":"2026-09-24T08:21:32.882055Z","steps":["trace[476153929] 'read index received' (duration: 19.445324ms)","trace[476153929] 'applied index is now lower than readState.Index' (duration: 124.327666ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:21:32.882465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.142229ms","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:6"} {"level":"info","ts":"2026-09-24T08:21:32.882527Z","caller":"traceutil/trace.go:171","msg":"trace[136897437] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:19171; }","duration":"144.318184ms","start":"2026-09-24T08:21:32.738186Z","end":"2026-09-24T08:21:32.882504Z","steps":["trace[136897437] 'agreement among raft nodes before linearized reading' (duration: 144.117439ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:21:33.315436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.124717ms","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-24T08:21:33.315517Z","caller":"traceutil/trace.go:171","msg":"trace[1455728384] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:19171; }","duration":"285.24273ms","start":"2026-09-24T08:21:33.030254Z","end":"2026-09-24T08:21:33.315497Z","steps":["trace[1455728384] 'range keys from in-memory index tree' (duration: 284.959243ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:21:33.316169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.65453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-09-24T08:21:33.316213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.133368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-m9gtt\" ","response":"range_response_count:1 size:20664"} {"level":"info","ts":"2026-09-24T08:21:33.316294Z","caller":"traceutil/trace.go:171","msg":"trace[1143363611] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-m9gtt; range_end:; response_count:1; response_revision:19171; }","duration":"287.263422ms","start":"2026-09-24T08:21:33.029009Z","end":"2026-09-24T08:21:33.316273Z","steps":["trace[1143363611] 'range keys from in-memory index tree' (duration: 286.953673ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:21:33.316295Z","caller":"traceutil/trace.go:171","msg":"trace[17012066] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19171; }","duration":"239.897687ms","start":"2026-09-24T08:21:33.076367Z","end":"2026-09-24T08:21:33.316265Z","steps":["trace[17012066] 'range keys from in-memory index tree' (duration: 239.368443ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:21:33.316176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.670819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-24T08:21:33.316381Z","caller":"traceutil/trace.go:171","msg":"trace[1384940721] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19171; }","duration":"145.950646ms","start":"2026-09-24T08:21:33.170408Z","end":"2026-09-24T08:21:33.316359Z","steps":["trace[1384940721] 'range keys from in-memory index tree' (duration: 145.385322ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:21:33.520407Z","caller":"traceutil/trace.go:171","msg":"trace[1606684961] transaction","detail":"{read_only:false; response_revision:19172; number_of_response:1; }","duration":"196.7535ms","start":"2026-09-24T08:21:33.323612Z","end":"2026-09-24T08:21:33.520366Z","steps":["trace[1606684961] 'process raft request' (duration: 196.554995ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:21:33.520546Z","caller":"traceutil/trace.go:171","msg":"trace[2075841801] linearizableReadLoop","detail":"{readStateIndex:19840; appliedIndex:19840; }","duration":"194.043289ms","start":"2026-09-24T08:21:33.326342Z","end":"2026-09-24T08:21:33.520385Z","steps":["trace[2075841801] 'read index received' (duration: 194.022689ms)","trace[2075841801] 'applied index is now lower than readState.Index' (duration: 16.75µs)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:21:33.521912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.532658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:948060"} {"level":"info","ts":"2026-09-24T08:21:33.521967Z","caller":"traceutil/trace.go:171","msg":"trace[1862447103] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:19172; }","duration":"195.684962ms","start":"2026-09-24T08:21:33.326267Z","end":"2026-09-24T08:21:33.521952Z","steps":["trace[1862447103] 'agreement among raft nodes before linearized reading' (duration: 194.143222ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:21:33.688334Z","caller":"traceutil/trace.go:171","msg":"trace[223896545] linearizableReadLoop","detail":"{readStateIndex:19842; appliedIndex:19840; }","duration":"167.624017ms","start":"2026-09-24T08:21:33.52068Z","end":"2026-09-24T08:21:33.688304Z","steps":["trace[223896545] 'read index received' (duration: 120.915768ms)","trace[223896545] 'applied index is now lower than readState.Index' (duration: 46.706729ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:21:33.688787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.304471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-24T08:21:33.688915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.424589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-24T08:21:33.688926Z","caller":"traceutil/trace.go:171","msg":"trace[1777787437] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:19174; }","duration":"210.478505ms","start":"2026-09-24T08:21:33.478418Z","end":"2026-09-24T08:21:33.688897Z","steps":["trace[1777787437] 'agreement among raft nodes before linearized reading' (duration: 210.27422ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:21:33.68895Z","caller":"traceutil/trace.go:171","msg":"trace[1512228286] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:19174; }","duration":"124.46891ms","start":"2026-09-24T08:21:33.56447Z","end":"2026-09-24T08:21:33.688939Z","steps":["trace[1512228286] 'agreement among raft nodes before linearized reading' (duration: 124.390198ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:21:33.688957Z","caller":"traceutil/trace.go:171","msg":"trace[94466195] transaction","detail":"{read_only:false; response_revision:19173; number_of_response:1; }","duration":"363.540535ms","start":"2026-09-24T08:21:33.325395Z","end":"2026-09-24T08:21:33.688935Z","steps":["trace[94466195] 'process raft request' (duration: 316.278352ms)","trace[94466195] 'compare' (duration: 46.238897ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:21:33.68897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.245569ms","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-24T08:21:33.689054Z","caller":"traceutil/trace.go:171","msg":"trace[686339602] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19174; }","duration":"227.440434ms","start":"2026-09-24T08:21:33.46159Z","end":"2026-09-24T08:21:33.68903Z","steps":["trace[686339602] 'agreement among raft nodes before linearized reading' (duration: 227.139807ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:21:33.689087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:21:33.325373Z","time spent":"363.626098ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-24T08:21:33.689197Z","caller":"traceutil/trace.go:171","msg":"trace[438406617] transaction","detail":"{read_only:false; response_revision:19174; number_of_response:1; }","duration":"341.664629ms","start":"2026-09-24T08:21:33.347522Z","end":"2026-09-24T08:21:33.689186Z","steps":["trace[438406617] 'process raft request' (duration: 340.660514ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:21:33.689281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:21:33.34748Z","time spent":"341.738181ms","remote":"127.0.0.1:52056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20781,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-24T08:21:33.689337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.490665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-24T08:21:33.689361Z","caller":"traceutil/trace.go:171","msg":"trace[518195633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19174; }","duration":"227.560317ms","start":"2026-09-24T08:21:33.461793Z","end":"2026-09-24T08:21:33.689353Z","steps":["trace[518195633] 'agreement among raft nodes before linearized reading' (duration: 227.458794ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:21:41.668168Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"427150911cb3975a","local-member-applied-index":20007,"local-member-snapshot-index":10006,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-24T08:21:41.678517Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20007} {"level":"info","ts":"2026-09-24T08:21:41.678684Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15007} {"level":"info","ts":"2026-09-24T08:26:26.666173Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19117} {"level":"info","ts":"2026-09-24T08:26:26.80571Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19117,"took":"133.780442ms","hash":3587828381,"current-db-size-bytes":57012224,"current-db-size":"57 MB","current-db-size-in-use-bytes":33980416,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-24T08:26:26.805786Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3587828381,"revision":19117,"compact-revision":16867} {"level":"info","ts":"2026-09-24T08:27:24.560966Z","caller":"traceutil/trace.go:171","msg":"trace[404428779] transaction","detail":"{read_only:false; response_revision:21901; number_of_response:1; }","duration":"124.709216ms","start":"2026-09-24T08:27:24.436234Z","end":"2026-09-24T08:27:24.560944Z","steps":["trace[404428779] 'process raft request' (duration: 117.832838ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:27:45.849963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.022723ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-24T08:27:45.850438Z","caller":"traceutil/trace.go:171","msg":"trace[1811261843] transaction","detail":"{read_only:false; response_revision:22013; number_of_response:1; }","duration":"283.67251ms","start":"2026-09-24T08:27:45.566742Z","end":"2026-09-24T08:27:45.850414Z","steps":["trace[1811261843] 'process raft request' (duration: 283.442643ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:27:45.850629Z","caller":"traceutil/trace.go:171","msg":"trace[842098277] linearizableReadLoop","detail":"{readStateIndex:22777; appliedIndex:22775; }","duration":"217.529619ms","start":"2026-09-24T08:27:45.633028Z","end":"2026-09-24T08:27:45.850558Z","steps":["trace[842098277] 'read index received' (duration: 47.935971ms)","trace[842098277] 'applied index is now lower than readState.Index' (duration: 169.582358ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:27:45.85093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.876338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-24T08:27:45.85096Z","caller":"traceutil/trace.go:171","msg":"trace[381770491] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22013; }","duration":"217.97158ms","start":"2026-09-24T08:27:45.632978Z","end":"2026-09-24T08:27:45.850949Z","steps":["trace[381770491] 'agreement among raft nodes before linearized reading' (duration: 217.731274ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:27:46.178206Z","caller":"traceutil/trace.go:171","msg":"trace[260537044] transaction","detail":"{read_only:false; response_revision:22015; number_of_response:1; }","duration":"323.217154ms","start":"2026-09-24T08:27:45.854968Z","end":"2026-09-24T08:27:46.178185Z","steps":["trace[260537044] 'process raft request' (duration: 323.0921ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:27:46.178441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:27:45.854941Z","time spent":"323.331206ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-24T08:27:46.179073Z","caller":"traceutil/trace.go:171","msg":"trace[1023172542] linearizableReadLoop","detail":"{readStateIndex:22779; appliedIndex:22779; }","duration":"293.633947ms","start":"2026-09-24T08:27:45.885429Z","end":"2026-09-24T08:27:46.179063Z","steps":["trace[1023172542] 'read index received' (duration: 293.629767ms)","trace[1023172542] 'applied index is now lower than readState.Index' (duration: 3.31µs)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:27:46.179208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.773431ms","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:6"} {"level":"info","ts":"2026-09-24T08:27:46.17924Z","caller":"traceutil/trace.go:171","msg":"trace[724836162] 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:22015; }","duration":"293.856913ms","start":"2026-09-24T08:27:45.885374Z","end":"2026-09-24T08:27:46.179231Z","steps":["trace[724836162] 'agreement among raft nodes before linearized reading' (duration: 293.7406ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:27:46.202026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.191879ms","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-24T08:27:46.202108Z","caller":"traceutil/trace.go:171","msg":"trace[1969384172] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22016; }","duration":"251.326363ms","start":"2026-09-24T08:27:45.950762Z","end":"2026-09-24T08:27:46.202089Z","steps":["trace[1969384172] 'agreement among raft nodes before linearized reading' (duration: 251.119067ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:27:46.202429Z","caller":"traceutil/trace.go:171","msg":"trace[657323233] transaction","detail":"{read_only:false; response_revision:22016; number_of_response:1; }","duration":"308.616735ms","start":"2026-09-24T08:27:45.893799Z","end":"2026-09-24T08:27:46.202416Z","steps":["trace[657323233] 'process raft request' (duration: 307.969668ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:27:46.2026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:27:45.893774Z","time spent":"308.685147ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-24T08:27:46.202684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.905039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-09-24T08:27:46.202723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.739651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-24T08:27:46.202822Z","caller":"traceutil/trace.go:171","msg":"trace[464435870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22016; }","duration":"150.909715ms","start":"2026-09-24T08:27:46.051893Z","end":"2026-09-24T08:27:46.202803Z","steps":["trace[464435870] 'agreement among raft nodes before linearized reading' (duration: 150.595157ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:27:46.202759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.205487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-24T08:27:46.202841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.37834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-09-24T08:27:46.202963Z","caller":"traceutil/trace.go:171","msg":"trace[1210357075] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:22016; }","duration":"121.528124ms","start":"2026-09-24T08:27:46.081396Z","end":"2026-09-24T08:27:46.202924Z","steps":["trace[1210357075] 'agreement among raft nodes before linearized reading' (duration: 121.115603ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:27:46.202894Z","caller":"traceutil/trace.go:171","msg":"trace[1041289261] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:22016; }","duration":"106.373902ms","start":"2026-09-24T08:27:46.096511Z","end":"2026-09-24T08:27:46.202885Z","steps":["trace[1041289261] 'agreement among raft nodes before linearized reading' (duration: 106.202387ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:27:46.202772Z","caller":"traceutil/trace.go:171","msg":"trace[830766198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22016; }","duration":"215.037602ms","start":"2026-09-24T08:27:45.987706Z","end":"2026-09-24T08:27:46.202744Z","steps":["trace[830766198] 'agreement among raft nodes before linearized reading' (duration: 214.818657ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:27:46.430661Z","caller":"traceutil/trace.go:171","msg":"trace[333494071] transaction","detail":"{read_only:false; response_revision:22017; number_of_response:1; }","duration":"222.747124ms","start":"2026-09-24T08:27:46.20789Z","end":"2026-09-24T08:27:46.430637Z","steps":["trace[333494071] 'process raft request' (duration: 146.366567ms)","trace[333494071] 'compare' (duration: 76.210743ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:27:46.430943Z","caller":"traceutil/trace.go:171","msg":"trace[1425486291] transaction","detail":"{read_only:false; response_revision:22018; number_of_response:1; }","duration":"222.433675ms","start":"2026-09-24T08:27:46.208486Z","end":"2026-09-24T08:27:46.43092Z","steps":["trace[1425486291] 'process raft request' (duration: 222.117667ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:27:46.43099Z","caller":"traceutil/trace.go:171","msg":"trace[855500587] linearizableReadLoop","detail":"{readStateIndex:22783; appliedIndex:22780; }","duration":"207.196541ms","start":"2026-09-24T08:27:46.223773Z","end":"2026-09-24T08:27:46.430969Z","steps":["trace[855500587] 'read index received' (duration: 130.435284ms)","trace[855500587] 'applied index is now lower than readState.Index' (duration: 76.760547ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:27:46.431109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.327825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-24T08:27:46.431142Z","caller":"traceutil/trace.go:171","msg":"trace[1472241388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22019; }","duration":"207.430107ms","start":"2026-09-24T08:27:46.223704Z","end":"2026-09-24T08:27:46.431134Z","steps":["trace[1472241388] 'agreement among raft nodes before linearized reading' (duration: 207.341735ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:27:46.431199Z","caller":"traceutil/trace.go:171","msg":"trace[1402024405] transaction","detail":"{read_only:false; response_revision:22019; number_of_response:1; }","duration":"222.680882ms","start":"2026-09-24T08:27:46.208507Z","end":"2026-09-24T08:27:46.431188Z","steps":["trace[1402024405] 'process raft request' (duration: 222.359674ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:27:46.806496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.278464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-24T08:27:46.806607Z","caller":"traceutil/trace.go:171","msg":"trace[1769748959] transaction","detail":"{read_only:false; response_revision:22020; number_of_response:1; }","duration":"344.808531ms","start":"2026-09-24T08:27:46.461765Z","end":"2026-09-24T08:27:46.806574Z","steps":["trace[1769748959] 'process raft request' (duration: 222.377374ms)","trace[1769748959] 'compare' (duration: 122.175301ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:27:46.806656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:27:46.461736Z","time spent":"344.898183ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-24T08:27:46.828457Z","caller":"traceutil/trace.go:171","msg":"trace[1446840595] transaction","detail":"{read_only:false; response_revision:22023; number_of_response:1; }","duration":"166.383545ms","start":"2026-09-24T08:27:46.662046Z","end":"2026-09-24T08:27:46.828429Z","steps":["trace[1446840595] 'process raft request' (duration: 166.348704ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:27:46.828663Z","caller":"traceutil/trace.go:171","msg":"trace[1125844612] transaction","detail":"{read_only:false; response_revision:22022; number_of_response:1; }","duration":"169.148347ms","start":"2026-09-24T08:27:46.65947Z","end":"2026-09-24T08:27:46.828618Z","steps":["trace[1125844612] 'process raft request' (duration: 168.8978ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:27:46.82865Z","caller":"traceutil/trace.go:171","msg":"trace[196674519] transaction","detail":"{read_only:false; response_revision:22021; number_of_response:1; }","duration":"297.695452ms","start":"2026-09-24T08:27:46.53087Z","end":"2026-09-24T08:27:46.828566Z","steps":["trace[196674519] 'process raft request' (duration: 297.378804ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:31:26.67453Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21565} {"level":"info","ts":"2026-09-24T08:31:26.795608Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21565,"took":"116.569306ms","hash":1115919567,"current-db-size-bytes":57012224,"current-db-size":"57 MB","current-db-size-in-use-bytes":30670848,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-24T08:31:26.7957Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1115919567,"revision":21565,"compact-revision":19117} {"level":"info","ts":"2026-09-24T08:34:24.089391Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068e1.wal"} {"level":"info","ts":"2026-09-24T08:36:01.200593Z","caller":"traceutil/trace.go:171","msg":"trace[2091075316] transaction","detail":"{read_only:false; response_revision:26913; number_of_response:1; }","duration":"168.680784ms","start":"2026-09-24T08:36:01.03189Z","end":"2026-09-24T08:36:01.200571Z","steps":["trace[2091075316] 'process raft request' (duration: 168.51072ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:01.49725Z","caller":"traceutil/trace.go:171","msg":"trace[1258510232] transaction","detail":"{read_only:false; response_revision:26915; number_of_response:1; }","duration":"348.370804ms","start":"2026-09-24T08:36:01.148865Z","end":"2026-09-24T08:36:01.497236Z","steps":["trace[1258510232] 'process raft request' (duration: 348.291912ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:01.49726Z","caller":"traceutil/trace.go:171","msg":"trace[1475105090] transaction","detail":"{read_only:false; response_revision:26914; number_of_response:1; }","duration":"463.224315ms","start":"2026-09-24T08:36:01.034005Z","end":"2026-09-24T08:36:01.497229Z","steps":["trace[1475105090] 'process raft request' (duration: 364.005388ms)","trace[1475105090] 'compare' (duration: 99.045892ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:36:01.497355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:01.14884Z","time spent":"348.450955ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-24T08:36:01.497223Z","caller":"traceutil/trace.go:171","msg":"trace[1644745378] linearizableReadLoop","detail":"{readStateIndex:27812; appliedIndex:27810; }","duration":"427.262315ms","start":"2026-09-24T08:36:01.069939Z","end":"2026-09-24T08:36:01.497201Z","steps":["trace[1644745378] 'read index received' (duration: 130.439965ms)","trace[1644745378] 'applied index is now lower than readState.Index' (duration: 296.82158ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:36:01.497394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:01.033986Z","time spent":"463.334308ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-24T08:36:01.497488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.542772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-09-24T08:36:01.497548Z","caller":"traceutil/trace.go:171","msg":"trace[1627150186] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26915; }","duration":"427.629334ms","start":"2026-09-24T08:36:01.069906Z","end":"2026-09-24T08:36:01.497536Z","steps":["trace[1627150186] 'agreement among raft nodes before linearized reading' (duration: 427.372438ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:01.497597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:01.06989Z","time spent":"427.698356ms","remote":"127.0.0.1:52032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1877,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-24T08:36:01.499547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.47576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-24T08:36:01.499622Z","caller":"traceutil/trace.go:171","msg":"trace[597686465] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:26916; }","duration":"105.623003ms","start":"2026-09-24T08:36:01.393982Z","end":"2026-09-24T08:36:01.499605Z","steps":["trace[597686465] 'agreement among raft nodes before linearized reading' (duration: 105.49077ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:01.49978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.01925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-24T08:36:01.499808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.181196ms","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-24T08:36:01.499879Z","caller":"traceutil/trace.go:171","msg":"trace[617214013] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26916; }","duration":"162.32269ms","start":"2026-09-24T08:36:01.337539Z","end":"2026-09-24T08:36:01.499862Z","steps":["trace[617214013] 'agreement among raft nodes before linearized reading' (duration: 162.085384ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:01.499883Z","caller":"traceutil/trace.go:171","msg":"trace[1217418752] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:26916; }","duration":"148.153493ms","start":"2026-09-24T08:36:01.351691Z","end":"2026-09-24T08:36:01.499845Z","steps":["trace[1217418752] 'agreement among raft nodes before linearized reading' (duration: 148.03969ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:01.499928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.302595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-24T08:36:01.499957Z","caller":"traceutil/trace.go:171","msg":"trace[331948803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26916; }","duration":"277.355726ms","start":"2026-09-24T08:36:01.222594Z","end":"2026-09-24T08:36:01.49995Z","steps":["trace[331948803] 'agreement among raft nodes before linearized reading' (duration: 277.305995ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:01.49998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.697069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-09-24T08:36:01.500061Z","caller":"traceutil/trace.go:171","msg":"trace[2109447135] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26916; }","duration":"426.820813ms","start":"2026-09-24T08:36:01.073226Z","end":"2026-09-24T08:36:01.500047Z","steps":["trace[2109447135] 'agreement among raft nodes before linearized reading' (duration: 426.231327ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:01.50009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:01.073204Z","time spent":"426.876964ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-24T08:36:01.764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.02161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-24T08:36:01.764179Z","caller":"traceutil/trace.go:171","msg":"trace[772851007] transaction","detail":"{read_only:false; response_revision:26920; number_of_response:1; }","duration":"259.587566ms","start":"2026-09-24T08:36:01.504579Z","end":"2026-09-24T08:36:01.764166Z","steps":["trace[772851007] 'process raft request' (duration: 259.555005ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:01.764317Z","caller":"traceutil/trace.go:171","msg":"trace[1393886197] transaction","detail":"{read_only:false; response_revision:26918; number_of_response:1; }","duration":"260.567902ms","start":"2026-09-24T08:36:01.503714Z","end":"2026-09-24T08:36:01.764282Z","steps":["trace[1393886197] 'process raft request' (duration: 146.184002ms)","trace[1393886197] 'compare' (duration: 113.929658ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:36:01.764338Z","caller":"traceutil/trace.go:171","msg":"trace[1115916948] transaction","detail":"{read_only:false; response_revision:26919; number_of_response:1; }","duration":"260.448369ms","start":"2026-09-24T08:36:01.503841Z","end":"2026-09-24T08:36:01.764289Z","steps":["trace[1115916948] 'process raft request' (duration: 260.254854ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:01.76544Z","caller":"traceutil/trace.go:171","msg":"trace[1469646586] transaction","detail":"{read_only:false; response_revision:26921; number_of_response:1; }","duration":"114.427051ms","start":"2026-09-24T08:36:01.650987Z","end":"2026-09-24T08:36:01.765414Z","steps":["trace[1469646586] 'process raft request' (duration: 114.308217ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:02.448369Z","caller":"traceutil/trace.go:171","msg":"trace[130102120] transaction","detail":"{read_only:false; response_revision:26923; number_of_response:1; }","duration":"140.980847ms","start":"2026-09-24T08:36:02.307362Z","end":"2026-09-24T08:36:02.448343Z","steps":["trace[130102120] 'process raft request' (duration: 140.793792ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:02.656007Z","caller":"traceutil/trace.go:171","msg":"trace[358735872] transaction","detail":"{read_only:false; response_revision:26924; number_of_response:1; }","duration":"157.683991ms","start":"2026-09-24T08:36:02.49829Z","end":"2026-09-24T08:36:02.655974Z","steps":["trace[358735872] 'process raft request' (duration: 133.367421ms)","trace[358735872] 'compare' (duration: 24.200697ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:36:03.304087Z","caller":"traceutil/trace.go:171","msg":"trace[1283491660] linearizableReadLoop","detail":"{readStateIndex:27824; appliedIndex:27823; }","duration":"247.38981ms","start":"2026-09-24T08:36:03.056673Z","end":"2026-09-24T08:36:03.304063Z","steps":["trace[1283491660] 'read index received' (duration: 247.235686ms)","trace[1283491660] 'applied index is now lower than readState.Index' (duration: 152.944µs)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:36:03.304375Z","caller":"traceutil/trace.go:171","msg":"trace[1439655656] transaction","detail":"{read_only:false; response_revision:26926; number_of_response:1; }","duration":"253.695234ms","start":"2026-09-24T08:36:03.050596Z","end":"2026-09-24T08:36:03.304291Z","steps":["trace[1439655656] 'process raft request' (duration: 253.316554ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:03.304624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.932624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-09-24T08:36:03.304661Z","caller":"traceutil/trace.go:171","msg":"trace[1238631011] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:26926; }","duration":"248.015526ms","start":"2026-09-24T08:36:03.056635Z","end":"2026-09-24T08:36:03.304651Z","steps":["trace[1238631011] 'agreement among raft nodes before linearized reading' (duration: 247.886793ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:03.305814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.213653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-24T08:36:03.30588Z","caller":"traceutil/trace.go:171","msg":"trace[1978907588] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:26927; }","duration":"185.303485ms","start":"2026-09-24T08:36:03.12056Z","end":"2026-09-24T08:36:03.305863Z","steps":["trace[1978907588] 'agreement among raft nodes before linearized reading' (duration: 185.162251ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:03.306098Z","caller":"traceutil/trace.go:171","msg":"trace[1568932696] transaction","detail":"{read_only:false; response_revision:26927; number_of_response:1; }","duration":"100.407368ms","start":"2026-09-24T08:36:03.205671Z","end":"2026-09-24T08:36:03.306078Z","steps":["trace[1568932696] 'process raft request' (duration: 99.927214ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:03.306144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.50686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-09-24T08:36:03.306177Z","caller":"traceutil/trace.go:171","msg":"trace[1972816341] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26927; }","duration":"122.571652ms","start":"2026-09-24T08:36:03.183595Z","end":"2026-09-24T08:36:03.306166Z","steps":["trace[1972816341] 'agreement among raft nodes before linearized reading' (duration: 122.455289ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:06.086548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.301251ms","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-24T08:36:06.086674Z","caller":"traceutil/trace.go:171","msg":"trace[566179075] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26949; }","duration":"181.470526ms","start":"2026-09-24T08:36:05.905166Z","end":"2026-09-24T08:36:06.086637Z","steps":["trace[566179075] 'range keys from in-memory index tree' (duration: 181.074315ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:06.208468Z","caller":"traceutil/trace.go:171","msg":"trace[1982786399] transaction","detail":"{read_only:false; response_revision:26950; number_of_response:1; }","duration":"117.11693ms","start":"2026-09-24T08:36:06.091316Z","end":"2026-09-24T08:36:06.208433Z","steps":["trace[1982786399] 'process raft request' (duration: 116.872844ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:06.404786Z","caller":"traceutil/trace.go:171","msg":"trace[782525157] transaction","detail":"{read_only:false; response_revision:26951; number_of_response:1; }","duration":"160.752659ms","start":"2026-09-24T08:36:06.243995Z","end":"2026-09-24T08:36:06.404748Z","steps":["trace[782525157] 'process raft request' (duration: 94.918716ms)","trace[782525157] 'compare' (duration: 65.70192ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:36:07.032326Z","caller":"traceutil/trace.go:171","msg":"trace[141594313] transaction","detail":"{read_only:false; response_revision:26953; number_of_response:1; }","duration":"363.788052ms","start":"2026-09-24T08:36:06.668518Z","end":"2026-09-24T08:36:07.032306Z","steps":["trace[141594313] 'process raft request' (duration: 363.647869ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:07.032453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:06.668499Z","time spent":"363.891835ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-24T08:36:07.329583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.285127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-24T08:36:07.329677Z","caller":"traceutil/trace.go:171","msg":"trace[1339622408] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26954; }","duration":"109.433601ms","start":"2026-09-24T08:36:07.220223Z","end":"2026-09-24T08:36:07.329656Z","steps":["trace[1339622408] 'range keys from in-memory index tree' (duration: 109.156884ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:07.32984Z","caller":"traceutil/trace.go:171","msg":"trace[1402539000] transaction","detail":"{read_only:false; response_revision:26955; number_of_response:1; }","duration":"109.032801ms","start":"2026-09-24T08:36:07.220732Z","end":"2026-09-24T08:36:07.329764Z","steps":["trace[1402539000] 'process raft request' (duration: 93.297924ms)","trace[1402539000] 'compare' (duration: 15.359357ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:36:07.485454Z","caller":"traceutil/trace.go:171","msg":"trace[272701801] linearizableReadLoop","detail":"{readStateIndex:27856; appliedIndex:27854; }","duration":"154.56746ms","start":"2026-09-24T08:36:07.330868Z","end":"2026-09-24T08:36:07.485436Z","steps":["trace[272701801] 'read index received' (duration: 140.138246ms)","trace[272701801] 'applied index is now lower than readState.Index' (duration: 14.428184ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:36:07.485513Z","caller":"traceutil/trace.go:171","msg":"trace[348290417] transaction","detail":"{read_only:false; response_revision:26956; number_of_response:1; }","duration":"191.916106ms","start":"2026-09-24T08:36:07.293559Z","end":"2026-09-24T08:36:07.485476Z","steps":["trace[348290417] 'process raft request' (duration: 177.513643ms)","trace[348290417] 'compare' (duration: 13.93089ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:36:07.485536Z","caller":"traceutil/trace.go:171","msg":"trace[2024193847] transaction","detail":"{read_only:false; response_revision:26957; number_of_response:1; }","duration":"167.930605ms","start":"2026-09-24T08:36:07.317584Z","end":"2026-09-24T08:36:07.485514Z","steps":["trace[2024193847] 'process raft request' (duration: 167.7656ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:07.485605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.724714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-24T08:36:07.485646Z","caller":"traceutil/trace.go:171","msg":"trace[127702460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26957; }","duration":"154.779185ms","start":"2026-09-24T08:36:07.330857Z","end":"2026-09-24T08:36:07.485636Z","steps":["trace[127702460] 'agreement among raft nodes before linearized reading' (duration: 154.693463ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:07.485889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.029582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-24T08:36:07.48598Z","caller":"traceutil/trace.go:171","msg":"trace[1521496426] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26957; }","duration":"138.289888ms","start":"2026-09-24T08:36:07.34767Z","end":"2026-09-24T08:36:07.48596Z","steps":["trace[1521496426] 'agreement among raft nodes before linearized reading' (duration: 138.104134ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:07.810763Z","caller":"traceutil/trace.go:171","msg":"trace[670655021] transaction","detail":"{read_only:false; response_revision:26958; number_of_response:1; }","duration":"284.797768ms","start":"2026-09-24T08:36:07.525938Z","end":"2026-09-24T08:36:07.810736Z","steps":["trace[670655021] 'process raft request' (duration: 284.603143ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:07.823035Z","caller":"traceutil/trace.go:171","msg":"trace[54651480] transaction","detail":"{read_only:false; response_revision:26959; number_of_response:1; }","duration":"296.718837ms","start":"2026-09-24T08:36:07.526286Z","end":"2026-09-24T08:36:07.823005Z","steps":["trace[54651480] 'process raft request' (duration: 296.519022ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:07.823286Z","caller":"traceutil/trace.go:171","msg":"trace[625865706] transaction","detail":"{read_only:false; response_revision:26960; number_of_response:1; }","duration":"295.102895ms","start":"2026-09-24T08:36:07.528152Z","end":"2026-09-24T08:36:07.823255Z","steps":["trace[625865706] 'process raft request' (duration: 294.814637ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:07.823397Z","caller":"traceutil/trace.go:171","msg":"trace[663830128] linearizableReadLoop","detail":"{readStateIndex:27859; appliedIndex:27857; }","duration":"275.488037ms","start":"2026-09-24T08:36:07.547882Z","end":"2026-09-24T08:36:07.82337Z","steps":["trace[663830128] 'read index received' (duration: 262.863981ms)","trace[663830128] 'applied index is now lower than readState.Index' (duration: 12.622486ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:36:07.823654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.761394ms","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-24T08:36:07.823751Z","caller":"traceutil/trace.go:171","msg":"trace[1092727643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26961; }","duration":"275.905798ms","start":"2026-09-24T08:36:07.547827Z","end":"2026-09-24T08:36:07.823732Z","steps":["trace[1092727643] 'agreement among raft nodes before linearized reading' (duration: 275.656412ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:08.10783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.022918ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-24T08:36:08.10805Z","caller":"traceutil/trace.go:171","msg":"trace[523042056] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26961; }","duration":"163.240294ms","start":"2026-09-24T08:36:07.944747Z","end":"2026-09-24T08:36:08.107987Z","steps":["trace[523042056] 'range keys from in-memory index tree' (duration: 162.989788ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:08.108728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.986666ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-24T08:36:08.108831Z","caller":"traceutil/trace.go:171","msg":"trace[142493070] transaction","detail":"{read_only:false; response_revision:26962; number_of_response:1; }","duration":"281.300888ms","start":"2026-09-24T08:36:07.827512Z","end":"2026-09-24T08:36:08.108813Z","steps":["trace[142493070] 'process raft request' (duration: 130.119047ms)","trace[142493070] 'compare' (duration: 150.852062ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:36:08.122577Z","caller":"traceutil/trace.go:171","msg":"trace[749400412] linearizableReadLoop","detail":"{readStateIndex:27864; appliedIndex:27860; }","duration":"169.587608ms","start":"2026-09-24T08:36:07.952963Z","end":"2026-09-24T08:36:08.122551Z","steps":["trace[749400412] 'read index received' (duration: 4.64964ms)","trace[749400412] 'applied index is now lower than readState.Index' (duration: 164.936688ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:36:08.122736Z","caller":"traceutil/trace.go:171","msg":"trace[1590040890] transaction","detail":"{read_only:false; response_revision:26964; number_of_response:1; }","duration":"294.396407ms","start":"2026-09-24T08:36:07.828287Z","end":"2026-09-24T08:36:08.122684Z","steps":["trace[1590040890] 'process raft request' (duration: 294.182932ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:08.122819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.827825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-24T08:36:08.122838Z","caller":"traceutil/trace.go:171","msg":"trace[1947474130] transaction","detail":"{read_only:false; response_revision:26965; number_of_response:1; }","duration":"293.502715ms","start":"2026-09-24T08:36:07.829314Z","end":"2026-09-24T08:36:08.122817Z","steps":["trace[1947474130] 'process raft request' (duration: 293.196737ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:08.122859Z","caller":"traceutil/trace.go:171","msg":"trace[1325035528] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:26965; }","duration":"169.917876ms","start":"2026-09-24T08:36:07.952926Z","end":"2026-09-24T08:36:08.122844Z","steps":["trace[1325035528] 'agreement among raft nodes before linearized reading' (duration: 169.725581ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:08.12281Z","caller":"traceutil/trace.go:171","msg":"trace[1114663563] transaction","detail":"{read_only:false; response_revision:26963; number_of_response:1; }","duration":"295.239789ms","start":"2026-09-24T08:36:07.827515Z","end":"2026-09-24T08:36:08.122755Z","steps":["trace[1114663563] 'process raft request' (duration: 294.809908ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:08.32178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.505417ms","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-24T08:36:08.321879Z","caller":"traceutil/trace.go:171","msg":"trace[1021817998] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26965; }","duration":"108.652711ms","start":"2026-09-24T08:36:08.213205Z","end":"2026-09-24T08:36:08.321858Z","steps":["trace[1021817998] 'range keys from in-memory index tree' (duration: 108.284372ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:08.466212Z","caller":"traceutil/trace.go:171","msg":"trace[747145752] transaction","detail":"{read_only:false; response_revision:26966; number_of_response:1; }","duration":"139.751206ms","start":"2026-09-24T08:36:08.326431Z","end":"2026-09-24T08:36:08.466182Z","steps":["trace[747145752] 'process raft request' (duration: 139.560201ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:09.176114Z","caller":"traceutil/trace.go:171","msg":"trace[354480127] transaction","detail":"{read_only:false; response_revision:26968; number_of_response:1; }","duration":"139.734135ms","start":"2026-09-24T08:36:09.036352Z","end":"2026-09-24T08:36:09.176086Z","steps":["trace[354480127] 'process raft request' (duration: 139.53726ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:09.504366Z","caller":"traceutil/trace.go:171","msg":"trace[991293997] linearizableReadLoop","detail":"{readStateIndex:27869; appliedIndex:27866; }","duration":"348.27299ms","start":"2026-09-24T08:36:09.15607Z","end":"2026-09-24T08:36:09.504343Z","steps":["trace[991293997] 'read index received' (duration: 19.928575ms)","trace[991293997] 'applied index is now lower than readState.Index' (duration: 328.342935ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:36:09.504628Z","caller":"traceutil/trace.go:171","msg":"trace[1749818986] transaction","detail":"{read_only:false; response_revision:26969; number_of_response:1; }","duration":"440.898257ms","start":"2026-09-24T08:36:09.063715Z","end":"2026-09-24T08:36:09.504614Z","steps":["trace[1749818986] 'process raft request' (duration: 420.832878ms)","trace[1749818986] 'compare' (duration: 19.344221ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:36:09.504748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.625819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-24T08:36:09.504773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:09.063681Z","time spent":"440.981349ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-24T08:36:09.504755Z","caller":"traceutil/trace.go:171","msg":"trace[89692163] transaction","detail":"{read_only:false; response_revision:26970; number_of_response:1; }","duration":"401.052386ms","start":"2026-09-24T08:36:09.103622Z","end":"2026-09-24T08:36:09.504674Z","steps":["trace[89692163] 'process raft request' (duration: 400.656036ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:09.504811Z","caller":"traceutil/trace.go:171","msg":"trace[690391977] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:26970; }","duration":"348.797243ms","start":"2026-09-24T08:36:09.155995Z","end":"2026-09-24T08:36:09.504792Z","steps":["trace[690391977] 'agreement among raft nodes before linearized reading' (duration: 348.639759ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:09.504846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:09.15598Z","time spent":"348.855146ms","remote":"127.0.0.1:55696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true "} {"level":"warn","ts":"2026-09-24T08:36:09.504907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:09.103601Z","time spent":"401.22623ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-24T08:36:09.504965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.492885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-24T08:36:09.505035Z","caller":"traceutil/trace.go:171","msg":"trace[641970051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26971; }","duration":"283.623318ms","start":"2026-09-24T08:36:09.221396Z","end":"2026-09-24T08:36:09.505019Z","steps":["trace[641970051] 'agreement among raft nodes before linearized reading' (duration: 283.444924ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:09.727755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.068885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-24T08:36:09.72786Z","caller":"traceutil/trace.go:171","msg":"trace[1024062899] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:26971; }","duration":"118.249619ms","start":"2026-09-24T08:36:09.609584Z","end":"2026-09-24T08:36:09.727833Z","steps":["trace[1024062899] 'count revisions from in-memory index tree' (duration: 117.977082ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:10.290748Z","caller":"traceutil/trace.go:171","msg":"trace[1607803936] linearizableReadLoop","detail":"{readStateIndex:27874; appliedIndex:27871; }","duration":"421.31771ms","start":"2026-09-24T08:36:09.869408Z","end":"2026-09-24T08:36:10.290726Z","steps":["trace[1607803936] 'read index received' (duration: 420.977961ms)","trace[1607803936] 'applied index is now lower than readState.Index' (duration: 338.589µs)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:36:10.290865Z","caller":"traceutil/trace.go:171","msg":"trace[393100966] transaction","detail":"{read_only:false; response_revision:26974; number_of_response:1; }","duration":"462.258429ms","start":"2026-09-24T08:36:09.828595Z","end":"2026-09-24T08:36:10.290853Z","steps":["trace[393100966] 'process raft request' (duration: 462.043454ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:10.290848Z","caller":"traceutil/trace.go:171","msg":"trace[1124553395] transaction","detail":"{read_only:false; response_revision:26973; number_of_response:1; }","duration":"462.206508ms","start":"2026-09-24T08:36:09.828595Z","end":"2026-09-24T08:36:10.290801Z","steps":["trace[1124553395] 'process raft request' (duration: 461.89575ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:10.290957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:09.828574Z","time spent":"462.311001ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-24T08:36:10.291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:09.828574Z","time spent":"462.344342ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-24T08:36:10.291289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.013377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-24T08:36:10.291381Z","caller":"traceutil/trace.go:171","msg":"trace[793332473] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26975; }","duration":"289.1265ms","start":"2026-09-24T08:36:10.002232Z","end":"2026-09-24T08:36:10.291358Z","steps":["trace[793332473] 'agreement among raft nodes before linearized reading' (duration: 288.958356ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:10.291471Z","caller":"traceutil/trace.go:171","msg":"trace[862916527] transaction","detail":"{read_only:false; response_revision:26975; number_of_response:1; }","duration":"462.474485ms","start":"2026-09-24T08:36:09.828987Z","end":"2026-09-24T08:36:10.291462Z","steps":["trace[862916527] 'process raft request' (duration: 461.685955ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:10.291519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:09.828978Z","time spent":"462.518237ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-24T08:36:10.291653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.680079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-09-24T08:36:10.291661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.262625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-24T08:36:10.291685Z","caller":"traceutil/trace.go:171","msg":"trace[495045790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26975; }","duration":"162.759811ms","start":"2026-09-24T08:36:10.128916Z","end":"2026-09-24T08:36:10.291676Z","steps":["trace[495045790] 'agreement among raft nodes before linearized reading' (duration: 162.656649ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:10.291691Z","caller":"traceutil/trace.go:171","msg":"trace[1847132828] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:26975; }","duration":"422.336807ms","start":"2026-09-24T08:36:09.869343Z","end":"2026-09-24T08:36:10.29168Z","steps":["trace[1847132828] 'agreement among raft nodes before linearized reading' (duration: 422.286006ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:10.291726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:09.869305Z","time spent":"422.415639ms","remote":"127.0.0.1:33382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true "} {"level":"warn","ts":"2026-09-24T08:36:10.291687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.143885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-24T08:36:10.291753Z","caller":"traceutil/trace.go:171","msg":"trace[791179714] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26975; }","duration":"179.258238ms","start":"2026-09-24T08:36:10.112485Z","end":"2026-09-24T08:36:10.291744Z","steps":["trace[791179714] 'agreement among raft nodes before linearized reading' (duration: 179.095544ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:10.291859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.737164ms","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-24T08:36:10.291885Z","caller":"traceutil/trace.go:171","msg":"trace[1943328828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26975; }","duration":"161.777604ms","start":"2026-09-24T08:36:10.130101Z","end":"2026-09-24T08:36:10.291879Z","steps":["trace[1943328828] 'agreement among raft nodes before linearized reading' (duration: 161.703403ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:10.291986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.529765ms","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-24T08:36:10.292002Z","caller":"traceutil/trace.go:171","msg":"trace[447179790] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26975; }","duration":"162.546445ms","start":"2026-09-24T08:36:10.12945Z","end":"2026-09-24T08:36:10.291996Z","steps":["trace[447179790] 'agreement among raft nodes before linearized reading' (duration: 162.496194ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:10.72196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.325001ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-24T08:36:10.722345Z","caller":"traceutil/trace.go:171","msg":"trace[364038022] transaction","detail":"{read_only:false; response_revision:26978; number_of_response:1; }","duration":"426.856393ms","start":"2026-09-24T08:36:10.295459Z","end":"2026-09-24T08:36:10.722315Z","steps":["trace[364038022] 'process raft request' (duration: 426.618897ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:10.722352Z","caller":"traceutil/trace.go:171","msg":"trace[1981597635] transaction","detail":"{read_only:false; response_revision:26979; number_of_response:1; }","duration":"425.900499ms","start":"2026-09-24T08:36:10.296417Z","end":"2026-09-24T08:36:10.722318Z","steps":["trace[1981597635] 'process raft request' (duration: 425.719765ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:10.72238Z","caller":"traceutil/trace.go:171","msg":"trace[1170020181] transaction","detail":"{read_only:false; response_revision:26977; number_of_response:1; }","duration":"426.952106ms","start":"2026-09-24T08:36:10.295379Z","end":"2026-09-24T08:36:10.722331Z","steps":["trace[1170020181] 'process raft request' (duration: 194.125912ms)","trace[1170020181] 'compare' (duration: 232.162857ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:36:10.722461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:10.295367Z","time spent":"427.059709ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-24T08:36:10.72237Z","caller":"traceutil/trace.go:171","msg":"trace[1323212386] linearizableReadLoop","detail":"{readStateIndex:27878; appliedIndex:27875; }","duration":"308.507981ms","start":"2026-09-24T08:36:10.41385Z","end":"2026-09-24T08:36:10.722358Z","steps":["trace[1323212386] 'read index received' (duration: 75.599815ms)","trace[1323212386] 'applied index is now lower than readState.Index' (duration: 232.905976ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:36:10.722495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:10.295451Z","time spent":"426.953936ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-24T08:36:10.722504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:10.296406Z","time spent":"426.010272ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-24T08:36:10.722694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.855471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.85\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-09-24T08:36:10.72276Z","caller":"traceutil/trace.go:171","msg":"trace[1550660465] range","detail":"{range_begin:/registry/masterleases/162.253.55.85; range_end:; response_count:1; response_revision:26979; }","duration":"308.975023ms","start":"2026-09-24T08:36:10.413775Z","end":"2026-09-24T08:36:10.72275Z","steps":["trace[1550660465] 'agreement among raft nodes before linearized reading' (duration: 308.640105ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:10.722786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:10.413745Z","time spent":"309.033475ms","remote":"127.0.0.1:51904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/162.253.55.85\" "} {"level":"info","ts":"2026-09-24T08:36:11.019292Z","caller":"traceutil/trace.go:171","msg":"trace[1225876967] transaction","detail":"{read_only:false; response_revision:26980; number_of_response:1; }","duration":"493.321754ms","start":"2026-09-24T08:36:10.525945Z","end":"2026-09-24T08:36:11.019267Z","steps":["trace[1225876967] 'process raft request' (duration: 445.993829ms)","trace[1225876967] 'compare' (duration: 46.911024ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:36:11.019422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:10.525917Z","time spent":"493.439307ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-24T08:36:11.019433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.630775ms","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-24T08:36:11.019463Z","caller":"traceutil/trace.go:171","msg":"trace[1538894712] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26981; }","duration":"548.716867ms","start":"2026-09-24T08:36:10.470737Z","end":"2026-09-24T08:36:11.019454Z","steps":["trace[1538894712] 'agreement among raft nodes before linearized reading' (duration: 548.610085ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:11.019489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:10.470672Z","time spent":"548.81239ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-24T08:36:11.019326Z","caller":"traceutil/trace.go:171","msg":"trace[535235214] linearizableReadLoop","detail":"{readStateIndex:27880; appliedIndex:27878; }","duration":"296.80611ms","start":"2026-09-24T08:36:10.72251Z","end":"2026-09-24T08:36:11.019317Z","steps":["trace[535235214] 'read index received' (duration: 249.425864ms)","trace[535235214] 'applied index is now lower than readState.Index' (duration: 47.379496ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:36:11.019469Z","caller":"traceutil/trace.go:171","msg":"trace[54420785] transaction","detail":"{read_only:false; response_revision:26981; number_of_response:1; }","duration":"404.508716ms","start":"2026-09-24T08:36:10.614928Z","end":"2026-09-24T08:36:11.019436Z","steps":["trace[54420785] 'process raft request' (duration: 404.27468ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:11.019609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:10.614892Z","time spent":"404.639289ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-24T08:36:11.196093Z","caller":"traceutil/trace.go:171","msg":"trace[67508775] transaction","detail":"{read_only:false; response_revision:26983; number_of_response:1; }","duration":"169.854865ms","start":"2026-09-24T08:36:11.026218Z","end":"2026-09-24T08:36:11.196072Z","steps":["trace[67508775] 'process raft request' (duration: 169.010903ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:11.83218Z","caller":"traceutil/trace.go:171","msg":"trace[179551061] transaction","detail":"{read_only:false; response_revision:26985; number_of_response:1; }","duration":"322.648908ms","start":"2026-09-24T08:36:11.509506Z","end":"2026-09-24T08:36:11.832155Z","steps":["trace[179551061] 'process raft request' (duration: 322.383811ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:11.832328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:11.509485Z","time spent":"322.764261ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-24T08:36:12.026071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.090195ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-24T08:36:12.026223Z","caller":"traceutil/trace.go:171","msg":"trace[2088886242] transaction","detail":"{read_only:false; response_revision:26986; number_of_response:1; }","duration":"514.44337ms","start":"2026-09-24T08:36:11.511758Z","end":"2026-09-24T08:36:12.026201Z","steps":["trace[2088886242] 'process raft request' (duration: 362.11973ms)","trace[2088886242] 'compare' (duration: 151.94784ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:36:12.026288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:11.511695Z","time spent":"514.563443ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-24T08:36:12.026289Z","caller":"traceutil/trace.go:171","msg":"trace[1457896372] transaction","detail":"{read_only:false; response_revision:26987; number_of_response:1; }","duration":"159.859156ms","start":"2026-09-24T08:36:11.866412Z","end":"2026-09-24T08:36:12.026271Z","steps":["trace[1457896372] 'process raft request' (duration: 159.777564ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:12.026335Z","caller":"traceutil/trace.go:171","msg":"trace[1947171311] linearizableReadLoop","detail":"{readStateIndex:27886; appliedIndex:27884; }","duration":"467.836655ms","start":"2026-09-24T08:36:11.558462Z","end":"2026-09-24T08:36:12.026298Z","steps":["trace[1947171311] 'read index received' (duration: 273.593869ms)","trace[1947171311] 'applied index is now lower than readState.Index' (duration: 194.229345ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:36:12.026486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.03326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-24T08:36:12.026526Z","caller":"traceutil/trace.go:171","msg":"trace[206978891] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:26987; }","duration":"468.119922ms","start":"2026-09-24T08:36:11.558394Z","end":"2026-09-24T08:36:12.026514Z","steps":["trace[206978891] 'agreement among raft nodes before linearized reading' (duration: 468.05195ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:12.026553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:11.558362Z","time spent":"468.183034ms","remote":"127.0.0.1:33200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true "} {"level":"warn","ts":"2026-09-24T08:36:12.026605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.384917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-24T08:36:12.02665Z","caller":"traceutil/trace.go:171","msg":"trace[926284995] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:26987; }","duration":"461.49587ms","start":"2026-09-24T08:36:11.565141Z","end":"2026-09-24T08:36:12.026637Z","steps":["trace[926284995] 'agreement among raft nodes before linearized reading' (duration: 461.364077ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:12.026678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:11.565122Z","time spent":"461.548072ms","remote":"127.0.0.1:59528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":31,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"warn","ts":"2026-09-24T08:36:12.352765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.766117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-24T08:36:12.352855Z","caller":"traceutil/trace.go:171","msg":"trace[53994478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26987; }","duration":"129.938371ms","start":"2026-09-24T08:36:12.222896Z","end":"2026-09-24T08:36:12.352834Z","steps":["trace[53994478] 'range keys from in-memory index tree' (duration: 129.677144ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:12.677704Z","caller":"traceutil/trace.go:171","msg":"trace[1346519490] transaction","detail":"{read_only:false; response_revision:26989; number_of_response:1; }","duration":"381.944531ms","start":"2026-09-24T08:36:12.295737Z","end":"2026-09-24T08:36:12.677682Z","steps":["trace[1346519490] 'process raft request' (duration: 381.745386ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:12.677859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:12.295687Z","time spent":"382.101916ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-24T08:36:12.677891Z","caller":"traceutil/trace.go:171","msg":"trace[471821390] linearizableReadLoop","detail":"{readStateIndex:27890; appliedIndex:27887; }","duration":"348.698152ms","start":"2026-09-24T08:36:12.329162Z","end":"2026-09-24T08:36:12.67786Z","steps":["trace[471821390] 'read index received' (duration: 56.122462ms)","trace[471821390] 'applied index is now lower than readState.Index' (duration: 292.572899ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:36:12.678014Z","caller":"traceutil/trace.go:171","msg":"trace[393562925] transaction","detail":"{read_only:false; response_revision:26990; number_of_response:1; }","duration":"379.888329ms","start":"2026-09-24T08:36:12.298066Z","end":"2026-09-24T08:36:12.677955Z","steps":["trace[393562925] 'process raft request' (duration: 379.573961ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:12.678121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.229839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-24T08:36:12.678151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:12.298056Z","time spent":"380.024792ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-24T08:36:12.678165Z","caller":"traceutil/trace.go:171","msg":"trace[1469284341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26990; }","duration":"324.344212ms","start":"2026-09-24T08:36:12.353812Z","end":"2026-09-24T08:36:12.678157Z","steps":["trace[1469284341] 'agreement among raft nodes before linearized reading' (duration: 324.219799ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:12.678125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.954698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-09-24T08:36:12.678328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:12.353797Z","time spent":"324.481815ms","remote":"127.0.0.1:51884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-24T08:36:12.678375Z","caller":"traceutil/trace.go:171","msg":"trace[610927150] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26990; }","duration":"349.236705ms","start":"2026-09-24T08:36:12.329108Z","end":"2026-09-24T08:36:12.678345Z","steps":["trace[610927150] 'agreement among raft nodes before linearized reading' (duration: 348.871416ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:12.678459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:12.329077Z","time spent":"349.352208ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-24T08:36:12.872336Z","caller":"traceutil/trace.go:171","msg":"trace[2085281968] transaction","detail":"{read_only:false; response_revision:26992; number_of_response:1; }","duration":"142.621279ms","start":"2026-09-24T08:36:12.729686Z","end":"2026-09-24T08:36:12.872307Z","steps":["trace[2085281968] 'process raft request' (duration: 131.710248ms)","trace[2085281968] 'compare' (duration: 10.460419ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:36:12.872434Z","caller":"traceutil/trace.go:171","msg":"trace[1252600109] transaction","detail":"{read_only:false; response_revision:26994; number_of_response:1; }","duration":"141.971252ms","start":"2026-09-24T08:36:12.730451Z","end":"2026-09-24T08:36:12.872422Z","steps":["trace[1252600109] 'process raft request' (duration: 141.90713ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:12.872503Z","caller":"traceutil/trace.go:171","msg":"trace[145260626] transaction","detail":"{read_only:false; response_revision:26993; number_of_response:1; }","duration":"142.260391ms","start":"2026-09-24T08:36:12.730232Z","end":"2026-09-24T08:36:12.872493Z","steps":["trace[145260626] 'process raft request' (duration: 142.024484ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:13.234203Z","caller":"traceutil/trace.go:171","msg":"trace[14188878] transaction","detail":"{read_only:false; response_revision:26995; number_of_response:1; }","duration":"207.521759ms","start":"2026-09-24T08:36:13.026638Z","end":"2026-09-24T08:36:13.23416Z","steps":["trace[14188878] 'process raft request' (duration: 207.151169ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:15.984079Z","caller":"traceutil/trace.go:171","msg":"trace[1144727058] transaction","detail":"{read_only:false; response_revision:27014; number_of_response:1; }","duration":"139.668324ms","start":"2026-09-24T08:36:15.844387Z","end":"2026-09-24T08:36:15.984056Z","steps":["trace[1144727058] 'process raft request' (duration: 139.504989ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:16.263081Z","caller":"traceutil/trace.go:171","msg":"trace[1945243263] transaction","detail":"{read_only:false; response_revision:27016; number_of_response:1; }","duration":"223.090952ms","start":"2026-09-24T08:36:16.039968Z","end":"2026-09-24T08:36:16.263059Z","steps":["trace[1945243263] 'process raft request' (duration: 223.043081ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:16.263411Z","caller":"traceutil/trace.go:171","msg":"trace[796286186] transaction","detail":"{read_only:false; response_revision:27015; number_of_response:1; }","duration":"223.485172ms","start":"2026-09-24T08:36:16.0399Z","end":"2026-09-24T08:36:16.263385Z","steps":["trace[796286186] 'process raft request' (duration: 221.860949ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:16.596332Z","caller":"traceutil/trace.go:171","msg":"trace[760756689] transaction","detail":"{read_only:false; response_revision:27017; number_of_response:1; }","duration":"188.505176ms","start":"2026-09-24T08:36:16.407797Z","end":"2026-09-24T08:36:16.596302Z","steps":["trace[760756689] 'process raft request' (duration: 188.118927ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:23.334289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.17903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-24T08:36:23.334377Z","caller":"traceutil/trace.go:171","msg":"trace[2035827986] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:27124; }","duration":"108.315723ms","start":"2026-09-24T08:36:23.226045Z","end":"2026-09-24T08:36:23.334361Z","steps":["trace[2035827986] 'count revisions from in-memory index tree' (duration: 108.050476ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:23.42818Z","caller":"traceutil/trace.go:171","msg":"trace[829967727] transaction","detail":"{read_only:false; response_revision:27126; number_of_response:1; }","duration":"122.768037ms","start":"2026-09-24T08:36:23.305385Z","end":"2026-09-24T08:36:23.428153Z","steps":["trace[829967727] 'process raft request' (duration: 122.56079ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:23.428714Z","caller":"traceutil/trace.go:171","msg":"trace[2129211483] linearizableReadLoop","detail":"{readStateIndex:28028; appliedIndex:28027; }","duration":"109.585645ms","start":"2026-09-24T08:36:23.319086Z","end":"2026-09-24T08:36:23.428671Z","steps":["trace[2129211483] 'read index received' (duration: 108.800664ms)","trace[2129211483] 'applied index is now lower than readState.Index' (duration: 780.511µs)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:36:23.428879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.77782ms","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-24T08:36:23.428916Z","caller":"traceutil/trace.go:171","msg":"trace[768764604] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27126; }","duration":"109.859092ms","start":"2026-09-24T08:36:23.319046Z","end":"2026-09-24T08:36:23.428905Z","steps":["trace[768764604] 'agreement among raft nodes before linearized reading' (duration: 109.727358ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:23.620316Z","caller":"traceutil/trace.go:171","msg":"trace[2094092773] transaction","detail":"{read_only:false; response_revision:27127; number_of_response:1; }","duration":"276.393111ms","start":"2026-09-24T08:36:23.343895Z","end":"2026-09-24T08:36:23.620288Z","steps":["trace[2094092773] 'process raft request' (duration: 243.940982ms)","trace[2094092773] 'compare' (duration: 31.834713ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:36:23.714864Z","caller":"traceutil/trace.go:171","msg":"trace[662444084] transaction","detail":"{read_only:false; response_revision:27128; number_of_response:1; }","duration":"281.445082ms","start":"2026-09-24T08:36:23.433398Z","end":"2026-09-24T08:36:23.714843Z","steps":["trace[662444084] 'process raft request' (duration: 281.287668ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:23.956039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.301537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-24T08:36:23.956113Z","caller":"traceutil/trace.go:171","msg":"trace[86799410] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:27128; }","duration":"199.44492ms","start":"2026-09-24T08:36:23.75665Z","end":"2026-09-24T08:36:23.956095Z","steps":["trace[86799410] 'count revisions from in-memory index tree' (duration: 199.210874ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:24.393716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.592475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-24T08:36:24.393815Z","caller":"traceutil/trace.go:171","msg":"trace[1973306213] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27129; }","duration":"172.745348ms","start":"2026-09-24T08:36:24.221049Z","end":"2026-09-24T08:36:24.393795Z","steps":["trace[1973306213] 'range keys from in-memory index tree' (duration: 172.490023ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:24.394164Z","caller":"traceutil/trace.go:171","msg":"trace[1703241397] transaction","detail":"{read_only:false; response_revision:27130; number_of_response:1; }","duration":"101.212309ms","start":"2026-09-24T08:36:24.292942Z","end":"2026-09-24T08:36:24.394154Z","steps":["trace[1703241397] 'process raft request' (duration: 101.095366ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:24.663857Z","caller":"traceutil/trace.go:171","msg":"trace[1886413452] transaction","detail":"{read_only:false; response_revision:27132; number_of_response:1; }","duration":"129.678826ms","start":"2026-09-24T08:36:24.533806Z","end":"2026-09-24T08:36:24.663485Z","steps":["trace[1886413452] 'process raft request' (duration: 129.471589ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:26.498996Z","caller":"traceutil/trace.go:171","msg":"trace[616174738] transaction","detail":"{read_only:false; response_revision:27146; number_of_response:1; }","duration":"100.12718ms","start":"2026-09-24T08:36:26.398836Z","end":"2026-09-24T08:36:26.498963Z","steps":["trace[616174738] 'process raft request' (duration: 99.35872ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:26.546911Z","caller":"traceutil/trace.go:171","msg":"trace[1717555006] transaction","detail":"{read_only:false; response_revision:27147; number_of_response:1; }","duration":"146.682725ms","start":"2026-09-24T08:36:26.400207Z","end":"2026-09-24T08:36:26.546889Z","steps":["trace[1717555006] 'process raft request' (duration: 146.504891ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:26.682526Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23766} {"level":"info","ts":"2026-09-24T08:36:26.81281Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23766,"took":"127.013407ms","hash":1729928583,"current-db-size-bytes":57856000,"current-db-size":"58 MB","current-db-size-in-use-bytes":44711936,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-09-24T08:36:26.812875Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1729928583,"revision":23766,"compact-revision":21565} {"level":"warn","ts":"2026-09-24T08:36:31.697861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.704489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10358"} {"level":"info","ts":"2026-09-24T08:36:31.697947Z","caller":"traceutil/trace.go:171","msg":"trace[1856550247] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:27205; }","duration":"264.823862ms","start":"2026-09-24T08:36:31.433101Z","end":"2026-09-24T08:36:31.697925Z","steps":["trace[1856550247] 'range keys from in-memory index tree' (duration: 264.518283ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:31.698265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.850403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-09-24T08:36:31.698351Z","caller":"traceutil/trace.go:171","msg":"trace[2066418823] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:27205; }","duration":"259.979646ms","start":"2026-09-24T08:36:31.438352Z","end":"2026-09-24T08:36:31.698331Z","steps":["trace[2066418823] 'range keys from in-memory index tree' (duration: 259.72102ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:31.698528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.915591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-24T08:36:31.698578Z","caller":"traceutil/trace.go:171","msg":"trace[181013664] transaction","detail":"{read_only:false; response_revision:27206; number_of_response:1; }","duration":"241.238181ms","start":"2026-09-24T08:36:31.45733Z","end":"2026-09-24T08:36:31.698568Z","steps":["trace[181013664] 'process raft request' (duration: 94.220317ms)","trace[181013664] 'compare' (duration: 146.819239ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:36:32.005474Z","caller":"traceutil/trace.go:171","msg":"trace[1392710460] linearizableReadLoop","detail":"{readStateIndex:28113; appliedIndex:28112; }","duration":"257.40227ms","start":"2026-09-24T08:36:31.748044Z","end":"2026-09-24T08:36:32.005446Z","steps":["trace[1392710460] 'read index received' (duration: 257.110592ms)","trace[1392710460] 'applied index is now lower than readState.Index' (duration: 290.348µs)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:36:32.005769Z","caller":"traceutil/trace.go:171","msg":"trace[533069030] transaction","detail":"{read_only:false; response_revision:27207; number_of_response:1; }","duration":"295.179877ms","start":"2026-09-24T08:36:31.710546Z","end":"2026-09-24T08:36:32.005726Z","steps":["trace[533069030] 'process raft request' (duration: 294.681634ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:32.005938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.895323ms","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-24T08:36:32.006015Z","caller":"traceutil/trace.go:171","msg":"trace[982909927] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27207; }","duration":"258.025537ms","start":"2026-09-24T08:36:31.747972Z","end":"2026-09-24T08:36:32.005997Z","steps":["trace[982909927] 'agreement among raft nodes before linearized reading' (duration: 257.80435ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:32.005937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.883353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-09-24T08:36:32.006129Z","caller":"traceutil/trace.go:171","msg":"trace[15469801] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:27207; }","duration":"103.114958ms","start":"2026-09-24T08:36:31.90299Z","end":"2026-09-24T08:36:32.006105Z","steps":["trace[15469801] 'agreement among raft nodes before linearized reading' (duration: 102.786609ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:32.246237Z","caller":"traceutil/trace.go:171","msg":"trace[1794725311] transaction","detail":"{read_only:false; response_revision:27208; number_of_response:1; }","duration":"235.854612ms","start":"2026-09-24T08:36:32.010353Z","end":"2026-09-24T08:36:32.246208Z","steps":["trace[1794725311] 'process raft request' (duration: 147.838635ms)","trace[1794725311] 'compare' (duration: 87.885204ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:36:32.276Z","caller":"traceutil/trace.go:171","msg":"trace[710994740] transaction","detail":"{read_only:false; response_revision:27209; number_of_response:1; }","duration":"262.953253ms","start":"2026-09-24T08:36:32.013021Z","end":"2026-09-24T08:36:32.275974Z","steps":["trace[710994740] 'process raft request' (duration: 262.660286ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:32.276577Z","caller":"traceutil/trace.go:171","msg":"trace[1324060621] transaction","detail":"{read_only:false; response_revision:27210; number_of_response:1; }","duration":"263.246551ms","start":"2026-09-24T08:36:32.013315Z","end":"2026-09-24T08:36:32.276561Z","steps":["trace[1324060621] 'process raft request' (duration: 262.521073ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:32.277196Z","caller":"traceutil/trace.go:171","msg":"trace[128380188] linearizableReadLoop","detail":"{readStateIndex:28118; appliedIndex:28113; }","duration":"250.108621ms","start":"2026-09-24T08:36:32.027074Z","end":"2026-09-24T08:36:32.277182Z","steps":["trace[128380188] 'read index received' (duration: 131.058222ms)","trace[128380188] 'applied index is now lower than readState.Index' (duration: 119.049349ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:36:32.277413Z","caller":"traceutil/trace.go:171","msg":"trace[731784073] transaction","detail":"{read_only:false; response_revision:27211; number_of_response:1; }","duration":"263.99352ms","start":"2026-09-24T08:36:32.013406Z","end":"2026-09-24T08:36:32.2774Z","steps":["trace[731784073] 'process raft request' (duration: 263.082716ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:32.277439Z","caller":"traceutil/trace.go:171","msg":"trace[229186781] transaction","detail":"{read_only:false; response_revision:27213; number_of_response:1; }","duration":"129.580323ms","start":"2026-09-24T08:36:32.147834Z","end":"2026-09-24T08:36:32.277414Z","steps":["trace[229186781] 'process raft request' (duration: 129.298716ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:32.277673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.588254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10651"} {"level":"info","ts":"2026-09-24T08:36:32.277725Z","caller":"traceutil/trace.go:171","msg":"trace[262306444] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:27213; }","duration":"250.694467ms","start":"2026-09-24T08:36:32.027018Z","end":"2026-09-24T08:36:32.277712Z","steps":["trace[262306444] 'agreement among raft nodes before linearized reading' (duration: 250.523261ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:32.277853Z","caller":"traceutil/trace.go:171","msg":"trace[936591559] transaction","detail":"{read_only:false; response_revision:27212; number_of_response:1; }","duration":"263.807006ms","start":"2026-09-24T08:36:32.014027Z","end":"2026-09-24T08:36:32.277834Z","steps":["trace[936591559] 'process raft request' (duration: 263.049545ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:32.277969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.852314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-24T08:36:32.278058Z","caller":"traceutil/trace.go:171","msg":"trace[625259032] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:27213; }","duration":"179.992498ms","start":"2026-09-24T08:36:32.098043Z","end":"2026-09-24T08:36:32.278035Z","steps":["trace[625259032] 'agreement among raft nodes before linearized reading' (duration: 179.835314ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:36.662823Z","caller":"traceutil/trace.go:171","msg":"trace[924524659] transaction","detail":"{read_only:false; response_revision:27251; number_of_response:1; }","duration":"123.707871ms","start":"2026-09-24T08:36:36.539092Z","end":"2026-09-24T08:36:36.6628Z","steps":["trace[924524659] 'process raft request' (duration: 123.412053ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:36.708912Z","caller":"traceutil/trace.go:171","msg":"trace[643075723] transaction","detail":"{read_only:false; response_revision:27252; number_of_response:1; }","duration":"110.269913ms","start":"2026-09-24T08:36:36.5986Z","end":"2026-09-24T08:36:36.70887Z","steps":["trace[643075723] 'process raft request' (duration: 110.043197ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:36.953311Z","caller":"traceutil/trace.go:171","msg":"trace[1006367867] transaction","detail":"{read_only:false; response_revision:27253; number_of_response:1; }","duration":"239.378014ms","start":"2026-09-24T08:36:36.713907Z","end":"2026-09-24T08:36:36.953285Z","steps":["trace[1006367867] 'process raft request' (duration: 239.208899ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:37.169441Z","caller":"traceutil/trace.go:171","msg":"trace[45663503] transaction","detail":"{read_only:false; response_revision:27254; number_of_response:1; }","duration":"396.199372ms","start":"2026-09-24T08:36:36.773225Z","end":"2026-09-24T08:36:37.169424Z","steps":["trace[45663503] 'process raft request' (duration: 310.696139ms)","trace[45663503] 'compare' (duration: 85.354249ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:36:37.169645Z","caller":"traceutil/trace.go:171","msg":"trace[886650904] linearizableReadLoop","detail":"{readStateIndex:28162; appliedIndex:28159; }","duration":"331.150637ms","start":"2026-09-24T08:36:36.838466Z","end":"2026-09-24T08:36:37.169616Z","steps":["trace[886650904] 'read index received' (duration: 114.720158ms)","trace[886650904] 'applied index is now lower than readState.Index' (duration: 216.428899ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:36:37.169739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:36.773204Z","time spent":"396.288554ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-24T08:36:37.169872Z","caller":"traceutil/trace.go:171","msg":"trace[1990181238] transaction","detail":"{read_only:false; response_revision:27255; number_of_response:1; }","duration":"396.400017ms","start":"2026-09-24T08:36:36.77346Z","end":"2026-09-24T08:36:37.16986Z","steps":["trace[1990181238] 'process raft request' (duration: 395.930025ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:37.169927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:36.773448Z","time spent":"396.452238ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-24T08:36:37.170042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.445021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-24T08:36:37.170079Z","caller":"traceutil/trace.go:171","msg":"trace[530620092] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:27255; }","duration":"252.519453ms","start":"2026-09-24T08:36:36.917551Z","end":"2026-09-24T08:36:37.17007Z","steps":["trace[530620092] 'agreement among raft nodes before linearized reading' (duration: 252.437721ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:37.170086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.599319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-09-24T08:36:37.170127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.902266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-24T08:36:37.170136Z","caller":"traceutil/trace.go:171","msg":"trace[1111655107] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27255; }","duration":"331.682941ms","start":"2026-09-24T08:36:36.838435Z","end":"2026-09-24T08:36:37.170117Z","steps":["trace[1111655107] 'agreement among raft nodes before linearized reading' (duration: 331.510057ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:37.17016Z","caller":"traceutil/trace.go:171","msg":"trace[328815478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27255; }","duration":"176.958328ms","start":"2026-09-24T08:36:36.993193Z","end":"2026-09-24T08:36:37.170151Z","steps":["trace[328815478] 'agreement among raft nodes before linearized reading' (duration: 176.905237ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:37.170171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:36.838422Z","time spent":"331.740862ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-24T08:36:37.170195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.392814ms","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-24T08:36:37.170223Z","caller":"traceutil/trace.go:171","msg":"trace[1593044866] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27255; }","duration":"181.440225ms","start":"2026-09-24T08:36:36.988776Z","end":"2026-09-24T08:36:37.170216Z","steps":["trace[1593044866] 'agreement among raft nodes before linearized reading' (duration: 181.394614ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:37.170294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.927823ms","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-24T08:36:37.170317Z","caller":"traceutil/trace.go:171","msg":"trace[1486815814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27255; }","duration":"182.951543ms","start":"2026-09-24T08:36:36.98736Z","end":"2026-09-24T08:36:37.170312Z","steps":["trace[1486815814] 'agreement among raft nodes before linearized reading' (duration: 182.908142ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:37.170405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.865009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-09-24T08:36:37.170432Z","caller":"traceutil/trace.go:171","msg":"trace[394582903] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:27255; }","duration":"151.929501ms","start":"2026-09-24T08:36:37.018494Z","end":"2026-09-24T08:36:37.170424Z","steps":["trace[394582903] 'agreement among raft nodes before linearized reading' (duration: 151.868169ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:37.322226Z","caller":"traceutil/trace.go:171","msg":"trace[772217143] linearizableReadLoop","detail":"{readStateIndex:28165; appliedIndex:28162; }","duration":"147.664119ms","start":"2026-09-24T08:36:37.174537Z","end":"2026-09-24T08:36:37.322201Z","steps":["trace[772217143] 'read index received' (duration: 4.591788ms)","trace[772217143] 'applied index is now lower than readState.Index' (duration: 143.070611ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:36:37.322672Z","caller":"traceutil/trace.go:171","msg":"trace[1902546140] transaction","detail":"{read_only:false; response_revision:27259; number_of_response:1; }","duration":"147.614269ms","start":"2026-09-24T08:36:37.175026Z","end":"2026-09-24T08:36:37.32264Z","steps":["trace[1902546140] 'process raft request' (duration: 147.123926ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:37.322946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.388449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-09-24T08:36:37.322988Z","caller":"traceutil/trace.go:171","msg":"trace[1161783087] transaction","detail":"{read_only:false; response_revision:27257; number_of_response:1; }","duration":"149.092418ms","start":"2026-09-24T08:36:37.173886Z","end":"2026-09-24T08:36:37.322979Z","steps":["trace[1161783087] 'process raft request' (duration: 130.771614ms)","trace[1161783087] 'compare' (duration: 17.248056ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:36:37.322998Z","caller":"traceutil/trace.go:171","msg":"trace[1690831427] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:27259; }","duration":"148.44876ms","start":"2026-09-24T08:36:37.174532Z","end":"2026-09-24T08:36:37.322981Z","steps":["trace[1690831427] 'agreement among raft nodes before linearized reading' (duration: 148.276845ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:37.323107Z","caller":"traceutil/trace.go:171","msg":"trace[320705174] transaction","detail":"{read_only:false; response_revision:27258; number_of_response:1; }","duration":"149.128958ms","start":"2026-09-24T08:36:37.173935Z","end":"2026-09-24T08:36:37.323064Z","steps":["trace[320705174] 'process raft request' (duration: 148.135372ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:55.424889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.264756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-24T08:36:55.424968Z","caller":"traceutil/trace.go:171","msg":"trace[599749524] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:27403; }","duration":"262.426339ms","start":"2026-09-24T08:36:55.162526Z","end":"2026-09-24T08:36:55.424952Z","steps":["trace[599749524] 'count revisions from in-memory index tree' (duration: 262.169993ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:55.425436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.306355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/groups/\" range_end:\"/registry/openstack.k-orc.cloud/groups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-24T08:36:55.425468Z","caller":"traceutil/trace.go:171","msg":"trace[564727597] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/groups/; range_end:/registry/openstack.k-orc.cloud/groups0; response_count:0; response_revision:27403; }","duration":"192.385868ms","start":"2026-09-24T08:36:55.233072Z","end":"2026-09-24T08:36:55.425458Z","steps":["trace[564727597] 'count revisions from in-memory index tree' (duration: 192.214853ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:55.425658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.167682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-24T08:36:55.425683Z","caller":"traceutil/trace.go:171","msg":"trace[2133790840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27403; }","duration":"202.222313ms","start":"2026-09-24T08:36:55.223454Z","end":"2026-09-24T08:36:55.425676Z","steps":["trace[2133790840] 'range keys from in-memory index tree' (duration: 202.08446ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:55.426105Z","caller":"traceutil/trace.go:171","msg":"trace[2085231647] transaction","detail":"{read_only:false; response_revision:27404; number_of_response:1; }","duration":"192.85149ms","start":"2026-09-24T08:36:55.233204Z","end":"2026-09-24T08:36:55.426055Z","steps":["trace[2085231647] 'process raft request' (duration: 100.156272ms)","trace[2085231647] 'compare' (duration: 91.949918ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:36:55.433997Z","caller":"traceutil/trace.go:171","msg":"trace[83766917] linearizableReadLoop","detail":"{readStateIndex:28316; appliedIndex:28314; }","duration":"175.86437ms","start":"2026-09-24T08:36:55.25811Z","end":"2026-09-24T08:36:55.433974Z","steps":["trace[83766917] 'read index received' (duration: 75.413491ms)","trace[83766917] 'applied index is now lower than readState.Index' (duration: 100.449859ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:36:55.434498Z","caller":"traceutil/trace.go:171","msg":"trace[114255728] transaction","detail":"{read_only:false; response_revision:27405; number_of_response:1; }","duration":"195.131699ms","start":"2026-09-24T08:36:55.239321Z","end":"2026-09-24T08:36:55.434453Z","steps":["trace[114255728] 'process raft request' (duration: 194.529294ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:55.434639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.498687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-09-24T08:36:55.434741Z","caller":"traceutil/trace.go:171","msg":"trace[47860391] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27405; }","duration":"176.657ms","start":"2026-09-24T08:36:55.258058Z","end":"2026-09-24T08:36:55.434715Z","steps":["trace[47860391] 'agreement among raft nodes before linearized reading' (duration: 176.344122ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:55.70482Z","caller":"traceutil/trace.go:171","msg":"trace[2057376761] linearizableReadLoop","detail":"{readStateIndex:28317; appliedIndex:28316; }","duration":"270.572339ms","start":"2026-09-24T08:36:55.434228Z","end":"2026-09-24T08:36:55.7048Z","steps":["trace[2057376761] 'read index received' (duration: 211.352657ms)","trace[2057376761] 'applied index is now lower than readState.Index' (duration: 59.219042ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:36:55.704941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.594018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-24T08:36:55.704967Z","caller":"traceutil/trace.go:171","msg":"trace[1848366419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27406; }","duration":"278.652478ms","start":"2026-09-24T08:36:55.426304Z","end":"2026-09-24T08:36:55.704957Z","steps":["trace[1848366419] 'agreement among raft nodes before linearized reading' (duration: 278.592537ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:55.70494Z","caller":"traceutil/trace.go:171","msg":"trace[984317861] transaction","detail":"{read_only:false; response_revision:27406; number_of_response:1; }","duration":"297.409895ms","start":"2026-09-24T08:36:55.407499Z","end":"2026-09-24T08:36:55.704908Z","steps":["trace[984317861] 'process raft request' (duration: 238.263035ms)","trace[984317861] 'compare' (duration: 58.807822ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:36:55.705034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.474899ms","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-24T08:36:55.705078Z","caller":"traceutil/trace.go:171","msg":"trace[1605363792] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27406; }","duration":"284.553901ms","start":"2026-09-24T08:36:55.420513Z","end":"2026-09-24T08:36:55.705067Z","steps":["trace[1605363792] 'agreement among raft nodes before linearized reading' (duration: 284.421418ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:55.705081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.749597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-09-24T08:36:55.705146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.851379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-24T08:36:55.705161Z","caller":"traceutil/trace.go:171","msg":"trace[430576833] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27406; }","duration":"301.86663ms","start":"2026-09-24T08:36:55.403272Z","end":"2026-09-24T08:36:55.705139Z","steps":["trace[430576833] 'agreement among raft nodes before linearized reading' (duration: 301.646644ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:55.705177Z","caller":"traceutil/trace.go:171","msg":"trace[715803000] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:27406; }","duration":"301.915101ms","start":"2026-09-24T08:36:55.403253Z","end":"2026-09-24T08:36:55.705168Z","steps":["trace[715803000] 'agreement among raft nodes before linearized reading' (duration: 301.8656ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:55.705195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:55.403256Z","time spent":"301.931511ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-24T08:36:55.705204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:55.403237Z","time spent":"301.960932ms","remote":"127.0.0.1:59542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":31,"request content":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true "} {"level":"warn","ts":"2026-09-24T08:36:55.705452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.138369ms","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-24T08:36:55.705477Z","caller":"traceutil/trace.go:171","msg":"trace[1255827258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27406; }","duration":"277.202161ms","start":"2026-09-24T08:36:55.428269Z","end":"2026-09-24T08:36:55.705471Z","steps":["trace[1255827258] 'agreement among raft nodes before linearized reading' (duration: 277.133629ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:55.705485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.287224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-24T08:36:55.705512Z","caller":"traceutil/trace.go:171","msg":"trace[494428527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27406; }","duration":"277.370296ms","start":"2026-09-24T08:36:55.428134Z","end":"2026-09-24T08:36:55.705504Z","steps":["trace[494428527] 'agreement among raft nodes before linearized reading' (duration: 276.72544ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:55.780597Z","caller":"traceutil/trace.go:171","msg":"trace[1608703990] transaction","detail":"{read_only:false; response_revision:27407; number_of_response:1; }","duration":"340.894739ms","start":"2026-09-24T08:36:55.439672Z","end":"2026-09-24T08:36:55.780566Z","steps":["trace[1608703990] 'process raft request' (duration: 340.649032ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:55.780758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:55.439646Z","time spent":"341.028652ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-24T08:36:55.780976Z","caller":"traceutil/trace.go:171","msg":"trace[875908942] transaction","detail":"{read_only:false; response_revision:27408; number_of_response:1; }","duration":"291.761079ms","start":"2026-09-24T08:36:55.489169Z","end":"2026-09-24T08:36:55.78093Z","steps":["trace[875908942] 'process raft request' (duration: 291.346938ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:55.781044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.073044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-24T08:36:55.781142Z","caller":"traceutil/trace.go:171","msg":"trace[413507198] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:27408; }","duration":"283.242899ms","start":"2026-09-24T08:36:55.497878Z","end":"2026-09-24T08:36:55.781121Z","steps":["trace[413507198] 'agreement among raft nodes before linearized reading' (duration: 283.034774ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:56.003937Z","caller":"traceutil/trace.go:171","msg":"trace[1051128348] transaction","detail":"{read_only:false; response_revision:27409; number_of_response:1; }","duration":"294.100349ms","start":"2026-09-24T08:36:55.709788Z","end":"2026-09-24T08:36:56.003888Z","steps":["trace[1051128348] 'process raft request' (duration: 293.801531ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:56.004332Z","caller":"traceutil/trace.go:171","msg":"trace[1438723187] transaction","detail":"{read_only:false; response_revision:27410; number_of_response:1; }","duration":"293.563096ms","start":"2026-09-24T08:36:55.710723Z","end":"2026-09-24T08:36:56.004286Z","steps":["trace[1438723187] 'process raft request' (duration: 293.108074ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:56.004696Z","caller":"traceutil/trace.go:171","msg":"trace[536375102] transaction","detail":"{read_only:false; response_revision:27411; number_of_response:1; }","duration":"293.473643ms","start":"2026-09-24T08:36:55.711179Z","end":"2026-09-24T08:36:56.004652Z","steps":["trace[536375102] 'process raft request' (duration: 293.016781ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:56.186396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.847336ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-24T08:36:56.186747Z","caller":"traceutil/trace.go:171","msg":"trace[912571860] linearizableReadLoop","detail":"{readStateIndex:28323; appliedIndex:28319; }","duration":"405.855101ms","start":"2026-09-24T08:36:55.780865Z","end":"2026-09-24T08:36:56.186721Z","steps":["trace[912571860] 'read index received' (duration: 222.781424ms)","trace[912571860] 'applied index is now lower than readState.Index' (duration: 183.071617ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:36:56.187184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.525665ms","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-24T08:36:56.187311Z","caller":"traceutil/trace.go:171","msg":"trace[1844339281] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27411; }","duration":"477.70429ms","start":"2026-09-24T08:36:55.709585Z","end":"2026-09-24T08:36:56.187289Z","steps":["trace[1844339281] 'agreement among raft nodes before linearized reading' (duration: 477.381731ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:56.187362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:55.709566Z","time spent":"477.787021ms","remote":"127.0.0.1:52056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-24T08:36:56.187747Z","caller":"traceutil/trace.go:171","msg":"trace[1467936901] transaction","detail":"{read_only:false; response_revision:27412; number_of_response:1; }","duration":"405.973474ms","start":"2026-09-24T08:36:55.781746Z","end":"2026-09-24T08:36:56.18772Z","steps":["trace[1467936901] 'process raft request' (duration: 404.799383ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:56.187985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:55.78172Z","time spent":"406.150338ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-24T08:36:56.235279Z","caller":"traceutil/trace.go:171","msg":"trace[1444938244] transaction","detail":"{read_only:false; response_revision:27413; number_of_response:1; }","duration":"191.362501ms","start":"2026-09-24T08:36:56.043862Z","end":"2026-09-24T08:36:56.235224Z","steps":["trace[1444938244] 'process raft request' (duration: 143.839262ms)","trace[1444938244] 'compare' (duration: 47.292753ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:36:56.459312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.948641ms","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-24T08:36:56.459392Z","caller":"traceutil/trace.go:171","msg":"trace[1246233956] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27413; }","duration":"116.069524ms","start":"2026-09-24T08:36:56.343298Z","end":"2026-09-24T08:36:56.459368Z","steps":["trace[1246233956] 'range keys from in-memory index tree' (duration: 115.652923ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:56.973868Z","caller":"traceutil/trace.go:171","msg":"trace[1415516001] transaction","detail":"{read_only:false; response_revision:27416; number_of_response:1; }","duration":"243.50115ms","start":"2026-09-24T08:36:56.730345Z","end":"2026-09-24T08:36:56.973846Z","steps":["trace[1415516001] 'process raft request' (duration: 243.324666ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:56.987681Z","caller":"traceutil/trace.go:171","msg":"trace[1910931050] transaction","detail":"{read_only:false; response_revision:27417; number_of_response:1; }","duration":"211.548923ms","start":"2026-09-24T08:36:56.77611Z","end":"2026-09-24T08:36:56.987659Z","steps":["trace[1910931050] 'process raft request' (duration: 211.41231ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:57.288886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.985846ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-24T08:36:57.289013Z","caller":"traceutil/trace.go:171","msg":"trace[971202840] transaction","detail":"{read_only:false; response_revision:27418; number_of_response:1; }","duration":"244.228659ms","start":"2026-09-24T08:36:57.044752Z","end":"2026-09-24T08:36:57.28898Z","steps":["trace[971202840] 'process raft request' (duration: 95.079039ms)","trace[971202840] 'compare' (duration: 148.658328ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:36:57.552992Z","caller":"traceutil/trace.go:171","msg":"trace[966085244] transaction","detail":"{read_only:false; response_revision:27419; number_of_response:1; }","duration":"121.605046ms","start":"2026-09-24T08:36:57.431345Z","end":"2026-09-24T08:36:57.55295Z","steps":["trace[966085244] 'process raft request' (duration: 40.024406ms)","trace[966085244] 'compare' (duration: 81.146139ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:36:57.701758Z","caller":"traceutil/trace.go:171","msg":"trace[1254396880] transaction","detail":"{read_only:false; response_revision:27420; number_of_response:1; }","duration":"261.585028ms","start":"2026-09-24T08:36:57.440117Z","end":"2026-09-24T08:36:57.701703Z","steps":["trace[1254396880] 'process raft request' (duration: 243.444089ms)","trace[1254396880] 'compare' (duration: 17.661176ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:36:58.05867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.093626ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-24T08:36:58.058809Z","caller":"traceutil/trace.go:171","msg":"trace[1064798778] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:27420; }","duration":"113.254851ms","start":"2026-09-24T08:36:57.945537Z","end":"2026-09-24T08:36:58.058792Z","steps":["trace[1064798778] 'range keys from in-memory index tree' (duration: 113.069756ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:58.101731Z","caller":"traceutil/trace.go:171","msg":"trace[920692305] linearizableReadLoop","detail":"{readStateIndex:28333; appliedIndex:28332; }","duration":"315.062112ms","start":"2026-09-24T08:36:57.786649Z","end":"2026-09-24T08:36:58.101711Z","steps":["trace[920692305] 'read index received' (duration: 314.848357ms)","trace[920692305] 'applied index is now lower than readState.Index' (duration: 213.095µs)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:36:58.101862Z","caller":"traceutil/trace.go:171","msg":"trace[1091622532] transaction","detail":"{read_only:false; response_revision:27421; number_of_response:1; }","duration":"315.869453ms","start":"2026-09-24T08:36:57.785973Z","end":"2026-09-24T08:36:58.101842Z","steps":["trace[1091622532] 'process raft request' (duration: 315.589075ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:58.101957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:57.78595Z","time spent":"315.933695ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-24T08:36:58.10223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.571655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-09-24T08:36:58.102288Z","caller":"traceutil/trace.go:171","msg":"trace[1348319561] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27421; }","duration":"315.668428ms","start":"2026-09-24T08:36:57.786606Z","end":"2026-09-24T08:36:58.102274Z","steps":["trace[1348319561] 'agreement among raft nodes before linearized reading' (duration: 315.560155ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:58.10231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:57.786592Z","time spent":"315.711938ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-24T08:36:58.305345Z","caller":"traceutil/trace.go:171","msg":"trace[877946752] transaction","detail":"{read_only:false; response_revision:27423; number_of_response:1; }","duration":"192.582333ms","start":"2026-09-24T08:36:58.112725Z","end":"2026-09-24T08:36:58.305307Z","steps":["trace[877946752] 'process raft request' (duration: 129.770478ms)","trace[877946752] 'compare' (duration: 62.613589ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:36:58.305379Z","caller":"traceutil/trace.go:171","msg":"trace[1926099413] transaction","detail":"{read_only:false; response_revision:27424; number_of_response:1; }","duration":"191.369602ms","start":"2026-09-24T08:36:58.113984Z","end":"2026-09-24T08:36:58.305354Z","steps":["trace[1926099413] 'process raft request' (duration: 191.245088ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:58.305397Z","caller":"traceutil/trace.go:171","msg":"trace[1844708881] transaction","detail":"{read_only:false; response_revision:27426; number_of_response:1; }","duration":"113.013155ms","start":"2026-09-24T08:36:58.192367Z","end":"2026-09-24T08:36:58.30538Z","steps":["trace[1844708881] 'process raft request' (duration: 112.972224ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:58.305635Z","caller":"traceutil/trace.go:171","msg":"trace[1265588018] transaction","detail":"{read_only:false; response_revision:27425; number_of_response:1; }","duration":"191.509425ms","start":"2026-09-24T08:36:58.114115Z","end":"2026-09-24T08:36:58.305625Z","steps":["trace[1265588018] 'process raft request' (duration: 191.176147ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:58.630446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.482117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-24T08:36:58.630665Z","caller":"traceutil/trace.go:171","msg":"trace[959017481] transaction","detail":"{read_only:false; response_revision:27429; number_of_response:1; }","duration":"147.360723ms","start":"2026-09-24T08:36:58.483292Z","end":"2026-09-24T08:36:58.630652Z","steps":["trace[959017481] 'process raft request' (duration: 147.320771ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:58.630847Z","caller":"traceutil/trace.go:171","msg":"trace[1393401996] transaction","detail":"{read_only:false; response_revision:27427; number_of_response:1; }","duration":"317.398392ms","start":"2026-09-24T08:36:58.313438Z","end":"2026-09-24T08:36:58.630836Z","steps":["trace[1393401996] 'process raft request' (duration: 111.429963ms)","trace[1393401996] 'compare' (duration: 205.089296ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:36:58.630898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:36:58.31335Z","time spent":"317.519755ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-24T08:36:58.631022Z","caller":"traceutil/trace.go:171","msg":"trace[67537688] transaction","detail":"{read_only:false; response_revision:27428; number_of_response:1; }","duration":"167.140585ms","start":"2026-09-24T08:36:58.463874Z","end":"2026-09-24T08:36:58.631014Z","steps":["trace[67537688] 'process raft request' (duration: 166.667783ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:58.631Z","caller":"traceutil/trace.go:171","msg":"trace[1848441471] linearizableReadLoop","detail":"{readStateIndex:28339; appliedIndex:28338; }","duration":"191.130495ms","start":"2026-09-24T08:36:58.439847Z","end":"2026-09-24T08:36:58.630978Z","steps":["trace[1848441471] 'read index received' (duration: 61.742µs)","trace[1848441471] 'applied index is now lower than readState.Index' (duration: 191.067103ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:36:58.631145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.285898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-24T08:36:58.631187Z","caller":"traceutil/trace.go:171","msg":"trace[205983113] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:27429; }","duration":"191.368511ms","start":"2026-09-24T08:36:58.439802Z","end":"2026-09-24T08:36:58.631171Z","steps":["trace[205983113] 'agreement among raft nodes before linearized reading' (duration: 191.290709ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:36:58.631416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.180419ms","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-24T08:36:58.631446Z","caller":"traceutil/trace.go:171","msg":"trace[194492009] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27429; }","duration":"130.234999ms","start":"2026-09-24T08:36:58.501201Z","end":"2026-09-24T08:36:58.631436Z","steps":["trace[194492009] 'agreement among raft nodes before linearized reading' (duration: 130.134597ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:59.108854Z","caller":"traceutil/trace.go:171","msg":"trace[788947051] transaction","detail":"{read_only:false; response_revision:27432; number_of_response:1; }","duration":"155.642966ms","start":"2026-09-24T08:36:58.953177Z","end":"2026-09-24T08:36:59.10882Z","steps":["trace[788947051] 'process raft request' (duration: 155.38704ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:59.163954Z","caller":"traceutil/trace.go:171","msg":"trace[1548585949] transaction","detail":"{read_only:false; response_revision:27434; number_of_response:1; }","duration":"170.401409ms","start":"2026-09-24T08:36:58.993529Z","end":"2026-09-24T08:36:59.163931Z","steps":["trace[1548585949] 'process raft request' (duration: 170.357698ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:36:59.164101Z","caller":"traceutil/trace.go:171","msg":"trace[1371507825] transaction","detail":"{read_only:false; response_revision:27433; number_of_response:1; }","duration":"185.14415ms","start":"2026-09-24T08:36:58.978919Z","end":"2026-09-24T08:36:59.164064Z","steps":["trace[1371507825] 'process raft request' (duration: 182.327347ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:37:04.492054Z","caller":"traceutil/trace.go:171","msg":"trace[520471601] linearizableReadLoop","detail":"{readStateIndex:28384; appliedIndex:28382; }","duration":"130.520807ms","start":"2026-09-24T08:37:04.361489Z","end":"2026-09-24T08:37:04.49201Z","steps":["trace[520471601] 'read index received' (duration: 130.311192ms)","trace[520471601] 'applied index is now lower than readState.Index' (duration: 208.615µs)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:37:04.492142Z","caller":"traceutil/trace.go:171","msg":"trace[469338518] transaction","detail":"{read_only:false; response_revision:27471; number_of_response:1; }","duration":"155.660408ms","start":"2026-09-24T08:37:04.336445Z","end":"2026-09-24T08:37:04.492106Z","steps":["trace[469338518] 'process raft request' (duration: 155.530475ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:37:04.492146Z","caller":"traceutil/trace.go:171","msg":"trace[284714221] transaction","detail":"{read_only:false; response_revision:27470; number_of_response:1; }","duration":"156.132189ms","start":"2026-09-24T08:37:04.335957Z","end":"2026-09-24T08:37:04.492089Z","steps":["trace[284714221] 'process raft request' (duration: 155.873853ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:37:04.492211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.660351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-24T08:37:04.492339Z","caller":"traceutil/trace.go:171","msg":"trace[908932241] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:27471; }","duration":"130.853086ms","start":"2026-09-24T08:37:04.361444Z","end":"2026-09-24T08:37:04.492297Z","steps":["trace[908932241] 'agreement among raft nodes before linearized reading' (duration: 130.673731ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:37:09.861204Z","caller":"traceutil/trace.go:171","msg":"trace[1580917757] transaction","detail":"{read_only:false; response_revision:27509; number_of_response:1; }","duration":"112.137181ms","start":"2026-09-24T08:37:09.749048Z","end":"2026-09-24T08:37:09.861185Z","steps":["trace[1580917757] 'process raft request' (duration: 112.019538ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:37:10.103567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.577301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-24T08:37:10.103671Z","caller":"traceutil/trace.go:171","msg":"trace[84262437] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:27509; }","duration":"144.728904ms","start":"2026-09-24T08:37:09.958914Z","end":"2026-09-24T08:37:10.103643Z","steps":["trace[84262437] 'count revisions from in-memory index tree' (duration: 144.503518ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:37:10.262961Z","caller":"traceutil/trace.go:171","msg":"trace[1276847171] transaction","detail":"{read_only:false; response_revision:27510; number_of_response:1; }","duration":"117.273404ms","start":"2026-09-24T08:37:10.145664Z","end":"2026-09-24T08:37:10.262938Z","steps":["trace[1276847171] 'process raft request' (duration: 116.848293ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:37:10.610712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.974464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-24T08:37:10.610882Z","caller":"traceutil/trace.go:171","msg":"trace[1124160015] linearizableReadLoop","detail":"{readStateIndex:28425; appliedIndex:28424; }","duration":"300.72582ms","start":"2026-09-24T08:37:10.310133Z","end":"2026-09-24T08:37:10.610858Z","steps":["trace[1124160015] 'read index received' (duration: 117.566552ms)","trace[1124160015] 'applied index is now lower than readState.Index' (duration: 183.157568ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:37:10.611052Z","caller":"traceutil/trace.go:171","msg":"trace[20879771] transaction","detail":"{read_only:false; response_revision:27511; number_of_response:1; }","duration":"343.432655ms","start":"2026-09-24T08:37:10.26755Z","end":"2026-09-24T08:37:10.610983Z","steps":["trace[20879771] 'process raft request' (duration: 160.071901ms)","trace[20879771] 'compare' (duration: 182.733648ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:37:10.611283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:37:10.267527Z","time spent":"343.63005ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-24T08:37:10.611256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.923835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-24T08:37:10.611362Z","caller":"traceutil/trace.go:171","msg":"trace[984230630] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:27511; }","duration":"301.267944ms","start":"2026-09-24T08:37:10.310071Z","end":"2026-09-24T08:37:10.611339Z","steps":["trace[984230630] 'agreement among raft nodes before linearized reading' (duration: 300.879784ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:37:10.611406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:37:10.31005Z","time spent":"301.343696ms","remote":"127.0.0.1:33178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true "} {"level":"info","ts":"2026-09-24T08:37:10.635463Z","caller":"traceutil/trace.go:171","msg":"trace[1831628129] transaction","detail":"{read_only:false; response_revision:27512; number_of_response:1; }","duration":"284.453751ms","start":"2026-09-24T08:37:10.350975Z","end":"2026-09-24T08:37:10.635428Z","steps":["trace[1831628129] 'process raft request' (duration: 284.204324ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:37:10.635486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.753454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-24T08:37:10.635559Z","caller":"traceutil/trace.go:171","msg":"trace[543718108] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27512; }","duration":"263.877456ms","start":"2026-09-24T08:37:10.37166Z","end":"2026-09-24T08:37:10.635538Z","steps":["trace[543718108] 'agreement among raft nodes before linearized reading' (duration: 263.658841ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:37:10.635563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.215743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-24T08:37:10.635647Z","caller":"traceutil/trace.go:171","msg":"trace[1333361488] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:27512; }","duration":"209.369888ms","start":"2026-09-24T08:37:10.426257Z","end":"2026-09-24T08:37:10.635627Z","steps":["trace[1333361488] 'agreement among raft nodes before linearized reading' (duration: 209.208274ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:37:10.635709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.652894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-24T08:37:10.63574Z","caller":"traceutil/trace.go:171","msg":"trace[610170800] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:27512; }","duration":"221.690055ms","start":"2026-09-24T08:37:10.414039Z","end":"2026-09-24T08:37:10.635729Z","steps":["trace[610170800] 'agreement among raft nodes before linearized reading' (duration: 221.632094ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:37:10.635817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.071017ms","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-24T08:37:10.635884Z","caller":"traceutil/trace.go:171","msg":"trace[157568025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27512; }","duration":"120.216081ms","start":"2026-09-24T08:37:10.515649Z","end":"2026-09-24T08:37:10.635865Z","steps":["trace[157568025] 'agreement among raft nodes before linearized reading' (duration: 119.906113ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:37:10.635886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.765616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.85\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-09-24T08:37:10.635971Z","caller":"traceutil/trace.go:171","msg":"trace[992091569] range","detail":"{range_begin:/registry/masterleases/162.253.55.85; range_end:; response_count:1; response_revision:27512; }","duration":"219.890409ms","start":"2026-09-24T08:37:10.416059Z","end":"2026-09-24T08:37:10.635949Z","steps":["trace[992091569] 'agreement among raft nodes before linearized reading' (duration: 219.605022ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:37:10.63627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.512698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-24T08:37:10.63635Z","caller":"traceutil/trace.go:171","msg":"trace[1522555036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27512; }","duration":"120.647772ms","start":"2026-09-24T08:37:10.515684Z","end":"2026-09-24T08:37:10.636332Z","steps":["trace[1522555036] 'agreement among raft nodes before linearized reading' (duration: 119.688457ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:37:10.914452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.077513ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-09-24T08:37:10.914904Z","caller":"traceutil/trace.go:171","msg":"trace[1239620751] linearizableReadLoop","detail":"{readStateIndex:28431; appliedIndex:28426; }","duration":"180.223963ms","start":"2026-09-24T08:37:10.734659Z","end":"2026-09-24T08:37:10.914883Z","steps":["trace[1239620751] 'read index received' (duration: 17.713698ms)","trace[1239620751] 'applied index is now lower than readState.Index' (duration: 162.509335ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:37:10.915037Z","caller":"traceutil/trace.go:171","msg":"trace[260727781] transaction","detail":"{read_only:false; response_revision:27514; number_of_response:1; }","duration":"273.976349ms","start":"2026-09-24T08:37:10.641044Z","end":"2026-09-24T08:37:10.91502Z","steps":["trace[260727781] 'process raft request' (duration: 273.65832ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:37:10.914995Z","caller":"traceutil/trace.go:171","msg":"trace[407756698] transaction","detail":"{read_only:false; response_revision:27513; number_of_response:1; }","duration":"274.484371ms","start":"2026-09-24T08:37:10.64045Z","end":"2026-09-24T08:37:10.914934Z","steps":["trace[407756698] 'process raft request' (duration: 274.120772ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:37:10.915289Z","caller":"traceutil/trace.go:171","msg":"trace[953978768] transaction","detail":"{read_only:false; response_revision:27515; number_of_response:1; }","duration":"274.115972ms","start":"2026-09-24T08:37:10.641136Z","end":"2026-09-24T08:37:10.915251Z","steps":["trace[953978768] 'process raft request' (duration: 273.61974ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:37:10.915297Z","caller":"traceutil/trace.go:171","msg":"trace[1808330156] transaction","detail":"{read_only:false; response_revision:27516; number_of_response:1; }","duration":"231.748587ms","start":"2026-09-24T08:37:10.683514Z","end":"2026-09-24T08:37:10.915263Z","steps":["trace[1808330156] 'process raft request' (duration: 231.317826ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:37:10.915344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.650585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-24T08:37:10.915409Z","caller":"traceutil/trace.go:171","msg":"trace[1409133868] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:27516; }","duration":"180.771197ms","start":"2026-09-24T08:37:10.73462Z","end":"2026-09-24T08:37:10.915391Z","steps":["trace[1409133868] 'agreement among raft nodes before linearized reading' (duration: 180.523871ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:37:10.915508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.267903ms","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-24T08:37:10.915609Z","caller":"traceutil/trace.go:171","msg":"trace[452351829] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27516; }","duration":"180.374896ms","start":"2026-09-24T08:37:10.735208Z","end":"2026-09-24T08:37:10.915583Z","steps":["trace[452351829] 'agreement among raft nodes before linearized reading' (duration: 179.974636ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:37:11.038109Z","caller":"traceutil/trace.go:171","msg":"trace[1009310804] transaction","detail":"{read_only:false; response_revision:27518; number_of_response:1; }","duration":"116.565546ms","start":"2026-09-24T08:37:10.921506Z","end":"2026-09-24T08:37:11.038072Z","steps":["trace[1009310804] 'process raft request' (duration: 116.271149ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:37:20.416455Z","caller":"traceutil/trace.go:171","msg":"trace[661181390] transaction","detail":"{read_only:false; response_revision:27596; number_of_response:1; }","duration":"110.880329ms","start":"2026-09-24T08:37:20.305547Z","end":"2026-09-24T08:37:20.416428Z","steps":["trace[661181390] 'process raft request' (duration: 110.673333ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:37:20.869364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.044166ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-24T08:37:20.869464Z","caller":"traceutil/trace.go:171","msg":"trace[800773037] linearizableReadLoop","detail":"{readStateIndex:28515; appliedIndex:28514; }","duration":"155.404821ms","start":"2026-09-24T08:37:20.71404Z","end":"2026-09-24T08:37:20.869444Z","steps":["trace[800773037] 'read index received' (duration: 11.106537ms)","trace[800773037] 'applied index is now lower than readState.Index' (duration: 144.297053ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:37:20.869573Z","caller":"traceutil/trace.go:171","msg":"trace[1438206287] transaction","detail":"{read_only:false; response_revision:27598; number_of_response:1; }","duration":"159.132207ms","start":"2026-09-24T08:37:20.710426Z","end":"2026-09-24T08:37:20.869558Z","steps":["trace[1438206287] 'process raft request' (duration: 14.806764ms)","trace[1438206287] 'compare' (duration: 143.934673ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:37:20.869716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.386679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-09-24T08:37:20.869793Z","caller":"traceutil/trace.go:171","msg":"trace[1447887310] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:27598; }","duration":"153.488192ms","start":"2026-09-24T08:37:20.716285Z","end":"2026-09-24T08:37:20.869773Z","steps":["trace[1447887310] 'agreement among raft nodes before linearized reading' (duration: 153.300117ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:37:20.870025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.426825ms","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-24T08:37:20.870054Z","caller":"traceutil/trace.go:171","msg":"trace[1090057411] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27598; }","duration":"159.867706ms","start":"2026-09-24T08:37:20.710177Z","end":"2026-09-24T08:37:20.870045Z","steps":["trace[1090057411] 'agreement among raft nodes before linearized reading' (duration: 159.813444ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:37:21.227623Z","caller":"traceutil/trace.go:171","msg":"trace[1836881104] linearizableReadLoop","detail":"{readStateIndex:28517; appliedIndex:28516; }","duration":"185.299484ms","start":"2026-09-24T08:37:21.0423Z","end":"2026-09-24T08:37:21.2276Z","steps":["trace[1836881104] 'read index received' (duration: 185.040997ms)","trace[1836881104] 'applied index is now lower than readState.Index' (duration: 257.327µs)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:37:21.227647Z","caller":"traceutil/trace.go:171","msg":"trace[605483927] transaction","detail":"{read_only:false; response_revision:27600; number_of_response:1; }","duration":"200.021235ms","start":"2026-09-24T08:37:21.027601Z","end":"2026-09-24T08:37:21.227622Z","steps":["trace[605483927] 'process raft request' (duration: 199.811739ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:37:21.227773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.452008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-09-24T08:37:21.228063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.229132ms","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-24T08:37:21.228121Z","caller":"traceutil/trace.go:171","msg":"trace[365467207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27600; }","duration":"185.345705ms","start":"2026-09-24T08:37:21.04276Z","end":"2026-09-24T08:37:21.228106Z","steps":["trace[365467207] 'agreement among raft nodes before linearized reading' (duration: 185.13805ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:37:21.228149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.572229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-09-24T08:37:21.228152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.654163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-24T08:37:21.2282Z","caller":"traceutil/trace.go:171","msg":"trace[569170308] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27600; }","duration":"142.673431ms","start":"2026-09-24T08:37:21.085511Z","end":"2026-09-24T08:37:21.228184Z","steps":["trace[569170308] 'agreement among raft nodes before linearized reading' (duration: 142.442835ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:37:21.228237Z","caller":"traceutil/trace.go:171","msg":"trace[585220860] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:27600; }","duration":"173.795937ms","start":"2026-09-24T08:37:21.054419Z","end":"2026-09-24T08:37:21.228215Z","steps":["trace[585220860] 'agreement among raft nodes before linearized reading' (duration: 173.596292ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:37:21.22826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.322154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-24T08:37:21.227852Z","caller":"traceutil/trace.go:171","msg":"trace[1070150737] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27600; }","duration":"185.577411ms","start":"2026-09-24T08:37:21.04226Z","end":"2026-09-24T08:37:21.227837Z","steps":["trace[1070150737] 'agreement among raft nodes before linearized reading' (duration: 185.434877ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:37:21.228342Z","caller":"traceutil/trace.go:171","msg":"trace[171660493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27600; }","duration":"185.427847ms","start":"2026-09-24T08:37:21.042894Z","end":"2026-09-24T08:37:21.228322Z","steps":["trace[171660493] 'agreement among raft nodes before linearized reading' (duration: 185.288414ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:37:21.497909Z","caller":"traceutil/trace.go:171","msg":"trace[608181950] transaction","detail":"{read_only:false; response_revision:27602; number_of_response:1; }","duration":"264.561225ms","start":"2026-09-24T08:37:21.233321Z","end":"2026-09-24T08:37:21.497882Z","steps":["trace[608181950] 'process raft request' (duration: 210.188298ms)","trace[608181950] 'compare' (duration: 53.925505ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:37:21.498418Z","caller":"traceutil/trace.go:171","msg":"trace[28090898] transaction","detail":"{read_only:false; response_revision:27603; number_of_response:1; }","duration":"265.066789ms","start":"2026-09-24T08:37:21.233322Z","end":"2026-09-24T08:37:21.498389Z","steps":["trace[28090898] 'process raft request' (duration: 264.512324ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:37:21.498486Z","caller":"traceutil/trace.go:171","msg":"trace[1284864639] transaction","detail":"{read_only:false; response_revision:27604; number_of_response:1; }","duration":"264.742781ms","start":"2026-09-24T08:37:21.233718Z","end":"2026-09-24T08:37:21.498461Z","steps":["trace[1284864639] 'process raft request' (duration: 264.572546ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:37:21.498553Z","caller":"traceutil/trace.go:171","msg":"trace[822018227] transaction","detail":"{read_only:false; response_revision:27605; number_of_response:1; }","duration":"258.787136ms","start":"2026-09-24T08:37:21.239738Z","end":"2026-09-24T08:37:21.498525Z","steps":["trace[822018227] 'process raft request' (duration: 258.678494ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:37:21.662603Z","caller":"traceutil/trace.go:171","msg":"trace[1112178682] transaction","detail":"{read_only:false; response_revision:27606; number_of_response:1; }","duration":"402.481783ms","start":"2026-09-24T08:37:21.260094Z","end":"2026-09-24T08:37:21.662576Z","steps":["trace[1112178682] 'process raft request' (duration: 379.474108ms)","trace[1112178682] 'compare' (duration: 22.095322ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:37:21.662741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:37:21.26007Z","time spent":"402.597035ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-24T08:37:21.662916Z","caller":"traceutil/trace.go:171","msg":"trace[1789048861] transaction","detail":"{read_only:false; response_revision:27607; number_of_response:1; }","duration":"291.969534ms","start":"2026-09-24T08:37:21.370936Z","end":"2026-09-24T08:37:21.662905Z","steps":["trace[1789048861] 'process raft request' (duration: 291.125743ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:39:15.272985Z","caller":"traceutil/trace.go:171","msg":"trace[77327638] transaction","detail":"{read_only:false; response_revision:28711; number_of_response:1; }","duration":"370.236628ms","start":"2026-09-24T08:39:14.902716Z","end":"2026-09-24T08:39:15.272953Z","steps":["trace[77327638] 'process raft request' (duration: 370.047243ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:39:15.273162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:39:14.902692Z","time spent":"370.375772ms","remote":"127.0.0.1:52100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":21327,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-24T08:39:15.331912Z","caller":"traceutil/trace.go:171","msg":"trace[1249923935] linearizableReadLoop","detail":"{readStateIndex:29654; appliedIndex:29653; }","duration":"348.612989ms","start":"2026-09-24T08:39:14.983276Z","end":"2026-09-24T08:39:15.331889Z","steps":["trace[1249923935] 'read index received' (duration: 290.003544ms)","trace[1249923935] 'applied index is now lower than readState.Index' (duration: 58.608565ms)"],"step_count":2} {"level":"info","ts":"2026-09-24T08:39:15.332116Z","caller":"traceutil/trace.go:171","msg":"trace[1674378817] transaction","detail":"{read_only:false; response_revision:28713; number_of_response:1; }","duration":"260.939062ms","start":"2026-09-24T08:39:15.071146Z","end":"2026-09-24T08:39:15.332085Z","steps":["trace[1674378817] 'process raft request' (duration: 260.684875ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:39:15.332155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.860996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-24T08:39:15.332222Z","caller":"traceutil/trace.go:171","msg":"trace[1842508231] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:28713; }","duration":"348.984629ms","start":"2026-09-24T08:39:14.983223Z","end":"2026-09-24T08:39:15.332208Z","steps":["trace[1842508231] 'agreement among raft nodes before linearized reading' (duration: 348.868416ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:39:15.332276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:39:14.983205Z","time spent":"349.057712ms","remote":"127.0.0.1:52100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "} {"level":"info","ts":"2026-09-24T08:39:15.332253Z","caller":"traceutil/trace.go:171","msg":"trace[77198886] transaction","detail":"{read_only:false; response_revision:28712; number_of_response:1; }","duration":"419.555165ms","start":"2026-09-24T08:39:14.912642Z","end":"2026-09-24T08:39:15.332197Z","steps":["trace[77198886] 'process raft request' (duration: 396.247912ms)","trace[77198886] 'compare' (duration: 22.776679ms)"],"step_count":2} {"level":"warn","ts":"2026-09-24T08:39:15.332486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.824734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-24T08:39:15.33249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.9255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-24T08:39:15.332486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:39:14.912615Z","time spent":"419.754581ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-24T08:39:15.332521Z","caller":"traceutil/trace.go:171","msg":"trace[626272224] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:28713; }","duration":"169.904206ms","start":"2026-09-24T08:39:15.162609Z","end":"2026-09-24T08:39:15.332514Z","steps":["trace[626272224] 'agreement among raft nodes before linearized reading' (duration: 169.819634ms)"],"step_count":1} {"level":"info","ts":"2026-09-24T08:39:15.332529Z","caller":"traceutil/trace.go:171","msg":"trace[1527177043] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28713; }","duration":"111.000982ms","start":"2026-09-24T08:39:15.221517Z","end":"2026-09-24T08:39:15.332518Z","steps":["trace[1527177043] 'agreement among raft nodes before linearized reading' (duration: 110.91778ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:39:15.332585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.041392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-24T08:39:15.332712Z","caller":"traceutil/trace.go:171","msg":"trace[2015585067] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:28713; }","duration":"264.234178ms","start":"2026-09-24T08:39:15.068448Z","end":"2026-09-24T08:39:15.332682Z","steps":["trace[2015585067] 'agreement among raft nodes before linearized reading' (duration: 263.986151ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:39:15.332744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.055069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:1 size:21345"} {"level":"info","ts":"2026-09-24T08:39:15.332771Z","caller":"traceutil/trace.go:171","msg":"trace[1911173794] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:1; response_revision:28713; }","duration":"347.119301ms","start":"2026-09-24T08:39:14.985644Z","end":"2026-09-24T08:39:15.332763Z","steps":["trace[1911173794] 'agreement among raft nodes before linearized reading' (duration: 346.685459ms)"],"step_count":1} {"level":"warn","ts":"2026-09-24T08:39:15.332896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-24T08:39:14.985625Z","time spent":"347.159392ms","remote":"127.0.0.1:52100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":21368,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "} {"level":"info","ts":"2026-09-24T08:39:58.347822Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"427150911cb3975a","local-member-applied-index":30008,"local-member-snapshot-index":20007,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-24T08:39:58.357811Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30008} {"level":"info","ts":"2026-09-24T08:39:58.357922Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25008} {"level":"info","ts":"2026-09-24T08:41:26.68798Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27149} {"level":"info","ts":"2026-09-24T08:41:26.839441Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27149,"took":"147.767652ms","hash":2267482166,"current-db-size-bytes":59965440,"current-db-size":"60 MB","current-db-size-in-use-bytes":36159488,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-09-24T08:41:26.839527Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2267482166,"revision":27149,"compact-revision":23766} {"level":"info","ts":"2026-09-24T08:46:26.695665Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30054} {"level":"info","ts":"2026-09-24T08:46:26.837247Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30054,"took":"137.934642ms","hash":118462824,"current-db-size-bytes":59965440,"current-db-size":"60 MB","current-db-size-in-use-bytes":31641600,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-24T08:46:26.837313Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":118462824,"revision":30054,"compact-revision":27149} {"level":"info","ts":"2026-09-24T08:51:26.707952Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32337} {"level":"info","ts":"2026-09-24T08:51:26.867548Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32337,"took":"155.578619ms","hash":2671586529,"current-db-size-bytes":61009920,"current-db-size":"61 MB","current-db-size-in-use-bytes":30314496,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-24T08:51:26.867611Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2671586529,"revision":32337,"compact-revision":30054} {"level":"info","ts":"2026-09-24T08:56:26.714051Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34563} {"level":"info","ts":"2026-09-24T08:56:26.841564Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34563,"took":"124.252778ms","hash":3209062113,"current-db-size-bytes":61009920,"current-db-size":"61 MB","current-db-size-in-use-bytes":30564352,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-24T08:56:26.841628Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3209062113,"revision":34563,"compact-revision":32337} {"level":"info","ts":"2026-09-24T09:00:31.181217Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"427150911cb3975a","local-member-applied-index":40009,"local-member-snapshot-index":30008,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-24T09:00:31.185838Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40009} {"level":"info","ts":"2026-09-24T09:00:31.18593Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35009} {"level":"info","ts":"2026-09-24T09:01:26.723331Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36795} {"level":"info","ts":"2026-09-24T09:01:26.856233Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36795,"took":"125.366666ms","hash":3840554493,"current-db-size-bytes":61009920,"current-db-size":"61 MB","current-db-size-in-use-bytes":32157696,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-24T09:01:26.856354Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3840554493,"revision":36795,"compact-revision":34563} {"level":"info","ts":"2026-09-24T09:06:26.731197Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39194} {"level":"info","ts":"2026-09-24T09:06:26.88902Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39194,"took":"154.361828ms","hash":2337415002,"current-db-size-bytes":61435904,"current-db-size":"61 MB","current-db-size-in-use-bytes":31936512,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-24T09:06:26.889088Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2337415002,"revision":39194,"compact-revision":36795} {"level":"info","ts":"2026-09-24T09:11:26.742377Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41458} {"level":"info","ts":"2026-09-24T09:11:26.863505Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41458,"took":"117.553984ms","hash":1560269075,"current-db-size-bytes":61435904,"current-db-size":"61 MB","current-db-size-in-use-bytes":30846976,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-24T09:11:26.863578Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1560269075,"revision":41458,"compact-revision":39194} {"level":"info","ts":"2026-09-24T09:16:26.751334Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":43687} {"level":"info","ts":"2026-09-24T09:16:26.881191Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":43687,"took":"124.721639ms","hash":493330211,"current-db-size-bytes":61435904,"current-db-size":"61 MB","current-db-size-in-use-bytes":29892608,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-24T09:16:26.881275Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":493330211,"revision":43687,"compact-revision":41458}