{"level":"warn","ts":"2026-04-22T13:11:33.747659Z","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-22T13:11:33.747764Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89: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.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89: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-22T13:11:33.747836Z","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-22T13:11:33.747855Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-04-22T13:11:33.747871Z","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-22T13:11:33.748644Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-04-22T13:11:33.748717Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89: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-22T13:11:33.753132Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.200294ms"} {"level":"info","ts":"2026-04-22T13:11:33.758807Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-04-22T13:11:33.758936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T13:11:33.75897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-04-22T13:11:33.758983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-22T13:11:33.758993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-04-22T13:11:33.759045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-04-22T13:11:33.763479Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T13:11:33.764885Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T13:11:33.766052Z","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-22T13:11:33.768275Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-22T13:11:33.768798Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-22T13:11:33.768908Z","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-22T13:11:33.769026Z","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-22T13:11:33.769082Z","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-22T13:11:33.770813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-04-22T13:11:33.771139Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-04-22T13:11:33.773616Z","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-22T13:11:33.773835Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-04-22T13:11:33.773963Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-22T13:11:33.774014Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T13:11:33.773966Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-04-22T13:11:34.659248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T13:11:34.659334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T13:11:34.659379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-04-22T13:11:34.659399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-04-22T13:11:34.659416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-04-22T13:11:34.659433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-04-22T13:11:34.659454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-04-22T13:11:34.660328Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T13:11:34.66107Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T13:11:34.661068Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-04-22T13:11:34.661136Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T13:11:34.661437Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T13:11:34.661548Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T13:11:34.661841Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T13:11:34.662034Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T13:11:34.662086Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T13:11:34.664081Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-22T13:11:34.666376Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-04-22T13:11:57.187853Z","caller":"traceutil/trace.go:171","msg":"trace[613194884] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"233.870048ms","start":"2026-04-22T13:11:56.953949Z","end":"2026-04-22T13:11:57.187819Z","steps":["trace[613194884] 'process raft request' (duration: 233.665583ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:06.174879Z","caller":"traceutil/trace.go:171","msg":"trace[311515025] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"124.837529ms","start":"2026-04-22T13:12:06.050019Z","end":"2026-04-22T13:12:06.174857Z","steps":["trace[311515025] 'process raft request' (duration: 122.337958ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:06.189934Z","caller":"traceutil/trace.go:171","msg":"trace[838363230] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"137.580846ms","start":"2026-04-22T13:12:06.05232Z","end":"2026-04-22T13:12:06.189901Z","steps":["trace[838363230] 'process raft request' (duration: 133.191097ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:06.191059Z","caller":"traceutil/trace.go:171","msg":"trace[1820934715] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"122.725425ms","start":"2026-04-22T13:12:06.068319Z","end":"2026-04-22T13:12:06.191045Z","steps":["trace[1820934715] 'process raft request' (duration: 121.491521ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:12.913894Z","caller":"traceutil/trace.go:171","msg":"trace[1794907134] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"159.754912ms","start":"2026-04-22T13:12:12.754106Z","end":"2026-04-22T13:12:12.913861Z","steps":["trace[1794907134] 'process raft request' (duration: 159.574879ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:12.973802Z","caller":"traceutil/trace.go:171","msg":"trace[1321655467] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:603; }","duration":"154.824514ms","start":"2026-04-22T13:12:12.818942Z","end":"2026-04-22T13:12:12.973766Z","steps":["trace[1321655467] 'read index received' (duration: 94.68076ms)","trace[1321655467] 'applied index is now lower than readState.Index' (duration: 60.142744ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:12:12.973944Z","caller":"traceutil/trace.go:171","msg":"trace[656839329] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"181.851608ms","start":"2026-04-22T13:12:12.792058Z","end":"2026-04-22T13:12:12.97391Z","steps":["trace[656839329] 'process raft request' (duration: 164.749713ms)","trace[656839329] 'compare' (duration: 16.605905ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:12:12.97414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.007817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qsbhc\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-22T13:12:12.97422Z","caller":"traceutil/trace.go:171","msg":"trace[1437802827] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qsbhc; range_end:; response_count:1; response_revision:583; }","duration":"155.307643ms","start":"2026-04-22T13:12:12.818897Z","end":"2026-04-22T13:12:12.974204Z","steps":["trace[1437802827] 'agreement among raft nodes before linearized reading' (duration: 154.986547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:13.895768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.963001ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:12:13.895897Z","caller":"traceutil/trace.go:171","msg":"trace[711782944] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:583; }","duration":"357.103033ms","start":"2026-04-22T13:12:13.538776Z","end":"2026-04-22T13:12:13.895879Z","steps":["trace[711782944] 'range keys from in-memory index tree' (duration: 356.94818ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:12:13.896001Z","caller":"traceutil/trace.go:171","msg":"trace[636308254] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"901.489503ms","start":"2026-04-22T13:12:12.994475Z","end":"2026-04-22T13:12:13.895964Z","steps":["trace[636308254] 'process raft request' (duration: 895.726327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:13.896766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:12.994448Z","time spent":"901.640367ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:12:13.907826Z","caller":"traceutil/trace.go:171","msg":"trace[1295746619] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"870.423316ms","start":"2026-04-22T13:12:13.037368Z","end":"2026-04-22T13:12:13.907791Z","steps":["trace[1295746619] 'process raft request' (duration: 870.289403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:13.908147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:13.037342Z","time spent":"870.567049ms","remote":"127.0.0.1:55918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T13:12:13.930995Z","caller":"traceutil/trace.go:171","msg":"trace[2040496354] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:605; }","duration":"539.596724ms","start":"2026-04-22T13:12:13.39137Z","end":"2026-04-22T13:12:13.930966Z","steps":["trace[2040496354] 'read index received' (duration: 498.846762ms)","trace[2040496354] 'applied index is now lower than readState.Index' (duration: 40.748092ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:12:13.931218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.823898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-22T13:12:13.931262Z","caller":"traceutil/trace.go:171","msg":"trace[2137014370] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:585; }","duration":"539.91951ms","start":"2026-04-22T13:12:13.391328Z","end":"2026-04-22T13:12:13.931248Z","steps":["trace[2137014370] 'agreement among raft nodes before linearized reading' (duration: 539.805768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:13.931303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:12:13.391308Z","time spent":"539.985011ms","remote":"127.0.0.1:55952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T13:12:13.931632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.714691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:12:13.931672Z","caller":"traceutil/trace.go:171","msg":"trace[693572196] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:585; }","duration":"210.785532ms","start":"2026-04-22T13:12:13.720874Z","end":"2026-04-22T13:12:13.931659Z","steps":["trace[693572196] 'agreement among raft nodes before linearized reading' (duration: 210.719041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:13.932338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.330324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:12:13.932399Z","caller":"traceutil/trace.go:171","msg":"trace[1351315162] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:585; }","duration":"211.380954ms","start":"2026-04-22T13:12:13.720991Z","end":"2026-04-22T13:12:13.932372Z","steps":["trace[1351315162] 'agreement among raft nodes before linearized reading' (duration: 211.314603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:13.932495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.498567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:12:13.932566Z","caller":"traceutil/trace.go:171","msg":"trace[1926915293] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:585; }","duration":"211.632509ms","start":"2026-04-22T13:12:13.720917Z","end":"2026-04-22T13:12:13.932549Z","steps":["trace[1926915293] 'agreement among raft nodes before linearized reading' (duration: 211.505106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:12:58.221179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.626647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:12:58.221274Z","caller":"traceutil/trace.go:171","msg":"trace[1418629650] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1140; }","duration":"153.77003ms","start":"2026-04-22T13:12:58.067484Z","end":"2026-04-22T13:12:58.221254Z","steps":["trace[1418629650] 'count revisions from in-memory index tree' (duration: 153.548886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:45.114445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.695786ms","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-22T13:13:45.114576Z","caller":"traceutil/trace.go:171","msg":"trace[799312549] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1810; }","duration":"243.874878ms","start":"2026-04-22T13:13:44.870674Z","end":"2026-04-22T13:13:45.114549Z","steps":["trace[799312549] 'range keys from in-memory index tree' (duration: 243.455801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:45.114965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.346589ms","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-22T13:13:45.11503Z","caller":"traceutil/trace.go:171","msg":"trace[2061113130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1810; }","duration":"355.454952ms","start":"2026-04-22T13:13:44.75956Z","end":"2026-04-22T13:13:45.115015Z","steps":["trace[2061113130] 'range keys from in-memory index tree' (duration: 355.080875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:45.11506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:13:44.759536Z","time spent":"355.517753ms","remote":"127.0.0.1:55952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T13:13:53.956996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.986491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-22T13:13:53.957084Z","caller":"traceutil/trace.go:171","msg":"trace[217045162] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1890; }","duration":"183.142275ms","start":"2026-04-22T13:13:53.773923Z","end":"2026-04-22T13:13:53.957065Z","steps":["trace[217045162] 'range keys from in-memory index tree' (duration: 182.762826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:13:57.366546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.381709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T13:13:57.36667Z","caller":"traceutil/trace.go:171","msg":"trace[1644324039] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:1912; }","duration":"121.499531ms","start":"2026-04-22T13:13:57.245127Z","end":"2026-04-22T13:13:57.366626Z","steps":["trace[1644324039] 'count revisions from in-memory index tree' (duration: 121.276217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:14:30.603991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.858778ms","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-22T13:14:30.604085Z","caller":"traceutil/trace.go:171","msg":"trace[1125678713] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2106; }","duration":"188.015401ms","start":"2026-04-22T13:14:30.41605Z","end":"2026-04-22T13:14:30.604065Z","steps":["trace[1125678713] 'range keys from in-memory index tree' (duration: 187.570463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:14:30.604068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.773947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-22T13:14:30.604156Z","caller":"traceutil/trace.go:171","msg":"trace[1795039866] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2106; }","duration":"229.9024ms","start":"2026-04-22T13:14:30.37423Z","end":"2026-04-22T13:14:30.604133Z","steps":["trace[1795039866] 'range keys from in-memory index tree' (duration: 229.593354ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:14:30.631194Z","caller":"traceutil/trace.go:171","msg":"trace[1338988700] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"123.190545ms","start":"2026-04-22T13:14:30.507959Z","end":"2026-04-22T13:14:30.63115Z","steps":["trace[1338988700] 'process raft request' (duration: 122.796167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:14:30.888968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.748336ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T13:14:30.88907Z","caller":"traceutil/trace.go:171","msg":"trace[1552725937] linearizableReadLoop","detail":"{readStateIndex:2188; appliedIndex:2187; }","duration":"256.270857ms","start":"2026-04-22T13:14:30.632784Z","end":"2026-04-22T13:14:30.889055Z","steps":["trace[1552725937] 'read index received' (duration: 109.060612ms)","trace[1552725937] 'applied index is now lower than readState.Index' (duration: 147.208995ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:14:30.889118Z","caller":"traceutil/trace.go:171","msg":"trace[91516288] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"280.755817ms","start":"2026-04-22T13:14:30.60832Z","end":"2026-04-22T13:14:30.889076Z","steps":["trace[91516288] 'process raft request' (duration: 133.641164ms)","trace[91516288] 'compare' (duration: 146.103063ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:14:30.88926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.574233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-22T13:14:30.889302Z","caller":"traceutil/trace.go:171","msg":"trace[1313400998] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2108; }","duration":"122.650494ms","start":"2026-04-22T13:14:30.766641Z","end":"2026-04-22T13:14:30.889292Z","steps":["trace[1313400998] 'agreement among raft nodes before linearized reading' (duration: 122.504131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:14:30.889575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.674595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:14:30.889788Z","caller":"traceutil/trace.go:171","msg":"trace[1451096961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2108; }","duration":"256.98543ms","start":"2026-04-22T13:14:30.632719Z","end":"2026-04-22T13:14:30.889705Z","steps":["trace[1451096961] 'agreement among raft nodes before linearized reading' (duration: 256.375809ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:15:23.868373Z","caller":"traceutil/trace.go:171","msg":"trace[993000906] linearizableReadLoop","detail":"{readStateIndex:2666; appliedIndex:2665; }","duration":"213.697097ms","start":"2026-04-22T13:15:23.654658Z","end":"2026-04-22T13:15:23.868355Z","steps":["trace[993000906] 'read index received' (duration: 213.551894ms)","trace[993000906] 'applied index is now lower than readState.Index' (duration: 144.393µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:15:23.868472Z","caller":"traceutil/trace.go:171","msg":"trace[2120481641] transaction","detail":"{read_only:false; response_revision:2566; number_of_response:1; }","duration":"217.366081ms","start":"2026-04-22T13:15:23.651099Z","end":"2026-04-22T13:15:23.868465Z","steps":["trace[2120481641] 'process raft request' (duration: 217.115446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:15:23.868673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.002953ms","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-22T13:15:23.868705Z","caller":"traceutil/trace.go:171","msg":"trace[2073482171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2566; }","duration":"214.046204ms","start":"2026-04-22T13:15:23.65465Z","end":"2026-04-22T13:15:23.868696Z","steps":["trace[2073482171] 'agreement among raft nodes before linearized reading' (duration: 213.922702ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:15:23.887304Z","caller":"traceutil/trace.go:171","msg":"trace[639332669] transaction","detail":"{read_only:false; response_revision:2567; number_of_response:1; }","duration":"177.668266ms","start":"2026-04-22T13:15:23.709622Z","end":"2026-04-22T13:15:23.887291Z","steps":["trace[639332669] 'process raft request' (duration: 177.560044ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:15:23.88746Z","caller":"traceutil/trace.go:171","msg":"trace[1327384490] transaction","detail":"{read_only:false; response_revision:2568; number_of_response:1; }","duration":"136.940361ms","start":"2026-04-22T13:15:23.750496Z","end":"2026-04-22T13:15:23.887436Z","steps":["trace[1327384490] 'process raft request' (duration: 136.770388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:15:23.887529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.644257ms","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-22T13:15:23.887557Z","caller":"traceutil/trace.go:171","msg":"trace[258087980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2568; }","duration":"232.704387ms","start":"2026-04-22T13:15:23.654846Z","end":"2026-04-22T13:15:23.88755Z","steps":["trace[258087980] 'agreement among raft nodes before linearized reading' (duration: 232.624056ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:18:32.967723Z","caller":"traceutil/trace.go:171","msg":"trace[596935612] transaction","detail":"{read_only:false; response_revision:3788; number_of_response:1; }","duration":"105.77296ms","start":"2026-04-22T13:18:32.861926Z","end":"2026-04-22T13:18:32.967699Z","steps":["trace[596935612] 'process raft request' (duration: 105.637648ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:18:33.02238Z","caller":"traceutil/trace.go:171","msg":"trace[559203517] transaction","detail":"{read_only:false; response_revision:3789; number_of_response:1; }","duration":"158.064048ms","start":"2026-04-22T13:18:32.864282Z","end":"2026-04-22T13:18:33.022346Z","steps":["trace[559203517] 'process raft request' (duration: 157.807393ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:19:53.35272Z","caller":"traceutil/trace.go:171","msg":"trace[806230574] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4754; }","duration":"126.016225ms","start":"2026-04-22T13:19:53.226684Z","end":"2026-04-22T13:19:53.3527Z","steps":["trace[806230574] 'process raft request' (duration: 35.901739ms)","trace[806230574] 'compare' (duration: 89.735057ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:19:57.797855Z","caller":"traceutil/trace.go:171","msg":"trace[1675644258] transaction","detail":"{read_only:false; response_revision:4850; number_of_response:1; }","duration":"103.448682ms","start":"2026-04-22T13:19:57.694375Z","end":"2026-04-22T13:19:57.797823Z","steps":["trace[1675644258] 'process raft request' (duration: 90.777299ms)","trace[1675644258] 'compare' (duration: 12.5394ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:20:01.113164Z","caller":"traceutil/trace.go:171","msg":"trace[1841645105] linearizableReadLoop","detail":"{readStateIndex:5184; appliedIndex:5183; }","duration":"191.158748ms","start":"2026-04-22T13:20:00.921969Z","end":"2026-04-22T13:20:01.113128Z","steps":["trace[1841645105] 'read index received' (duration: 110.343169ms)","trace[1841645105] 'applied index is now lower than readState.Index' (duration: 80.814169ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:20:01.113369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.390134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7c25d8e1-50aa-408a-ae16-84a39cafb98a\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-04-22T13:20:01.113396Z","caller":"traceutil/trace.go:171","msg":"trace[1221934766] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7c25d8e1-50aa-408a-ae16-84a39cafb98a; range_end:; response_count:1; response_revision:4994; }","duration":"191.499216ms","start":"2026-04-22T13:20:00.921887Z","end":"2026-04-22T13:20:01.113386Z","steps":["trace[1221934766] 'agreement among raft nodes before linearized reading' (duration: 191.345092ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:20:01.113501Z","caller":"traceutil/trace.go:171","msg":"trace[1937743471] transaction","detail":"{read_only:false; response_revision:4994; number_of_response:1; }","duration":"213.057198ms","start":"2026-04-22T13:20:00.900321Z","end":"2026-04-22T13:20:01.113378Z","steps":["trace[1937743471] 'process raft request' (duration: 131.949854ms)","trace[1937743471] 'compare' (duration: 80.679006ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:20:10.416386Z","caller":"traceutil/trace.go:171","msg":"trace[1822008867] transaction","detail":"{read_only:false; response_revision:5194; number_of_response:1; }","duration":"106.275371ms","start":"2026-04-22T13:20:10.310048Z","end":"2026-04-22T13:20:10.416323Z","steps":["trace[1822008867] 'process raft request' (duration: 48.687036ms)","trace[1822008867] 'compare' (duration: 57.299169ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:20:10.593293Z","caller":"traceutil/trace.go:171","msg":"trace[1949831883] transaction","detail":"{read_only:false; response_revision:5200; number_of_response:1; }","duration":"103.921174ms","start":"2026-04-22T13:20:10.489347Z","end":"2026-04-22T13:20:10.593269Z","steps":["trace[1949831883] 'process raft request' (duration: 33.146625ms)","trace[1949831883] 'compare' (duration: 70.235438ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:20:25.712952Z","caller":"traceutil/trace.go:171","msg":"trace[1368356994] linearizableReadLoop","detail":"{readStateIndex:5665; appliedIndex:5664; }","duration":"119.072527ms","start":"2026-04-22T13:20:25.593834Z","end":"2026-04-22T13:20:25.712907Z","steps":["trace[1368356994] 'read index received' (duration: 118.620168ms)","trace[1368356994] 'applied index is now lower than readState.Index' (duration: 450.629µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:20:25.713018Z","caller":"traceutil/trace.go:171","msg":"trace[1781396637] transaction","detail":"{read_only:false; response_revision:5462; number_of_response:1; }","duration":"137.097429ms","start":"2026-04-22T13:20:25.575874Z","end":"2026-04-22T13:20:25.712971Z","steps":["trace[1781396637] 'process raft request' (duration: 136.63786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:20:25.713204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.333762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:20:25.713509Z","caller":"traceutil/trace.go:171","msg":"trace[1621128737] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5462; }","duration":"119.661849ms","start":"2026-04-22T13:20:25.593798Z","end":"2026-04-22T13:20:25.71346Z","steps":["trace[1621128737] 'agreement among raft nodes before linearized reading' (duration: 119.290112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:20:25.771995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.496946ms","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-22T13:20:25.77208Z","caller":"traceutil/trace.go:171","msg":"trace[1398861188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5463; }","duration":"133.624349ms","start":"2026-04-22T13:20:25.638433Z","end":"2026-04-22T13:20:25.772057Z","steps":["trace[1398861188] 'agreement among raft nodes before linearized reading' (duration: 133.364704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:20:25.772133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.373755ms","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-22T13:20:25.772273Z","caller":"traceutil/trace.go:171","msg":"trace[1070443910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5463; }","duration":"152.548169ms","start":"2026-04-22T13:20:25.619696Z","end":"2026-04-22T13:20:25.772244Z","steps":["trace[1070443910] 'agreement among raft nodes before linearized reading' (duration: 152.311844ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:20:25.772337Z","caller":"traceutil/trace.go:171","msg":"trace[350038759] transaction","detail":"{read_only:false; response_revision:5463; number_of_response:1; }","duration":"144.745071ms","start":"2026-04-22T13:20:25.627577Z","end":"2026-04-22T13:20:25.772322Z","steps":["trace[350038759] 'process raft request' (duration: 143.993476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:20:25.772532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.888513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-22T13:20:25.7727Z","caller":"traceutil/trace.go:171","msg":"trace[637522024] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5463; }","duration":"123.051646ms","start":"2026-04-22T13:20:25.649627Z","end":"2026-04-22T13:20:25.772678Z","steps":["trace[637522024] 'agreement among raft nodes before linearized reading' (duration: 122.801861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:20:26.098341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.87128ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T13:20:26.098607Z","caller":"traceutil/trace.go:171","msg":"trace[1728005592] transaction","detail":"{read_only:false; response_revision:5464; number_of_response:1; }","duration":"319.093799ms","start":"2026-04-22T13:20:25.77946Z","end":"2026-04-22T13:20:26.098554Z","steps":["trace[1728005592] 'process raft request' (duration: 73.907972ms)","trace[1728005592] 'compare' (duration: 244.727878ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:20:26.098668Z","caller":"traceutil/trace.go:171","msg":"trace[1023017996] transaction","detail":"{read_only:false; response_revision:5465; number_of_response:1; }","duration":"318.755471ms","start":"2026-04-22T13:20:25.779877Z","end":"2026-04-22T13:20:26.098633Z","steps":["trace[1023017996] 'process raft request' (duration: 318.584228ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:20:26.098716Z","caller":"traceutil/trace.go:171","msg":"trace[1085580258] transaction","detail":"{read_only:false; response_revision:5466; number_of_response:1; }","duration":"317.010947ms","start":"2026-04-22T13:20:25.78168Z","end":"2026-04-22T13:20:26.098691Z","steps":["trace[1085580258] 'process raft request' (duration: 316.850473ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:20:26.098744Z","caller":"traceutil/trace.go:171","msg":"trace[985934499] linearizableReadLoop","detail":"{readStateIndex:5669; appliedIndex:5666; }","duration":"315.383404ms","start":"2026-04-22T13:20:25.78334Z","end":"2026-04-22T13:20:26.098723Z","steps":["trace[985934499] 'read index received' (duration: 70.049824ms)","trace[985934499] 'applied index is now lower than readState.Index' (duration: 245.33199ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:20:26.098777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:20:25.779434Z","time spent":"319.257152ms","remote":"127.0.0.1:55952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:20:26.098842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:20:25.781658Z","time spent":"317.118318ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3750,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:20:26.098866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:20:25.779836Z","time spent":"318.916385ms","remote":"127.0.0.1:55952","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-22T13:20:26.099054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.450485ms","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-22T13:20:26.0991Z","caller":"traceutil/trace.go:171","msg":"trace[1917889346] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5467; }","duration":"310.517756ms","start":"2026-04-22T13:20:25.788567Z","end":"2026-04-22T13:20:26.099085Z","steps":["trace[1917889346] 'agreement among raft nodes before linearized reading' (duration: 310.317342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:20:26.099097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.750301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"warn","ts":"2026-04-22T13:20:26.099123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.745257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wbbgq\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-22T13:20:26.09915Z","caller":"traceutil/trace.go:171","msg":"trace[79011479] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5467; }","duration":"315.807692ms","start":"2026-04-22T13:20:25.783327Z","end":"2026-04-22T13:20:26.099134Z","steps":["trace[79011479] 'agreement among raft nodes before linearized reading' (duration: 315.457105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:20:26.099148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:20:25.788556Z","time spent":"310.576707ms","remote":"127.0.0.1:55952","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":"warn","ts":"2026-04-22T13:20:26.099178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:20:25.783309Z","time spent":"315.860963ms","remote":"127.0.0.1:55842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1959,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-04-22T13:20:26.09918Z","caller":"traceutil/trace.go:171","msg":"trace[812155347] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wbbgq; range_end:; response_count:1; response_revision:5467; }","duration":"243.87936ms","start":"2026-04-22T13:20:25.855282Z","end":"2026-04-22T13:20:26.099162Z","steps":["trace[812155347] 'agreement among raft nodes before linearized reading' (duration: 243.627275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:20:26.099315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.055398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-22T13:20:26.099431Z","caller":"traceutil/trace.go:171","msg":"trace[1158251558] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5467; }","duration":"135.280062ms","start":"2026-04-22T13:20:25.96412Z","end":"2026-04-22T13:20:26.0994Z","steps":["trace[1158251558] 'agreement among raft nodes before linearized reading' (duration: 134.773562ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:20:26.556388Z","caller":"traceutil/trace.go:171","msg":"trace[1179540379] transaction","detail":"{read_only:false; response_revision:5469; number_of_response:1; }","duration":"442.748028ms","start":"2026-04-22T13:20:26.113612Z","end":"2026-04-22T13:20:26.55636Z","steps":["trace[1179540379] 'process raft request' (duration: 440.187837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:20:26.556491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.042954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T13:20:26.556377Z","caller":"traceutil/trace.go:171","msg":"trace[1011781665] linearizableReadLoop","detail":"{readStateIndex:5672; appliedIndex:5671; }","duration":"364.479969ms","start":"2026-04-22T13:20:26.19186Z","end":"2026-04-22T13:20:26.55634Z","steps":["trace[1011781665] 'read index received' (duration: 361.935497ms)","trace[1011781665] 'applied index is now lower than readState.Index' (duration: 2.542752ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:20:26.556542Z","caller":"traceutil/trace.go:171","msg":"trace[912595696] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5469; }","duration":"445.111595ms","start":"2026-04-22T13:20:26.111419Z","end":"2026-04-22T13:20:26.55653Z","steps":["trace[912595696] 'agreement among raft nodes before linearized reading' (duration: 445.009533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:20:26.556538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:20:26.113596Z","time spent":"442.85439ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13643,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:20:26.556584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:20:26.111367Z","time spent":"445.206347ms","remote":"127.0.0.1:44896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-22T13:20:26.556559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.243415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:20:26.55666Z","caller":"traceutil/trace.go:171","msg":"trace[1689158298] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:5469; }","duration":"410.410388ms","start":"2026-04-22T13:20:26.146222Z","end":"2026-04-22T13:20:26.556633Z","steps":["trace[1689158298] 'agreement among raft nodes before linearized reading' (duration: 410.220865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:20:26.556733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:20:26.146192Z","time spent":"410.523191ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true "} {"level":"info","ts":"2026-04-22T13:20:26.563647Z","caller":"traceutil/trace.go:171","msg":"trace[201259272] transaction","detail":"{read_only:false; response_revision:5470; number_of_response:1; }","duration":"124.488477ms","start":"2026-04-22T13:20:26.439138Z","end":"2026-04-22T13:20:26.563627Z","steps":["trace[201259272] 'process raft request' (duration: 124.238112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:20:26.563728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.893611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"warn","ts":"2026-04-22T13:20:26.563778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.287726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-543be6c7-5bb8-436a-b299-d52732db370d\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-04-22T13:20:26.563792Z","caller":"traceutil/trace.go:171","msg":"trace[1120610824] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5470; }","duration":"254.018714ms","start":"2026-04-22T13:20:26.309758Z","end":"2026-04-22T13:20:26.563777Z","steps":["trace[1120610824] 'agreement among raft nodes before linearized reading' (duration: 253.8355ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:20:26.56384Z","caller":"traceutil/trace.go:171","msg":"trace[1648715093] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-543be6c7-5bb8-436a-b299-d52732db370d; range_end:; response_count:1; response_revision:5470; }","duration":"224.388589ms","start":"2026-04-22T13:20:26.339436Z","end":"2026-04-22T13:20:26.563825Z","steps":["trace[1648715093] 'agreement among raft nodes before linearized reading' (duration: 224.203755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:20:26.56389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.289672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-22T13:20:26.563929Z","caller":"traceutil/trace.go:171","msg":"trace[1439389947] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5470; }","duration":"319.359984ms","start":"2026-04-22T13:20:26.244556Z","end":"2026-04-22T13:20:26.563916Z","steps":["trace[1439389947] 'agreement among raft nodes before linearized reading' (duration: 319.265202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:20:26.563961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:20:26.244532Z","time spent":"319.421614ms","remote":"127.0.0.1:55952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-22T13:20:26.563789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.798911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:20:26.564027Z","caller":"traceutil/trace.go:171","msg":"trace[2074409196] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5470; }","duration":"302.061726ms","start":"2026-04-22T13:20:26.261942Z","end":"2026-04-22T13:20:26.564004Z","steps":["trace[2074409196] 'agreement among raft nodes before linearized reading' (duration: 301.809911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:20:26.564105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:20:26.261916Z","time spent":"302.166869ms","remote":"127.0.0.1:56182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-04-22T13:20:37.882066Z","caller":"traceutil/trace.go:171","msg":"trace[938743180] transaction","detail":"{read_only:false; response_revision:5578; number_of_response:1; }","duration":"168.388116ms","start":"2026-04-22T13:20:37.71363Z","end":"2026-04-22T13:20:37.882018Z","steps":["trace[938743180] 'process raft request' (duration: 168.092671ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:20:37.882205Z","caller":"traceutil/trace.go:171","msg":"trace[283585847] transaction","detail":"{read_only:false; response_revision:5579; number_of_response:1; }","duration":"165.724263ms","start":"2026-04-22T13:20:37.716456Z","end":"2026-04-22T13:20:37.88218Z","steps":["trace[283585847] 'process raft request' (duration: 165.354335ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:20:37.882255Z","caller":"traceutil/trace.go:171","msg":"trace[706688693] transaction","detail":"{read_only:false; response_revision:5577; number_of_response:1; }","duration":"168.553739ms","start":"2026-04-22T13:20:37.713643Z","end":"2026-04-22T13:20:37.882197Z","steps":["trace[706688693] 'process raft request' (duration: 99.580017ms)","trace[706688693] 'compare' (duration: 68.328149ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:21:03.958896Z","caller":"traceutil/trace.go:171","msg":"trace[947573861] transaction","detail":"{read_only:false; response_revision:5734; number_of_response:1; }","duration":"154.253013ms","start":"2026-04-22T13:21:03.804621Z","end":"2026-04-22T13:21:03.958874Z","steps":["trace[947573861] 'process raft request' (duration: 153.979418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:21:15.702948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.931845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:21:15.703051Z","caller":"traceutil/trace.go:171","msg":"trace[603295579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5899; }","duration":"108.082579ms","start":"2026-04-22T13:21:15.594951Z","end":"2026-04-22T13:21:15.703034Z","steps":["trace[603295579] 'range keys from in-memory index tree' (duration: 107.857604ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:21:34.692699Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2974} {"level":"info","ts":"2026-04-22T13:21:34.772743Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2974,"took":"78.704188ms","hash":391642370,"current-db-size-bytes":58712064,"current-db-size":"59 MB","current-db-size-in-use-bytes":33775616,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-22T13:21:34.772842Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":391642370,"revision":2974,"compact-revision":-1} {"level":"warn","ts":"2026-04-22T13:26:23.646226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.795992ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-04-22T13:26:23.646386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.381614ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:26:23.646529Z","caller":"traceutil/trace.go:171","msg":"trace[1011693220] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8193; }","duration":"107.550917ms","start":"2026-04-22T13:26:23.538959Z","end":"2026-04-22T13:26:23.64651Z","steps":["trace[1011693220] 'range keys from in-memory index tree' (duration: 107.364453ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:26:23.647231Z","caller":"traceutil/trace.go:171","msg":"trace[1102413212] transaction","detail":"{read_only:false; response_revision:8194; number_of_response:1; }","duration":"336.585141ms","start":"2026-04-22T13:26:23.31061Z","end":"2026-04-22T13:26:23.647195Z","steps":["trace[1102413212] 'process raft request' (duration: 335.798016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:26:23.647419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:26:23.310578Z","time spent":"336.735215ms","remote":"127.0.0.1:55952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T13:26:23.718228Z","caller":"traceutil/trace.go:171","msg":"trace[1639342269] transaction","detail":"{read_only:false; response_revision:8195; number_of_response:1; }","duration":"111.928365ms","start":"2026-04-22T13:26:23.606276Z","end":"2026-04-22T13:26:23.718205Z","steps":["trace[1639342269] 'process raft request' (duration: 111.755952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:26:23.718594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.777901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T13:26:23.718627Z","caller":"traceutil/trace.go:171","msg":"trace[1349692679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8195; }","duration":"123.859333ms","start":"2026-04-22T13:26:23.59476Z","end":"2026-04-22T13:26:23.71862Z","steps":["trace[1349692679] 'agreement among raft nodes before linearized reading' (duration: 123.800012ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:26:34.874768Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6056} {"level":"warn","ts":"2026-04-22T13:26:34.875581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.132867ms","expected-duration":"100ms","prefix":"","request":"header: compaction: ","response":"size:5"} {"level":"info","ts":"2026-04-22T13:26:34.875702Z","caller":"traceutil/trace.go:171","msg":"trace[2111450832] compact","detail":"{revision:6056; response_revision:8256; }","duration":"155.813432ms","start":"2026-04-22T13:26:34.719872Z","end":"2026-04-22T13:26:34.875686Z","steps":["trace[2111450832] 'check and update compact revision' (duration: 149.986035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:26:35.173282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.007996ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T13:26:35.17356Z","caller":"traceutil/trace.go:171","msg":"trace[637618408] transaction","detail":"{read_only:false; response_revision:8257; number_of_response:1; }","duration":"145.029455ms","start":"2026-04-22T13:26:35.028459Z","end":"2026-04-22T13:26:35.173489Z","steps":["trace[637618408] 'process raft request' (duration: 33.717643ms)","trace[637618408] 'compare' (duration: 61.428437ms)","trace[637618408] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; req_size:536; } (duration: 49.428997ms)"],"step_count":3} {"level":"info","ts":"2026-04-22T13:26:35.6025Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6056,"took":"725.539569ms","hash":173403445,"current-db-size-bytes":58712064,"current-db-size":"59 MB","current-db-size-in-use-bytes":23502848,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-22T13:26:35.602598Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":173403445,"revision":6056,"compact-revision":2974} {"level":"info","ts":"2026-04-22T13:26:36.567158Z","caller":"traceutil/trace.go:171","msg":"trace[95659022] linearizableReadLoop","detail":"{readStateIndex:8557; appliedIndex:8556; }","duration":"280.842898ms","start":"2026-04-22T13:26:36.286288Z","end":"2026-04-22T13:26:36.567131Z","steps":["trace[95659022] 'read index received' (duration: 258.310745ms)","trace[95659022] 'applied index is now lower than readState.Index' (duration: 22.530803ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:26:36.567186Z","caller":"traceutil/trace.go:171","msg":"trace[2072941971] transaction","detail":"{read_only:false; response_revision:8263; number_of_response:1; }","duration":"336.728219ms","start":"2026-04-22T13:26:36.230438Z","end":"2026-04-22T13:26:36.567166Z","steps":["trace[2072941971] 'process raft request' (duration: 314.291749ms)","trace[2072941971] 'compare' (duration: 22.111903ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:26:36.567488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.168454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-22T13:26:36.567525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.716643ms","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-22T13:26:36.567601Z","caller":"traceutil/trace.go:171","msg":"trace[357862257] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8263; }","duration":"253.859196ms","start":"2026-04-22T13:26:36.313723Z","end":"2026-04-22T13:26:36.567582Z","steps":["trace[357862257] 'agreement among raft nodes before linearized reading' (duration: 253.587031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:26:36.567525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.410579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wbbgq\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-22T13:26:36.567771Z","caller":"traceutil/trace.go:171","msg":"trace[813847946] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wbbgq; range_end:; response_count:1; response_revision:8263; }","duration":"150.677204ms","start":"2026-04-22T13:26:36.417048Z","end":"2026-04-22T13:26:36.567725Z","steps":["trace[813847946] 'agreement among raft nodes before linearized reading' (duration: 150.391008ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:26:36.569071Z","caller":"traceutil/trace.go:171","msg":"trace[1103650472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8263; }","duration":"282.753936ms","start":"2026-04-22T13:26:36.286277Z","end":"2026-04-22T13:26:36.569031Z","steps":["trace[1103650472] 'agreement among raft nodes before linearized reading' (duration: 280.948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:26:36.572688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:26:36.23042Z","time spent":"336.866152ms","remote":"127.0.0.1:55952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:27:03.79509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.952594ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-22T13:27:03.796144Z","caller":"traceutil/trace.go:171","msg":"trace[1252232651] linearizableReadLoop","detail":"{readStateIndex:8762; appliedIndex:8760; }","duration":"420.801767ms","start":"2026-04-22T13:27:03.375298Z","end":"2026-04-22T13:27:03.7961Z","steps":["trace[1252232651] 'read index received' (duration: 210.72947ms)","trace[1252232651] 'applied index is now lower than readState.Index' (duration: 210.069237ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:27:03.796151Z","caller":"traceutil/trace.go:171","msg":"trace[938536030] transaction","detail":"{read_only:false; response_revision:8460; number_of_response:1; }","duration":"459.526263ms","start":"2026-04-22T13:27:03.336586Z","end":"2026-04-22T13:27:03.796112Z","steps":["trace[938536030] 'process raft request' (duration: 458.603775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:27:03.796386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:27:03.336561Z","time spent":"459.713837ms","remote":"127.0.0.1:55952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T13:27:03.805281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.92393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:414469"} {"level":"info","ts":"2026-04-22T13:27:03.805358Z","caller":"traceutil/trace.go:171","msg":"trace[1732226459] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:8460; }","duration":"430.048713ms","start":"2026-04-22T13:27:03.375291Z","end":"2026-04-22T13:27:03.80534Z","steps":["trace[1732226459] 'agreement among raft nodes before linearized reading' (duration: 420.890468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:27:03.805392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T13:27:03.37522Z","time spent":"430.165585ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":414493,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"info","ts":"2026-04-22T13:27:05.94788Z","caller":"traceutil/trace.go:171","msg":"trace[1885658186] transaction","detail":"{read_only:false; response_revision:8472; number_of_response:1; }","duration":"136.49199ms","start":"2026-04-22T13:27:05.81136Z","end":"2026-04-22T13:27:05.947852Z","steps":["trace[1885658186] 'process raft request' (duration: 136.295225ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:27:05.968191Z","caller":"traceutil/trace.go:171","msg":"trace[109436022] transaction","detail":"{read_only:false; response_revision:8473; number_of_response:1; }","duration":"137.158643ms","start":"2026-04-22T13:27:05.830979Z","end":"2026-04-22T13:27:05.968137Z","steps":["trace[109436022] 'process raft request' (duration: 136.898647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:27:15.974487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.62808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-77glg\" ","response":"range_response_count:1 size:4644"} {"level":"info","ts":"2026-04-22T13:27:15.974608Z","caller":"traceutil/trace.go:171","msg":"trace[837595360] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-77glg; range_end:; response_count:1; response_revision:8564; }","duration":"109.806073ms","start":"2026-04-22T13:27:15.864776Z","end":"2026-04-22T13:27:15.974582Z","steps":["trace[837595360] 'range keys from in-memory index tree' (duration: 109.479817ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:27:16.571792Z","caller":"traceutil/trace.go:171","msg":"trace[1530604249] transaction","detail":"{read_only:false; response_revision:8571; number_of_response:1; }","duration":"162.718465ms","start":"2026-04-22T13:27:16.409044Z","end":"2026-04-22T13:27:16.571762Z","steps":["trace[1530604249] 'process raft request' (duration: 162.542912ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:27:16.920371Z","caller":"traceutil/trace.go:171","msg":"trace[1283552487] linearizableReadLoop","detail":"{readStateIndex:8880; appliedIndex:8879; }","duration":"102.232142ms","start":"2026-04-22T13:27:16.818113Z","end":"2026-04-22T13:27:16.920345Z","steps":["trace[1283552487] 'read index received' (duration: 47.617556ms)","trace[1283552487] 'applied index is now lower than readState.Index' (duration: 54.613666ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:27:16.920536Z","caller":"traceutil/trace.go:171","msg":"trace[110881442] transaction","detail":"{read_only:false; response_revision:8573; number_of_response:1; }","duration":"119.386016ms","start":"2026-04-22T13:27:16.801119Z","end":"2026-04-22T13:27:16.920505Z","steps":["trace[110881442] 'process raft request' (duration: 64.558126ms)","trace[110881442] 'compare' (duration: 54.533494ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T13:27:16.920664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.530127ms","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-22T13:27:16.920711Z","caller":"traceutil/trace.go:171","msg":"trace[1962441853] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8573; }","duration":"102.64573ms","start":"2026-04-22T13:27:16.818052Z","end":"2026-04-22T13:27:16.920697Z","steps":["trace[1962441853] 'agreement among raft nodes before linearized reading' (duration: 102.454376ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:27:17.600232Z","caller":"traceutil/trace.go:171","msg":"trace[781513023] transaction","detail":"{read_only:false; response_revision:8581; number_of_response:1; }","duration":"196.366241ms","start":"2026-04-22T13:27:17.403844Z","end":"2026-04-22T13:27:17.60021Z","steps":["trace[781513023] 'process raft request' (duration: 196.077355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T13:27:17.881996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.902497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-04-22T13:27:17.882101Z","caller":"traceutil/trace.go:171","msg":"trace[847488639] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:8581; }","duration":"124.080601ms","start":"2026-04-22T13:27:17.758Z","end":"2026-04-22T13:27:17.882081Z","steps":["trace[847488639] 'range keys from in-memory index tree' (duration: 123.682263ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T13:27:40.10479Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023ce.wal"} {"level":"info","ts":"2026-04-22T13:28:20.358226Z","caller":"traceutil/trace.go:171","msg":"trace[1742285773] transaction","detail":"{read_only:false; response_revision:9183; number_of_response:1; }","duration":"106.676181ms","start":"2026-04-22T13:28:20.251522Z","end":"2026-04-22T13:28:20.358198Z","steps":["trace[1742285773] 'process raft request' (duration: 49.154136ms)","trace[1742285773] 'compare' (duration: 57.358681ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T13:29:20.210535Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c3b8446219deb8c1","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-22T13:29:20.218989Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-22T13:29:20.21911Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-22T13:31:34.886403Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8256} {"level":"info","ts":"2026-04-22T13:31:34.969393Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8256,"took":"80.721285ms","hash":2172844150,"current-db-size-bytes":58712064,"current-db-size":"59 MB","current-db-size-in-use-bytes":25112576,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-22T13:31:34.96947Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2172844150,"revision":8256,"compact-revision":6056} {"level":"info","ts":"2026-04-22T13:36:34.891983Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10682} {"level":"info","ts":"2026-04-22T13:36:34.966521Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10682,"took":"72.219338ms","hash":658745019,"current-db-size-bytes":58712064,"current-db-size":"59 MB","current-db-size-in-use-bytes":20402176,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-22T13:36:34.966581Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":658745019,"revision":10682,"compact-revision":8256}