{"level":"warn","ts":"2026-04-28T22:18:26.734587Z","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-28T22:18:26.7347Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138: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-28T22:18:26.734768Z","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-28T22:18:26.734781Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-04-28T22:18:26.734799Z","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-28T22:18:26.735399Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-04-28T22:18:26.735467Z","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":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138: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-28T22:18:26.73974Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.091878ms"} {"level":"info","ts":"2026-04-28T22:18:26.75103Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-04-28T22:18:26.751095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T22:18:26.751117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-04-28T22:18:26.751131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T22:18:26.75114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-04-28T22:18:26.751214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-04-28T22:18:26.759494Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T22:18:26.762111Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T22:18:26.763784Z","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-28T22:18:26.76821Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T22:18:26.768792Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T22:18:26.76895Z","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-28T22:18:26.769032Z","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-28T22:18:26.769047Z","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-28T22:18:26.769399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-04-28T22:18:26.769571Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-04-28T22:18:26.776343Z","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-28T22:18:26.777045Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T22:18:26.777077Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T22:18:26.777289Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-04-28T22:18:26.777365Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-04-28T22:18:27.451464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T22:18:27.451524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T22:18:27.45155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-04-28T22:18:27.451562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-04-28T22:18:27.451567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-04-28T22:18:27.451575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-04-28T22:18:27.451582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-04-28T22:18:27.724434Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T22:18:27.914216Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T22:18:27.914237Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T22:18:27.914303Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T22:18:27.914643Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T22:18:27.914733Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T22:18:27.91896Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"info","ts":"2026-04-28T22:18:27.919014Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T22:18:27.946681Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T22:18:27.94693Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T22:18:27.946979Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-28T22:18:56.607539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.72531ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-28T22:18:56.656727Z","caller":"traceutil/trace.go:171","msg":"trace[261668382] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"203.986167ms","start":"2026-04-28T22:18:56.452711Z","end":"2026-04-28T22:18:56.656697Z","steps":["trace[261668382] 'process raft request' (duration: 203.850586ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:56.733007Z","caller":"traceutil/trace.go:171","msg":"trace[1134425661] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"263.532243ms","start":"2026-04-28T22:18:56.469443Z","end":"2026-04-28T22:18:56.732976Z","steps":["trace[1134425661] 'process raft request' (duration: 263.19485ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:56.733354Z","caller":"traceutil/trace.go:171","msg":"trace[1891616674] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"263.901855ms","start":"2026-04-28T22:18:56.469431Z","end":"2026-04-28T22:18:56.733333Z","steps":["trace[1891616674] 'process raft request' (duration: 263.139469ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:56.733327Z","caller":"traceutil/trace.go:171","msg":"trace[1853636721] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"259.983647ms","start":"2026-04-28T22:18:56.47331Z","end":"2026-04-28T22:18:56.733294Z","steps":["trace[1853636721] 'process raft request' (duration: 259.507764ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:56.733391Z","caller":"traceutil/trace.go:171","msg":"trace[624271376] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"261.043614ms","start":"2026-04-28T22:18:56.472308Z","end":"2026-04-28T22:18:56.733352Z","steps":["trace[624271376] 'process raft request' (duration: 260.46478ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:56.733506Z","caller":"traceutil/trace.go:171","msg":"trace[1687971327] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"254.243173ms","start":"2026-04-28T22:18:56.479226Z","end":"2026-04-28T22:18:56.73347Z","steps":["trace[1687971327] 'process raft request' (duration: 253.666209ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:56.733433Z","caller":"traceutil/trace.go:171","msg":"trace[1926432587] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"261.981311ms","start":"2026-04-28T22:18:56.471372Z","end":"2026-04-28T22:18:56.733353Z","steps":["trace[1926432587] 'process raft request' (duration: 261.359417ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:56.733389Z","caller":"traceutil/trace.go:171","msg":"trace[1395956164] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"276.979144ms","start":"2026-04-28T22:18:56.456301Z","end":"2026-04-28T22:18:56.73328Z","steps":["trace[1395956164] 'process raft request' (duration: 275.935586ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:56.733625Z","caller":"traceutil/trace.go:171","msg":"trace[1579412301] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"227.659864ms","start":"2026-04-28T22:18:56.505844Z","end":"2026-04-28T22:18:56.733504Z","steps":["trace[1579412301] 'process raft request' (duration: 227.162821ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:56.733432Z","caller":"traceutil/trace.go:171","msg":"trace[426338433] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"239.089321ms","start":"2026-04-28T22:18:56.49432Z","end":"2026-04-28T22:18:56.733409Z","steps":["trace[426338433] 'process raft request' (duration: 238.628808ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:18:56.733303Z","caller":"traceutil/trace.go:171","msg":"trace[1429763956] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"276.836023ms","start":"2026-04-28T22:18:56.456391Z","end":"2026-04-28T22:18:56.733227Z","steps":["trace[1429763956] 'process raft request' (duration: 276.078537ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:01.982184Z","caller":"traceutil/trace.go:171","msg":"trace[2050617879] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"155.525773ms","start":"2026-04-28T22:19:01.826626Z","end":"2026-04-28T22:19:01.982152Z","steps":["trace[2050617879] 'process raft request' (duration: 149.641072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:02.29675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.94425ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T22:19:02.296932Z","caller":"traceutil/trace.go:171","msg":"trace[1895680491] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"265.609356ms","start":"2026-04-28T22:19:02.031308Z","end":"2026-04-28T22:19:02.296917Z","steps":["trace[1895680491] 'process raft request' (duration: 265.550585ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:02.297126Z","caller":"traceutil/trace.go:171","msg":"trace[1274242708] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"309.953078ms","start":"2026-04-28T22:19:01.987106Z","end":"2026-04-28T22:19:02.297059Z","steps":["trace[1274242708] 'process raft request' (duration: 94.251044ms)","trace[1274242708] 'compare' (duration: 214.774138ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:19:02.297307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:01.987082Z","time spent":"310.126029ms","remote":"127.0.0.1:53508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T22:19:02.918945Z","caller":"traceutil/trace.go:171","msg":"trace[1680078344] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"128.006629ms","start":"2026-04-28T22:19:02.790899Z","end":"2026-04-28T22:19:02.918905Z","steps":["trace[1680078344] 'process raft request' (duration: 63.378146ms)","trace[1680078344] 'compare' (duration: 64.401252ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:19:03.293649Z","caller":"traceutil/trace.go:171","msg":"trace[1389473562] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"179.444429ms","start":"2026-04-28T22:19:03.114179Z","end":"2026-04-28T22:19:03.293624Z","steps":["trace[1389473562] 'read index received' (duration: 135.03912ms)","trace[1389473562] 'applied index is now lower than readState.Index' (duration: 44.403869ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:19:03.293865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.65157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T22:19:03.293901Z","caller":"traceutil/trace.go:171","msg":"trace[1237689586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:617; }","duration":"179.744671ms","start":"2026-04-28T22:19:03.114145Z","end":"2026-04-28T22:19:03.29389Z","steps":["trace[1237689586] 'agreement among raft nodes before linearized reading' (duration: 179.57976ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:03.293948Z","caller":"traceutil/trace.go:171","msg":"trace[1111249467] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"283.101709ms","start":"2026-04-28T22:19:03.01082Z","end":"2026-04-28T22:19:03.293922Z","steps":["trace[1111249467] 'process raft request' (duration: 238.390048ms)","trace[1111249467] 'compare' (duration: 43.985045ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:19:04.021197Z","caller":"traceutil/trace.go:171","msg":"trace[679725684] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"661.054498ms","start":"2026-04-28T22:19:03.360097Z","end":"2026-04-28T22:19:04.021152Z","steps":["trace[679725684] 'process raft request' (duration: 646.962891ms)","trace[679725684] 'compare' (duration: 13.408233ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:19:04.021342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:03.360058Z","time spent":"661.196109ms","remote":"127.0.0.1:53326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T22:19:04.021456Z","caller":"traceutil/trace.go:171","msg":"trace[855440722] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"511.94449ms","start":"2026-04-28T22:19:03.509494Z","end":"2026-04-28T22:19:04.021439Z","steps":["trace[855440722] 'read index received' (duration: 497.31117ms)","trace[855440722] 'applied index is now lower than readState.Index' (duration: 14.6314ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:19:04.021817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.638322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T22:19:04.021825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.296743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-l826k\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-28T22:19:04.0219Z","caller":"traceutil/trace.go:171","msg":"trace[703028910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"106.790242ms","start":"2026-04-28T22:19:03.915088Z","end":"2026-04-28T22:19:04.021879Z","steps":["trace[703028910] 'agreement among raft nodes before linearized reading' (duration: 106.657252ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:04.021943Z","caller":"traceutil/trace.go:171","msg":"trace[1378087724] range","detail":"{range_begin:/registry/pods/kube-system/cilium-l826k; range_end:; response_count:1; response_revision:619; }","duration":"512.469635ms","start":"2026-04-28T22:19:03.509443Z","end":"2026-04-28T22:19:04.021912Z","steps":["trace[1378087724] 'agreement among raft nodes before linearized reading' (duration: 512.102981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:04.02198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.691573ms","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-28T22:19:04.022068Z","caller":"traceutil/trace.go:171","msg":"trace[943536028] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:619; }","duration":"303.855064ms","start":"2026-04-28T22:19:03.718197Z","end":"2026-04-28T22:19:04.022052Z","steps":["trace[943536028] 'agreement among raft nodes before linearized reading' (duration: 303.539492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:04.022009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:03.50942Z","time spent":"512.578795ms","remote":"127.0.0.1:53422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-l826k\" "} {"level":"warn","ts":"2026-04-28T22:19:04.022147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:03.718167Z","time spent":"303.969804ms","remote":"127.0.0.1:53508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T22:19:16.456023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.511099ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-04-28T22:19:18.943873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.689333ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T22:19:18.944042Z","caller":"traceutil/trace.go:171","msg":"trace[919469142] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:808; }","duration":"330.316228ms","start":"2026-04-28T22:19:18.613697Z","end":"2026-04-28T22:19:18.944013Z","steps":["trace[919469142] 'read index received' (duration: 103.362784ms)","trace[919469142] 'applied index is now lower than readState.Index' (duration: 226.951194ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:19:18.944454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.640191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-44hs4\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-28T22:19:18.944523Z","caller":"traceutil/trace.go:171","msg":"trace[1237369913] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-44hs4; range_end:; response_count:1; response_revision:780; }","duration":"330.871432ms","start":"2026-04-28T22:19:18.613626Z","end":"2026-04-28T22:19:18.944497Z","steps":["trace[1237369913] 'agreement among raft nodes before linearized reading' (duration: 330.46063ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:19:18.944518Z","caller":"traceutil/trace.go:171","msg":"trace[1765431343] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"470.681482ms","start":"2026-04-28T22:19:18.473787Z","end":"2026-04-28T22:19:18.944468Z","steps":["trace[1765431343] 'process raft request' (duration: 243.249914ms)","trace[1765431343] 'compare' (duration: 226.315221ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:19:18.944579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:18.613595Z","time spent":"330.966213ms","remote":"127.0.0.1:53422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3989,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-44hs4\" "} {"level":"warn","ts":"2026-04-28T22:19:18.944753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:18.473766Z","time spent":"470.848133ms","remote":"127.0.0.1:53508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T22:19:48.241562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.982781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:19:48.24166Z","caller":"traceutil/trace.go:171","msg":"trace[1605169479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1182; }","duration":"327.159589ms","start":"2026-04-28T22:19:47.91448Z","end":"2026-04-28T22:19:48.24164Z","steps":["trace[1605169479] 'range keys from in-memory index tree' (duration: 326.887658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:19:48.241697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:19:47.914458Z","time spent":"327.233602ms","remote":"127.0.0.1:53226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T22:20:33.650069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.897746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-04-28T22:20:33.650096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.696412ms","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-28T22:20:33.650198Z","caller":"traceutil/trace.go:171","msg":"trace[695444918] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1833; }","duration":"350.09196ms","start":"2026-04-28T22:20:33.30007Z","end":"2026-04-28T22:20:33.650162Z","steps":["trace[695444918] 'range keys from in-memory index tree' (duration: 349.699361ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:20:33.65022Z","caller":"traceutil/trace.go:171","msg":"trace[671715627] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1833; }","duration":"312.898927ms","start":"2026-04-28T22:20:33.337292Z","end":"2026-04-28T22:20:33.650191Z","steps":["trace[671715627] 'range keys from in-memory index tree' (duration: 312.406155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:20:33.650249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:20:33.300036Z","time spent":"350.200953ms","remote":"127.0.0.1:32798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-28T22:20:33.65027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:20:33.337262Z","time spent":"312.997549ms","remote":"127.0.0.1:53508","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":"info","ts":"2026-04-28T22:20:34.282706Z","caller":"traceutil/trace.go:171","msg":"trace[1338261022] linearizableReadLoop","detail":"{readStateIndex:1913; appliedIndex:1912; }","duration":"232.793429ms","start":"2026-04-28T22:20:34.049883Z","end":"2026-04-28T22:20:34.282676Z","steps":["trace[1338261022] 'read index received' (duration: 232.553552ms)","trace[1338261022] 'applied index is now lower than readState.Index' (duration: 238.637µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:20:34.283051Z","caller":"traceutil/trace.go:171","msg":"trace[1048616071] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"252.681917ms","start":"2026-04-28T22:20:34.030318Z","end":"2026-04-28T22:20:34.283Z","steps":["trace[1048616071] 'process raft request' (duration: 252.201835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:20:34.283149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.201089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-28T22:20:34.283204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.30456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-28T22:20:34.28325Z","caller":"traceutil/trace.go:171","msg":"trace[2090470636] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1839; }","duration":"123.391393ms","start":"2026-04-28T22:20:34.159835Z","end":"2026-04-28T22:20:34.283227Z","steps":["trace[2090470636] 'agreement among raft nodes before linearized reading' (duration: 123.202239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:20:34.283198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.553165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T22:20:34.283319Z","caller":"traceutil/trace.go:171","msg":"trace[1328195350] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1839; }","duration":"233.460524ms","start":"2026-04-28T22:20:34.049826Z","end":"2026-04-28T22:20:34.283287Z","steps":["trace[1328195350] 'agreement among raft nodes before linearized reading' (duration: 233.133416ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:20:34.283725Z","caller":"traceutil/trace.go:171","msg":"trace[718916570] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1839; }","duration":"159.795721ms","start":"2026-04-28T22:20:34.123504Z","end":"2026-04-28T22:20:34.283299Z","steps":["trace[718916570] 'agreement among raft nodes before linearized reading' (duration: 159.516665ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:21:09.457308Z","caller":"traceutil/trace.go:171","msg":"trace[569231129] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"258.561851ms","start":"2026-04-28T22:21:09.198363Z","end":"2026-04-28T22:21:09.456925Z","steps":["trace[569231129] 'process raft request' (duration: 258.195834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:21:09.726935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.513363ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T22:21:09.727127Z","caller":"traceutil/trace.go:171","msg":"trace[1396719379] linearizableReadLoop","detail":"{readStateIndex:2169; appliedIndex:2168; }","duration":"124.571573ms","start":"2026-04-28T22:21:09.602481Z","end":"2026-04-28T22:21:09.727053Z","steps":["trace[1396719379] 'read index received' (duration: 14.713034ms)","trace[1396719379] 'applied index is now lower than readState.Index' (duration: 109.856469ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:21:09.727483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.99919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-28T22:21:09.727297Z","caller":"traceutil/trace.go:171","msg":"trace[1241912029] transaction","detail":"{read_only:false; response_revision:2088; number_of_response:1; }","duration":"415.143698ms","start":"2026-04-28T22:21:09.312116Z","end":"2026-04-28T22:21:09.72726Z","steps":["trace[1241912029] 'process raft request' (duration: 305.161416ms)","trace[1241912029] 'compare' (duration: 109.228599ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:21:09.727546Z","caller":"traceutil/trace.go:171","msg":"trace[1258566242] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2088; }","duration":"125.101173ms","start":"2026-04-28T22:21:09.602426Z","end":"2026-04-28T22:21:09.727527Z","steps":["trace[1258566242] 'agreement among raft nodes before linearized reading' (duration: 124.838879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:21:09.727781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:21:09.312087Z","time spent":"415.495214ms","remote":"127.0.0.1:53508","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-28T22:21:10.110653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.605693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:21:10.110756Z","caller":"traceutil/trace.go:171","msg":"trace[1331613099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2089; }","duration":"196.758777ms","start":"2026-04-28T22:21:09.913977Z","end":"2026-04-28T22:21:10.110735Z","steps":["trace[1331613099] 'range keys from in-memory index tree' (duration: 196.532112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:21:10.110944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.814458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-28T22:21:10.111008Z","caller":"traceutil/trace.go:171","msg":"trace[989603815] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2089; }","duration":"197.205855ms","start":"2026-04-28T22:21:09.913785Z","end":"2026-04-28T22:21:10.110991Z","steps":["trace[989603815] 'range keys from in-memory index tree' (duration: 196.647736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:21:10.111053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.388514ms","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-28T22:21:10.111084Z","caller":"traceutil/trace.go:171","msg":"trace[1212150768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2089; }","duration":"228.454555ms","start":"2026-04-28T22:21:09.88262Z","end":"2026-04-28T22:21:10.111075Z","steps":["trace[1212150768] 'range keys from in-memory index tree' (duration: 228.204001ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:22:04.505672Z","caller":"traceutil/trace.go:171","msg":"trace[1994637842] transaction","detail":"{read_only:false; response_revision:2432; number_of_response:1; }","duration":"122.499036ms","start":"2026-04-28T22:22:04.383149Z","end":"2026-04-28T22:22:04.505648Z","steps":["trace[1994637842] 'process raft request' (duration: 122.436435ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:22:04.505807Z","caller":"traceutil/trace.go:171","msg":"trace[1835014715] transaction","detail":"{read_only:false; response_revision:2431; number_of_response:1; }","duration":"124.740293ms","start":"2026-04-28T22:22:04.381043Z","end":"2026-04-28T22:22:04.505783Z","steps":["trace[1835014715] 'process raft request' (duration: 24.717105ms)","trace[1835014715] 'compare' (duration: 99.668893ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:22:04.505802Z","caller":"traceutil/trace.go:171","msg":"trace[1847455710] linearizableReadLoop","detail":"{readStateIndex:2525; appliedIndex:2524; }","duration":"123.680961ms","start":"2026-04-28T22:22:04.382091Z","end":"2026-04-28T22:22:04.505772Z","steps":["trace[1847455710] 'read index received' (duration: 23.650383ms)","trace[1847455710] 'applied index is now lower than readState.Index' (duration: 100.027838ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:22:04.505987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.883613ms","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-28T22:22:04.50606Z","caller":"traceutil/trace.go:171","msg":"trace[846506451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2432; }","duration":"123.973334ms","start":"2026-04-28T22:22:04.38207Z","end":"2026-04-28T22:22:04.506043Z","steps":["trace[846506451] 'agreement among raft nodes before linearized reading' (duration: 123.786062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:22:04.506076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.828453ms","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-28T22:22:04.506113Z","caller":"traceutil/trace.go:171","msg":"trace[320196219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2432; }","duration":"123.892374ms","start":"2026-04-28T22:22:04.382211Z","end":"2026-04-28T22:22:04.506103Z","steps":["trace[320196219] 'agreement among raft nodes before linearized reading' (duration: 123.714572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:22:12.358193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.704957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-04-28T22:22:12.358269Z","caller":"traceutil/trace.go:171","msg":"trace[1754238047] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2525; }","duration":"323.818608ms","start":"2026-04-28T22:22:12.034434Z","end":"2026-04-28T22:22:12.358253Z","steps":["trace[1754238047] 'range keys from in-memory index tree' (duration: 323.566605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:22:12.358303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:22:12.034418Z","time spent":"323.87708ms","remote":"127.0.0.1:53434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1390,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-04-28T22:22:46.104436Z","caller":"traceutil/trace.go:171","msg":"trace[1435610708] transaction","detail":"{read_only:false; response_revision:2712; number_of_response:1; }","duration":"132.931506ms","start":"2026-04-28T22:22:45.971468Z","end":"2026-04-28T22:22:46.1044Z","steps":["trace[1435610708] 'process raft request' (duration: 132.614482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:22:46.511074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.959051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T22:22:46.511499Z","caller":"traceutil/trace.go:171","msg":"trace[282373194] transaction","detail":"{read_only:false; response_revision:2713; number_of_response:1; }","duration":"454.974963ms","start":"2026-04-28T22:22:46.056482Z","end":"2026-04-28T22:22:46.511457Z","steps":["trace[282373194] 'process raft request' (duration: 172.484016ms)","trace[282373194] 'compare' (duration: 281.745508ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:22:46.511722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:22:46.056458Z","time spent":"455.163454ms","remote":"127.0.0.1:53508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T22:22:46.767542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.313428ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-28T22:22:46.767729Z","caller":"traceutil/trace.go:171","msg":"trace[1582102219] linearizableReadLoop","detail":"{readStateIndex:2817; appliedIndex:2815; }","duration":"659.260387ms","start":"2026-04-28T22:22:46.108452Z","end":"2026-04-28T22:22:46.767712Z","steps":["trace[1582102219] 'read index received' (duration: 120.446222ms)","trace[1582102219] 'applied index is now lower than readState.Index' (duration: 538.813125ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:22:46.7679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.46017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1447"} {"level":"info","ts":"2026-04-28T22:22:46.767932Z","caller":"traceutil/trace.go:171","msg":"trace[418013504] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2713; }","duration":"659.54952ms","start":"2026-04-28T22:22:46.108367Z","end":"2026-04-28T22:22:46.767916Z","steps":["trace[418013504] 'agreement among raft nodes before linearized reading' (duration: 659.440419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:22:46.767973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:22:46.108347Z","time spent":"659.62018ms","remote":"127.0.0.1:53434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":1471,"request content":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" "} {"level":"info","ts":"2026-04-28T22:22:46.76825Z","caller":"traceutil/trace.go:171","msg":"trace[1782539596] transaction","detail":"{read_only:false; response_revision:2714; number_of_response:1; }","duration":"216.830627ms","start":"2026-04-28T22:22:46.551396Z","end":"2026-04-28T22:22:46.768226Z","steps":["trace[1782539596] 'process raft request' (duration: 216.237781ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:26:39.632523Z","caller":"traceutil/trace.go:171","msg":"trace[1264079528] transaction","detail":"{read_only:false; response_revision:4469; number_of_response:1; }","duration":"102.202779ms","start":"2026-04-28T22:26:39.530289Z","end":"2026-04-28T22:26:39.632492Z","steps":["trace[1264079528] 'process raft request' (duration: 101.716551ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:26:46.27158Z","caller":"traceutil/trace.go:171","msg":"trace[957660221] transaction","detail":"{read_only:false; response_revision:4677; number_of_response:1; }","duration":"199.592912ms","start":"2026-04-28T22:26:46.071956Z","end":"2026-04-28T22:26:46.271549Z","steps":["trace[957660221] 'process raft request' (duration: 199.411719ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:26:46.302958Z","caller":"traceutil/trace.go:171","msg":"trace[382893469] linearizableReadLoop","detail":"{readStateIndex:4857; appliedIndex:4855; }","duration":"197.072123ms","start":"2026-04-28T22:26:46.105862Z","end":"2026-04-28T22:26:46.302934Z","steps":["trace[382893469] 'read index received' (duration: 165.635132ms)","trace[382893469] 'applied index is now lower than readState.Index' (duration: 31.435741ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:26:46.303426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.52634ms","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-28T22:26:46.303495Z","caller":"traceutil/trace.go:171","msg":"trace[441839310] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4677; }","duration":"197.669932ms","start":"2026-04-28T22:26:46.105814Z","end":"2026-04-28T22:26:46.303484Z","steps":["trace[441839310] 'agreement among raft nodes before linearized reading' (duration: 197.438179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:26:46.303647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.225315ms","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-28T22:26:46.303739Z","caller":"traceutil/trace.go:171","msg":"trace[81797801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4677; }","duration":"178.350527ms","start":"2026-04-28T22:26:46.12537Z","end":"2026-04-28T22:26:46.303721Z","steps":["trace[81797801] 'agreement among raft nodes before linearized reading' (duration: 178.026242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:26:46.30373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.787898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-28T22:26:46.304036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.825024ms","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-28T22:26:46.304098Z","caller":"traceutil/trace.go:171","msg":"trace[479170334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4677; }","duration":"193.901135ms","start":"2026-04-28T22:26:46.110188Z","end":"2026-04-28T22:26:46.304089Z","steps":["trace[479170334] 'agreement among raft nodes before linearized reading' (duration: 193.707431ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:26:46.303963Z","caller":"traceutil/trace.go:171","msg":"trace[668929695] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4677; }","duration":"195.069433ms","start":"2026-04-28T22:26:46.108869Z","end":"2026-04-28T22:26:46.303939Z","steps":["trace[668929695] 'agreement among raft nodes before linearized reading' (duration: 194.519615ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:26:52.646758Z","caller":"traceutil/trace.go:171","msg":"trace[1001255747] transaction","detail":"{read_only:false; response_revision:4787; number_of_response:1; }","duration":"274.144666ms","start":"2026-04-28T22:26:52.372535Z","end":"2026-04-28T22:26:52.64668Z","steps":["trace[1001255747] 'process raft request' (duration: 273.941752ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:26:52.917647Z","caller":"traceutil/trace.go:171","msg":"trace[153249308] transaction","detail":"{read_only:false; response_revision:4788; number_of_response:1; }","duration":"543.888846ms","start":"2026-04-28T22:26:52.373725Z","end":"2026-04-28T22:26:52.917614Z","steps":["trace[153249308] 'process raft request' (duration: 485.860091ms)","trace[153249308] 'compare' (duration: 57.763081ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:26:52.917905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:26:52.373717Z","time spent":"544.026318ms","remote":"127.0.0.1:53508","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-28T22:26:52.918007Z","caller":"traceutil/trace.go:171","msg":"trace[1082948251] transaction","detail":"{read_only:false; response_revision:4789; number_of_response:1; }","duration":"543.619491ms","start":"2026-04-28T22:26:52.374359Z","end":"2026-04-28T22:26:52.917979Z","steps":["trace[1082948251] 'process raft request' (duration: 543.200295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:26:52.918181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:26:52.374351Z","time spent":"543.738354ms","remote":"127.0.0.1:53508","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-28T22:26:52.918443Z","caller":"traceutil/trace.go:171","msg":"trace[1767740615] linearizableReadLoop","detail":"{readStateIndex:4973; appliedIndex:4969; }","duration":"419.981526ms","start":"2026-04-28T22:26:52.498429Z","end":"2026-04-28T22:26:52.918411Z","steps":["trace[1767740615] 'read index received' (duration: 148.562911ms)","trace[1767740615] 'applied index is now lower than readState.Index' (duration: 271.417235ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:26:52.918413Z","caller":"traceutil/trace.go:171","msg":"trace[1282984288] transaction","detail":"{read_only:false; response_revision:4790; number_of_response:1; }","duration":"541.932476ms","start":"2026-04-28T22:26:52.376456Z","end":"2026-04-28T22:26:52.918389Z","steps":["trace[1282984288] 'process raft request' (duration: 541.432838ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:26:52.918515Z","caller":"traceutil/trace.go:171","msg":"trace[1087565618] transaction","detail":"{read_only:false; response_revision:4791; number_of_response:1; }","duration":"529.119503ms","start":"2026-04-28T22:26:52.389362Z","end":"2026-04-28T22:26:52.918482Z","steps":["trace[1087565618] 'process raft request' (duration: 528.940921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:26:52.918619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:26:52.376427Z","time spent":"542.120459ms","remote":"127.0.0.1:53508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T22:26:52.918652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:26:52.389349Z","time spent":"529.228975ms","remote":"127.0.0.1:45104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2229,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T22:26:52.919845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.401458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2348486"} {"level":"info","ts":"2026-04-28T22:26:52.919899Z","caller":"traceutil/trace.go:171","msg":"trace[1115600092] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4791; }","duration":"421.513629ms","start":"2026-04-28T22:26:52.498371Z","end":"2026-04-28T22:26:52.919884Z","steps":["trace[1115600092] 'agreement among raft nodes before linearized reading' (duration: 420.187869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:26:52.919934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:26:52.498348Z","time spent":"421.576401ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2348510,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-04-28T22:26:52.920564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.819834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-28T22:26:52.920609Z","caller":"traceutil/trace.go:171","msg":"trace[1411202813] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4793; }","duration":"309.927156ms","start":"2026-04-28T22:26:52.61067Z","end":"2026-04-28T22:26:52.920597Z","steps":["trace[1411202813] 'agreement among raft nodes before linearized reading' (duration: 309.718463ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:26:52.920625Z","caller":"traceutil/trace.go:171","msg":"trace[1750933630] transaction","detail":"{read_only:false; response_revision:4792; number_of_response:1; }","duration":"262.940698ms","start":"2026-04-28T22:26:52.65767Z","end":"2026-04-28T22:26:52.92061Z","steps":["trace[1750933630] 'process raft request' (duration: 262.546032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:26:52.921151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.636261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-04-28T22:26:52.921155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:26:52.610632Z","time spent":"310.513835ms","remote":"127.0.0.1:53508","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-28T22:26:52.921191Z","caller":"traceutil/trace.go:171","msg":"trace[764356855] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4793; }","duration":"301.756713ms","start":"2026-04-28T22:26:52.619422Z","end":"2026-04-28T22:26:52.921179Z","steps":["trace[764356855] 'agreement among raft nodes before linearized reading' (duration: 301.629241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:26:52.921227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:26:52.619402Z","time spent":"301.819785ms","remote":"127.0.0.1:53508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-28T22:26:53.460067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.69164ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T22:26:53.460307Z","caller":"traceutil/trace.go:171","msg":"trace[89110814] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"376.516277ms","start":"2026-04-28T22:26:53.083745Z","end":"2026-04-28T22:26:53.460262Z","steps":["trace[89110814] 'process raft request' (duration: 272.531702ms)","trace[89110814] 'compare' (duration: 103.334915ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:26:53.460392Z","caller":"traceutil/trace.go:171","msg":"trace[364875659] linearizableReadLoop","detail":"{readStateIndex:4986; appliedIndex:4984; }","duration":"376.432236ms","start":"2026-04-28T22:26:53.083938Z","end":"2026-04-28T22:26:53.460371Z","steps":["trace[364875659] 'read index received' (duration: 34.045222ms)","trace[364875659] 'applied index is now lower than readState.Index' (duration: 342.382094ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:26:53.460474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:26:53.083721Z","time spent":"376.652439ms","remote":"127.0.0.1:53514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1853,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T22:26:53.460565Z","caller":"traceutil/trace.go:171","msg":"trace[573675484] transaction","detail":"{read_only:false; response_revision:4805; number_of_response:1; }","duration":"376.559078ms","start":"2026-04-28T22:26:53.083997Z","end":"2026-04-28T22:26:53.460556Z","steps":["trace[573675484] 'process raft request' (duration: 376.206493ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:26:53.460797Z","caller":"traceutil/trace.go:171","msg":"trace[1373656200] transaction","detail":"{read_only:false; response_revision:4806; number_of_response:1; }","duration":"376.165462ms","start":"2026-04-28T22:26:53.084622Z","end":"2026-04-28T22:26:53.460787Z","steps":["trace[1373656200] 'process raft request' (duration: 375.656035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:26:53.46276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:26:53.084614Z","time spent":"378.053781ms","remote":"127.0.0.1:53514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1393,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T22:26:53.460832Z","caller":"traceutil/trace.go:171","msg":"trace[378525833] transaction","detail":"{read_only:false; response_revision:4807; number_of_response:1; }","duration":"356.368494ms","start":"2026-04-28T22:26:53.104458Z","end":"2026-04-28T22:26:53.460826Z","steps":["trace[378525833] 'process raft request' (duration: 355.855756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:26:53.461525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:26:53.083975Z","time spent":"377.206648ms","remote":"127.0.0.1:53396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":850,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T22:26:53.460988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.035895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-04-28T22:26:53.463114Z","caller":"traceutil/trace.go:171","msg":"trace[1914665623] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4807; }","duration":"379.185628ms","start":"2026-04-28T22:26:53.083895Z","end":"2026-04-28T22:26:53.46308Z","steps":["trace[1914665623] 'agreement among raft nodes before linearized reading' (duration: 376.932534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:26:53.463192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:26:53.083885Z","time spent":"379.287079ms","remote":"127.0.0.1:53326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":1366,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" "} {"level":"warn","ts":"2026-04-28T22:26:53.46314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:26:53.104448Z","time spent":"358.469806ms","remote":"127.0.0.1:53308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":922,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T22:26:53.716776Z","caller":"traceutil/trace.go:171","msg":"trace[1846530009] transaction","detail":"{read_only:false; response_revision:4810; number_of_response:1; }","duration":"148.025979ms","start":"2026-04-28T22:26:53.568711Z","end":"2026-04-28T22:26:53.716737Z","steps":["trace[1846530009] 'process raft request' (duration: 146.741879ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:26:54.831919Z","caller":"traceutil/trace.go:171","msg":"trace[1101616649] transaction","detail":"{read_only:false; response_revision:4820; number_of_response:1; }","duration":"175.1042ms","start":"2026-04-28T22:26:54.656701Z","end":"2026-04-28T22:26:54.831805Z","steps":["trace[1101616649] 'process raft request' (duration: 174.571582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:27:01.755237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.123142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-04-28T22:27:01.755359Z","caller":"traceutil/trace.go:171","msg":"trace[509620494] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4930; }","duration":"119.310105ms","start":"2026-04-28T22:27:01.636022Z","end":"2026-04-28T22:27:01.755332Z","steps":["trace[509620494] 'range keys from in-memory index tree' (duration: 118.716197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:27:01.755311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.247681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-04-28T22:27:01.755446Z","caller":"traceutil/trace.go:171","msg":"trace[885736274] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4930; }","duration":"160.450333ms","start":"2026-04-28T22:27:01.594964Z","end":"2026-04-28T22:27:01.755415Z","steps":["trace[885736274] 'range keys from in-memory index tree' (duration: 159.971666ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:27:04.6797Z","caller":"traceutil/trace.go:171","msg":"trace[1908818898] transaction","detail":"{read_only:false; response_revision:4949; number_of_response:1; }","duration":"265.454245ms","start":"2026-04-28T22:27:04.414216Z","end":"2026-04-28T22:27:04.67967Z","steps":["trace[1908818898] 'process raft request' (duration: 265.260952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:27:05.204189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.647045ms","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-28T22:27:05.204283Z","caller":"traceutil/trace.go:171","msg":"trace[998727522] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4949; }","duration":"230.810808ms","start":"2026-04-28T22:27:04.973451Z","end":"2026-04-28T22:27:05.204262Z","steps":["trace[998727522] 'range keys from in-memory index tree' (duration: 230.415932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:27:05.204659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.927418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-28T22:27:05.204708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.526628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-28T22:27:05.20475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.706438ms","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-28T22:27:05.204771Z","caller":"traceutil/trace.go:171","msg":"trace[1749138798] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4949; }","duration":"220.06854ms","start":"2026-04-28T22:27:04.984659Z","end":"2026-04-28T22:27:05.204727Z","steps":["trace[1749138798] 'range keys from in-memory index tree' (duration: 219.752385ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:27:05.204804Z","caller":"traceutil/trace.go:171","msg":"trace[1629739931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4949; }","duration":"226.783039ms","start":"2026-04-28T22:27:04.978003Z","end":"2026-04-28T22:27:05.204786Z","steps":["trace[1629739931] 'range keys from in-memory index tree' (duration: 226.580846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:27:05.204896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.280738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T22:27:05.204802Z","caller":"traceutil/trace.go:171","msg":"trace[82109483] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4949; }","duration":"290.626509ms","start":"2026-04-28T22:27:04.914153Z","end":"2026-04-28T22:27:05.204779Z","steps":["trace[82109483] 'range keys from in-memory index tree' (duration: 290.338285ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:27:05.204934Z","caller":"traceutil/trace.go:171","msg":"trace[1582535948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4949; }","duration":"223.338629ms","start":"2026-04-28T22:27:04.981586Z","end":"2026-04-28T22:27:05.204925Z","steps":["trace[1582535948] 'range keys from in-memory index tree' (duration: 223.172567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:27:05.204811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.304804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:27:05.204999Z","caller":"traceutil/trace.go:171","msg":"trace[972161613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4949; }","duration":"290.539558ms","start":"2026-04-28T22:27:04.914442Z","end":"2026-04-28T22:27:05.204982Z","steps":["trace[972161613] 'range keys from in-memory index tree' (duration: 290.193012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:27:05.205008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.453949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-28T22:27:05.205128Z","caller":"traceutil/trace.go:171","msg":"trace[151400754] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4949; }","duration":"195.591181ms","start":"2026-04-28T22:27:05.009484Z","end":"2026-04-28T22:27:05.205075Z","steps":["trace[151400754] 'range keys from in-memory index tree' (duration: 195.272366ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:27:05.329589Z","caller":"traceutil/trace.go:171","msg":"trace[600191906] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"105.596301ms","start":"2026-04-28T22:27:05.223937Z","end":"2026-04-28T22:27:05.329533Z","steps":["trace[600191906] 'process raft request' (duration: 84.659694ms)","trace[600191906] 'compare' (duration: 20.666513ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:27:05.759904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.384635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T22:27:05.760345Z","caller":"traceutil/trace.go:171","msg":"trace[2015892167] transaction","detail":"{read_only:false; response_revision:4964; number_of_response:1; }","duration":"166.93075ms","start":"2026-04-28T22:27:05.593386Z","end":"2026-04-28T22:27:05.760316Z","steps":["trace[2015892167] 'process raft request' (duration: 22.380958ms)","trace[2015892167] 'compare' (duration: 143.256823ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:27:06.451627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.275363ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T22:27:06.451766Z","caller":"traceutil/trace.go:171","msg":"trace[1557218074] transaction","detail":"{read_only:false; response_revision:4969; number_of_response:1; }","duration":"127.457736ms","start":"2026-04-28T22:27:06.324286Z","end":"2026-04-28T22:27:06.451744Z","steps":["trace[1557218074] 'process raft request' (duration: 12.964691ms)","trace[1557218074] 'compare' (duration: 114.09703ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:27:55.838916Z","caller":"traceutil/trace.go:171","msg":"trace[281110111] transaction","detail":"{read_only:false; response_revision:5394; number_of_response:1; }","duration":"188.344699ms","start":"2026-04-28T22:27:55.650545Z","end":"2026-04-28T22:27:55.83889Z","steps":["trace[281110111] 'process raft request' (duration: 188.185387ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:27:55.863143Z","caller":"traceutil/trace.go:171","msg":"trace[1119589271] transaction","detail":"{read_only:false; response_revision:5396; number_of_response:1; }","duration":"198.259041ms","start":"2026-04-28T22:27:55.664859Z","end":"2026-04-28T22:27:55.863118Z","steps":["trace[1119589271] 'process raft request' (duration: 198.16474ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:27:55.863364Z","caller":"traceutil/trace.go:171","msg":"trace[1521786794] transaction","detail":"{read_only:false; response_revision:5395; number_of_response:1; }","duration":"198.498684ms","start":"2026-04-28T22:27:55.664855Z","end":"2026-04-28T22:27:55.863354Z","steps":["trace[1521786794] 'process raft request' (duration: 198.008458ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:27:55.863488Z","caller":"traceutil/trace.go:171","msg":"trace[246545882] transaction","detail":"{read_only:false; response_revision:5397; number_of_response:1; }","duration":"124.261808ms","start":"2026-04-28T22:27:55.739196Z","end":"2026-04-28T22:27:55.863458Z","steps":["trace[246545882] 'process raft request' (duration: 123.876883ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:28:27.951862Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2933} {"level":"info","ts":"2026-04-28T22:28:28.031114Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2933,"took":"77.954511ms","hash":1939429615,"current-db-size-bytes":54706176,"current-db-size":"55 MB","current-db-size-in-use-bytes":29876224,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-28T22:28:28.031197Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1939429615,"revision":2933,"compact-revision":-1} {"level":"info","ts":"2026-04-28T22:30:45.014594Z","caller":"traceutil/trace.go:171","msg":"trace[1411620650] transaction","detail":"{read_only:false; response_revision:6599; number_of_response:1; }","duration":"135.220357ms","start":"2026-04-28T22:30:44.879349Z","end":"2026-04-28T22:30:45.01457Z","steps":["trace[1411620650] 'process raft request' (duration: 135.093196ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:30:45.014767Z","caller":"traceutil/trace.go:171","msg":"trace[598923877] linearizableReadLoop","detail":"{readStateIndex:6841; appliedIndex:6841; }","duration":"135.268738ms","start":"2026-04-28T22:30:44.879469Z","end":"2026-04-28T22:30:45.014738Z","steps":["trace[598923877] 'read index received' (duration: 135.258058ms)","trace[598923877] 'applied index is now lower than readState.Index' (duration: 8.55µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:30:45.015037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.530621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2643"} {"level":"info","ts":"2026-04-28T22:30:45.01509Z","caller":"traceutil/trace.go:171","msg":"trace[254295518] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:6599; }","duration":"135.625971ms","start":"2026-04-28T22:30:44.879452Z","end":"2026-04-28T22:30:45.015078Z","steps":["trace[254295518] 'agreement among raft nodes before linearized reading' (duration: 135.410109ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:30:45.033398Z","caller":"traceutil/trace.go:171","msg":"trace[1748257834] transaction","detail":"{read_only:false; number_of_response:1; response_revision:6599; }","duration":"153.513037ms","start":"2026-04-28T22:30:44.879869Z","end":"2026-04-28T22:30:45.033382Z","steps":["trace[1748257834] 'process raft request' (duration: 153.324335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:30:45.033436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.940973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:30:45.033508Z","caller":"traceutil/trace.go:171","msg":"trace[820271039] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6599; }","duration":"121.042103ms","start":"2026-04-28T22:30:44.912448Z","end":"2026-04-28T22:30:45.03349Z","steps":["trace[820271039] 'agreement among raft nodes before linearized reading' (duration: 120.894342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:30:45.033545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.549932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-04-28T22:30:45.033678Z","caller":"traceutil/trace.go:171","msg":"trace[381946639] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:6599; }","duration":"150.657814ms","start":"2026-04-28T22:30:44.882932Z","end":"2026-04-28T22:30:45.03359Z","steps":["trace[381946639] 'agreement among raft nodes before linearized reading' (duration: 150.421362ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:30:56.529621Z","caller":"traceutil/trace.go:171","msg":"trace[1621526708] linearizableReadLoop","detail":"{readStateIndex:6916; appliedIndex:6915; }","duration":"116.09804ms","start":"2026-04-28T22:30:56.413487Z","end":"2026-04-28T22:30:56.529585Z","steps":["trace[1621526708] 'read index received' (duration: 54.146571ms)","trace[1621526708] 'applied index is now lower than readState.Index' (duration: 61.950199ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:30:56.529923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.384083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:30:56.530011Z","caller":"traceutil/trace.go:171","msg":"trace[2013944459] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:6670; }","duration":"116.512634ms","start":"2026-04-28T22:30:56.413446Z","end":"2026-04-28T22:30:56.529958Z","steps":["trace[2013944459] 'agreement among raft nodes before linearized reading' (duration: 116.313872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:30:56.530092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.175764ms","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-28T22:30:56.530216Z","caller":"traceutil/trace.go:171","msg":"trace[1445206412] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6670; }","duration":"105.295666ms","start":"2026-04-28T22:30:56.424885Z","end":"2026-04-28T22:30:56.53018Z","steps":["trace[1445206412] 'agreement among raft nodes before linearized reading' (duration: 105.016482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:30:57.338521Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725917248847359,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T22:30:57.391616Z","caller":"traceutil/trace.go:171","msg":"trace[1638963553] linearizableReadLoop","detail":"{readStateIndex:6921; appliedIndex:6920; }","duration":"553.932922ms","start":"2026-04-28T22:30:56.837624Z","end":"2026-04-28T22:30:57.391557Z","steps":["trace[1638963553] 'read index received' (duration: 553.498476ms)","trace[1638963553] 'applied index is now lower than readState.Index' (duration: 433.236µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:30:57.392051Z","caller":"traceutil/trace.go:171","msg":"trace[1216821226] transaction","detail":"{read_only:false; response_revision:6675; number_of_response:1; }","duration":"573.527936ms","start":"2026-04-28T22:30:56.81851Z","end":"2026-04-28T22:30:57.392038Z","steps":["trace[1216821226] 'process raft request' (duration: 572.731498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:30:57.392323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:30:56.818499Z","time spent":"573.680088ms","remote":"127.0.0.1:53508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T22:30:57.392688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.762819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-28T22:30:57.392752Z","caller":"traceutil/trace.go:171","msg":"trace[2048801004] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6675; }","duration":"552.87074ms","start":"2026-04-28T22:30:56.839867Z","end":"2026-04-28T22:30:57.392738Z","steps":["trace[2048801004] 'agreement among raft nodes before linearized reading' (duration: 552.658816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:30:57.39279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:30:56.83985Z","time spent":"552.9315ms","remote":"127.0.0.1:53508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-28T22:30:57.393054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.148535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:30:57.393149Z","caller":"traceutil/trace.go:171","msg":"trace[1896715725] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:6675; }","duration":"486.193375ms","start":"2026-04-28T22:30:56.906891Z","end":"2026-04-28T22:30:57.393084Z","steps":["trace[1896715725] 'agreement among raft nodes before linearized reading' (duration: 486.121555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:30:57.393193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:30:56.906812Z","time spent":"486.371447ms","remote":"127.0.0.1:45196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-04-28T22:30:57.393361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.024191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-28T22:30:57.393638Z","caller":"traceutil/trace.go:171","msg":"trace[824599015] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:6675; }","duration":"256.336204ms","start":"2026-04-28T22:30:57.137276Z","end":"2026-04-28T22:30:57.393613Z","steps":["trace[824599015] 'agreement among raft nodes before linearized reading' (duration: 255.708507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:30:57.393924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.302888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T22:30:57.394023Z","caller":"traceutil/trace.go:171","msg":"trace[1197546374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6675; }","duration":"556.44634ms","start":"2026-04-28T22:30:56.83757Z","end":"2026-04-28T22:30:57.394016Z","steps":["trace[1197546374] 'agreement among raft nodes before linearized reading' (duration: 556.194367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:30:57.394108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:30:56.837554Z","time spent":"556.532842ms","remote":"127.0.0.1:53508","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":"warn","ts":"2026-04-28T22:30:57.394282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.565269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-28T22:30:57.394335Z","caller":"traceutil/trace.go:171","msg":"trace[389224055] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:6675; }","duration":"188.674121ms","start":"2026-04-28T22:30:57.205649Z","end":"2026-04-28T22:30:57.394324Z","steps":["trace[389224055] 'agreement among raft nodes before linearized reading' (duration: 188.545499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:30:57.393552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.789817ms","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-28T22:30:57.394498Z","caller":"traceutil/trace.go:171","msg":"trace[1986117983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6675; }","duration":"552.789898ms","start":"2026-04-28T22:30:56.841698Z","end":"2026-04-28T22:30:57.394488Z","steps":["trace[1986117983] 'agreement among raft nodes before linearized reading' (duration: 551.772917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:30:57.394549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:30:56.841665Z","time spent":"552.874ms","remote":"127.0.0.1:53508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T22:30:57.394505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.496372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:30:57.394778Z","caller":"traceutil/trace.go:171","msg":"trace[25072965] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:6675; }","duration":"101.779555ms","start":"2026-04-28T22:30:57.292961Z","end":"2026-04-28T22:30:57.394741Z","steps":["trace[25072965] 'agreement among raft nodes before linearized reading' (duration: 101.514572ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:30:58.761516Z","caller":"traceutil/trace.go:171","msg":"trace[175554358] linearizableReadLoop","detail":"{readStateIndex:6929; appliedIndex:6928; }","duration":"140.302395ms","start":"2026-04-28T22:30:58.621184Z","end":"2026-04-28T22:30:58.761486Z","steps":["trace[175554358] 'read index received' (duration: 130.010868ms)","trace[175554358] 'applied index is now lower than readState.Index' (duration: 10.290317ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:30:58.761665Z","caller":"traceutil/trace.go:171","msg":"trace[615947551] transaction","detail":"{read_only:false; response_revision:6683; number_of_response:1; }","duration":"195.358424ms","start":"2026-04-28T22:30:58.566263Z","end":"2026-04-28T22:30:58.761622Z","steps":["trace[615947551] 'process raft request' (duration: 184.917585ms)","trace[615947551] 'compare' (duration: 10.163797ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:30:58.762127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.905912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-28T22:30:58.762186Z","caller":"traceutil/trace.go:171","msg":"trace[374074694] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6683; }","duration":"141.017173ms","start":"2026-04-28T22:30:58.621153Z","end":"2026-04-28T22:30:58.76217Z","steps":["trace[374074694] 'agreement among raft nodes before linearized reading' (duration: 140.487417ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:30:58.917401Z","caller":"traceutil/trace.go:171","msg":"trace[1754308741] transaction","detail":"{read_only:false; response_revision:6685; number_of_response:1; }","duration":"142.257209ms","start":"2026-04-28T22:30:58.775126Z","end":"2026-04-28T22:30:58.917383Z","steps":["trace[1754308741] 'process raft request' (duration: 113.448559ms)","trace[1754308741] 'compare' (duration: 28.719928ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:30:58.927284Z","caller":"traceutil/trace.go:171","msg":"trace[486294233] transaction","detail":"{read_only:false; response_revision:6686; number_of_response:1; }","duration":"151.162349ms","start":"2026-04-28T22:30:58.776086Z","end":"2026-04-28T22:30:58.927249Z","steps":["trace[486294233] 'process raft request' (duration: 150.839465ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:33:27.962702Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5644} {"level":"info","ts":"2026-04-28T22:33:28.041511Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5644,"took":"76.489103ms","hash":2765125500,"current-db-size-bytes":54706176,"current-db-size":"55 MB","current-db-size-in-use-bytes":18403328,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-28T22:33:28.041571Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2765125500,"revision":5644,"compact-revision":2933}