{"level":"warn","ts":"2026-04-27T21:02:34.832498Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T21:02:34.832619Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.23:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.23:2380","--initial-cluster=instance=https://199.19.213.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.23: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-04-27T21:02:34.832711Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T21:02:34.832732Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.23:2380"]} {"level":"info","ts":"2026-04-27T21:02:34.83276Z","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-04-27T21:02:34.833822Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.23:2379"]} {"level":"info","ts":"2026-04-27T21:02:34.83398Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.23:2380"],"listen-peer-urls":["https://199.19.213.23:2380"],"advertise-client-urls":["https://199.19.213.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.23: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-04-27T21:02:34.838106Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.836204ms"} {"level":"info","ts":"2026-04-27T21:02:34.851925Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1812db8446d3ac67","cluster-id":"57ff06cb872baa33"} {"level":"info","ts":"2026-04-27T21:02:34.852027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 switched to configuration voters=()"} {"level":"info","ts":"2026-04-27T21:02:34.85206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 became follower at term 0"} {"level":"info","ts":"2026-04-27T21:02:34.852089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1812db8446d3ac67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-27T21:02:34.852126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 became follower at term 1"} {"level":"info","ts":"2026-04-27T21:02:34.852186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 switched to configuration voters=(1734690167661505639)"} {"level":"warn","ts":"2026-04-27T21:02:34.85751Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-27T21:02:34.863729Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-27T21:02:34.865104Z","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-04-27T21:02:34.867652Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1812db8446d3ac67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-27T21:02:34.867952Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1812db8446d3ac67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-27T21:02:34.867989Z","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-04-27T21:02:34.868117Z","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-04-27T21:02:34.868132Z","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-04-27T21:02:34.868773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 switched to configuration voters=(1734690167661505639)"} {"level":"info","ts":"2026-04-27T21:02:34.868929Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"57ff06cb872baa33","local-member-id":"1812db8446d3ac67","added-peer-id":"1812db8446d3ac67","added-peer-peer-urls":["https://199.19.213.23:2380"]} {"level":"info","ts":"2026-04-27T21:02:34.870657Z","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-04-27T21:02:34.870819Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.23:2380"} {"level":"info","ts":"2026-04-27T21:02:34.871029Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.23:2380"} {"level":"info","ts":"2026-04-27T21:02:34.87096Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1812db8446d3ac67","initial-advertise-peer-urls":["https://199.19.213.23:2380"],"listen-peer-urls":["https://199.19.213.23:2380"],"advertise-client-urls":["https://199.19.213.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-27T21:02:34.871Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-27T21:02:34.952747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 is starting a new election at term 1"} {"level":"info","ts":"2026-04-27T21:02:34.952816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-27T21:02:34.952855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 received MsgPreVoteResp from 1812db8446d3ac67 at term 1"} {"level":"info","ts":"2026-04-27T21:02:34.952876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 became candidate at term 2"} {"level":"info","ts":"2026-04-27T21:02:34.95289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 received MsgVoteResp from 1812db8446d3ac67 at term 2"} {"level":"info","ts":"2026-04-27T21:02:34.952908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1812db8446d3ac67 became leader at term 2"} {"level":"info","ts":"2026-04-27T21:02:34.952924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1812db8446d3ac67 elected leader 1812db8446d3ac67 at term 2"} {"level":"info","ts":"2026-04-27T21:02:34.954244Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1812db8446d3ac67","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.23:2379]}","request-path":"/0/members/1812db8446d3ac67/attributes","cluster-id":"57ff06cb872baa33","publish-timeout":"7s"} {"level":"info","ts":"2026-04-27T21:02:34.954304Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T21:02:34.954259Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T21:02:34.954503Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T21:02:34.954667Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-27T21:02:34.954723Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-27T21:02:34.955864Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"57ff06cb872baa33","local-member-id":"1812db8446d3ac67","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T21:02:34.95607Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T21:02:34.956126Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T21:02:34.95847Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.23:2379"} {"level":"info","ts":"2026-04-27T21:02:34.958475Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-27T21:02:49.452708Z","caller":"traceutil/trace.go:171","msg":"trace[1589270483] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"131.170462ms","start":"2026-04-27T21:02:49.321511Z","end":"2026-04-27T21:02:49.452681Z","steps":["trace[1589270483] 'process raft request' (duration: 130.805565ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:03:05.22249Z","caller":"traceutil/trace.go:171","msg":"trace[979191878] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"118.965519ms","start":"2026-04-27T21:03:05.103491Z","end":"2026-04-27T21:03:05.222456Z","steps":["trace[979191878] 'process raft request' (duration: 89.546922ms)","trace[979191878] 'compare' (duration: 29.195623ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:03:05.232677Z","caller":"traceutil/trace.go:171","msg":"trace[1379499276] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"112.27151ms","start":"2026-04-27T21:03:05.120364Z","end":"2026-04-27T21:03:05.232636Z","steps":["trace[1379499276] 'process raft request' (duration: 112.045384ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:03:05.232776Z","caller":"traceutil/trace.go:171","msg":"trace[543241808] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"103.387019ms","start":"2026-04-27T21:03:05.129363Z","end":"2026-04-27T21:03:05.23275Z","steps":["trace[543241808] 'process raft request' (duration: 103.197615ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:03:05.232732Z","caller":"traceutil/trace.go:171","msg":"trace[718833370] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"104.268687ms","start":"2026-04-27T21:03:05.128416Z","end":"2026-04-27T21:03:05.232684Z","steps":["trace[718833370] 'process raft request' (duration: 104.113104ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:03:05.39532Z","caller":"traceutil/trace.go:171","msg":"trace[1564107525] transaction","detail":"{read_only:false; number_of_response:1; response_revision:534; }","duration":"100.271819ms","start":"2026-04-27T21:03:05.29502Z","end":"2026-04-27T21:03:05.395292Z","steps":["trace[1564107525] 'process raft request' (duration: 100.005944ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:03:10.768393Z","caller":"traceutil/trace.go:171","msg":"trace[143446560] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"267.638298ms","start":"2026-04-27T21:03:10.500705Z","end":"2026-04-27T21:03:10.768343Z","steps":["trace[143446560] 'process raft request' (duration: 267.384063ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:03:10.911358Z","caller":"traceutil/trace.go:171","msg":"trace[1819754475] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:628; }","duration":"216.045745ms","start":"2026-04-27T21:03:10.695285Z","end":"2026-04-27T21:03:10.911331Z","steps":["trace[1819754475] 'read index received' (duration: 72.850611ms)","trace[1819754475] 'applied index is now lower than readState.Index' (duration: 143.194034ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:03:10.911501Z","caller":"traceutil/trace.go:171","msg":"trace[2117065567] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"336.369129ms","start":"2026-04-27T21:03:10.575099Z","end":"2026-04-27T21:03:10.911468Z","steps":["trace[2117065567] 'process raft request' (duration: 333.855431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:03:10.911727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.332911ms","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-04-27T21:03:10.911848Z","caller":"traceutil/trace.go:171","msg":"trace[479885157] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:608; }","duration":"216.604565ms","start":"2026-04-27T21:03:10.695228Z","end":"2026-04-27T21:03:10.911833Z","steps":["trace[479885157] 'agreement among raft nodes before linearized reading' (duration: 216.251959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:03:10.912074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:03:10.57506Z","time spent":"336.511592ms","remote":"127.0.0.1:43010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036229,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:03:11.81812Z","caller":"traceutil/trace.go:171","msg":"trace[86461155] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"153.098634ms","start":"2026-04-27T21:03:11.664991Z","end":"2026-04-27T21:03:11.81809Z","steps":["trace[86461155] 'process raft request' (duration: 152.91425ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:03:12.332731Z","caller":"traceutil/trace.go:171","msg":"trace[1403512936] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"385.410833ms","start":"2026-04-27T21:03:11.947291Z","end":"2026-04-27T21:03:12.332702Z","steps":["trace[1403512936] 'read index received' (duration: 384.403304ms)","trace[1403512936] 'applied index is now lower than readState.Index' (duration: 1.006259ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:03:12.332865Z","caller":"traceutil/trace.go:171","msg":"trace[1100896396] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"427.183786ms","start":"2026-04-27T21:03:11.905616Z","end":"2026-04-27T21:03:12.3328Z","steps":["trace[1100896396] 'process raft request' (duration: 426.436102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:03:12.333146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:03:11.905585Z","time spent":"427.40916ms","remote":"127.0.0.1:43010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:03:12.333157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.355712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-llqjk\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-27T21:03:12.333318Z","caller":"traceutil/trace.go:171","msg":"trace[1380982057] range","detail":"{range_begin:/registry/pods/kube-system/cilium-llqjk; range_end:; response_count:1; response_revision:619; }","duration":"110.567936ms","start":"2026-04-27T21:03:12.222727Z","end":"2026-04-27T21:03:12.333295Z","steps":["trace[1380982057] 'agreement among raft nodes before linearized reading' (duration: 110.27411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:03:12.333518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.584066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:03:12.333634Z","caller":"traceutil/trace.go:171","msg":"trace[1680739619] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"386.377882ms","start":"2026-04-27T21:03:11.947223Z","end":"2026-04-27T21:03:12.333601Z","steps":["trace[1680739619] 'agreement among raft nodes before linearized reading' (duration: 385.621337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:03:12.333699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:03:11.947194Z","time spent":"386.483683ms","remote":"127.0.0.1:42932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T21:04:43.762926Z","caller":"traceutil/trace.go:171","msg":"trace[930832780] linearizableReadLoop","detail":"{readStateIndex:1927; appliedIndex:1926; }","duration":"202.271417ms","start":"2026-04-27T21:04:43.560628Z","end":"2026-04-27T21:04:43.762899Z","steps":["trace[930832780] 'read index received' (duration: 202.150375ms)","trace[930832780] 'applied index is now lower than readState.Index' (duration: 119.202µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:04:43.763199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.554482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-61209f3f-3de6-4087-852a-2a6721fa2a25\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-27T21:04:43.763251Z","caller":"traceutil/trace.go:171","msg":"trace[1013656403] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-61209f3f-3de6-4087-852a-2a6721fa2a25; range_end:; response_count:1; response_revision:1858; }","duration":"202.656305ms","start":"2026-04-27T21:04:43.560579Z","end":"2026-04-27T21:04:43.763235Z","steps":["trace[1013656403] 'agreement among raft nodes before linearized reading' (duration: 202.470931ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:04:43.763228Z","caller":"traceutil/trace.go:171","msg":"trace[817423530] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"255.99684ms","start":"2026-04-27T21:04:43.507186Z","end":"2026-04-27T21:04:43.763183Z","steps":["trace[817423530] 'process raft request' (duration: 255.545431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:04:43.763345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.721571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-27T21:04:43.763765Z","caller":"traceutil/trace.go:171","msg":"trace[2001501067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1858; }","duration":"171.22417ms","start":"2026-04-27T21:04:43.592524Z","end":"2026-04-27T21:04:43.763748Z","steps":["trace[2001501067] 'agreement among raft nodes before linearized reading' (duration: 170.599298ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:04:43.919749Z","caller":"traceutil/trace.go:171","msg":"trace[1516283904] linearizableReadLoop","detail":"{readStateIndex:1929; appliedIndex:1927; }","duration":"144.173981ms","start":"2026-04-27T21:04:43.77555Z","end":"2026-04-27T21:04:43.919724Z","steps":["trace[1516283904] 'read index received' (duration: 47.054115ms)","trace[1516283904] 'applied index is now lower than readState.Index' (duration: 97.118336ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:04:43.919845Z","caller":"traceutil/trace.go:171","msg":"trace[1201919671] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"147.716429ms","start":"2026-04-27T21:04:43.772073Z","end":"2026-04-27T21:04:43.91979Z","steps":["trace[1201919671] 'process raft request' (duration: 147.469944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:04:43.920133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.529488ms","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-04-27T21:04:43.92017Z","caller":"traceutil/trace.go:171","msg":"trace[1139603662] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1860; }","duration":"144.642621ms","start":"2026-04-27T21:04:43.775518Z","end":"2026-04-27T21:04:43.920161Z","steps":["trace[1139603662] 'agreement among raft nodes before linearized reading' (duration: 144.326555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:04:43.920814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.722227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-27T21:04:43.920934Z","caller":"traceutil/trace.go:171","msg":"trace[603861088] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1860; }","duration":"112.321408ms","start":"2026-04-27T21:04:43.808571Z","end":"2026-04-27T21:04:43.920892Z","steps":["trace[603861088] 'agreement among raft nodes before linearized reading' (duration: 111.519533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:04:44.385398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.785778ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T21:04:44.385676Z","caller":"traceutil/trace.go:171","msg":"trace[1087769225] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"398.865395ms","start":"2026-04-27T21:04:43.986775Z","end":"2026-04-27T21:04:44.385641Z","steps":["trace[1087769225] 'process raft request' (duration: 273.509396ms)","trace[1087769225] 'compare' (duration: 124.652116ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:04:44.385866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:04:43.986749Z","time spent":"399.023138ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:04:44.387943Z","caller":"traceutil/trace.go:171","msg":"trace[2101322404] linearizableReadLoop","detail":"{readStateIndex:1933; appliedIndex:1930; }","duration":"166.745085ms","start":"2026-04-27T21:04:44.221174Z","end":"2026-04-27T21:04:44.38792Z","steps":["trace[2101322404] 'read index received' (duration: 39.345236ms)","trace[2101322404] 'applied index is now lower than readState.Index' (duration: 127.398599ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:04:44.388043Z","caller":"traceutil/trace.go:171","msg":"trace[852065016] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"213.166627ms","start":"2026-04-27T21:04:44.174833Z","end":"2026-04-27T21:04:44.388Z","steps":["trace[852065016] 'process raft request' (duration: 213.000994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:04:44.388236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.040951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-27T21:04:44.388367Z","caller":"traceutil/trace.go:171","msg":"trace[1763892403] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1863; }","duration":"167.244454ms","start":"2026-04-27T21:04:44.2211Z","end":"2026-04-27T21:04:44.388345Z","steps":["trace[1763892403] 'agreement among raft nodes before linearized reading' (duration: 166.907038ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:04:59.486544Z","caller":"traceutil/trace.go:171","msg":"trace[327216953] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"122.30103ms","start":"2026-04-27T21:04:59.36422Z","end":"2026-04-27T21:04:59.486522Z","steps":["trace[327216953] 'process raft request' (duration: 122.126617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:05:33.051202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.862535ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:05:33.051384Z","caller":"traceutil/trace.go:171","msg":"trace[486096238] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2176; }","duration":"105.079219ms","start":"2026-04-27T21:05:32.94628Z","end":"2026-04-27T21:05:33.051359Z","steps":["trace[486096238] 'range keys from in-memory index tree' (duration: 104.831384ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:05:33.051818Z","caller":"traceutil/trace.go:171","msg":"trace[1469364800] linearizableReadLoop","detail":"{readStateIndex:2257; appliedIndex:2256; }","duration":"144.18821ms","start":"2026-04-27T21:05:32.907616Z","end":"2026-04-27T21:05:33.051805Z","steps":["trace[1469364800] 'read index received' (duration: 62.230856ms)","trace[1469364800] 'applied index is now lower than readState.Index' (duration: 81.955874ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:05:33.051947Z","caller":"traceutil/trace.go:171","msg":"trace[136485845] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"158.20456ms","start":"2026-04-27T21:05:32.893704Z","end":"2026-04-27T21:05:33.051908Z","steps":["trace[136485845] 'process raft request' (duration: 76.116963ms)","trace[136485845] 'compare' (duration: 81.472006ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:05:33.052095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.497198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-04-27T21:05:33.052104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.923137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:05:33.052229Z","caller":"traceutil/trace.go:171","msg":"trace[1226449804] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2178; }","duration":"105.097271ms","start":"2026-04-27T21:05:32.947102Z","end":"2026-04-27T21:05:33.0522Z","steps":["trace[1226449804] 'agreement among raft nodes before linearized reading' (duration: 104.892217ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:05:33.05214Z","caller":"traceutil/trace.go:171","msg":"trace[1229607382] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2178; }","duration":"144.62426ms","start":"2026-04-27T21:05:32.907504Z","end":"2026-04-27T21:05:33.052128Z","steps":["trace[1229607382] 'agreement among raft nodes before linearized reading' (duration: 144.414305ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:05:33.600592Z","caller":"traceutil/trace.go:171","msg":"trace[407383715] linearizableReadLoop","detail":"{readStateIndex:2259; appliedIndex:2258; }","duration":"126.225306ms","start":"2026-04-27T21:05:33.47433Z","end":"2026-04-27T21:05:33.600555Z","steps":["trace[407383715] 'read index received' (duration: 125.973381ms)","trace[407383715] 'applied index is now lower than readState.Index' (duration: 249.945µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:05:33.600719Z","caller":"traceutil/trace.go:171","msg":"trace[1736667324] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"189.210556ms","start":"2026-04-27T21:05:33.411462Z","end":"2026-04-27T21:05:33.600673Z","steps":["trace[1736667324] 'process raft request' (duration: 188.844229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:05:33.60096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.606763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-27T21:05:33.601071Z","caller":"traceutil/trace.go:171","msg":"trace[146161855] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2179; }","duration":"126.772816ms","start":"2026-04-27T21:05:33.47427Z","end":"2026-04-27T21:05:33.601042Z","steps":["trace[146161855] 'agreement among raft nodes before linearized reading' (duration: 126.43703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:05:39.412829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.349766ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T21:05:39.41298Z","caller":"traceutil/trace.go:171","msg":"trace[814363952] linearizableReadLoop","detail":"{readStateIndex:2292; appliedIndex:2290; }","duration":"316.499683ms","start":"2026-04-27T21:05:39.096457Z","end":"2026-04-27T21:05:39.412957Z","steps":["trace[814363952] 'read index received' (duration: 55.707221ms)","trace[814363952] 'applied index is now lower than readState.Index' (duration: 260.790722ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:05:39.413139Z","caller":"traceutil/trace.go:171","msg":"trace[993419784] transaction","detail":"{read_only:false; response_revision:2210; number_of_response:1; }","duration":"339.191819ms","start":"2026-04-27T21:05:39.073909Z","end":"2026-04-27T21:05:39.413101Z","steps":["trace[993419784] 'process raft request' (duration: 222.441805ms)","trace[993419784] 'compare' (duration: 116.04182ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:05:39.413237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.74226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:05:39.413301Z","caller":"traceutil/trace.go:171","msg":"trace[41051409] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2210; }","duration":"237.856422ms","start":"2026-04-27T21:05:39.17543Z","end":"2026-04-27T21:05:39.413287Z","steps":["trace[41051409] 'agreement among raft nodes before linearized reading' (duration: 237.7552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:05:39.41329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:05:39.073863Z","time spent":"339.362102ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:05:39.413264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.814649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-27T21:05:39.413426Z","caller":"traceutil/trace.go:171","msg":"trace[2096692565] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2210; }","duration":"317.017332ms","start":"2026-04-27T21:05:39.096391Z","end":"2026-04-27T21:05:39.413409Z","steps":["trace[2096692565] 'agreement among raft nodes before linearized reading' (duration: 316.710207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:05:39.413649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:05:39.096367Z","time spent":"317.200306ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-27T21:06:15.310589Z","caller":"traceutil/trace.go:171","msg":"trace[2012060046] linearizableReadLoop","detail":"{readStateIndex:2677; appliedIndex:2676; }","duration":"165.934735ms","start":"2026-04-27T21:06:15.144615Z","end":"2026-04-27T21:06:15.31055Z","steps":["trace[2012060046] 'read index received' (duration: 165.849213ms)","trace[2012060046] 'applied index is now lower than readState.Index' (duration: 83.902µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:06:15.310748Z","caller":"traceutil/trace.go:171","msg":"trace[602027717] transaction","detail":"{read_only:false; response_revision:2579; number_of_response:1; }","duration":"168.879161ms","start":"2026-04-27T21:06:15.141856Z","end":"2026-04-27T21:06:15.310735Z","steps":["trace[602027717] 'process raft request' (duration: 168.504823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:06:15.311046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.425904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-27T21:06:15.31108Z","caller":"traceutil/trace.go:171","msg":"trace[494885962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2579; }","duration":"166.542336ms","start":"2026-04-27T21:06:15.144528Z","end":"2026-04-27T21:06:15.31107Z","steps":["trace[494885962] 'agreement among raft nodes before linearized reading' (duration: 166.380133ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:06:47.799045Z","caller":"traceutil/trace.go:171","msg":"trace[628304347] transaction","detail":"{read_only:false; response_revision:2777; number_of_response:1; }","duration":"112.050207ms","start":"2026-04-27T21:06:47.686947Z","end":"2026-04-27T21:06:47.798997Z","steps":["trace[628304347] 'process raft request' (duration: 111.775101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:10:54.035903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.513285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-04-27T21:10:54.036006Z","caller":"traceutil/trace.go:171","msg":"trace[1847070681] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4877; }","duration":"121.646687ms","start":"2026-04-27T21:10:53.91434Z","end":"2026-04-27T21:10:54.035987Z","steps":["trace[1847070681] 'range keys from in-memory index tree' (duration: 121.383322ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:10:54.06105Z","caller":"traceutil/trace.go:171","msg":"trace[232828119] linearizableReadLoop","detail":"{readStateIndex:5060; appliedIndex:5059; }","duration":"112.110757ms","start":"2026-04-27T21:10:53.948925Z","end":"2026-04-27T21:10:54.061036Z","steps":["trace[232828119] 'read index received' (duration: 111.952594ms)","trace[232828119] 'applied index is now lower than readState.Index' (duration: 157.483µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:10:54.061125Z","caller":"traceutil/trace.go:171","msg":"trace[1065569773] transaction","detail":"{read_only:false; response_revision:4878; number_of_response:1; }","duration":"143.5779ms","start":"2026-04-27T21:10:53.917506Z","end":"2026-04-27T21:10:54.061084Z","steps":["trace[1065569773] 'process raft request' (duration: 143.420157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:10:54.061166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.235619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:10:54.061241Z","caller":"traceutil/trace.go:171","msg":"trace[1114621831] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4878; }","duration":"112.350711ms","start":"2026-04-27T21:10:53.948874Z","end":"2026-04-27T21:10:54.061225Z","steps":["trace[1114621831] 'agreement among raft nodes before linearized reading' (duration: 112.244629ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:10:54.157324Z","caller":"traceutil/trace.go:171","msg":"trace[1957044852] transaction","detail":"{read_only:false; response_revision:4879; number_of_response:1; }","duration":"202.419222ms","start":"2026-04-27T21:10:53.954873Z","end":"2026-04-27T21:10:54.157292Z","steps":["trace[1957044852] 'process raft request' (duration: 188.662622ms)","trace[1957044852] 'compare' (duration: 13.556956ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:10:54.160525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.068084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:10:54.160565Z","caller":"traceutil/trace.go:171","msg":"trace[405581739] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:4880; }","duration":"140.158866ms","start":"2026-04-27T21:10:54.020395Z","end":"2026-04-27T21:10:54.160554Z","steps":["trace[405581739] 'agreement among raft nodes before linearized reading' (duration: 140.064255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:10:54.160923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.584876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-04-27T21:10:54.160978Z","caller":"traceutil/trace.go:171","msg":"trace[1325673881] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4880; }","duration":"120.670289ms","start":"2026-04-27T21:10:54.040294Z","end":"2026-04-27T21:10:54.160964Z","steps":["trace[1325673881] 'agreement among raft nodes before linearized reading' (duration: 120.191969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:10:54.163009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.075971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:23 size:520796"} {"level":"info","ts":"2026-04-27T21:10:54.163175Z","caller":"traceutil/trace.go:171","msg":"trace[2059832383] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:23; response_revision:4880; }","duration":"107.255795ms","start":"2026-04-27T21:10:54.055874Z","end":"2026-04-27T21:10:54.16313Z","steps":["trace[2059832383] 'agreement among raft nodes before linearized reading' (duration: 104.551074ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:10:58.988255Z","caller":"traceutil/trace.go:171","msg":"trace[1497611396] transaction","detail":"{read_only:false; response_revision:5059; number_of_response:1; }","duration":"112.153518ms","start":"2026-04-27T21:10:58.876068Z","end":"2026-04-27T21:10:58.988221Z","steps":["trace[1497611396] 'process raft request' (duration: 111.966174ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:10:59.123268Z","caller":"traceutil/trace.go:171","msg":"trace[325050565] transaction","detail":"{read_only:false; response_revision:5061; number_of_response:1; }","duration":"131.367351ms","start":"2026-04-27T21:10:58.99186Z","end":"2026-04-27T21:10:59.123227Z","steps":["trace[325050565] 'process raft request' (duration: 118.277213ms)","trace[325050565] 'compare' (duration: 12.847813ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:11:09.053581Z","caller":"traceutil/trace.go:171","msg":"trace[1456262032] transaction","detail":"{read_only:false; response_revision:5262; number_of_response:1; }","duration":"102.523416ms","start":"2026-04-27T21:11:08.951038Z","end":"2026-04-27T21:11:09.053562Z","steps":["trace[1456262032] 'process raft request' (duration: 47.447726ms)","trace[1456262032] 'compare' (duration: 54.928138ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:11:18.007853Z","caller":"traceutil/trace.go:171","msg":"trace[1044104099] transaction","detail":"{read_only:false; response_revision:5440; number_of_response:1; }","duration":"105.235916ms","start":"2026-04-27T21:11:17.902599Z","end":"2026-04-27T21:11:18.007835Z","steps":["trace[1044104099] 'process raft request' (duration: 105.123954ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:11:18.191371Z","caller":"traceutil/trace.go:171","msg":"trace[724118342] transaction","detail":"{read_only:false; response_revision:5443; number_of_response:1; }","duration":"140.810909ms","start":"2026-04-27T21:11:18.050534Z","end":"2026-04-27T21:11:18.191345Z","steps":["trace[724118342] 'process raft request' (duration: 110.014658ms)","trace[724118342] 'compare' (duration: 30.644929ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:11:18.321217Z","caller":"traceutil/trace.go:171","msg":"trace[613653728] linearizableReadLoop","detail":"{readStateIndex:5645; appliedIndex:5642; }","duration":"264.142186ms","start":"2026-04-27T21:11:18.057042Z","end":"2026-04-27T21:11:18.321184Z","steps":["trace[613653728] 'read index received' (duration: 103.526734ms)","trace[613653728] 'applied index is now lower than readState.Index' (duration: 160.614592ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:11:18.321311Z","caller":"traceutil/trace.go:171","msg":"trace[622403451] transaction","detail":"{read_only:false; response_revision:5444; number_of_response:1; }","duration":"270.387385ms","start":"2026-04-27T21:11:18.050893Z","end":"2026-04-27T21:11:18.321281Z","steps":["trace[622403451] 'process raft request' (duration: 255.395202ms)","trace[622403451] 'compare' (duration: 14.672367ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:11:18.321373Z","caller":"traceutil/trace.go:171","msg":"trace[1813610343] transaction","detail":"{read_only:false; response_revision:5445; number_of_response:1; }","duration":"268.262265ms","start":"2026-04-27T21:11:18.053099Z","end":"2026-04-27T21:11:18.321361Z","steps":["trace[1813610343] 'process raft request' (duration: 268.013041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:11:18.321688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.130371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-27T21:11:18.321732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.661966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-04-27T21:11:18.321812Z","caller":"traceutil/trace.go:171","msg":"trace[1251062211] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5445; }","duration":"264.755518ms","start":"2026-04-27T21:11:18.057038Z","end":"2026-04-27T21:11:18.321793Z","steps":["trace[1251062211] 'agreement among raft nodes before linearized reading' (duration: 264.520373ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:11:18.321762Z","caller":"traceutil/trace.go:171","msg":"trace[1358218278] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:5445; }","duration":"135.256814ms","start":"2026-04-27T21:11:18.186487Z","end":"2026-04-27T21:11:18.321744Z","steps":["trace[1358218278] 'agreement among raft nodes before linearized reading' (duration: 135.06778ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:11:29.329143Z","caller":"traceutil/trace.go:171","msg":"trace[600160828] transaction","detail":"{read_only:false; response_revision:5595; number_of_response:1; }","duration":"153.558968ms","start":"2026-04-27T21:11:29.17556Z","end":"2026-04-27T21:11:29.329119Z","steps":["trace[600160828] 'process raft request' (duration: 146.584297ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:11:29.329483Z","caller":"traceutil/trace.go:171","msg":"trace[950381540] transaction","detail":"{read_only:false; response_revision:5596; number_of_response:1; }","duration":"152.262006ms","start":"2026-04-27T21:11:29.177205Z","end":"2026-04-27T21:11:29.329467Z","steps":["trace[950381540] 'process raft request' (duration: 151.845457ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:11:29.329839Z","caller":"traceutil/trace.go:171","msg":"trace[2097946464] transaction","detail":"{read_only:false; response_revision:5597; number_of_response:1; }","duration":"149.621866ms","start":"2026-04-27T21:11:29.180192Z","end":"2026-04-27T21:11:29.329814Z","steps":["trace[2097946464] 'process raft request' (duration: 149.151647ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:11:29.329997Z","caller":"traceutil/trace.go:171","msg":"trace[1324836543] transaction","detail":"{read_only:false; response_revision:5598; number_of_response:1; }","duration":"148.596616ms","start":"2026-04-27T21:11:29.181372Z","end":"2026-04-27T21:11:29.329968Z","steps":["trace[1324836543] 'process raft request' (duration: 148.122007ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:12:35.234723Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3037} {"level":"info","ts":"2026-04-27T21:12:35.317681Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3037,"took":"81.28686ms","hash":2999448841,"current-db-size-bytes":58155008,"current-db-size":"58 MB","current-db-size-in-use-bytes":31653888,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-27T21:12:35.31777Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2999448841,"revision":3037,"compact-revision":-1} {"level":"info","ts":"2026-04-27T21:17:35.241048Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6139} {"level":"info","ts":"2026-04-27T21:17:35.351028Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6139,"took":"108.157229ms","hash":134190001,"current-db-size-bytes":58155008,"current-db-size":"58 MB","current-db-size-in-use-bytes":23392256,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-27T21:17:35.351178Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":134190001,"revision":6139,"compact-revision":3037} {"level":"info","ts":"2026-04-27T21:17:36.48063Z","caller":"traceutil/trace.go:171","msg":"trace[384601288] transaction","detail":"{read_only:false; response_revision:8339; number_of_response:1; }","duration":"325.784078ms","start":"2026-04-27T21:17:36.154817Z","end":"2026-04-27T21:17:36.480602Z","steps":["trace[384601288] 'process raft request' (duration: 252.079876ms)","trace[384601288] 'compare' (duration: 73.58264ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:17:36.481019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:17:36.15479Z","time spent":"326.133904ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:17:37.371264Z","caller":"traceutil/trace.go:171","msg":"trace[836724991] linearizableReadLoop","detail":"{readStateIndex:8633; appliedIndex:8632; }","duration":"168.844785ms","start":"2026-04-27T21:17:37.202393Z","end":"2026-04-27T21:17:37.371238Z","steps":["trace[836724991] 'read index received' (duration: 168.750994ms)","trace[836724991] 'applied index is now lower than readState.Index' (duration: 92.511µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:17:37.371388Z","caller":"traceutil/trace.go:171","msg":"trace[1592010540] transaction","detail":"{read_only:false; response_revision:8340; number_of_response:1; }","duration":"175.358478ms","start":"2026-04-27T21:17:37.196002Z","end":"2026-04-27T21:17:37.371361Z","steps":["trace[1592010540] 'process raft request' (duration: 175.020991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:17:37.371794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.364146ms","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-04-27T21:17:37.371923Z","caller":"traceutil/trace.go:171","msg":"trace[1549329085] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8340; }","duration":"169.503658ms","start":"2026-04-27T21:17:37.202375Z","end":"2026-04-27T21:17:37.371878Z","steps":["trace[1549329085] 'agreement among raft nodes before linearized reading' (duration: 169.020809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:17:38.038959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.853195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:17:38.039058Z","caller":"traceutil/trace.go:171","msg":"trace[1509410373] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:8346; }","duration":"105.02969ms","start":"2026-04-27T21:17:37.934007Z","end":"2026-04-27T21:17:38.039037Z","steps":["trace[1509410373] 'count revisions from in-memory index tree' (duration: 104.776764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:17:38.730863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.557681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.23\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-27T21:17:38.730941Z","caller":"traceutil/trace.go:171","msg":"trace[1286149858] range","detail":"{range_begin:/registry/masterleases/199.19.213.23; range_end:; response_count:1; response_revision:8349; }","duration":"103.680155ms","start":"2026-04-27T21:17:38.627241Z","end":"2026-04-27T21:17:38.730921Z","steps":["trace[1286149858] 'range keys from in-memory index tree' (duration: 103.346267ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:18:06.47444Z","caller":"traceutil/trace.go:171","msg":"trace[681456330] linearizableReadLoop","detail":"{readStateIndex:8852; appliedIndex:8851; }","duration":"184.123863ms","start":"2026-04-27T21:18:06.290289Z","end":"2026-04-27T21:18:06.474413Z","steps":["trace[681456330] 'read index received' (duration: 183.965ms)","trace[681456330] 'applied index is now lower than readState.Index' (duration: 158.033µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:18:06.474644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.346267ms","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-04-27T21:18:06.474681Z","caller":"traceutil/trace.go:171","msg":"trace[91823266] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8551; }","duration":"184.439229ms","start":"2026-04-27T21:18:06.290232Z","end":"2026-04-27T21:18:06.474672Z","steps":["trace[91823266] 'agreement among raft nodes before linearized reading' (duration: 184.280016ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:18:06.474945Z","caller":"traceutil/trace.go:171","msg":"trace[941548107] transaction","detail":"{read_only:false; response_revision:8551; number_of_response:1; }","duration":"242.350864ms","start":"2026-04-27T21:18:06.232583Z","end":"2026-04-27T21:18:06.474934Z","steps":["trace[941548107] 'process raft request' (duration: 241.723242ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:18:06.63147Z","caller":"traceutil/trace.go:171","msg":"trace[1378200206] linearizableReadLoop","detail":"{readStateIndex:8853; appliedIndex:8852; }","duration":"119.131153ms","start":"2026-04-27T21:18:06.512321Z","end":"2026-04-27T21:18:06.631452Z","steps":["trace[1378200206] 'read index received' (duration: 47.542222ms)","trace[1378200206] 'applied index is now lower than readState.Index' (duration: 71.588061ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:18:06.631645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.304496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T21:18:06.631685Z","caller":"traceutil/trace.go:171","msg":"trace[537284549] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:8552; }","duration":"119.380748ms","start":"2026-04-27T21:18:06.512297Z","end":"2026-04-27T21:18:06.631678Z","steps":["trace[537284549] 'agreement among raft nodes before linearized reading' (duration: 119.229655ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:18:06.631889Z","caller":"traceutil/trace.go:171","msg":"trace[408102743] transaction","detail":"{read_only:false; response_revision:8552; number_of_response:1; }","duration":"153.459786ms","start":"2026-04-27T21:18:06.47842Z","end":"2026-04-27T21:18:06.63188Z","steps":["trace[408102743] 'process raft request' (duration: 81.505848ms)","trace[408102743] 'compare' (duration: 71.441619ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:18:19.148083Z","caller":"traceutil/trace.go:171","msg":"trace[1956415380] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8658; }","duration":"227.799851ms","start":"2026-04-27T21:18:18.920265Z","end":"2026-04-27T21:18:19.148065Z","steps":["trace[1956415380] 'process raft request' (duration: 227.671229ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:18:19.285934Z","caller":"traceutil/trace.go:171","msg":"trace[1514319082] transaction","detail":"{read_only:false; response_revision:8659; number_of_response:1; }","duration":"362.720661ms","start":"2026-04-27T21:18:18.92318Z","end":"2026-04-27T21:18:19.285901Z","steps":["trace[1514319082] 'process raft request' (duration: 362.518617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:18:19.286112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:18:18.923165Z","time spent":"362.848253ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4637,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:18:19.772667Z","caller":"traceutil/trace.go:171","msg":"trace[235073383] transaction","detail":"{read_only:false; response_revision:8662; number_of_response:1; }","duration":"188.753378ms","start":"2026-04-27T21:18:19.583886Z","end":"2026-04-27T21:18:19.772639Z","steps":["trace[235073383] 'process raft request' (duration: 188.594635ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:18:19.777616Z","caller":"traceutil/trace.go:171","msg":"trace[533616137] transaction","detail":"{read_only:false; response_revision:8663; number_of_response:1; }","duration":"193.182412ms","start":"2026-04-27T21:18:19.584405Z","end":"2026-04-27T21:18:19.777587Z","steps":["trace[533616137] 'process raft request' (duration: 192.855756ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:18:19.777561Z","caller":"traceutil/trace.go:171","msg":"trace[840562961] linearizableReadLoop","detail":"{readStateIndex:8971; appliedIndex:8969; }","duration":"167.794756ms","start":"2026-04-27T21:18:19.609732Z","end":"2026-04-27T21:18:19.777527Z","steps":["trace[840562961] 'read index received' (duration: 162.850623ms)","trace[840562961] 'applied index is now lower than readState.Index' (duration: 4.942763ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:18:19.777935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.169903ms","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-04-27T21:18:19.778008Z","caller":"traceutil/trace.go:171","msg":"trace[953549709] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8664; }","duration":"168.331786ms","start":"2026-04-27T21:18:19.609663Z","end":"2026-04-27T21:18:19.777995Z","steps":["trace[953549709] 'agreement among raft nodes before linearized reading' (duration: 168.00179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:18:19.778114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.553876ms","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-04-27T21:18:19.778154Z","caller":"traceutil/trace.go:171","msg":"trace[48413602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8664; }","duration":"163.705969ms","start":"2026-04-27T21:18:19.614438Z","end":"2026-04-27T21:18:19.778144Z","steps":["trace[48413602] 'agreement among raft nodes before linearized reading' (duration: 163.491925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:18:19.778269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.232405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:18:19.778295Z","caller":"traceutil/trace.go:171","msg":"trace[1444199791] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8664; }","duration":"128.289525ms","start":"2026-04-27T21:18:19.649997Z","end":"2026-04-27T21:18:19.778286Z","steps":["trace[1444199791] 'agreement among raft nodes before linearized reading' (duration: 128.247655ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:18:20.054194Z","caller":"traceutil/trace.go:171","msg":"trace[1483246252] linearizableReadLoop","detail":"{readStateIndex:8974; appliedIndex:8973; }","duration":"191.205186ms","start":"2026-04-27T21:18:19.862966Z","end":"2026-04-27T21:18:20.054171Z","steps":["trace[1483246252] 'read index received' (duration: 186.152161ms)","trace[1483246252] 'applied index is now lower than readState.Index' (duration: 5.051895ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:18:20.054261Z","caller":"traceutil/trace.go:171","msg":"trace[1263286133] transaction","detail":"{read_only:false; response_revision:8666; number_of_response:1; }","duration":"257.808594ms","start":"2026-04-27T21:18:19.796426Z","end":"2026-04-27T21:18:20.054235Z","steps":["trace[1263286133] 'process raft request' (duration: 252.701148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:18:20.054392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.422139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5146"} {"level":"info","ts":"2026-04-27T21:18:20.054421Z","caller":"traceutil/trace.go:171","msg":"trace[820298255] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8666; }","duration":"191.513731ms","start":"2026-04-27T21:18:19.862901Z","end":"2026-04-27T21:18:20.054414Z","steps":["trace[820298255] 'agreement among raft nodes before linearized reading' (duration: 191.387509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:18:20.073703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.247976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:18:20.073785Z","caller":"traceutil/trace.go:171","msg":"trace[331639993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8667; }","duration":"127.386209ms","start":"2026-04-27T21:18:19.94638Z","end":"2026-04-27T21:18:20.073766Z","steps":["trace[331639993] 'agreement among raft nodes before linearized reading' (duration: 127.250166ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:18:20.721368Z","caller":"traceutil/trace.go:171","msg":"trace[229644646] transaction","detail":"{read_only:false; response_revision:8672; number_of_response:1; }","duration":"166.013172ms","start":"2026-04-27T21:18:20.55532Z","end":"2026-04-27T21:18:20.721334Z","steps":["trace[229644646] 'process raft request' (duration: 154.11626ms)","trace[229644646] 'compare' (duration: 11.446684ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:18:20.779163Z","caller":"traceutil/trace.go:171","msg":"trace[914846100] transaction","detail":"{read_only:false; response_revision:8673; number_of_response:1; }","duration":"223.736755ms","start":"2026-04-27T21:18:20.555393Z","end":"2026-04-27T21:18:20.77913Z","steps":["trace[914846100] 'process raft request' (duration: 223.48237ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:19:03.015943Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024a2.wal"} {"level":"info","ts":"2026-04-27T21:20:10.731846Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1812db8446d3ac67","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-27T21:20:10.739108Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-27T21:20:10.739223Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-27T21:22:25.067363Z","caller":"traceutil/trace.go:171","msg":"trace[676811735] transaction","detail":"{read_only:false; response_revision:10619; number_of_response:1; }","duration":"106.306521ms","start":"2026-04-27T21:22:24.961032Z","end":"2026-04-27T21:22:25.067338Z","steps":["trace[676811735] 'process raft request' (duration: 106.152649ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:22:25.101228Z","caller":"traceutil/trace.go:171","msg":"trace[319482385] transaction","detail":"{read_only:false; response_revision:10620; number_of_response:1; }","duration":"100.965882ms","start":"2026-04-27T21:22:25.000224Z","end":"2026-04-27T21:22:25.10119Z","steps":["trace[319482385] 'process raft request' (duration: 100.752398ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:22:25.19013Z","caller":"traceutil/trace.go:171","msg":"trace[1058177545] transaction","detail":"{read_only:false; response_revision:10621; number_of_response:1; }","duration":"114.290321ms","start":"2026-04-27T21:22:25.075798Z","end":"2026-04-27T21:22:25.190088Z","steps":["trace[1058177545] 'process raft request' (duration: 79.481129ms)","trace[1058177545] 'compare' (duration: 34.601589ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:22:25.190252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.046313ms","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-04-27T21:22:25.190325Z","caller":"traceutil/trace.go:171","msg":"trace[426186394] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10621; }","duration":"101.154635ms","start":"2026-04-27T21:22:25.089148Z","end":"2026-04-27T21:22:25.190302Z","steps":["trace[426186394] 'agreement among raft nodes before linearized reading' (duration: 100.948971ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:22:35.247694Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8331} {"level":"info","ts":"2026-04-27T21:22:35.336326Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8331,"took":"86.45276ms","hash":4290897354,"current-db-size-bytes":58155008,"current-db-size":"58 MB","current-db-size-in-use-bytes":24985600,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-27T21:22:35.336388Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4290897354,"revision":8331,"compact-revision":6139} {"level":"info","ts":"2026-04-27T21:23:50.238219Z","caller":"traceutil/trace.go:171","msg":"trace[160640501] transaction","detail":"{read_only:false; response_revision:11412; number_of_response:1; }","duration":"265.499544ms","start":"2026-04-27T21:23:49.972694Z","end":"2026-04-27T21:23:50.238193Z","steps":["trace[160640501] 'process raft request' (duration: 265.340161ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:27:35.254127Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10706} {"level":"info","ts":"2026-04-27T21:27:35.325645Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10706,"took":"69.551882ms","hash":1569133048,"current-db-size-bytes":58155008,"current-db-size":"58 MB","current-db-size-in-use-bytes":26583040,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-27T21:27:35.325708Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1569133048,"revision":10706,"compact-revision":8331} {"level":"info","ts":"2026-04-27T21:29:42.441814Z","caller":"traceutil/trace.go:171","msg":"trace[1469750655] transaction","detail":"{read_only:false; response_revision:13813; number_of_response:1; }","duration":"126.642321ms","start":"2026-04-27T21:29:42.315144Z","end":"2026-04-27T21:29:42.441787Z","steps":["trace[1469750655] 'process raft request' (duration: 126.422967ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:29:42.443626Z","caller":"traceutil/trace.go:171","msg":"trace[119555338] transaction","detail":"{read_only:false; response_revision:13814; number_of_response:1; }","duration":"108.189086ms","start":"2026-04-27T21:29:42.33542Z","end":"2026-04-27T21:29:42.443609Z","steps":["trace[119555338] 'process raft request' (duration: 108.044383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:30:13.957535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.676513ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-04-27T21:30:21.845176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.493446ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T21:30:21.845433Z","caller":"traceutil/trace.go:171","msg":"trace[1357738936] linearizableReadLoop","detail":"{readStateIndex:14516; appliedIndex:14513; }","duration":"237.85326ms","start":"2026-04-27T21:30:21.607559Z","end":"2026-04-27T21:30:21.845412Z","steps":["trace[1357738936] 'read index received' (duration: 49.015348ms)","trace[1357738936] 'applied index is now lower than readState.Index' (duration: 188.836822ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:30:21.845551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.989283ms","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:5"} {"level":"info","ts":"2026-04-27T21:30:21.84559Z","caller":"traceutil/trace.go:171","msg":"trace[1342448524] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:14036; }","duration":"238.060224ms","start":"2026-04-27T21:30:21.607519Z","end":"2026-04-27T21:30:21.845579Z","steps":["trace[1342448524] 'agreement among raft nodes before linearized reading' (duration: 237.960652ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:30:21.845618Z","caller":"traceutil/trace.go:171","msg":"trace[539712789] transaction","detail":"{read_only:false; response_revision:14036; number_of_response:1; }","duration":"238.152016ms","start":"2026-04-27T21:30:21.607454Z","end":"2026-04-27T21:30:21.845606Z","steps":["trace[539712789] 'process raft request' (duration: 237.913261ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:30:21.845758Z","caller":"traceutil/trace.go:171","msg":"trace[1091752660] transaction","detail":"{read_only:false; response_revision:14035; number_of_response:1; }","duration":"239.47329ms","start":"2026-04-27T21:30:21.606267Z","end":"2026-04-27T21:30:21.84574Z","steps":["trace[1091752660] 'process raft request' (duration: 239.041852ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:30:21.845433Z","caller":"traceutil/trace.go:171","msg":"trace[1567909784] transaction","detail":"{read_only:false; response_revision:14034; number_of_response:1; }","duration":"239.691304ms","start":"2026-04-27T21:30:21.605713Z","end":"2026-04-27T21:30:21.845405Z","steps":["trace[1567909784] 'process raft request' (duration: 50.866912ms)","trace[1567909784] 'compare' (duration: 188.351193ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:30:43.14802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.021871ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:30:43.148135Z","caller":"traceutil/trace.go:171","msg":"trace[195131228] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:14337; }","duration":"201.146983ms","start":"2026-04-27T21:30:42.946973Z","end":"2026-04-27T21:30:43.14812Z","steps":["trace[195131228] 'range keys from in-memory index tree' (duration: 201.009501ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:30:43.148628Z","caller":"traceutil/trace.go:171","msg":"trace[564579450] transaction","detail":"{read_only:false; response_revision:14338; number_of_response:1; }","duration":"473.467159ms","start":"2026-04-27T21:30:42.675122Z","end":"2026-04-27T21:30:43.148589Z","steps":["trace[564579450] 'process raft request' (duration: 465.928198ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:30:43.148681Z","caller":"traceutil/trace.go:171","msg":"trace[1832307104] linearizableReadLoop","detail":"{readStateIndex:14832; appliedIndex:14830; }","duration":"434.99891ms","start":"2026-04-27T21:30:42.71367Z","end":"2026-04-27T21:30:43.148669Z","steps":["trace[1832307104] 'read index received' (duration: 427.58831ms)","trace[1832307104] 'applied index is now lower than readState.Index' (duration: 7.41ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:30:43.148743Z","caller":"traceutil/trace.go:171","msg":"trace[144981308] transaction","detail":"{read_only:false; response_revision:14339; number_of_response:1; }","duration":"457.44695ms","start":"2026-04-27T21:30:42.691289Z","end":"2026-04-27T21:30:43.148736Z","steps":["trace[144981308] 'process raft request' (duration: 456.980571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:30:43.148791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:30:42.675096Z","time spent":"473.608482ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:30:43.148813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:30:42.691252Z","time spent":"457.50769ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3738,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:30:43.149081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.352926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e04f7f4b-a2fb-4cbc-982b-59d2380ba8fb\" ","response":"range_response_count:1 size:3753"} {"level":"info","ts":"2026-04-27T21:30:43.149175Z","caller":"traceutil/trace.go:171","msg":"trace[1176373647] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e04f7f4b-a2fb-4cbc-982b-59d2380ba8fb; range_end:; response_count:1; response_revision:14339; }","duration":"435.541539ms","start":"2026-04-27T21:30:42.713613Z","end":"2026-04-27T21:30:43.149155Z","steps":["trace[1176373647] 'agreement among raft nodes before linearized reading' (duration: 435.190113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:30:43.150044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:30:42.713596Z","time spent":"436.420925ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3775,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e04f7f4b-a2fb-4cbc-982b-59d2380ba8fb\" "} {"level":"info","ts":"2026-04-27T21:30:43.217919Z","caller":"traceutil/trace.go:171","msg":"trace[811132991] transaction","detail":"{read_only:false; response_revision:14340; number_of_response:1; }","duration":"388.951058ms","start":"2026-04-27T21:30:42.828938Z","end":"2026-04-27T21:30:43.217889Z","steps":["trace[811132991] 'process raft request' (duration: 388.662312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:30:43.218127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.741807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:30:43.21817Z","caller":"traceutil/trace.go:171","msg":"trace[374079970] transaction","detail":"{read_only:false; response_revision:14341; number_of_response:1; }","duration":"355.189526ms","start":"2026-04-27T21:30:42.862969Z","end":"2026-04-27T21:30:43.218159Z","steps":["trace[374079970] 'process raft request' (duration: 354.778099ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:30:43.218178Z","caller":"traceutil/trace.go:171","msg":"trace[1018486481] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14342; }","duration":"270.822998ms","start":"2026-04-27T21:30:42.947339Z","end":"2026-04-27T21:30:43.218162Z","steps":["trace[1018486481] 'agreement among raft nodes before linearized reading' (duration: 270.763397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:30:43.218276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:30:42.86293Z","time spent":"355.277448ms","remote":"127.0.0.1:43082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18798,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:30:43.218304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:30:42.828907Z","time spent":"389.224004ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:30:43.217937Z","caller":"traceutil/trace.go:171","msg":"trace[1168941510] transaction","detail":"{read_only:false; response_revision:14342; number_of_response:1; }","duration":"301.069994ms","start":"2026-04-27T21:30:42.916845Z","end":"2026-04-27T21:30:43.217915Z","steps":["trace[1168941510] 'process raft request' (duration: 300.965652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:30:43.218592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:30:42.916813Z","time spent":"301.738806ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:30:43.21806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.735402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:30:43.218828Z","caller":"traceutil/trace.go:171","msg":"trace[1983863050] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:14342; }","duration":"468.424125ms","start":"2026-04-27T21:30:42.750243Z","end":"2026-04-27T21:30:43.218668Z","steps":["trace[1983863050] 'agreement among raft nodes before linearized reading' (duration: 467.687701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:30:43.219309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:30:42.75022Z","time spent":"468.818502ms","remote":"127.0.0.1:43000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-04-27T21:31:44.145622Z","caller":"traceutil/trace.go:171","msg":"trace[410397547] transaction","detail":"{read_only:false; response_revision:15022; number_of_response:1; }","duration":"175.731708ms","start":"2026-04-27T21:31:43.96965Z","end":"2026-04-27T21:31:44.145382Z","steps":["trace[410397547] 'process raft request' (duration: 175.539195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:31:44.54143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.902574ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T21:31:44.541505Z","caller":"traceutil/trace.go:171","msg":"trace[2095585906] linearizableReadLoop","detail":"{readStateIndex:15535; appliedIndex:15534; }","duration":"286.018692ms","start":"2026-04-27T21:31:44.255473Z","end":"2026-04-27T21:31:44.541492Z","steps":["trace[2095585906] 'read index received' (duration: 41.920005ms)","trace[2095585906] 'applied index is now lower than readState.Index' (duration: 244.097907ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:31:44.541722Z","caller":"traceutil/trace.go:171","msg":"trace[586012734] transaction","detail":"{read_only:false; response_revision:15023; number_of_response:1; }","duration":"434.419739ms","start":"2026-04-27T21:31:44.107292Z","end":"2026-04-27T21:31:44.541712Z","steps":["trace[586012734] 'process raft request' (duration: 190.174919ms)","trace[586012734] 'compare' (duration: 243.619339ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:31:44.541772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:31:44.107269Z","time spent":"434.47898ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:31:44.541998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.536411ms","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-04-27T21:31:44.542024Z","caller":"traceutil/trace.go:171","msg":"trace[1321488871] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15023; }","duration":"286.605703ms","start":"2026-04-27T21:31:44.255412Z","end":"2026-04-27T21:31:44.542017Z","steps":["trace[1321488871] 'agreement among raft nodes before linearized reading' (duration: 286.516961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:31:44.542334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.027984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:31:44.542374Z","caller":"traceutil/trace.go:171","msg":"trace[1955026849] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15023; }","duration":"200.078804ms","start":"2026-04-27T21:31:44.342283Z","end":"2026-04-27T21:31:44.542361Z","steps":["trace[1955026849] 'agreement among raft nodes before linearized reading' (duration: 200.009763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:31:44.542389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.553071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T21:31:44.542452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.10314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-27T21:31:44.542458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.649642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T21:31:44.542488Z","caller":"traceutil/trace.go:171","msg":"trace[2598755] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15023; }","duration":"201.688894ms","start":"2026-04-27T21:31:44.340782Z","end":"2026-04-27T21:31:44.54247Z","steps":["trace[2598755] 'agreement among raft nodes before linearized reading' (duration: 201.527631ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:31:44.542514Z","caller":"traceutil/trace.go:171","msg":"trace[1777272692] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:15023; }","duration":"210.757825ms","start":"2026-04-27T21:31:44.331746Z","end":"2026-04-27T21:31:44.542503Z","steps":["trace[1777272692] 'agreement among raft nodes before linearized reading' (duration: 210.636313ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:31:44.542571Z","caller":"traceutil/trace.go:171","msg":"trace[2106845638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15023; }","duration":"282.13723ms","start":"2026-04-27T21:31:44.260339Z","end":"2026-04-27T21:31:44.542476Z","steps":["trace[2106845638] 'agreement among raft nodes before linearized reading' (duration: 281.932727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:31:44.54256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.457379ms","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-04-27T21:31:44.542648Z","caller":"traceutil/trace.go:171","msg":"trace[1846031939] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15023; }","duration":"279.560542ms","start":"2026-04-27T21:31:44.263055Z","end":"2026-04-27T21:31:44.542615Z","steps":["trace[1846031939] 'agreement among raft nodes before linearized reading' (duration: 279.400419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:31:44.542725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.455552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-04-27T21:31:44.542757Z","caller":"traceutil/trace.go:171","msg":"trace[107019123] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15023; }","duration":"200.486642ms","start":"2026-04-27T21:31:44.342262Z","end":"2026-04-27T21:31:44.542749Z","steps":["trace[107019123] 'agreement among raft nodes before linearized reading' (duration: 200.404601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:31:44.542785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.93517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-04-27T21:31:44.542807Z","caller":"traceutil/trace.go:171","msg":"trace[1689930703] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15023; }","duration":"200.977001ms","start":"2026-04-27T21:31:44.341825Z","end":"2026-04-27T21:31:44.542802Z","steps":["trace[1689930703] 'agreement among raft nodes before linearized reading' (duration: 200.89024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:31:44.542888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.868721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-04-27T21:31:44.542918Z","caller":"traceutil/trace.go:171","msg":"trace[545774231] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15023; }","duration":"199.918041ms","start":"2026-04-27T21:31:44.342993Z","end":"2026-04-27T21:31:44.542911Z","steps":["trace[545774231] 'agreement among raft nodes before linearized reading' (duration: 199.755188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:31:44.543088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.105155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-04-27T21:31:44.543121Z","caller":"traceutil/trace.go:171","msg":"trace[680461003] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15023; }","duration":"199.155627ms","start":"2026-04-27T21:31:44.343958Z","end":"2026-04-27T21:31:44.543114Z","steps":["trace[680461003] 'agreement among raft nodes before linearized reading' (duration: 199.078626ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:31:44.700578Z","caller":"traceutil/trace.go:171","msg":"trace[2016553798] transaction","detail":"{read_only:false; response_revision:15024; number_of_response:1; }","duration":"152.32521ms","start":"2026-04-27T21:31:44.548221Z","end":"2026-04-27T21:31:44.700547Z","steps":["trace[2016553798] 'process raft request' (duration: 103.580548ms)","trace[2016553798] 'compare' (duration: 48.559638ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:31:44.70058Z","caller":"traceutil/trace.go:171","msg":"trace[1561996645] linearizableReadLoop","detail":"{readStateIndex:15538; appliedIndex:15535; }","duration":"140.594201ms","start":"2026-04-27T21:31:44.559969Z","end":"2026-04-27T21:31:44.700564Z","steps":["trace[1561996645] 'read index received' (duration: 91.840939ms)","trace[1561996645] 'applied index is now lower than readState.Index' (duration: 48.752512ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:31:44.701352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.359515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737563"} {"level":"info","ts":"2026-04-27T21:31:44.7014Z","caller":"traceutil/trace.go:171","msg":"trace[1097809769] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:15026; }","duration":"141.454786ms","start":"2026-04-27T21:31:44.559935Z","end":"2026-04-27T21:31:44.70139Z","steps":["trace[1097809769] 'agreement among raft nodes before linearized reading' (duration: 140.699922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:31:44.70148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.448638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737563"} {"level":"info","ts":"2026-04-27T21:31:44.701531Z","caller":"traceutil/trace.go:171","msg":"trace[1474964769] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:15026; }","duration":"140.51621ms","start":"2026-04-27T21:31:44.561004Z","end":"2026-04-27T21:31:44.70152Z","steps":["trace[1474964769] 'agreement among raft nodes before linearized reading' (duration: 139.940229ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:31:44.701753Z","caller":"traceutil/trace.go:171","msg":"trace[1095014676] transaction","detail":"{read_only:false; response_revision:15025; number_of_response:1; }","duration":"149.058359ms","start":"2026-04-27T21:31:44.552684Z","end":"2026-04-27T21:31:44.701743Z","steps":["trace[1095014676] 'process raft request' (duration: 147.803126ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:31:44.701887Z","caller":"traceutil/trace.go:171","msg":"trace[39530424] transaction","detail":"{read_only:false; response_revision:15026; number_of_response:1; }","duration":"149.13116ms","start":"2026-04-27T21:31:44.552746Z","end":"2026-04-27T21:31:44.701878Z","steps":["trace[39530424] 'process raft request' (duration: 147.781225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:31:44.702363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.410951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-gr9dp\" ","response":"range_response_count:1 size:20766"} {"level":"info","ts":"2026-04-27T21:31:44.702408Z","caller":"traceutil/trace.go:171","msg":"trace[608394905] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-gr9dp; range_end:; response_count:1; response_revision:15026; }","duration":"122.484272ms","start":"2026-04-27T21:31:44.579914Z","end":"2026-04-27T21:31:44.702398Z","steps":["trace[608394905] 'agreement among raft nodes before linearized reading' (duration: 122.346119ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:32:35.260824Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12873} {"level":"info","ts":"2026-04-27T21:32:35.338341Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12873,"took":"74.922692ms","hash":1767171277,"current-db-size-bytes":58155008,"current-db-size":"58 MB","current-db-size-in-use-bytes":29847552,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-27T21:32:35.338416Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1767171277,"revision":12873,"compact-revision":10706} {"level":"info","ts":"2026-04-27T21:37:35.267785Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15290} {"level":"info","ts":"2026-04-27T21:37:35.363962Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15290,"took":"92.96579ms","hash":3934055491,"current-db-size-bytes":58155008,"current-db-size":"58 MB","current-db-size-in-use-bytes":31694848,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-27T21:37:35.364032Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3934055491,"revision":15290,"compact-revision":12873} {"level":"info","ts":"2026-04-27T21:38:03.11669Z","caller":"traceutil/trace.go:171","msg":"trace[453214275] transaction","detail":"{read_only:false; response_revision:17772; number_of_response:1; }","duration":"119.805682ms","start":"2026-04-27T21:38:02.996819Z","end":"2026-04-27T21:38:03.116625Z","steps":["trace[453214275] 'process raft request' (duration: 119.634129ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:38:03.643521Z","caller":"traceutil/trace.go:171","msg":"trace[362019323] transaction","detail":"{read_only:false; response_revision:17773; number_of_response:1; }","duration":"176.650936ms","start":"2026-04-27T21:38:03.466843Z","end":"2026-04-27T21:38:03.643494Z","steps":["trace[362019323] 'process raft request' (duration: 176.501563ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:38:04.195398Z","caller":"traceutil/trace.go:171","msg":"trace[1316798085] linearizableReadLoop","detail":"{readStateIndex:18379; appliedIndex:18378; }","duration":"176.936191ms","start":"2026-04-27T21:38:04.018406Z","end":"2026-04-27T21:38:04.195342Z","steps":["trace[1316798085] 'read index received' (duration: 124.321816ms)","trace[1316798085] 'applied index is now lower than readState.Index' (duration: 52.612205ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:38:04.19557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.125674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:38:04.195723Z","caller":"traceutil/trace.go:171","msg":"trace[692402709] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17773; }","duration":"177.323437ms","start":"2026-04-27T21:38:04.01837Z","end":"2026-04-27T21:38:04.195693Z","steps":["trace[692402709] 'agreement among raft nodes before linearized reading' (duration: 177.085273ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:41:09.665485Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1812db8446d3ac67","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-27T21:41:09.670895Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-27T21:41:09.671192Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-27T21:42:35.274873Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17586} {"level":"info","ts":"2026-04-27T21:42:35.371994Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17586,"took":"93.708241ms","hash":3872902108,"current-db-size-bytes":58155008,"current-db-size":"58 MB","current-db-size-in-use-bytes":34471936,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-27T21:42:35.37208Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3872902108,"revision":17586,"compact-revision":15290} {"level":"warn","ts":"2026-04-27T21:43:47.240557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.50802ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:43:47.240935Z","caller":"traceutil/trace.go:171","msg":"trace[1240264840] transaction","detail":"{read_only:false; response_revision:20439; number_of_response:1; }","duration":"319.078723ms","start":"2026-04-27T21:43:46.92183Z","end":"2026-04-27T21:43:47.240909Z","steps":["trace[1240264840] 'process raft request' (duration: 46.129265ms)","trace[1240264840] 'compare' (duration: 272.132333ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:43:47.241006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:43:46.92181Z","time spent":"319.158985ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:47:35.281816Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20040} {"level":"info","ts":"2026-04-27T21:47:35.405954Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20040,"took":"119.153947ms","hash":3061536433,"current-db-size-bytes":58155008,"current-db-size":"58 MB","current-db-size-in-use-bytes":39473152,"current-db-size-in-use":"40 MB"} {"level":"info","ts":"2026-04-27T21:47:35.406038Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3061536433,"revision":20040,"compact-revision":17586} {"level":"info","ts":"2026-04-27T21:49:08.246127Z","caller":"traceutil/trace.go:171","msg":"trace[1882985049] transaction","detail":"{read_only:false; response_revision:23819; number_of_response:1; }","duration":"112.486128ms","start":"2026-04-27T21:49:08.133615Z","end":"2026-04-27T21:49:08.246102Z","steps":["trace[1882985049] 'process raft request' (duration: 80.987222ms)","trace[1882985049] 'compare' (duration: 31.263502ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:51:10.078479Z","caller":"traceutil/trace.go:171","msg":"trace[1339210428] linearizableReadLoop","detail":"{readStateIndex:25827; appliedIndex:25826; }","duration":"140.932151ms","start":"2026-04-27T21:51:09.937526Z","end":"2026-04-27T21:51:10.078458Z","steps":["trace[1339210428] 'read index received' (duration: 140.819689ms)","trace[1339210428] 'applied index is now lower than readState.Index' (duration: 111.662µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:51:10.078592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.040953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:51:10.078618Z","caller":"traceutil/trace.go:171","msg":"trace[1715306676] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:25008; }","duration":"141.087403ms","start":"2026-04-27T21:51:09.93752Z","end":"2026-04-27T21:51:10.078607Z","steps":["trace[1715306676] 'agreement among raft nodes before linearized reading' (duration: 141.017612ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:10.078563Z","caller":"traceutil/trace.go:171","msg":"trace[931721006] transaction","detail":"{read_only:false; response_revision:25008; number_of_response:1; }","duration":"163.991698ms","start":"2026-04-27T21:51:09.914533Z","end":"2026-04-27T21:51:10.078525Z","steps":["trace[931721006] 'process raft request' (duration: 163.788895ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:10.234298Z","caller":"traceutil/trace.go:171","msg":"trace[280370004] transaction","detail":"{read_only:false; response_revision:25009; number_of_response:1; }","duration":"272.349503ms","start":"2026-04-27T21:51:09.961922Z","end":"2026-04-27T21:51:10.234271Z","steps":["trace[280370004] 'process raft request' (duration: 272.200861ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:10.414368Z","caller":"traceutil/trace.go:171","msg":"trace[2065395096] transaction","detail":"{read_only:false; response_revision:25010; number_of_response:1; }","duration":"280.168232ms","start":"2026-04-27T21:51:10.13417Z","end":"2026-04-27T21:51:10.414338Z","steps":["trace[2065395096] 'process raft request' (duration: 246.600368ms)","trace[2065395096] 'compare' (duration: 33.208717ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:51:10.415447Z","caller":"traceutil/trace.go:171","msg":"trace[1491167730] transaction","detail":"{read_only:false; response_revision:25011; number_of_response:1; }","duration":"178.136708ms","start":"2026-04-27T21:51:10.236288Z","end":"2026-04-27T21:51:10.414425Z","steps":["trace[1491167730] 'process raft request' (duration: 177.958305ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:15.047426Z","caller":"traceutil/trace.go:171","msg":"trace[1161239434] linearizableReadLoop","detail":"{readStateIndex:25866; appliedIndex:25865; }","duration":"117.154941ms","start":"2026-04-27T21:51:14.930244Z","end":"2026-04-27T21:51:15.047399Z","steps":["trace[1161239434] 'read index received' (duration: 116.917057ms)","trace[1161239434] 'applied index is now lower than readState.Index' (duration: 236.724µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:51:15.047764Z","caller":"traceutil/trace.go:171","msg":"trace[102830690] transaction","detail":"{read_only:false; response_revision:25046; number_of_response:1; }","duration":"135.83216ms","start":"2026-04-27T21:51:14.911919Z","end":"2026-04-27T21:51:15.047751Z","steps":["trace[102830690] 'process raft request' (duration: 135.315471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:15.048014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.752981ms","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-04-27T21:51:15.048042Z","caller":"traceutil/trace.go:171","msg":"trace[1761898658] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25046; }","duration":"117.835583ms","start":"2026-04-27T21:51:14.930198Z","end":"2026-04-27T21:51:15.048034Z","steps":["trace[1761898658] 'agreement among raft nodes before linearized reading' (duration: 117.716911ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:15.180311Z","caller":"traceutil/trace.go:171","msg":"trace[161830638] linearizableReadLoop","detail":"{readStateIndex:25868; appliedIndex:25866; }","duration":"132.788838ms","start":"2026-04-27T21:51:15.047496Z","end":"2026-04-27T21:51:15.180285Z","steps":["trace[161830638] 'read index received' (duration: 132.448811ms)","trace[161830638] 'applied index is now lower than readState.Index' (duration: 336.197µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:51:15.180361Z","caller":"traceutil/trace.go:171","msg":"trace[1944554089] transaction","detail":"{read_only:false; response_revision:25047; number_of_response:1; }","duration":"213.442394ms","start":"2026-04-27T21:51:14.966881Z","end":"2026-04-27T21:51:15.180323Z","steps":["trace[1944554089] 'process raft request' (duration: 213.136017ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:15.180448Z","caller":"traceutil/trace.go:171","msg":"trace[1877380365] transaction","detail":"{read_only:false; response_revision:25048; number_of_response:1; }","duration":"183.526624ms","start":"2026-04-27T21:51:14.996888Z","end":"2026-04-27T21:51:15.180415Z","steps":["trace[1877380365] 'process raft request' (duration: 183.32839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:15.180547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.986874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-04-27T21:51:15.18056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.616577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-27T21:51:15.18059Z","caller":"traceutil/trace.go:171","msg":"trace[1441606880] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25048; }","duration":"209.065246ms","start":"2026-04-27T21:51:14.971508Z","end":"2026-04-27T21:51:15.180573Z","steps":["trace[1441606880] 'agreement among raft nodes before linearized reading' (duration: 208.906663ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:15.180626Z","caller":"traceutil/trace.go:171","msg":"trace[1111585347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25048; }","duration":"174.721819ms","start":"2026-04-27T21:51:15.005889Z","end":"2026-04-27T21:51:15.18061Z","steps":["trace[1111585347] 'agreement among raft nodes before linearized reading' (duration: 174.507985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:15.447466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.127417ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:51:15.447622Z","caller":"traceutil/trace.go:171","msg":"trace[1396452275] transaction","detail":"{read_only:false; response_revision:25049; number_of_response:1; }","duration":"389.634147ms","start":"2026-04-27T21:51:15.057946Z","end":"2026-04-27T21:51:15.44758Z","steps":["trace[1396452275] 'process raft request' (duration: 215.305586ms)","trace[1396452275] 'compare' (duration: 174.002765ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:51:15.447722Z","caller":"traceutil/trace.go:171","msg":"trace[1738364499] transaction","detail":"{read_only:false; response_revision:25051; number_of_response:1; }","duration":"262.479769ms","start":"2026-04-27T21:51:15.185225Z","end":"2026-04-27T21:51:15.447705Z","steps":["trace[1738364499] 'process raft request' (duration: 262.393388ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:15.447774Z","caller":"traceutil/trace.go:171","msg":"trace[1452416258] transaction","detail":"{read_only:false; response_revision:25050; number_of_response:1; }","duration":"262.643272ms","start":"2026-04-27T21:51:15.185102Z","end":"2026-04-27T21:51:15.447745Z","steps":["trace[1452416258] 'process raft request' (duration: 262.461889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:15.44782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:15.057915Z","time spent":"389.80944ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:51:15.447913Z","caller":"traceutil/trace.go:171","msg":"trace[296056901] linearizableReadLoop","detail":"{readStateIndex:25869; appliedIndex:25868; }","duration":"267.510268ms","start":"2026-04-27T21:51:15.180389Z","end":"2026-04-27T21:51:15.447899Z","steps":["trace[296056901] 'read index received' (duration: 92.881761ms)","trace[296056901] 'applied index is now lower than readState.Index' (duration: 174.621946ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:51:15.448078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.662696ms","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-04-27T21:51:15.44813Z","caller":"traceutil/trace.go:171","msg":"trace[272611595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25051; }","duration":"398.716017ms","start":"2026-04-27T21:51:15.049399Z","end":"2026-04-27T21:51:15.448115Z","steps":["trace[272611595] 'agreement among raft nodes before linearized reading' (duration: 398.554114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:15.448179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:15.049368Z","time spent":"398.798308ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T21:51:15.870878Z","caller":"traceutil/trace.go:171","msg":"trace[1927714446] linearizableReadLoop","detail":"{readStateIndex:25873; appliedIndex:25872; }","duration":"234.987994ms","start":"2026-04-27T21:51:15.635859Z","end":"2026-04-27T21:51:15.870847Z","steps":["trace[1927714446] 'read index received' (duration: 234.80629ms)","trace[1927714446] 'applied index is now lower than readState.Index' (duration: 180.643µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:51:15.871029Z","caller":"traceutil/trace.go:171","msg":"trace[1920684884] transaction","detail":"{read_only:false; response_revision:25053; number_of_response:1; }","duration":"299.32596ms","start":"2026-04-27T21:51:15.571694Z","end":"2026-04-27T21:51:15.87102Z","steps":["trace[1920684884] 'process raft request' (duration: 298.970824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:15.871324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.362751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:51:15.871419Z","caller":"traceutil/trace.go:171","msg":"trace[1154145271] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:25053; }","duration":"235.570014ms","start":"2026-04-27T21:51:15.635822Z","end":"2026-04-27T21:51:15.871392Z","steps":["trace[1154145271] 'agreement among raft nodes before linearized reading' (duration: 235.307359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:16.159222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.334076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:51:16.159334Z","caller":"traceutil/trace.go:171","msg":"trace[218372434] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25054; }","duration":"211.517549ms","start":"2026-04-27T21:51:15.947789Z","end":"2026-04-27T21:51:16.159307Z","steps":["trace[218372434] 'range keys from in-memory index tree' (duration: 211.115631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:16.159479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.833356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-04-27T21:51:16.159612Z","caller":"traceutil/trace.go:171","msg":"trace[1836911197] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:25054; }","duration":"108.05855ms","start":"2026-04-27T21:51:16.051524Z","end":"2026-04-27T21:51:16.159582Z","steps":["trace[1836911197] 'count revisions from in-memory index tree' (duration: 107.618272ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:16.374817Z","caller":"traceutil/trace.go:171","msg":"trace[418714539] transaction","detail":"{read_only:false; response_revision:25055; number_of_response:1; }","duration":"115.161826ms","start":"2026-04-27T21:51:16.259622Z","end":"2026-04-27T21:51:16.374784Z","steps":["trace[418714539] 'process raft request' (duration: 114.963202ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:16.561869Z","caller":"traceutil/trace.go:171","msg":"trace[1400963056] transaction","detail":"{read_only:false; response_revision:25056; number_of_response:1; }","duration":"126.923494ms","start":"2026-04-27T21:51:16.434912Z","end":"2026-04-27T21:51:16.561835Z","steps":["trace[1400963056] 'process raft request' (duration: 89.171526ms)","trace[1400963056] 'compare' (duration: 37.438063ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:51:16.562094Z","caller":"traceutil/trace.go:171","msg":"trace[1928660280] transaction","detail":"{read_only:false; response_revision:25057; number_of_response:1; }","duration":"122.586967ms","start":"2026-04-27T21:51:16.439465Z","end":"2026-04-27T21:51:16.562052Z","steps":["trace[1928660280] 'process raft request' (duration: 122.313312ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:17.194357Z","caller":"traceutil/trace.go:171","msg":"trace[1113308523] transaction","detail":"{read_only:false; response_revision:25058; number_of_response:1; }","duration":"430.357506ms","start":"2026-04-27T21:51:16.763973Z","end":"2026-04-27T21:51:17.194331Z","steps":["trace[1113308523] 'process raft request' (duration: 430.174063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:17.194517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:16.763948Z","time spent":"430.485369ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:51:17.216277Z","caller":"traceutil/trace.go:171","msg":"trace[1551650462] linearizableReadLoop","detail":"{readStateIndex:25879; appliedIndex:25877; }","duration":"162.534073ms","start":"2026-04-27T21:51:17.053719Z","end":"2026-04-27T21:51:17.216253Z","steps":["trace[1551650462] 'read index received' (duration: 140.499113ms)","trace[1551650462] 'applied index is now lower than readState.Index' (duration: 22.03356ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:51:17.216546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.804127ms","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-04-27T21:51:17.216605Z","caller":"traceutil/trace.go:171","msg":"trace[1578506699] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25061; }","duration":"162.91593ms","start":"2026-04-27T21:51:17.053674Z","end":"2026-04-27T21:51:17.21659Z","steps":["trace[1578506699] 'agreement among raft nodes before linearized reading' (duration: 162.721266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:17.216772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.453956ms","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-04-27T21:51:17.216849Z","caller":"traceutil/trace.go:171","msg":"trace[481746096] transaction","detail":"{read_only:false; response_revision:25059; number_of_response:1; }","duration":"355.213167ms","start":"2026-04-27T21:51:16.861601Z","end":"2026-04-27T21:51:17.216814Z","steps":["trace[481746096] 'process raft request' (duration: 354.405183ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:17.216869Z","caller":"traceutil/trace.go:171","msg":"trace[37381504] 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:25061; }","duration":"160.564418ms","start":"2026-04-27T21:51:17.056277Z","end":"2026-04-27T21:51:17.216841Z","steps":["trace[37381504] 'agreement among raft nodes before linearized reading' (duration: 160.408675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:17.216994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:16.861558Z","time spent":"355.358721ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:51:17.347267Z","caller":"traceutil/trace.go:171","msg":"trace[793077082] linearizableReadLoop","detail":"{readStateIndex:25882; appliedIndex:25881; }","duration":"123.675306ms","start":"2026-04-27T21:51:17.223561Z","end":"2026-04-27T21:51:17.347236Z","steps":["trace[793077082] 'read index received' (duration: 118.595256ms)","trace[793077082] 'applied index is now lower than readState.Index' (duration: 5.079ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:51:17.347326Z","caller":"traceutil/trace.go:171","msg":"trace[290241401] transaction","detail":"{read_only:false; response_revision:25062; number_of_response:1; }","duration":"124.801396ms","start":"2026-04-27T21:51:17.222485Z","end":"2026-04-27T21:51:17.347287Z","steps":["trace[290241401] 'process raft request' (duration: 119.664695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:17.347474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.857049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:51:17.34756Z","caller":"traceutil/trace.go:171","msg":"trace[1848915620] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25062; }","duration":"124.040062ms","start":"2026-04-27T21:51:17.223498Z","end":"2026-04-27T21:51:17.347538Z","steps":["trace[1848915620] 'agreement among raft nodes before linearized reading' (duration: 123.831309ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:17.777099Z","caller":"traceutil/trace.go:171","msg":"trace[1235443328] transaction","detail":"{read_only:false; response_revision:25063; number_of_response:1; }","duration":"253.772085ms","start":"2026-04-27T21:51:17.523295Z","end":"2026-04-27T21:51:17.777067Z","steps":["trace[1235443328] 'process raft request' (duration: 253.551401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:18.021281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.791959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:51:18.021412Z","caller":"traceutil/trace.go:171","msg":"trace[1863975867] transaction","detail":"{read_only:false; response_revision:25064; number_of_response:1; }","duration":"497.997582ms","start":"2026-04-27T21:51:17.523401Z","end":"2026-04-27T21:51:18.021399Z","steps":["trace[1863975867] 'process raft request' (duration: 353.01739ms)","trace[1863975867] 'compare' (duration: 144.607176ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:51:18.021474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:17.523383Z","time spent":"498.070134ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:51:18.021943Z","caller":"traceutil/trace.go:171","msg":"trace[2038987670] transaction","detail":"{read_only:false; response_revision:25066; number_of_response:1; }","duration":"495.117761ms","start":"2026-04-27T21:51:17.526808Z","end":"2026-04-27T21:51:18.021926Z","steps":["trace[2038987670] 'process raft request' (duration: 495.01827ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:18.021925Z","caller":"traceutil/trace.go:171","msg":"trace[1001249952] transaction","detail":"{read_only:false; response_revision:25065; number_of_response:1; }","duration":"495.964776ms","start":"2026-04-27T21:51:17.525921Z","end":"2026-04-27T21:51:18.021885Z","steps":["trace[1001249952] 'process raft request' (duration: 495.434307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:18.021997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:17.526791Z","time spent":"495.179592ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:51:18.022063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:17.525911Z","time spent":"496.083239ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:51:18.022265Z","caller":"traceutil/trace.go:171","msg":"trace[1169650064] transaction","detail":"{read_only:false; response_revision:25067; number_of_response:1; }","duration":"437.228918ms","start":"2026-04-27T21:51:17.585025Z","end":"2026-04-27T21:51:18.022254Z","steps":["trace[1169650064] 'process raft request' (duration: 436.837651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:18.022315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:17.584979Z","time spent":"437.31022ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:51:18.02237Z","caller":"traceutil/trace.go:171","msg":"trace[999048954] transaction","detail":"{read_only:false; response_revision:25068; number_of_response:1; }","duration":"145.784497ms","start":"2026-04-27T21:51:17.876564Z","end":"2026-04-27T21:51:18.022348Z","steps":["trace[999048954] 'process raft request' (duration: 145.663475ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:18.02243Z","caller":"traceutil/trace.go:171","msg":"trace[99355840] linearizableReadLoop","detail":"{readStateIndex:25886; appliedIndex:25882; }","duration":"462.186259ms","start":"2026-04-27T21:51:17.560214Z","end":"2026-04-27T21:51:18.022401Z","steps":["trace[99355840] 'read index received' (duration: 216.608858ms)","trace[99355840] 'applied index is now lower than readState.Index' (duration: 245.573671ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:51:18.02259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.365892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:51:18.022631Z","caller":"traceutil/trace.go:171","msg":"trace[535933336] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25068; }","duration":"462.455003ms","start":"2026-04-27T21:51:17.560165Z","end":"2026-04-27T21:51:18.02262Z","steps":["trace[535933336] 'agreement among raft nodes before linearized reading' (duration: 462.365372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:18.022721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:17.560147Z","time spent":"462.560236ms","remote":"127.0.0.1:37838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true "} {"level":"info","ts":"2026-04-27T21:51:18.189168Z","caller":"traceutil/trace.go:171","msg":"trace[329114153] linearizableReadLoop","detail":"{readStateIndex:25889; appliedIndex:25888; }","duration":"166.650037ms","start":"2026-04-27T21:51:18.022498Z","end":"2026-04-27T21:51:18.189148Z","steps":["trace[329114153] 'read index received' (duration: 166.517184ms)","trace[329114153] 'applied index is now lower than readState.Index' (duration: 131.693µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:51:18.189275Z","caller":"traceutil/trace.go:171","msg":"trace[517926839] transaction","detail":"{read_only:false; response_revision:25069; number_of_response:1; }","duration":"266.845247ms","start":"2026-04-27T21:51:17.922402Z","end":"2026-04-27T21:51:18.189247Z","steps":["trace[517926839] 'process raft request' (duration: 266.626254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:18.189341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.785346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:51:18.189366Z","caller":"traceutil/trace.go:171","msg":"trace[869393857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25069; }","duration":"240.827177ms","start":"2026-04-27T21:51:17.948533Z","end":"2026-04-27T21:51:18.18936Z","steps":["trace[869393857] 'agreement among raft nodes before linearized reading' (duration: 240.789487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:18.189304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.099226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:51:18.189543Z","caller":"traceutil/trace.go:171","msg":"trace[1360742046] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:25069; }","duration":"265.385461ms","start":"2026-04-27T21:51:17.924146Z","end":"2026-04-27T21:51:18.189532Z","steps":["trace[1360742046] 'agreement among raft nodes before linearized reading' (duration: 265.080086ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:18.498899Z","caller":"traceutil/trace.go:171","msg":"trace[347367298] transaction","detail":"{read_only:false; response_revision:25070; number_of_response:1; }","duration":"117.198762ms","start":"2026-04-27T21:51:18.381675Z","end":"2026-04-27T21:51:18.498874Z","steps":["trace[347367298] 'process raft request' (duration: 105.22842ms)","trace[347367298] 'compare' (duration: 11.495593ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:51:18.86778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.734401ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:51:18.867879Z","caller":"traceutil/trace.go:171","msg":"trace[2145088475] transaction","detail":"{read_only:false; response_revision:25072; number_of_response:1; }","duration":"300.242017ms","start":"2026-04-27T21:51:18.567621Z","end":"2026-04-27T21:51:18.867863Z","steps":["trace[2145088475] 'process raft request' (duration: 182.344883ms)","trace[2145088475] 'compare' (duration: 117.184421ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:51:18.867933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:18.56758Z","time spent":"300.330558ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:51:18.998615Z","caller":"traceutil/trace.go:171","msg":"trace[1413311165] transaction","detail":"{read_only:false; response_revision:25073; number_of_response:1; }","duration":"430.480809ms","start":"2026-04-27T21:51:18.568106Z","end":"2026-04-27T21:51:18.998587Z","steps":["trace[1413311165] 'process raft request' (duration: 430.190843ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:18.998817Z","caller":"traceutil/trace.go:171","msg":"trace[963864155] linearizableReadLoop","detail":"{readStateIndex:25893; appliedIndex:25891; }","duration":"339.003252ms","start":"2026-04-27T21:51:18.65978Z","end":"2026-04-27T21:51:18.998784Z","steps":["trace[963864155] 'read index received' (duration: 90.275756ms)","trace[963864155] 'applied index is now lower than readState.Index' (duration: 248.724696ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:51:18.999023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.220445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:51:18.999066Z","caller":"traceutil/trace.go:171","msg":"trace[1586534967] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25074; }","duration":"339.282526ms","start":"2026-04-27T21:51:18.659771Z","end":"2026-04-27T21:51:18.999053Z","steps":["trace[1586534967] 'agreement among raft nodes before linearized reading' (duration: 339.173635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:18.999081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.988953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.23\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-04-27T21:51:18.998993Z","caller":"traceutil/trace.go:171","msg":"trace[1499158071] transaction","detail":"{read_only:false; response_revision:25074; number_of_response:1; }","duration":"292.826516ms","start":"2026-04-27T21:51:18.706085Z","end":"2026-04-27T21:51:18.998911Z","steps":["trace[1499158071] 'process raft request' (duration: 292.434928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:18.999111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:18.659708Z","time spent":"339.390938ms","remote":"127.0.0.1:43360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-04-27T21:51:18.999128Z","caller":"traceutil/trace.go:171","msg":"trace[2049042022] range","detail":"{range_begin:/registry/masterleases/199.19.213.23; range_end:; response_count:1; response_revision:25074; }","duration":"261.079095ms","start":"2026-04-27T21:51:18.738037Z","end":"2026-04-27T21:51:18.999116Z","steps":["trace[2049042022] 'agreement among raft nodes before linearized reading' (duration: 260.923882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:18.999793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:18.568076Z","time spent":"430.626581ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:51:19.676319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.507745ms","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-04-27T21:51:19.676416Z","caller":"traceutil/trace.go:171","msg":"trace[175103287] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25078; }","duration":"324.657548ms","start":"2026-04-27T21:51:19.351731Z","end":"2026-04-27T21:51:19.676389Z","steps":["trace[175103287] 'range keys from in-memory index tree' (duration: 324.305951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:19.676461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:19.351708Z","time spent":"324.742969ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T21:51:19.676626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.297955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:51:19.676718Z","caller":"traceutil/trace.go:171","msg":"trace[61869745] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:25078; }","duration":"301.455828ms","start":"2026-04-27T21:51:19.375238Z","end":"2026-04-27T21:51:19.676694Z","steps":["trace[61869745] 'count revisions from in-memory index tree' (duration: 301.184904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:19.676773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:19.375211Z","time spent":"301.54607ms","remote":"127.0.0.1:34884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-27T21:51:20.0711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.848703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-27T21:51:20.071108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.460589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:51:20.071169Z","caller":"traceutil/trace.go:171","msg":"trace[282530550] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:25079; }","duration":"173.971964ms","start":"2026-04-27T21:51:19.897183Z","end":"2026-04-27T21:51:20.071155Z","steps":["trace[282530550] 'count revisions from in-memory index tree' (duration: 173.744671ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:20.071198Z","caller":"traceutil/trace.go:171","msg":"trace[791763855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25079; }","duration":"124.595862ms","start":"2026-04-27T21:51:19.94658Z","end":"2026-04-27T21:51:20.071176Z","steps":["trace[791763855] 'range keys from in-memory index tree' (duration: 124.363358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:20.071108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.405465ms","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-04-27T21:51:20.0715Z","caller":"traceutil/trace.go:171","msg":"trace[557844561] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25079; }","duration":"289.863564ms","start":"2026-04-27T21:51:19.781614Z","end":"2026-04-27T21:51:20.071477Z","steps":["trace[557844561] 'range keys from in-memory index tree' (duration: 289.173621ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:20.407542Z","caller":"traceutil/trace.go:171","msg":"trace[1686671278] linearizableReadLoop","detail":"{readStateIndex:25901; appliedIndex:25900; }","duration":"334.165617ms","start":"2026-04-27T21:51:20.073347Z","end":"2026-04-27T21:51:20.407513Z","steps":["trace[1686671278] 'read index received' (duration: 334.028054ms)","trace[1686671278] 'applied index is now lower than readState.Index' (duration: 136.493µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:51:20.407633Z","caller":"traceutil/trace.go:171","msg":"trace[1387307267] transaction","detail":"{read_only:false; response_revision:25080; number_of_response:1; }","duration":"379.408496ms","start":"2026-04-27T21:51:20.028195Z","end":"2026-04-27T21:51:20.407604Z","steps":["trace[1387307267] 'process raft request' (duration: 379.160822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:20.407775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:20.028167Z","time spent":"379.534739ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:51:20.407766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.390381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:51:20.407844Z","caller":"traceutil/trace.go:171","msg":"trace[1120900193] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25080; }","duration":"334.531003ms","start":"2026-04-27T21:51:20.073294Z","end":"2026-04-27T21:51:20.407825Z","steps":["trace[1120900193] 'agreement among raft nodes before linearized reading' (duration: 334.35479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:20.4079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:20.073275Z","time spent":"334.615875ms","remote":"127.0.0.1:42944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T21:51:20.649749Z","caller":"traceutil/trace.go:171","msg":"trace[731497374] transaction","detail":"{read_only:false; response_revision:25081; number_of_response:1; }","duration":"573.768122ms","start":"2026-04-27T21:51:20.075963Z","end":"2026-04-27T21:51:20.649731Z","steps":["trace[731497374] 'process raft request' (duration: 573.505257ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:20.649725Z","caller":"traceutil/trace.go:171","msg":"trace[1616101529] transaction","detail":"{read_only:false; response_revision:25082; number_of_response:1; }","duration":"573.481197ms","start":"2026-04-27T21:51:20.076221Z","end":"2026-04-27T21:51:20.649702Z","steps":["trace[1616101529] 'process raft request' (duration: 573.393596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:20.649857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:20.075929Z","time spent":"573.865114ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:51:20.649895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:20.076206Z","time spent":"573.6438ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:51:20.650022Z","caller":"traceutil/trace.go:171","msg":"trace[1609675236] transaction","detail":"{read_only:false; response_revision:25083; number_of_response:1; }","duration":"573.542787ms","start":"2026-04-27T21:51:20.07647Z","end":"2026-04-27T21:51:20.650013Z","steps":["trace[1609675236] 'process raft request' (duration: 573.198021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:20.650062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:20.076448Z","time spent":"573.594149ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:51:20.650118Z","caller":"traceutil/trace.go:171","msg":"trace[561701449] transaction","detail":"{read_only:false; response_revision:25085; number_of_response:1; }","duration":"455.671014ms","start":"2026-04-27T21:51:20.19444Z","end":"2026-04-27T21:51:20.650111Z","steps":["trace[561701449] 'process raft request' (duration: 455.606403ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:20.65012Z","caller":"traceutil/trace.go:171","msg":"trace[2129545237] linearizableReadLoop","detail":"{readStateIndex:25906; appliedIndex:25901; }","duration":"242.441145ms","start":"2026-04-27T21:51:20.407646Z","end":"2026-04-27T21:51:20.650087Z","steps":["trace[2129545237] 'read index received' (duration: 241.861775ms)","trace[2129545237] 'applied index is now lower than readState.Index' (duration: 578.51µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:51:20.650159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:20.194425Z","time spent":"455.708926ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:51:20.650304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.820991ms","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-04-27T21:51:20.650362Z","caller":"traceutil/trace.go:171","msg":"trace[2120049872] 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:25085; }","duration":"457.916553ms","start":"2026-04-27T21:51:20.19243Z","end":"2026-04-27T21:51:20.650347Z","steps":["trace[2120049872] 'agreement among raft nodes before linearized reading' (duration: 457.73249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:20.650396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:20.19241Z","time spent":"457.978354ms","remote":"127.0.0.1:43068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-04-27T21:51:20.650383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.507046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-04-27T21:51:20.650495Z","caller":"traceutil/trace.go:171","msg":"trace[1737610727] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25085; }","duration":"513.657538ms","start":"2026-04-27T21:51:20.136812Z","end":"2026-04-27T21:51:20.65047Z","steps":["trace[1737610727] 'agreement among raft nodes before linearized reading' (duration: 513.424664ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:20.65027Z","caller":"traceutil/trace.go:171","msg":"trace[358669035] transaction","detail":"{read_only:false; response_revision:25084; number_of_response:1; }","duration":"573.345514ms","start":"2026-04-27T21:51:20.076873Z","end":"2026-04-27T21:51:20.650218Z","steps":["trace[358669035] 'process raft request' (duration: 573.031969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:20.650555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:20.136796Z","time spent":"513.744371ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":583,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-27T21:51:20.650577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:20.076863Z","time spent":"573.685191ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:51:20.682923Z","caller":"traceutil/trace.go:171","msg":"trace[268577332] transaction","detail":"{read_only:false; response_revision:25086; number_of_response:1; }","duration":"177.59105ms","start":"2026-04-27T21:51:20.505299Z","end":"2026-04-27T21:51:20.68289Z","steps":["trace[268577332] 'process raft request' (duration: 177.361636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:20.969613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.81786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:51:20.969758Z","caller":"traceutil/trace.go:171","msg":"trace[462344827] transaction","detail":"{read_only:false; response_revision:25087; number_of_response:1; }","duration":"314.883975ms","start":"2026-04-27T21:51:20.654849Z","end":"2026-04-27T21:51:20.969733Z","steps":["trace[462344827] 'process raft request' (duration: 169.879512ms)","trace[462344827] 'compare' (duration: 144.592666ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:51:20.969829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:20.65483Z","time spent":"314.966906ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:51:20.980539Z","caller":"traceutil/trace.go:171","msg":"trace[1206779670] transaction","detail":"{read_only:false; response_revision:25088; number_of_response:1; }","duration":"106.100495ms","start":"2026-04-27T21:51:20.874412Z","end":"2026-04-27T21:51:20.980513Z","steps":["trace[1206779670] 'process raft request' (duration: 105.717319ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:21.125621Z","caller":"traceutil/trace.go:171","msg":"trace[1699253836] linearizableReadLoop","detail":"{readStateIndex:25910; appliedIndex:25909; }","duration":"100.136111ms","start":"2026-04-27T21:51:21.025452Z","end":"2026-04-27T21:51:21.125588Z","steps":["trace[1699253836] 'read index received' (duration: 98.376919ms)","trace[1699253836] 'applied index is now lower than readState.Index' (duration: 1.757301ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:51:21.12573Z","caller":"traceutil/trace.go:171","msg":"trace[1204957275] transaction","detail":"{read_only:false; response_revision:25089; number_of_response:1; }","duration":"119.97194ms","start":"2026-04-27T21:51:21.005708Z","end":"2026-04-27T21:51:21.12568Z","steps":["trace[1204957275] 'process raft request' (duration: 118.322341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:21.125885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.413785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-27T21:51:21.125928Z","caller":"traceutil/trace.go:171","msg":"trace[2055339479] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25089; }","duration":"100.517657ms","start":"2026-04-27T21:51:21.025397Z","end":"2026-04-27T21:51:21.125914Z","steps":["trace[2055339479] 'agreement among raft nodes before linearized reading' (duration: 100.302563ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:30.026433Z","caller":"traceutil/trace.go:171","msg":"trace[1237255862] transaction","detail":"{read_only:false; response_revision:25156; number_of_response:1; }","duration":"218.129436ms","start":"2026-04-27T21:51:29.808273Z","end":"2026-04-27T21:51:30.026403Z","steps":["trace[1237255862] 'process raft request' (duration: 217.738599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:30.410553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.567884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:51:30.410666Z","caller":"traceutil/trace.go:171","msg":"trace[1306961439] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:25156; }","duration":"215.784219ms","start":"2026-04-27T21:51:30.194852Z","end":"2026-04-27T21:51:30.410636Z","steps":["trace[1306961439] 'count revisions from in-memory index tree' (duration: 215.465053ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:30.722966Z","caller":"traceutil/trace.go:171","msg":"trace[2064646460] transaction","detail":"{read_only:false; response_revision:25157; number_of_response:1; }","duration":"263.562127ms","start":"2026-04-27T21:51:30.459363Z","end":"2026-04-27T21:51:30.722926Z","steps":["trace[2064646460] 'process raft request' (duration: 263.245881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:31.276465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.046781ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:51:31.276534Z","caller":"traceutil/trace.go:171","msg":"trace[412932702] linearizableReadLoop","detail":"{readStateIndex:25981; appliedIndex:25980; }","duration":"553.667199ms","start":"2026-04-27T21:51:30.722857Z","end":"2026-04-27T21:51:31.276524Z","steps":["trace[412932702] 'read index received' (duration: 269.506235ms)","trace[412932702] 'applied index is now lower than readState.Index' (duration: 284.159894ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:51:31.276678Z","caller":"traceutil/trace.go:171","msg":"trace[1128367338] transaction","detail":"{read_only:false; response_revision:25158; number_of_response:1; }","duration":"576.525387ms","start":"2026-04-27T21:51:30.700146Z","end":"2026-04-27T21:51:31.276671Z","steps":["trace[1128367338] 'process raft request' (duration: 292.202341ms)","trace[1128367338] 'compare' (duration: 283.606153ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:51:31.276728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:30.700127Z","time spent":"576.572818ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:51:31.276824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.77036ms","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-04-27T21:51:31.276935Z","caller":"traceutil/trace.go:171","msg":"trace[2083567365] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25158; }","duration":"571.932255ms","start":"2026-04-27T21:51:30.704983Z","end":"2026-04-27T21:51:31.276916Z","steps":["trace[2083567365] 'agreement among raft nodes before linearized reading' (duration: 571.651479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:31.276984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:30.704964Z","time spent":"572.011476ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-27T21:51:31.277275Z","caller":"traceutil/trace.go:171","msg":"trace[151422558] transaction","detail":"{read_only:false; response_revision:25159; number_of_response:1; }","duration":"549.85173ms","start":"2026-04-27T21:51:30.727416Z","end":"2026-04-27T21:51:31.277268Z","steps":["trace[151422558] 'process raft request' (duration: 549.806929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:31.277318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:30.727384Z","time spent":"549.915782ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:51:31.578651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.678164ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:51:31.578849Z","caller":"traceutil/trace.go:171","msg":"trace[387846640] linearizableReadLoop","detail":"{readStateIndex:25984; appliedIndex:25981; }","duration":"302.285796ms","start":"2026-04-27T21:51:31.276547Z","end":"2026-04-27T21:51:31.578833Z","steps":["trace[387846640] 'read index received' (duration: 646.493µs)","trace[387846640] 'applied index is now lower than readState.Index' (duration: 301.638103ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:51:31.578927Z","caller":"traceutil/trace.go:171","msg":"trace[370722794] transaction","detail":"{read_only:false; response_revision:25160; number_of_response:1; }","duration":"549.399251ms","start":"2026-04-27T21:51:31.029516Z","end":"2026-04-27T21:51:31.578915Z","steps":["trace[370722794] 'process raft request' (duration: 353.358351ms)","trace[370722794] 'compare' (duration: 195.47311ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:51:31.578982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:31.02949Z","time spent":"549.459982ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:51:31.579262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.709929ms","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-04-27T21:51:31.579299Z","caller":"traceutil/trace.go:171","msg":"trace[1166141772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25161; }","duration":"850.78057ms","start":"2026-04-27T21:51:30.728509Z","end":"2026-04-27T21:51:31.579289Z","steps":["trace[1166141772] 'agreement among raft nodes before linearized reading' (duration: 850.663838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:31.579328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:30.728489Z","time spent":"850.832341ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T21:51:31.579307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.453702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-27T21:51:31.579338Z","caller":"traceutil/trace.go:171","msg":"trace[1481262706] transaction","detail":"{read_only:false; response_revision:25161; number_of_response:1; }","duration":"399.977625ms","start":"2026-04-27T21:51:31.179332Z","end":"2026-04-27T21:51:31.579309Z","steps":["trace[1481262706] 'process raft request' (duration: 399.410344ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:31.579407Z","caller":"traceutil/trace.go:171","msg":"trace[1858985749] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25161; }","duration":"845.598055ms","start":"2026-04-27T21:51:30.733778Z","end":"2026-04-27T21:51:31.579376Z","steps":["trace[1858985749] 'agreement among raft nodes before linearized reading' (duration: 845.31915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:31.579482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:30.733757Z","time spent":"845.704687ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T21:51:31.579485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.378543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-27T21:51:31.579515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:31.179314Z","time spent":"400.099036ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:51:31.579545Z","caller":"traceutil/trace.go:171","msg":"trace[1096253219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25161; }","duration":"844.473085ms","start":"2026-04-27T21:51:30.735053Z","end":"2026-04-27T21:51:31.579526Z","steps":["trace[1096253219] 'agreement among raft nodes before linearized reading' (duration: 843.859453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:31.579586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:30.735038Z","time spent":"844.538206ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-27T21:51:31.669957Z","caller":"traceutil/trace.go:171","msg":"trace[1253179139] transaction","detail":"{read_only:false; response_revision:25163; number_of_response:1; }","duration":"374.862235ms","start":"2026-04-27T21:51:31.295068Z","end":"2026-04-27T21:51:31.66993Z","steps":["trace[1253179139] 'process raft request' (duration: 374.739993ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:31.669975Z","caller":"traceutil/trace.go:171","msg":"trace[1510187480] transaction","detail":"{read_only:false; response_revision:25162; number_of_response:1; }","duration":"388.26227ms","start":"2026-04-27T21:51:31.281683Z","end":"2026-04-27T21:51:31.669946Z","steps":["trace[1510187480] 'process raft request' (duration: 387.964015ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:31.670242Z","caller":"traceutil/trace.go:171","msg":"trace[1302461143] transaction","detail":"{read_only:false; response_revision:25164; number_of_response:1; }","duration":"373.152304ms","start":"2026-04-27T21:51:31.297055Z","end":"2026-04-27T21:51:31.670207Z","steps":["trace[1302461143] 'process raft request' (duration: 372.799348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:31.670378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.646074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-27T21:51:31.670402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:31.297041Z","time spent":"373.276596ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:51:31.670442Z","caller":"traceutil/trace.go:171","msg":"trace[224259075] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/floatingips/; range_end:/registry/openstack.k-orc.cloud/floatingips0; response_count:0; response_revision:25165; }","duration":"141.778337ms","start":"2026-04-27T21:51:31.528648Z","end":"2026-04-27T21:51:31.670427Z","steps":["trace[224259075] 'agreement among raft nodes before linearized reading' (duration: 141.646684ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:31.670436Z","caller":"traceutil/trace.go:171","msg":"trace[152873417] transaction","detail":"{read_only:false; response_revision:25165; number_of_response:1; }","duration":"371.486954ms","start":"2026-04-27T21:51:31.29887Z","end":"2026-04-27T21:51:31.670357Z","steps":["trace[152873417] 'process raft request' (duration: 371.28234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:31.670549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:31.298858Z","time spent":"371.640656ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:51:31.670124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:31.29505Z","time spent":"374.984778ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:51:31.670412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:31.281662Z","time spent":"388.532556ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:51:31.685334Z","caller":"traceutil/trace.go:171","msg":"trace[1712103344] transaction","detail":"{read_only:false; response_revision:25166; number_of_response:1; }","duration":"101.499879ms","start":"2026-04-27T21:51:31.583802Z","end":"2026-04-27T21:51:31.685302Z","steps":["trace[1712103344] 'process raft request' (duration: 99.696027ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:31.685719Z","caller":"traceutil/trace.go:171","msg":"trace[1992638053] transaction","detail":"{read_only:false; response_revision:25167; number_of_response:1; }","duration":"101.763783ms","start":"2026-04-27T21:51:31.583937Z","end":"2026-04-27T21:51:31.685701Z","steps":["trace[1992638053] 'process raft request' (duration: 101.299715ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:31.685769Z","caller":"traceutil/trace.go:171","msg":"trace[87157948] transaction","detail":"{read_only:false; response_revision:25168; number_of_response:1; }","duration":"101.653051ms","start":"2026-04-27T21:51:31.584095Z","end":"2026-04-27T21:51:31.685748Z","steps":["trace[87157948] 'process raft request' (duration: 101.530439ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:34.898626Z","caller":"traceutil/trace.go:171","msg":"trace[1617871691] transaction","detail":"{read_only:false; response_revision:25246; number_of_response:1; }","duration":"164.466522ms","start":"2026-04-27T21:51:34.734122Z","end":"2026-04-27T21:51:34.898589Z","steps":["trace[1617871691] 'process raft request' (duration: 164.213767ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:40.780083Z","caller":"traceutil/trace.go:171","msg":"trace[1540711372] transaction","detail":"{read_only:false; response_revision:25313; number_of_response:1; }","duration":"172.967618ms","start":"2026-04-27T21:51:40.60709Z","end":"2026-04-27T21:51:40.780058Z","steps":["trace[1540711372] 'process raft request' (duration: 172.796715ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:58.838453Z","caller":"traceutil/trace.go:171","msg":"trace[1720421955] transaction","detail":"{read_only:false; response_revision:25460; number_of_response:1; }","duration":"285.131922ms","start":"2026-04-27T21:51:58.553277Z","end":"2026-04-27T21:51:58.838409Z","steps":["trace[1720421955] 'process raft request' (duration: 284.941158ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:59.203974Z","caller":"traceutil/trace.go:171","msg":"trace[1415544737] linearizableReadLoop","detail":"{readStateIndex:26291; appliedIndex:26289; }","duration":"462.905758ms","start":"2026-04-27T21:51:58.741043Z","end":"2026-04-27T21:51:59.203949Z","steps":["trace[1415544737] 'read index received' (duration: 97.20666ms)","trace[1415544737] 'applied index is now lower than readState.Index' (duration: 365.697888ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:51:59.204069Z","caller":"traceutil/trace.go:171","msg":"trace[1440853470] transaction","detail":"{read_only:false; response_revision:25461; number_of_response:1; }","duration":"609.560353ms","start":"2026-04-27T21:51:58.594471Z","end":"2026-04-27T21:51:59.204032Z","steps":["trace[1440853470] 'process raft request' (duration: 609.299489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:59.204272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:58.594445Z","time spent":"609.725807ms","remote":"127.0.0.1:43376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9529,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:51:59.204354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.259525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.23\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-04-27T21:51:59.204421Z","caller":"traceutil/trace.go:171","msg":"trace[945007798] range","detail":"{range_begin:/registry/masterleases/199.19.213.23; range_end:; response_count:1; response_revision:25461; }","duration":"463.418158ms","start":"2026-04-27T21:51:58.740984Z","end":"2026-04-27T21:51:59.204403Z","steps":["trace[945007798] 'agreement among raft nodes before linearized reading' (duration: 463.205194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:59.20448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:58.740964Z","time spent":"463.504509ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.23\" "} {"level":"info","ts":"2026-04-27T21:51:59.243642Z","caller":"traceutil/trace.go:171","msg":"trace[1839406591] transaction","detail":"{read_only:false; response_revision:25462; number_of_response:1; }","duration":"259.551394ms","start":"2026-04-27T21:51:58.984048Z","end":"2026-04-27T21:51:59.2436Z","steps":["trace[1839406591] 'process raft request' (duration: 259.196737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:59.243797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.358816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:51:59.243874Z","caller":"traceutil/trace.go:171","msg":"trace[1971982631] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25462; }","duration":"297.479927ms","start":"2026-04-27T21:51:58.946377Z","end":"2026-04-27T21:51:59.243857Z","steps":["trace[1971982631] 'agreement among raft nodes before linearized reading' (duration: 297.326575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:59.243842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.108331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18419"} {"level":"info","ts":"2026-04-27T21:51:59.244068Z","caller":"traceutil/trace.go:171","msg":"trace[805693627] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:25462; }","duration":"398.378235ms","start":"2026-04-27T21:51:58.845627Z","end":"2026-04-27T21:51:59.244005Z","steps":["trace[805693627] 'agreement among raft nodes before linearized reading' (duration: 397.890106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:59.244262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:58.845582Z","time spent":"398.65393ms","remote":"127.0.0.1:43082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":18441,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" "} {"level":"warn","ts":"2026-04-27T21:51:59.516735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:59.211907Z","time spent":"304.820372ms","remote":"127.0.0.1:42954","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-27T21:51:59.516804Z","caller":"traceutil/trace.go:171","msg":"trace[1878561193] linearizableReadLoop","detail":"{readStateIndex:26294; appliedIndex:26292; }","duration":"271.661925ms","start":"2026-04-27T21:51:59.245114Z","end":"2026-04-27T21:51:59.516776Z","steps":["trace[1878561193] 'read index received' (duration: 271.488152ms)","trace[1878561193] 'applied index is now lower than readState.Index' (duration: 172.213µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:51:59.516947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.825078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:51:59.516986Z","caller":"traceutil/trace.go:171","msg":"trace[968888041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25463; }","duration":"271.90842ms","start":"2026-04-27T21:51:59.245064Z","end":"2026-04-27T21:51:59.516973Z","steps":["trace[968888041] 'agreement among raft nodes before linearized reading' (duration: 271.829548ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:59.516963Z","caller":"traceutil/trace.go:171","msg":"trace[30627259] transaction","detail":"{read_only:false; response_revision:25463; number_of_response:1; }","duration":"301.600683ms","start":"2026-04-27T21:51:59.215317Z","end":"2026-04-27T21:51:59.516918Z","steps":["trace[30627259] 'process raft request' (duration: 301.315568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:59.517181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:51:59.215305Z","time spent":"301.754945ms","remote":"127.0.0.1:54166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3290,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:51:59.52819Z","caller":"traceutil/trace.go:171","msg":"trace[525477931] transaction","detail":"{read_only:false; response_revision:25464; number_of_response:1; }","duration":"153.223556ms","start":"2026-04-27T21:51:59.37495Z","end":"2026-04-27T21:51:59.528174Z","steps":["trace[525477931] 'process raft request' (duration: 153.093523ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:59.528457Z","caller":"traceutil/trace.go:171","msg":"trace[901077449] transaction","detail":"{read_only:false; response_revision:25465; number_of_response:1; }","duration":"142.590901ms","start":"2026-04-27T21:51:59.385846Z","end":"2026-04-27T21:51:59.528437Z","steps":["trace[901077449] 'process raft request' (duration: 142.297595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:59.528474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.150711ms","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-04-27T21:51:59.528507Z","caller":"traceutil/trace.go:171","msg":"trace[647294602] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:25465; }","duration":"268.248733ms","start":"2026-04-27T21:51:59.260246Z","end":"2026-04-27T21:51:59.528494Z","steps":["trace[647294602] 'agreement among raft nodes before linearized reading' (duration: 268.147121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:59.528528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.516424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:51:59.528594Z","caller":"traceutil/trace.go:171","msg":"trace[1773380462] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:25465; }","duration":"122.682396ms","start":"2026-04-27T21:51:59.405896Z","end":"2026-04-27T21:51:59.528578Z","steps":["trace[1773380462] 'agreement among raft nodes before linearized reading' (duration: 122.491403ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:59.68276Z","caller":"traceutil/trace.go:171","msg":"trace[2090215203] linearizableReadLoop","detail":"{readStateIndex:26298; appliedIndex:26297; }","duration":"133.668448ms","start":"2026-04-27T21:51:59.549072Z","end":"2026-04-27T21:51:59.682741Z","steps":["trace[2090215203] 'read index received' (duration: 123.516052ms)","trace[2090215203] 'applied index is now lower than readState.Index' (duration: 10.151366ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:51:59.682886Z","caller":"traceutil/trace.go:171","msg":"trace[279014391] transaction","detail":"{read_only:false; response_revision:25467; number_of_response:1; }","duration":"137.129801ms","start":"2026-04-27T21:51:59.545712Z","end":"2026-04-27T21:51:59.682842Z","steps":["trace[279014391] 'process raft request' (duration: 126.933834ms)","trace[279014391] 'compare' (duration: 10.008603ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:51:59.683026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.923353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"info","ts":"2026-04-27T21:51:59.683116Z","caller":"traceutil/trace.go:171","msg":"trace[165676442] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:25467; }","duration":"134.040725ms","start":"2026-04-27T21:51:59.549056Z","end":"2026-04-27T21:51:59.683097Z","steps":["trace[165676442] 'agreement among raft nodes before linearized reading' (duration: 133.79277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:59.684623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.292213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:51:59.684683Z","caller":"traceutil/trace.go:171","msg":"trace[901676867] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:25469; }","duration":"108.393665ms","start":"2026-04-27T21:51:59.576271Z","end":"2026-04-27T21:51:59.684665Z","steps":["trace[901676867] 'agreement among raft nodes before linearized reading' (duration: 108.280963ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:59.685186Z","caller":"traceutil/trace.go:171","msg":"trace[1805325590] transaction","detail":"{read_only:false; response_revision:25468; number_of_response:1; }","duration":"124.294826ms","start":"2026-04-27T21:51:59.56088Z","end":"2026-04-27T21:51:59.685175Z","steps":["trace[1805325590] 'process raft request' (duration: 123.505412ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:52:03.516923Z","caller":"traceutil/trace.go:171","msg":"trace[252047175] transaction","detail":"{read_only:false; response_revision:25495; number_of_response:1; }","duration":"216.147448ms","start":"2026-04-27T21:52:03.300736Z","end":"2026-04-27T21:52:03.516883Z","steps":["trace[252047175] 'process raft request' (duration: 215.893344ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:52:03.851184Z","caller":"traceutil/trace.go:171","msg":"trace[369254033] transaction","detail":"{read_only:false; response_revision:25498; number_of_response:1; }","duration":"153.024972ms","start":"2026-04-27T21:52:03.698131Z","end":"2026-04-27T21:52:03.851156Z","steps":["trace[369254033] 'process raft request' (duration: 134.545114ms)","trace[369254033] 'compare' (duration: 18.105971ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:52:04.012868Z","caller":"traceutil/trace.go:171","msg":"trace[692039333] transaction","detail":"{read_only:false; response_revision:25499; number_of_response:1; }","duration":"305.371473ms","start":"2026-04-27T21:52:03.707464Z","end":"2026-04-27T21:52:04.012836Z","steps":["trace[692039333] 'process raft request' (duration: 253.907751ms)","trace[692039333] 'compare' (duration: 51.059484ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:52:04.013014Z","caller":"traceutil/trace.go:171","msg":"trace[90148629] linearizableReadLoop","detail":"{readStateIndex:26333; appliedIndex:26328; }","duration":"191.7019ms","start":"2026-04-27T21:52:03.82129Z","end":"2026-04-27T21:52:04.012992Z","steps":["trace[90148629] 'read index received' (duration: 11.44342ms)","trace[90148629] 'applied index is now lower than readState.Index' (duration: 180.25701ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:52:04.013039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:52:03.707449Z","time spent":"305.492534ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:52:04.013153Z","caller":"traceutil/trace.go:171","msg":"trace[2005041683] transaction","detail":"{read_only:false; response_revision:25500; number_of_response:1; }","duration":"229.34612ms","start":"2026-04-27T21:52:03.783791Z","end":"2026-04-27T21:52:04.013138Z","steps":["trace[2005041683] 'process raft request' (duration: 228.911012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:52:04.013274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.978805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:52:04.013413Z","caller":"traceutil/trace.go:171","msg":"trace[682722848] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:25502; }","duration":"192.071667ms","start":"2026-04-27T21:52:03.821235Z","end":"2026-04-27T21:52:04.013307Z","steps":["trace[682722848] 'agreement among raft nodes before linearized reading' (duration: 191.930394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:52:04.013426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.12344ms","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-04-27T21:52:04.013422Z","caller":"traceutil/trace.go:171","msg":"trace[203385156] transaction","detail":"{read_only:false; response_revision:25501; number_of_response:1; }","duration":"229.293558ms","start":"2026-04-27T21:52:03.784093Z","end":"2026-04-27T21:52:04.013386Z","steps":["trace[203385156] 'process raft request' (duration: 228.762719ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:52:04.013482Z","caller":"traceutil/trace.go:171","msg":"trace[1299171246] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25502; }","duration":"191.180841ms","start":"2026-04-27T21:52:03.822286Z","end":"2026-04-27T21:52:04.013467Z","steps":["trace[1299171246] 'agreement among raft nodes before linearized reading' (duration: 191.012448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:52:04.013543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.807101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-27T21:52:04.013617Z","caller":"traceutil/trace.go:171","msg":"trace[2122913640] transaction","detail":"{read_only:false; response_revision:25502; number_of_response:1; }","duration":"227.12461ms","start":"2026-04-27T21:52:03.786481Z","end":"2026-04-27T21:52:04.013606Z","steps":["trace[2122913640] 'process raft request' (duration: 226.448417ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:52:04.013628Z","caller":"traceutil/trace.go:171","msg":"trace[2013471817] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25502; }","duration":"174.932174ms","start":"2026-04-27T21:52:03.838677Z","end":"2026-04-27T21:52:04.013609Z","steps":["trace[2013471817] 'agreement among raft nodes before linearized reading' (duration: 174.72783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:52:04.013715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.820059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-04-27T21:52:04.013652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.29256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-27T21:52:04.013755Z","caller":"traceutil/trace.go:171","msg":"trace[1356653053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25502; }","duration":"181.872421ms","start":"2026-04-27T21:52:03.83187Z","end":"2026-04-27T21:52:04.013743Z","steps":["trace[1356653053] 'agreement among raft nodes before linearized reading' (duration: 181.8004ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:52:04.013767Z","caller":"traceutil/trace.go:171","msg":"trace[1531922183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25502; }","duration":"181.413562ms","start":"2026-04-27T21:52:03.832342Z","end":"2026-04-27T21:52:04.013755Z","steps":["trace[1531922183] 'agreement among raft nodes before linearized reading' (duration: 181.245129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:52:04.239098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.501706ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:52:04.239205Z","caller":"traceutil/trace.go:171","msg":"trace[605387796] transaction","detail":"{read_only:false; response_revision:25503; number_of_response:1; }","duration":"221.496536ms","start":"2026-04-27T21:52:04.017686Z","end":"2026-04-27T21:52:04.239183Z","steps":["trace[605387796] 'process raft request' (duration: 100.45289ms)","trace[605387796] 'compare' (duration: 119.893296ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:52:04.26071Z","caller":"traceutil/trace.go:171","msg":"trace[390770954] transaction","detail":"{read_only:false; response_revision:25504; number_of_response:1; }","duration":"242.704065ms","start":"2026-04-27T21:52:04.017976Z","end":"2026-04-27T21:52:04.26068Z","steps":["trace[390770954] 'process raft request' (duration: 242.533092ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:52:04.260713Z","caller":"traceutil/trace.go:171","msg":"trace[698539741] transaction","detail":"{read_only:false; response_revision:25506; number_of_response:1; }","duration":"242.202176ms","start":"2026-04-27T21:52:04.018488Z","end":"2026-04-27T21:52:04.26069Z","steps":["trace[698539741] 'process raft request' (duration: 242.149895ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:52:04.260901Z","caller":"traceutil/trace.go:171","msg":"trace[2000154573] transaction","detail":"{read_only:false; response_revision:25505; number_of_response:1; }","duration":"242.42113ms","start":"2026-04-27T21:52:04.018445Z","end":"2026-04-27T21:52:04.260866Z","steps":["trace[2000154573] 'process raft request' (duration: 242.166416ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:52:04.354502Z","caller":"traceutil/trace.go:171","msg":"trace[2076081913] linearizableReadLoop","detail":"{readStateIndex:26338; appliedIndex:26333; }","duration":"261.690222ms","start":"2026-04-27T21:52:04.092784Z","end":"2026-04-27T21:52:04.354474Z","steps":["trace[2076081913] 'read index received' (duration: 25.370445ms)","trace[2076081913] 'applied index is now lower than readState.Index' (duration: 236.317947ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:52:04.354798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.480137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"warn","ts":"2026-04-27T21:52:04.3548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.002858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"info","ts":"2026-04-27T21:52:04.354843Z","caller":"traceutil/trace.go:171","msg":"trace[1799823404] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:25506; }","duration":"191.582099ms","start":"2026-04-27T21:52:04.163247Z","end":"2026-04-27T21:52:04.35483Z","steps":["trace[1799823404] 'agreement among raft nodes before linearized reading' (duration: 191.476267ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:52:04.354859Z","caller":"traceutil/trace.go:171","msg":"trace[1259297638] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25506; }","duration":"262.10509ms","start":"2026-04-27T21:52:04.092736Z","end":"2026-04-27T21:52:04.354841Z","steps":["trace[1259297638] 'agreement among raft nodes before linearized reading' (duration: 261.883146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:52:04.649765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.803477ms","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-04-27T21:52:04.649871Z","caller":"traceutil/trace.go:171","msg":"trace[148699154] 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:25507; }","duration":"117.98229ms","start":"2026-04-27T21:52:04.531862Z","end":"2026-04-27T21:52:04.649845Z","steps":["trace[148699154] 'count revisions from in-memory index tree' (duration: 117.690755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:52:04.649861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.817788ms","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-04-27T21:52:04.650092Z","caller":"traceutil/trace.go:171","msg":"trace[531753716] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:25507; }","duration":"118.158113ms","start":"2026-04-27T21:52:04.531917Z","end":"2026-04-27T21:52:04.650075Z","steps":["trace[531753716] 'count revisions from in-memory index tree' (duration: 117.660345ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:52:06.401472Z","caller":"traceutil/trace.go:171","msg":"trace[1742144115] transaction","detail":"{read_only:false; response_revision:25519; number_of_response:1; }","duration":"126.805942ms","start":"2026-04-27T21:52:06.274642Z","end":"2026-04-27T21:52:06.401448Z","steps":["trace[1742144115] 'process raft request' (duration: 126.7059ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:52:06.401631Z","caller":"traceutil/trace.go:171","msg":"trace[1226059471] transaction","detail":"{read_only:false; response_revision:25518; number_of_response:1; }","duration":"127.653428ms","start":"2026-04-27T21:52:06.273942Z","end":"2026-04-27T21:52:06.401596Z","steps":["trace[1226059471] 'process raft request' (duration: 62.570407ms)","trace[1226059471] 'compare' (duration: 64.494131ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:52:06.40177Z","caller":"traceutil/trace.go:171","msg":"trace[1789154645] transaction","detail":"{read_only:false; response_revision:25520; number_of_response:1; }","duration":"126.456165ms","start":"2026-04-27T21:52:06.27529Z","end":"2026-04-27T21:52:06.401747Z","steps":["trace[1789154645] 'process raft request' (duration: 126.115749ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:52:06.401984Z","caller":"traceutil/trace.go:171","msg":"trace[1052703319] transaction","detail":"{read_only:false; response_revision:25521; number_of_response:1; }","duration":"125.872275ms","start":"2026-04-27T21:52:06.276087Z","end":"2026-04-27T21:52:06.40196Z","steps":["trace[1052703319] 'process raft request' (duration: 125.5736ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:52:09.004011Z","caller":"traceutil/trace.go:171","msg":"trace[1870883984] transaction","detail":"{read_only:false; response_revision:25537; number_of_response:1; }","duration":"224.938999ms","start":"2026-04-27T21:52:08.779033Z","end":"2026-04-27T21:52:09.003972Z","steps":["trace[1870883984] 'process raft request' (duration: 135.114774ms)","trace[1870883984] 'compare' (duration: 89.452128ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:52:12.435631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.110157ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:52:12.43584Z","caller":"traceutil/trace.go:171","msg":"trace[1664164190] transaction","detail":"{read_only:false; response_revision:25559; number_of_response:1; }","duration":"300.852599ms","start":"2026-04-27T21:52:12.134962Z","end":"2026-04-27T21:52:12.435815Z","steps":["trace[1664164190] 'process raft request' (duration: 173.473677ms)","trace[1664164190] 'compare' (duration: 127.003245ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:52:12.435916Z","caller":"traceutil/trace.go:171","msg":"trace[119331972] transaction","detail":"{read_only:false; response_revision:25560; number_of_response:1; }","duration":"300.865929ms","start":"2026-04-27T21:52:12.13502Z","end":"2026-04-27T21:52:12.435886Z","steps":["trace[119331972] 'process raft request' (duration: 300.740577ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:52:12.435914Z","caller":"traceutil/trace.go:171","msg":"trace[2120540974] transaction","detail":"{read_only:false; response_revision:25561; number_of_response:1; }","duration":"300.562493ms","start":"2026-04-27T21:52:12.135334Z","end":"2026-04-27T21:52:12.435896Z","steps":["trace[2120540974] 'process raft request' (duration: 300.483482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:52:12.435979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:52:12.134946Z","time spent":"300.969461ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:52:12.435999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:52:12.135327Z","time spent":"300.631086ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:52:12.436007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:52:12.135016Z","time spent":"300.93879ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:52:12.436085Z","caller":"traceutil/trace.go:171","msg":"trace[397564750] transaction","detail":"{read_only:false; response_revision:25562; number_of_response:1; }","duration":"140.473423ms","start":"2026-04-27T21:52:12.295605Z","end":"2026-04-27T21:52:12.436078Z","steps":["trace[397564750] 'process raft request' (duration: 140.259819ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:52:15.45834Z","caller":"traceutil/trace.go:171","msg":"trace[277776749] transaction","detail":"{read_only:false; response_revision:25582; number_of_response:1; }","duration":"119.921517ms","start":"2026-04-27T21:52:15.338382Z","end":"2026-04-27T21:52:15.458303Z","steps":["trace[277776749] 'process raft request' (duration: 119.730283ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:52:18.379004Z","caller":"traceutil/trace.go:171","msg":"trace[736753072] transaction","detail":"{read_only:false; response_revision:25600; number_of_response:1; }","duration":"126.863524ms","start":"2026-04-27T21:52:18.252114Z","end":"2026-04-27T21:52:18.378978Z","steps":["trace[736753072] 'process raft request' (duration: 126.71226ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:52:18.597087Z","caller":"traceutil/trace.go:171","msg":"trace[1878389899] transaction","detail":"{read_only:false; response_revision:25602; number_of_response:1; }","duration":"141.45739ms","start":"2026-04-27T21:52:18.455606Z","end":"2026-04-27T21:52:18.597064Z","steps":["trace[1878389899] 'process raft request' (duration: 124.664092ms)","trace[1878389899] 'compare' (duration: 16.592064ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:52:18.5974Z","caller":"traceutil/trace.go:171","msg":"trace[1235177484] linearizableReadLoop","detail":"{readStateIndex:26437; appliedIndex:26434; }","duration":"132.807512ms","start":"2026-04-27T21:52:18.464549Z","end":"2026-04-27T21:52:18.597357Z","steps":["trace[1235177484] 'read index received' (duration: 79.957315ms)","trace[1235177484] 'applied index is now lower than readState.Index' (duration: 52.844097ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:52:18.597609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.045687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-04-27T21:52:18.597653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.213848ms","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-04-27T21:52:18.597696Z","caller":"traceutil/trace.go:171","msg":"trace[627803918] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25604; }","duration":"133.18478ms","start":"2026-04-27T21:52:18.464491Z","end":"2026-04-27T21:52:18.597676Z","steps":["trace[627803918] 'agreement among raft nodes before linearized reading' (duration: 132.989716ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:52:18.597703Z","caller":"traceutil/trace.go:171","msg":"trace[1287353319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25604; }","duration":"128.31043ms","start":"2026-04-27T21:52:18.46938Z","end":"2026-04-27T21:52:18.597691Z","steps":["trace[1287353319] 'agreement among raft nodes before linearized reading' (duration: 128.166917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:52:18.597721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.574439ms","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-04-27T21:52:18.597772Z","caller":"traceutil/trace.go:171","msg":"trace[573891408] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25604; }","duration":"126.65237ms","start":"2026-04-27T21:52:18.47111Z","end":"2026-04-27T21:52:18.597763Z","steps":["trace[573891408] 'agreement among raft nodes before linearized reading' (duration: 126.504538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:52:18.597811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.794403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-27T21:52:18.59787Z","caller":"traceutil/trace.go:171","msg":"trace[125419665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25604; }","duration":"126.876144ms","start":"2026-04-27T21:52:18.470978Z","end":"2026-04-27T21:52:18.597854Z","steps":["trace[125419665] 'agreement among raft nodes before linearized reading' (duration: 126.67494ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:52:18.597833Z","caller":"traceutil/trace.go:171","msg":"trace[1237534601] transaction","detail":"{read_only:false; response_revision:25603; number_of_response:1; }","duration":"141.057613ms","start":"2026-04-27T21:52:18.456286Z","end":"2026-04-27T21:52:18.597344Z","steps":["trace[1237534601] 'process raft request' (duration: 140.689836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:52:20.406458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.805389ms","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-04-27T21:52:20.406539Z","caller":"traceutil/trace.go:171","msg":"trace[1855268031] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25616; }","duration":"164.92538ms","start":"2026-04-27T21:52:20.241595Z","end":"2026-04-27T21:52:20.406521Z","steps":["trace[1855268031] 'range keys from in-memory index tree' (duration: 164.647296ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:52:20.590901Z","caller":"traceutil/trace.go:171","msg":"trace[1505957870] linearizableReadLoop","detail":"{readStateIndex:26452; appliedIndex:26451; }","duration":"205.662186ms","start":"2026-04-27T21:52:20.385216Z","end":"2026-04-27T21:52:20.590878Z","steps":["trace[1505957870] 'read index received' (duration: 205.563274ms)","trace[1505957870] 'applied index is now lower than readState.Index' (duration: 98.052µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:52:20.591228Z","caller":"traceutil/trace.go:171","msg":"trace[1248963341] transaction","detail":"{read_only:false; response_revision:25617; number_of_response:1; }","duration":"206.728286ms","start":"2026-04-27T21:52:20.384491Z","end":"2026-04-27T21:52:20.591219Z","steps":["trace[1248963341] 'process raft request' (duration: 206.236427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:52:20.591404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.171525ms","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-04-27T21:52:20.59143Z","caller":"traceutil/trace.go:171","msg":"trace[434172219] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:25617; }","duration":"206.258706ms","start":"2026-04-27T21:52:20.385161Z","end":"2026-04-27T21:52:20.59142Z","steps":["trace[434172219] 'agreement among raft nodes before linearized reading' (duration: 206.195595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:52:20.599304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.552071ms","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-04-27T21:52:20.599386Z","caller":"traceutil/trace.go:171","msg":"trace[1860629109] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25618; }","duration":"183.691123ms","start":"2026-04-27T21:52:20.415677Z","end":"2026-04-27T21:52:20.599368Z","steps":["trace[1860629109] 'agreement among raft nodes before linearized reading' (duration: 183.433088ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:52:35.287735Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22748} {"level":"info","ts":"2026-04-27T21:52:35.442062Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22748,"took":"150.065077ms","hash":3990049104,"current-db-size-bytes":58998784,"current-db-size":"59 MB","current-db-size-in-use-bytes":35868672,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-04-27T21:52:35.442141Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3990049104,"revision":22748,"compact-revision":20040} {"level":"warn","ts":"2026-04-27T21:52:36.469002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.821023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:52:36.469121Z","caller":"traceutil/trace.go:171","msg":"trace[1227068268] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:25744; }","duration":"108.984416ms","start":"2026-04-27T21:52:36.360104Z","end":"2026-04-27T21:52:36.469088Z","steps":["trace[1227068268] 'count revisions from in-memory index tree' (duration: 108.673371ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:52:36.894764Z","caller":"traceutil/trace.go:171","msg":"trace[199821794] linearizableReadLoop","detail":"{readStateIndex:26587; appliedIndex:26583; }","duration":"193.831629ms","start":"2026-04-27T21:52:36.700903Z","end":"2026-04-27T21:52:36.894734Z","steps":["trace[199821794] 'read index received' (duration: 459.818µs)","trace[199821794] 'applied index is now lower than readState.Index' (duration: 193.370441ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:52:36.895156Z","caller":"traceutil/trace.go:171","msg":"trace[2032621345] transaction","detail":"{read_only:false; response_revision:25746; number_of_response:1; }","duration":"233.340273ms","start":"2026-04-27T21:52:36.661792Z","end":"2026-04-27T21:52:36.895133Z","steps":["trace[2032621345] 'process raft request' (duration: 148.928217ms)","trace[2032621345] 'compare' (duration: 83.265945ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:52:36.895262Z","caller":"traceutil/trace.go:171","msg":"trace[1607582531] transaction","detail":"{read_only:false; response_revision:25748; number_of_response:1; }","duration":"227.74819ms","start":"2026-04-27T21:52:36.667478Z","end":"2026-04-27T21:52:36.895226Z","steps":["trace[1607582531] 'process raft request' (duration: 227.19092ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:52:36.895272Z","caller":"traceutil/trace.go:171","msg":"trace[1973318987] transaction","detail":"{read_only:false; response_revision:25747; number_of_response:1; }","duration":"233.380684ms","start":"2026-04-27T21:52:36.661836Z","end":"2026-04-27T21:52:36.895217Z","steps":["trace[1973318987] 'process raft request' (duration: 232.711712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:52:36.895414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.994315ms","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-04-27T21:52:36.895501Z","caller":"traceutil/trace.go:171","msg":"trace[1087830766] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25748; }","duration":"192.112048ms","start":"2026-04-27T21:52:36.703368Z","end":"2026-04-27T21:52:36.89548Z","steps":["trace[1087830766] 'agreement among raft nodes before linearized reading' (duration: 191.886924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:52:36.895536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.103688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-27T21:52:36.895447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.353254ms","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-04-27T21:52:36.895598Z","caller":"traceutil/trace.go:171","msg":"trace[2135690341] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:25748; }","duration":"114.208541ms","start":"2026-04-27T21:52:36.781374Z","end":"2026-04-27T21:52:36.895583Z","steps":["trace[2135690341] 'agreement among raft nodes before linearized reading' (duration: 114.100829ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:52:36.895607Z","caller":"traceutil/trace.go:171","msg":"trace[2127867154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25748; }","duration":"185.524677ms","start":"2026-04-27T21:52:36.710071Z","end":"2026-04-27T21:52:36.895595Z","steps":["trace[2127867154] 'agreement among raft nodes before linearized reading' (duration: 185.291333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:52:36.895633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.071927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-27T21:52:36.895756Z","caller":"traceutil/trace.go:171","msg":"trace[1292050432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25748; }","duration":"186.21156ms","start":"2026-04-27T21:52:36.709527Z","end":"2026-04-27T21:52:36.895738Z","steps":["trace[1292050432] 'agreement among raft nodes before linearized reading' (duration: 185.922914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:52:36.895543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.528392ms","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-04-27T21:52:36.896625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.27674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-27T21:52:36.89589Z","caller":"traceutil/trace.go:171","msg":"trace[954992250] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25748; }","duration":"195.022871ms","start":"2026-04-27T21:52:36.700847Z","end":"2026-04-27T21:52:36.89587Z","steps":["trace[954992250] 'agreement among raft nodes before linearized reading' (duration: 194.328288ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:52:36.896772Z","caller":"traceutil/trace.go:171","msg":"trace[1416007469] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25748; }","duration":"187.459813ms","start":"2026-04-27T21:52:36.709292Z","end":"2026-04-27T21:52:36.896752Z","steps":["trace[1416007469] 'agreement among raft nodes before linearized reading' (duration: 186.213499ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:52:37.158213Z","caller":"traceutil/trace.go:171","msg":"trace[1301294577] transaction","detail":"{read_only:false; response_revision:25751; number_of_response:1; }","duration":"256.821213ms","start":"2026-04-27T21:52:36.901367Z","end":"2026-04-27T21:52:37.158188Z","steps":["trace[1301294577] 'process raft request' (duration: 256.728172ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:52:37.158225Z","caller":"traceutil/trace.go:171","msg":"trace[1089443541] transaction","detail":"{read_only:false; response_revision:25750; number_of_response:1; }","duration":"257.037827ms","start":"2026-04-27T21:52:36.901154Z","end":"2026-04-27T21:52:37.158192Z","steps":["trace[1089443541] 'process raft request' (duration: 256.769463ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:52:37.159274Z","caller":"traceutil/trace.go:171","msg":"trace[1689844668] transaction","detail":"{read_only:false; response_revision:25752; number_of_response:1; }","duration":"256.554968ms","start":"2026-04-27T21:52:36.902686Z","end":"2026-04-27T21:52:37.159241Z","steps":["trace[1689844668] 'process raft request' (duration: 255.458608ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:52:37.159765Z","caller":"traceutil/trace.go:171","msg":"trace[688753930] linearizableReadLoop","detail":"{readStateIndex:26592; appliedIndex:26587; }","duration":"212.190816ms","start":"2026-04-27T21:52:36.947559Z","end":"2026-04-27T21:52:37.15975Z","steps":["trace[688753930] 'read index received' (duration: 32.152778ms)","trace[688753930] 'applied index is now lower than readState.Index' (duration: 180.036268ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:52:37.159983Z","caller":"traceutil/trace.go:171","msg":"trace[1924033898] transaction","detail":"{read_only:false; response_revision:25753; number_of_response:1; }","duration":"256.415876ms","start":"2026-04-27T21:52:36.903553Z","end":"2026-04-27T21:52:37.159969Z","steps":["trace[1924033898] 'process raft request' (duration: 255.598681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:52:37.160147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.577593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:52:37.160642Z","caller":"traceutil/trace.go:171","msg":"trace[1542274875] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25753; }","duration":"213.126213ms","start":"2026-04-27T21:52:36.947486Z","end":"2026-04-27T21:52:37.160612Z","steps":["trace[1542274875] 'agreement among raft nodes before linearized reading' (duration: 212.451241ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:53:42.58593Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006aab.wal"} {"level":"info","ts":"2026-04-27T21:57:35.296472Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25738} {"level":"info","ts":"2026-04-27T21:57:35.429234Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25738,"took":"128.830331ms","hash":419698287,"current-db-size-bytes":58998784,"current-db-size":"59 MB","current-db-size-in-use-bytes":37883904,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-04-27T21:57:35.429311Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":419698287,"revision":25738,"compact-revision":22748} {"level":"info","ts":"2026-04-27T21:58:38.034869Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1812db8446d3ac67","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-27T21:58:38.041979Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-27T21:58:38.042155Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-04-27T22:02:35.302947Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28618} {"level":"info","ts":"2026-04-27T22:02:35.455054Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28618,"took":"148.542607ms","hash":1060472891,"current-db-size-bytes":58998784,"current-db-size":"59 MB","current-db-size-in-use-bytes":31879168,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-27T22:02:35.455138Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1060472891,"revision":28618,"compact-revision":25738} {"level":"info","ts":"2026-04-27T22:07:35.308727Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30908} {"level":"info","ts":"2026-04-27T22:07:35.44Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30908,"took":"127.937296ms","hash":2880580760,"current-db-size-bytes":58998784,"current-db-size":"59 MB","current-db-size-in-use-bytes":31367168,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-27T22:07:35.44016Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2880580760,"revision":30908,"compact-revision":28618} {"level":"info","ts":"2026-04-27T22:12:35.314136Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33207} {"level":"info","ts":"2026-04-27T22:12:35.468341Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33207,"took":"149.131715ms","hash":1677238318,"current-db-size-bytes":60043264,"current-db-size":"60 MB","current-db-size-in-use-bytes":31809536,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-27T22:12:35.468415Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1677238318,"revision":33207,"compact-revision":30908} {"level":"info","ts":"2026-04-27T22:17:35.322746Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35504} {"level":"info","ts":"2026-04-27T22:17:35.460976Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35504,"took":"133.641676ms","hash":3684579272,"current-db-size-bytes":60887040,"current-db-size":"61 MB","current-db-size-in-use-bytes":31608832,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-27T22:17:35.461056Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3684579272,"revision":35504,"compact-revision":33207} {"level":"info","ts":"2026-04-27T22:19:53.99547Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1812db8446d3ac67","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-27T22:19:54.000523Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-04-27T22:19:54.000642Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}