{"level":"warn","ts":"2026-03-31T05:05:46.554013Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-31T05:05:46.554117Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.157: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.157:2380","--initial-cluster=instance=https://199.204.45.157:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.157:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.157: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-03-31T05:05:46.554187Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-31T05:05:46.554202Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-03-31T05:05:46.554224Z","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-03-31T05:05:46.554842Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"]} {"level":"info","ts":"2026-03-31T05:05:46.554928Z","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.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.157: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-03-31T05:05:46.561046Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.903175ms"} {"level":"info","ts":"2026-03-31T05:05:46.573826Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f170c75a1d97cc","cluster-id":"44a3176ec85feb9"} {"level":"info","ts":"2026-03-31T05:05:46.573947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=()"} {"level":"info","ts":"2026-03-31T05:05:46.57399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 0"} {"level":"info","ts":"2026-03-31T05:05:46.574011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f170c75a1d97cc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-31T05:05:46.574034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 1"} {"level":"info","ts":"2026-03-31T05:05:46.57409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"warn","ts":"2026-03-31T05:05:46.582032Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-31T05:05:46.584821Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-31T05:05:46.586341Z","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-03-31T05:05:46.589109Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f170c75a1d97cc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-31T05:05:46.59427Z","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-03-31T05:05:46.598046Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-03-31T05:05:46.598198Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-03-31T05:05:46.598353Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f170c75a1d97cc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-31T05:05:46.598476Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f170c75a1d97cc","initial-advertise-peer-urls":["https://199.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-31T05:05:46.598528Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-31T05:05:46.598503Z","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-03-31T05:05:46.598839Z","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-03-31T05:05:46.598868Z","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-03-31T05:05:46.599107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"info","ts":"2026-03-31T05:05:46.599317Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","added-peer-id":"5f170c75a1d97cc","added-peer-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-03-31T05:05:46.775207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc is starting a new election at term 1"} {"level":"info","ts":"2026-03-31T05:05:46.775307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became pre-candidate at term 1"} {"level":"info","ts":"2026-03-31T05:05:46.775353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgPreVoteResp from 5f170c75a1d97cc at term 1"} {"level":"info","ts":"2026-03-31T05:05:46.775376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became candidate at term 2"} {"level":"info","ts":"2026-03-31T05:05:46.775393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgVoteResp from 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-03-31T05:05:46.775409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became leader at term 2"} {"level":"info","ts":"2026-03-31T05:05:46.775428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f170c75a1d97cc elected leader 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-03-31T05:05:46.77641Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T05:05:46.777176Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f170c75a1d97cc","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.157:2379]}","request-path":"/0/members/5f170c75a1d97cc/attributes","cluster-id":"44a3176ec85feb9","publish-timeout":"7s"} {"level":"info","ts":"2026-03-31T05:05:46.777251Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T05:05:46.777401Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T05:05:46.777573Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T05:05:46.777916Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T05:05:46.777903Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-31T05:05:46.777969Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-31T05:05:46.777971Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T05:05:46.781175Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.157:2379"} {"level":"info","ts":"2026-03-31T05:05:46.781538Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-31T05:05:51.285108Z","caller":"traceutil/trace.go:171","msg":"trace[726339611] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"124.512458ms","start":"2026-03-31T05:05:51.160569Z","end":"2026-03-31T05:05:51.285081Z","steps":["trace[726339611] 'process raft request' (duration: 124.359595ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:05:59.010794Z","caller":"traceutil/trace.go:171","msg":"trace[1391484862] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"167.179914ms","start":"2026-03-31T05:05:58.843585Z","end":"2026-03-31T05:05:59.010765Z","steps":["trace[1391484862] 'process raft request' (duration: 166.99221ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:05:59.492964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.451644ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T05:05:59.493113Z","caller":"traceutil/trace.go:171","msg":"trace[30251866] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"473.591597ms","start":"2026-03-31T05:05:59.019502Z","end":"2026-03-31T05:05:59.493093Z","steps":["trace[30251866] 'process raft request' (duration: 110.115941ms)","trace[30251866] 'compare' (duration: 362.318931ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:05:59.493182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:05:59.019475Z","time spent":"473.673238ms","remote":"127.0.0.1:56888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2903,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:06:17.912784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.488378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:06:17.912894Z","caller":"traceutil/trace.go:171","msg":"trace[166487283] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:471; }","duration":"118.657202ms","start":"2026-03-31T05:06:17.794212Z","end":"2026-03-31T05:06:17.912869Z","steps":["trace[166487283] 'range keys from in-memory index tree' (duration: 118.373966ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:06:24.142207Z","caller":"traceutil/trace.go:171","msg":"trace[2038130981] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"157.470947ms","start":"2026-03-31T05:06:23.984715Z","end":"2026-03-31T05:06:24.142186Z","steps":["trace[2038130981] 'read index received' (duration: 157.392085ms)","trace[2038130981] 'applied index is now lower than readState.Index' (duration: 77.862µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:06:24.142258Z","caller":"traceutil/trace.go:171","msg":"trace[1093633713] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"219.117597ms","start":"2026-03-31T05:06:23.923107Z","end":"2026-03-31T05:06:24.142224Z","steps":["trace[1093633713] 'process raft request' (duration: 218.855691ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:06:24.142391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.653501ms","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-03-31T05:06:24.142443Z","caller":"traceutil/trace.go:171","msg":"trace[1674473658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:579; }","duration":"157.752183ms","start":"2026-03-31T05:06:23.984679Z","end":"2026-03-31T05:06:24.142431Z","steps":["trace[1674473658] 'agreement among raft nodes before linearized reading' (duration: 157.5956ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:06:24.349764Z","caller":"traceutil/trace.go:171","msg":"trace[806330807] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"295.664365ms","start":"2026-03-31T05:06:24.054063Z","end":"2026-03-31T05:06:24.349727Z","steps":["trace[806330807] 'process raft request' (duration: 222.331117ms)","trace[806330807] 'compare' (duration: 70.709043ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:06:24.349889Z","caller":"traceutil/trace.go:171","msg":"trace[421066450] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"203.281742ms","start":"2026-03-31T05:06:24.146561Z","end":"2026-03-31T05:06:24.349842Z","steps":["trace[421066450] 'process raft request' (duration: 202.478505ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:06:24.3501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.985915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:503"} {"level":"info","ts":"2026-03-31T05:06:24.350168Z","caller":"traceutil/trace.go:171","msg":"trace[1842699927] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:581; }","duration":"132.118418ms","start":"2026-03-31T05:06:24.218033Z","end":"2026-03-31T05:06:24.350151Z","steps":["trace[1842699927] 'agreement among raft nodes before linearized reading' (duration: 131.969155ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:06:24.349151Z","caller":"traceutil/trace.go:171","msg":"trace[1643864993] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:599; }","duration":"130.996154ms","start":"2026-03-31T05:06:24.218105Z","end":"2026-03-31T05:06:24.349101Z","steps":["trace[1643864993] 'read index received' (duration: 57.554113ms)","trace[1643864993] 'applied index is now lower than readState.Index' (duration: 73.441101ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:06:24.898345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.390417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:06:24.898508Z","caller":"traceutil/trace.go:171","msg":"trace[1334191018] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"131.52579ms","start":"2026-03-31T05:06:24.7669Z","end":"2026-03-31T05:06:24.898426Z","steps":["trace[1334191018] 'range keys from in-memory index tree' (duration: 131.298266ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:06:25.201807Z","caller":"traceutil/trace.go:171","msg":"trace[1750319330] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"301.76394ms","start":"2026-03-31T05:06:24.900012Z","end":"2026-03-31T05:06:25.201776Z","steps":["trace[1750319330] 'read index received' (duration: 300.693477ms)","trace[1750319330] 'applied index is now lower than readState.Index' (duration: 1.068663ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:06:25.201872Z","caller":"traceutil/trace.go:171","msg":"trace[1249739734] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"332.332891ms","start":"2026-03-31T05:06:24.869509Z","end":"2026-03-31T05:06:25.201842Z","steps":["trace[1249739734] 'process raft request' (duration: 331.526194ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:06:25.201954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.937314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:06:25.201995Z","caller":"traceutil/trace.go:171","msg":"trace[1758902233] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"302.064766ms","start":"2026-03-31T05:06:24.899918Z","end":"2026-03-31T05:06:25.201983Z","steps":["trace[1758902233] 'agreement among raft nodes before linearized reading' (duration: 301.972544ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:06:25.202034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:06:24.899881Z","time spent":"302.145798ms","remote":"127.0.0.1:56378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T05:06:25.202266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:06:24.869485Z","time spent":"332.442534ms","remote":"127.0.0.1:56480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:06:30.21616Z","caller":"traceutil/trace.go:171","msg":"trace[1725921219] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"138.597054ms","start":"2026-03-31T05:06:30.077532Z","end":"2026-03-31T05:06:30.216129Z","steps":["trace[1725921219] 'process raft request' (duration: 119.985477ms)","trace[1725921219] 'compare' (duration: 18.483184ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:06:30.237283Z","caller":"traceutil/trace.go:171","msg":"trace[1996246668] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"133.070546ms","start":"2026-03-31T05:06:30.104184Z","end":"2026-03-31T05:06:30.237255Z","steps":["trace[1996246668] 'process raft request' (duration: 132.853681ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:08:45.597077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.472135ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T05:08:45.597305Z","caller":"traceutil/trace.go:171","msg":"trace[1956319106] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"352.768754ms","start":"2026-03-31T05:08:45.244506Z","end":"2026-03-31T05:08:45.597274Z","steps":["trace[1956319106] 'process raft request' (duration: 206.014983ms)","trace[1956319106] 'compare' (duration: 146.379923ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:08:45.597374Z","caller":"traceutil/trace.go:171","msg":"trace[875982424] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"344.424426ms","start":"2026-03-31T05:08:45.252921Z","end":"2026-03-31T05:08:45.597346Z","steps":["trace[875982424] 'process raft request' (duration: 344.270463ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:08:45.597431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:08:45.244483Z","time spent":"352.886397ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:08:45.597507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:08:45.252906Z","time spent":"344.542138ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:08:45.755665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.393277ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-31T05:08:45.755852Z","caller":"traceutil/trace.go:171","msg":"trace[1585841785] linearizableReadLoop","detail":"{readStateIndex:2203; appliedIndex:2200; }","duration":"494.67254ms","start":"2026-03-31T05:08:45.261164Z","end":"2026-03-31T05:08:45.755836Z","steps":["trace[1585841785] 'read index received' (duration: 37.372767ms)","trace[1585841785] 'applied index is now lower than readState.Index' (duration: 457.298422ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:08:45.756026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.857153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-31T05:08:45.756058Z","caller":"traceutil/trace.go:171","msg":"trace[676932496] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2127; }","duration":"494.923205ms","start":"2026-03-31T05:08:45.261122Z","end":"2026-03-31T05:08:45.756046Z","steps":["trace[676932496] 'agreement among raft nodes before linearized reading' (duration: 494.802372ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:08:45.756088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:08:45.261102Z","time spent":"494.979136ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-31T05:08:45.756206Z","caller":"traceutil/trace.go:171","msg":"trace[428303216] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"113.746055ms","start":"2026-03-31T05:08:45.642437Z","end":"2026-03-31T05:08:45.756183Z","steps":["trace[428303216] 'process raft request' (duration: 113.326086ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:08:45.812339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.475517ms","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-03-31T05:08:45.812363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.328363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-03-31T05:08:45.812408Z","caller":"traceutil/trace.go:171","msg":"trace[1662219018] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2128; }","duration":"480.565039ms","start":"2026-03-31T05:08:45.331819Z","end":"2026-03-31T05:08:45.812384Z","steps":["trace[1662219018] 'agreement among raft nodes before linearized reading' (duration: 424.520634ms)","trace[1662219018] 'range keys from in-memory index tree' (duration: 55.908022ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:08:45.812421Z","caller":"traceutil/trace.go:171","msg":"trace[1514071799] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2128; }","duration":"253.416796ms","start":"2026-03-31T05:08:45.558985Z","end":"2026-03-31T05:08:45.812401Z","steps":["trace[1514071799] 'agreement among raft nodes before linearized reading' (duration: 197.342689ms)","trace[1514071799] 'range keys from in-memory index tree' (duration: 55.938222ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:08:45.812471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:08:45.331803Z","time spent":"480.651112ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-31T05:08:45.812331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.551675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:08:45.812608Z","caller":"traceutil/trace.go:171","msg":"trace[1915414524] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:2128; }","duration":"523.861163ms","start":"2026-03-31T05:08:45.288731Z","end":"2026-03-31T05:08:45.812593Z","steps":["trace[1915414524] 'agreement among raft nodes before linearized reading' (duration: 467.617983ms)","trace[1915414524] 'count revisions from in-memory index tree' (duration: 55.934202ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:08:45.812457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.468619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:503"} {"level":"info","ts":"2026-03-31T05:08:45.812789Z","caller":"traceutil/trace.go:171","msg":"trace[739687521] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2128; }","duration":"549.836047ms","start":"2026-03-31T05:08:45.262927Z","end":"2026-03-31T05:08:45.812763Z","steps":["trace[739687521] 'agreement among raft nodes before linearized reading' (duration: 493.223019ms)","trace[739687521] 'range keys from in-memory index tree' (duration: 56.164028ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:08:45.812847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:08:45.262914Z","time spent":"549.912379ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":525,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T05:08:45.812839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:08:45.28872Z","time spent":"523.921684ms","remote":"127.0.0.1:56658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-03-31T05:10:10.361612Z","caller":"traceutil/trace.go:171","msg":"trace[467277060] transaction","detail":"{read_only:false; response_revision:2779; number_of_response:1; }","duration":"117.322828ms","start":"2026-03-31T05:10:10.244202Z","end":"2026-03-31T05:10:10.361525Z","steps":["trace[467277060] 'process raft request' (duration: 117.119524ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:10:10.941887Z","caller":"traceutil/trace.go:171","msg":"trace[1424204419] transaction","detail":"{read_only:false; response_revision:2782; number_of_response:1; }","duration":"166.186403ms","start":"2026-03-31T05:10:10.77567Z","end":"2026-03-31T05:10:10.941856Z","steps":["trace[1424204419] 'process raft request' (duration: 165.952948ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:12:47.120171Z","caller":"traceutil/trace.go:171","msg":"trace[727982324] transaction","detail":"{read_only:false; response_revision:3805; number_of_response:1; }","duration":"145.408825ms","start":"2026-03-31T05:12:46.97472Z","end":"2026-03-31T05:12:47.120129Z","steps":["trace[727982324] 'process raft request' (duration: 145.102898ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:14:06.134799Z","caller":"traceutil/trace.go:171","msg":"trace[1996444170] transaction","detail":"{read_only:false; response_revision:4597; number_of_response:1; }","duration":"104.483624ms","start":"2026-03-31T05:14:06.030265Z","end":"2026-03-31T05:14:06.134748Z","steps":["trace[1996444170] 'process raft request' (duration: 56.14762ms)","trace[1996444170] 'compare' (duration: 48.035488ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:14:06.134984Z","caller":"traceutil/trace.go:171","msg":"trace[1413405839] linearizableReadLoop","detail":"{readStateIndex:4774; appliedIndex:4769; }","duration":"100.95516ms","start":"2026-03-31T05:14:06.034014Z","end":"2026-03-31T05:14:06.134969Z","steps":["trace[1413405839] 'read index received' (duration: 32.502289ms)","trace[1413405839] 'applied index is now lower than readState.Index' (duration: 68.451561ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:14:06.135068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.030351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/kube-prometheus-stack-admission\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:14:06.135099Z","caller":"traceutil/trace.go:171","msg":"trace[498217590] range","detail":"{range_begin:/registry/clusterroles/kube-prometheus-stack-admission; range_end:; response_count:0; response_revision:4602; }","duration":"101.110453ms","start":"2026-03-31T05:14:06.03398Z","end":"2026-03-31T05:14:06.13509Z","steps":["trace[498217590] 'agreement among raft nodes before linearized reading' (duration: 101.032842ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:14:06.134797Z","caller":"traceutil/trace.go:171","msg":"trace[462168091] transaction","detail":"{read_only:false; response_revision:4598; number_of_response:1; }","duration":"102.760007ms","start":"2026-03-31T05:14:06.03201Z","end":"2026-03-31T05:14:06.13477Z","steps":["trace[462168091] 'process raft request' (duration: 102.679125ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:14:06.135429Z","caller":"traceutil/trace.go:171","msg":"trace[749823666] transaction","detail":"{read_only:false; response_revision:4599; number_of_response:1; }","duration":"101.314197ms","start":"2026-03-31T05:14:06.0341Z","end":"2026-03-31T05:14:06.135415Z","steps":["trace[749823666] 'process raft request' (duration: 100.631122ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:14:11.622283Z","caller":"traceutil/trace.go:171","msg":"trace[413226394] transaction","detail":"{read_only:false; response_revision:4784; number_of_response:1; }","duration":"137.87265ms","start":"2026-03-31T05:14:11.484383Z","end":"2026-03-31T05:14:11.622255Z","steps":["trace[413226394] 'process raft request' (duration: 137.703027ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:14:16.874104Z","caller":"traceutil/trace.go:171","msg":"trace[1520388573] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"127.565722ms","start":"2026-03-31T05:14:16.746495Z","end":"2026-03-31T05:14:16.874061Z","steps":["trace[1520388573] 'process raft request' (duration: 125.226472ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:14:16.874222Z","caller":"traceutil/trace.go:171","msg":"trace[1235524517] transaction","detail":"{read_only:false; response_revision:4990; number_of_response:1; }","duration":"122.190259ms","start":"2026-03-31T05:14:16.751988Z","end":"2026-03-31T05:14:16.874178Z","steps":["trace[1235524517] 'process raft request' (duration: 122.001255ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:14:16.874589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.957886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:14:16.875862Z","caller":"traceutil/trace.go:171","msg":"trace[275628177] transaction","detail":"{read_only:false; response_revision:4991; number_of_response:1; }","duration":"122.649199ms","start":"2026-03-31T05:14:16.753199Z","end":"2026-03-31T05:14:16.875848Z","steps":["trace[275628177] 'process raft request' (duration: 120.962993ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:14:16.875888Z","caller":"traceutil/trace.go:171","msg":"trace[160751565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4991; }","duration":"108.336685ms","start":"2026-03-31T05:14:16.767529Z","end":"2026-03-31T05:14:16.875866Z","steps":["trace[160751565] 'agreement among raft nodes before linearized reading' (duration: 106.928265ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:14:16.876253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.637088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9cf10a97-91d2-41eb-a86c-c1faf77e265b\" ","response":"range_response_count:1 size:2535"} {"level":"info","ts":"2026-03-31T05:14:16.876298Z","caller":"traceutil/trace.go:171","msg":"trace[1793110822] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9cf10a97-91d2-41eb-a86c-c1faf77e265b; range_end:; response_count:1; response_revision:4991; }","duration":"130.73743ms","start":"2026-03-31T05:14:16.745549Z","end":"2026-03-31T05:14:16.876287Z","steps":["trace[1793110822] 'agreement among raft nodes before linearized reading' (duration: 130.522156ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:14:19.769065Z","caller":"traceutil/trace.go:171","msg":"trace[1769581573] transaction","detail":"{read_only:false; response_revision:5043; number_of_response:1; }","duration":"107.848295ms","start":"2026-03-31T05:14:19.661182Z","end":"2026-03-31T05:14:19.76903Z","steps":["trace[1769581573] 'process raft request' (duration: 107.438946ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:14:21.842085Z","caller":"traceutil/trace.go:171","msg":"trace[405815724] transaction","detail":"{read_only:false; response_revision:5096; number_of_response:1; }","duration":"117.736684ms","start":"2026-03-31T05:14:21.724317Z","end":"2026-03-31T05:14:21.842054Z","steps":["trace[405815724] 'process raft request' (duration: 117.457209ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:14:21.958544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.156362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-0\" ","response":"range_response_count:1 size:4702"} {"level":"info","ts":"2026-03-31T05:14:21.958646Z","caller":"traceutil/trace.go:171","msg":"trace[2100767000] range","detail":"{range_begin:/registry/pods/monitoring/loki-0; range_end:; response_count:1; response_revision:5100; }","duration":"108.326716ms","start":"2026-03-31T05:14:21.850295Z","end":"2026-03-31T05:14:21.958622Z","steps":["trace[2100767000] 'agreement among raft nodes before linearized reading' (duration: 108.007699ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:14:27.394419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.969667ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T05:14:27.394543Z","caller":"traceutil/trace.go:171","msg":"trace[1401519347] transaction","detail":"{read_only:false; response_revision:5185; number_of_response:1; }","duration":"285.589891ms","start":"2026-03-31T05:14:27.108932Z","end":"2026-03-31T05:14:27.394522Z","steps":["trace[1401519347] 'process raft request' (duration: 92.416888ms)","trace[1401519347] 'compare' (duration: 192.845495ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:14:30.144358Z","caller":"traceutil/trace.go:171","msg":"trace[663075491] transaction","detail":"{read_only:false; response_revision:5239; number_of_response:1; }","duration":"110.349167ms","start":"2026-03-31T05:14:30.033969Z","end":"2026-03-31T05:14:30.144318Z","steps":["trace[663075491] 'process raft request' (duration: 11.497023ms)","trace[663075491] 'compare' (duration: 98.682921ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:14:41.184393Z","caller":"traceutil/trace.go:171","msg":"trace[1918271030] transaction","detail":"{read_only:false; response_revision:5426; number_of_response:1; }","duration":"105.013594ms","start":"2026-03-31T05:14:41.079351Z","end":"2026-03-31T05:14:41.184364Z","steps":["trace[1918271030] 'process raft request' (duration: 104.81915ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:14:41.357522Z","caller":"traceutil/trace.go:171","msg":"trace[510951875] transaction","detail":"{read_only:false; response_revision:5428; number_of_response:1; }","duration":"160.437049ms","start":"2026-03-31T05:14:41.197036Z","end":"2026-03-31T05:14:41.357473Z","steps":["trace[510951875] 'process raft request' (duration: 70.551085ms)","trace[510951875] 'compare' (duration: 89.570928ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:14:41.731325Z","caller":"traceutil/trace.go:171","msg":"trace[2069463255] linearizableReadLoop","detail":"{readStateIndex:5628; appliedIndex:5627; }","duration":"224.273282ms","start":"2026-03-31T05:14:41.50702Z","end":"2026-03-31T05:14:41.731293Z","steps":["trace[2069463255] 'read index received' (duration: 186.990112ms)","trace[2069463255] 'applied index is now lower than readState.Index' (duration: 37.2815ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:14:41.731497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.794463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:14:41.731539Z","caller":"traceutil/trace.go:171","msg":"trace[2112789391] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:5430; }","duration":"115.914596ms","start":"2026-03-31T05:14:41.615615Z","end":"2026-03-31T05:14:41.731529Z","steps":["trace[2112789391] 'agreement among raft nodes before linearized reading' (duration: 115.823214ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:14:41.731623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.109715ms","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":"warn","ts":"2026-03-31T05:14:41.731648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.588459ms","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-03-31T05:14:41.731722Z","caller":"traceutil/trace.go:171","msg":"trace[21538260] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:5430; }","duration":"104.275639ms","start":"2026-03-31T05:14:41.627425Z","end":"2026-03-31T05:14:41.731701Z","steps":["trace[21538260] 'agreement among raft nodes before linearized reading' (duration: 104.105355ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:14:41.731791Z","caller":"traceutil/trace.go:171","msg":"trace[1920246012] transaction","detail":"{read_only:false; response_revision:5430; number_of_response:1; }","duration":"334.680581ms","start":"2026-03-31T05:14:41.396676Z","end":"2026-03-31T05:14:41.731357Z","steps":["trace[1920246012] 'process raft request' (duration: 297.387251ms)","trace[1920246012] 'compare' (duration: 36.922742ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:14:41.731827Z","caller":"traceutil/trace.go:171","msg":"trace[693009652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5430; }","duration":"224.807983ms","start":"2026-03-31T05:14:41.506951Z","end":"2026-03-31T05:14:41.731759Z","steps":["trace[693009652] 'agreement among raft nodes before linearized reading' (duration: 224.429195ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:14:41.731997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:14:41.396659Z","time spent":"335.235443ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":997,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:15:46.814089Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2979} {"level":"info","ts":"2026-03-31T05:15:46.914638Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2979,"took":"98.967097ms","hash":2075242057,"current-db-size-bytes":57524224,"current-db-size":"58 MB","current-db-size-in-use-bytes":33325056,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-03-31T05:15:46.914697Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2075242057,"revision":2979,"compact-revision":-1} {"level":"info","ts":"2026-03-31T05:20:44.91483Z","caller":"traceutil/trace.go:171","msg":"trace[1211191598] linearizableReadLoop","detail":"{readStateIndex:8530; appliedIndex:8529; }","duration":"146.983452ms","start":"2026-03-31T05:20:44.767815Z","end":"2026-03-31T05:20:44.914799Z","steps":["trace[1211191598] 'read index received' (duration: 146.835699ms)","trace[1211191598] 'applied index is now lower than readState.Index' (duration: 146.383µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:20:44.914889Z","caller":"traceutil/trace.go:171","msg":"trace[1156346621] transaction","detail":"{read_only:false; response_revision:8247; number_of_response:1; }","duration":"211.555726ms","start":"2026-03-31T05:20:44.703288Z","end":"2026-03-31T05:20:44.914844Z","steps":["trace[1156346621] 'process raft request' (duration: 211.277831ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:20:44.915118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.268458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:20:44.915236Z","caller":"traceutil/trace.go:171","msg":"trace[366702326] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8247; }","duration":"147.457632ms","start":"2026-03-31T05:20:44.767752Z","end":"2026-03-31T05:20:44.915209Z","steps":["trace[366702326] 'agreement among raft nodes before linearized reading' (duration: 147.224737ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:20:45.114377Z","caller":"traceutil/trace.go:171","msg":"trace[1923680830] linearizableReadLoop","detail":"{readStateIndex:8531; appliedIndex:8530; }","duration":"199.403151ms","start":"2026-03-31T05:20:44.91494Z","end":"2026-03-31T05:20:45.114343Z","steps":["trace[1923680830] 'read index received' (duration: 183.517105ms)","trace[1923680830] 'applied index is now lower than readState.Index' (duration: 15.884686ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:20:45.114477Z","caller":"traceutil/trace.go:171","msg":"trace[1867046075] transaction","detail":"{read_only:false; response_revision:8248; number_of_response:1; }","duration":"285.164051ms","start":"2026-03-31T05:20:44.829265Z","end":"2026-03-31T05:20:45.114429Z","steps":["trace[1867046075] 'process raft request' (duration: 269.277745ms)","trace[1867046075] 'compare' (duration: 15.637401ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:20:45.114525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.761234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T05:20:45.114563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.73317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-03-31T05:20:45.114609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.96791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:20:45.114637Z","caller":"traceutil/trace.go:171","msg":"trace[1582528942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8248; }","duration":"318.816362ms","start":"2026-03-31T05:20:44.795806Z","end":"2026-03-31T05:20:45.114623Z","steps":["trace[1582528942] 'agreement among raft nodes before linearized reading' (duration: 318.613738ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:20:45.11464Z","caller":"traceutil/trace.go:171","msg":"trace[706744776] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:8248; }","duration":"321.903347ms","start":"2026-03-31T05:20:44.792716Z","end":"2026-03-31T05:20:45.114619Z","steps":["trace[706744776] 'agreement among raft nodes before linearized reading' (duration: 321.718573ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:20:45.114679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:20:44.795801Z","time spent":"318.869373ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-31T05:20:45.114676Z","caller":"traceutil/trace.go:171","msg":"trace[208600151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8248; }","duration":"198.111083ms","start":"2026-03-31T05:20:44.916549Z","end":"2026-03-31T05:20:45.11466Z","steps":["trace[208600151] 'agreement among raft nodes before linearized reading' (duration: 197.97459ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:20:45.114682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:20:44.792663Z","time spent":"322.007739ms","remote":"127.0.0.1:42980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"info","ts":"2026-03-31T05:20:46.823435Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6032} {"level":"info","ts":"2026-03-31T05:20:46.92505Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6032,"took":"99.125743ms","hash":900528235,"current-db-size-bytes":57524224,"current-db-size":"58 MB","current-db-size-in-use-bytes":23674880,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-03-31T05:20:46.925125Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":900528235,"revision":6032,"compact-revision":2979} {"level":"info","ts":"2026-03-31T05:20:56.99315Z","caller":"traceutil/trace.go:171","msg":"trace[208235253] linearizableReadLoop","detail":"{readStateIndex:8598; appliedIndex:8597; }","duration":"141.546549ms","start":"2026-03-31T05:20:56.851567Z","end":"2026-03-31T05:20:56.993113Z","steps":["trace[208235253] 'read index received' (duration: 141.424356ms)","trace[208235253] 'applied index is now lower than readState.Index' (duration: 120.933µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:20:56.993223Z","caller":"traceutil/trace.go:171","msg":"trace[1115772769] transaction","detail":"{read_only:false; response_revision:8310; number_of_response:1; }","duration":"142.021969ms","start":"2026-03-31T05:20:56.851186Z","end":"2026-03-31T05:20:56.993208Z","steps":["trace[1115772769] 'process raft request' (duration: 141.703812ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:20:56.993337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.740433ms","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-03-31T05:20:56.993452Z","caller":"traceutil/trace.go:171","msg":"trace[411559277] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8310; }","duration":"141.942028ms","start":"2026-03-31T05:20:56.851488Z","end":"2026-03-31T05:20:56.99343Z","steps":["trace[411559277] 'agreement among raft nodes before linearized reading' (duration: 141.763274ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:20:57.092685Z","caller":"traceutil/trace.go:171","msg":"trace[1712220558] transaction","detail":"{read_only:false; response_revision:8311; number_of_response:1; }","duration":"128.598554ms","start":"2026-03-31T05:20:56.964059Z","end":"2026-03-31T05:20:57.092658Z","steps":["trace[1712220558] 'process raft request' (duration: 120.627677ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:21:30.273613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.093657ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T05:21:30.274137Z","caller":"traceutil/trace.go:171","msg":"trace[1939490422] transaction","detail":"{read_only:false; response_revision:8539; number_of_response:1; }","duration":"228.749119ms","start":"2026-03-31T05:21:30.045338Z","end":"2026-03-31T05:21:30.274087Z","steps":["trace[1939490422] 'process raft request' (duration: 89.0971ms)","trace[1939490422] 'compare' (duration: 138.788311ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:21:39.848435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.852333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-31T05:21:39.848502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.407393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pv7g8\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-31T05:21:39.848555Z","caller":"traceutil/trace.go:171","msg":"trace[1975965173] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:8631; }","duration":"113.025776ms","start":"2026-03-31T05:21:39.735504Z","end":"2026-03-31T05:21:39.84853Z","steps":["trace[1975965173] 'agreement among raft nodes before linearized reading' (duration: 112.792052ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:21:39.848588Z","caller":"traceutil/trace.go:171","msg":"trace[143267226] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8631; }","duration":"123.458537ms","start":"2026-03-31T05:21:39.725085Z","end":"2026-03-31T05:21:39.848544Z","steps":["trace[143267226] 'process raft request' (duration: 123.048748ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:21:39.848501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.653151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-03-31T05:21:39.848604Z","caller":"traceutil/trace.go:171","msg":"trace[1475315469] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pv7g8; range_end:; response_count:1; response_revision:8631; }","duration":"121.540176ms","start":"2026-03-31T05:21:39.72704Z","end":"2026-03-31T05:21:39.84858Z","steps":["trace[1475315469] 'agreement among raft nodes before linearized reading' (duration: 121.28827ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:21:39.848791Z","caller":"traceutil/trace.go:171","msg":"trace[820676989] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8631; }","duration":"123.974617ms","start":"2026-03-31T05:21:39.724797Z","end":"2026-03-31T05:21:39.848771Z","steps":["trace[820676989] 'agreement among raft nodes before linearized reading' (duration: 123.61239ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:21:40.105697Z","caller":"traceutil/trace.go:171","msg":"trace[1166996111] linearizableReadLoop","detail":"{readStateIndex:8937; appliedIndex:8936; }","duration":"155.300149ms","start":"2026-03-31T05:21:39.950341Z","end":"2026-03-31T05:21:40.105641Z","steps":["trace[1166996111] 'read index received' (duration: 155.074254ms)","trace[1166996111] 'applied index is now lower than readState.Index' (duration: 224.655µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:21:40.105796Z","caller":"traceutil/trace.go:171","msg":"trace[982950199] transaction","detail":"{read_only:false; response_revision:8635; number_of_response:1; }","duration":"178.410127ms","start":"2026-03-31T05:21:39.927325Z","end":"2026-03-31T05:21:40.105735Z","steps":["trace[982950199] 'process raft request' (duration: 178.155441ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:21:40.105972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.599425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-31T05:21:40.106026Z","caller":"traceutil/trace.go:171","msg":"trace[1711114273] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:1; response_revision:8635; }","duration":"155.726248ms","start":"2026-03-31T05:21:39.950284Z","end":"2026-03-31T05:21:40.106011Z","steps":["trace[1711114273] 'agreement among raft nodes before linearized reading' (duration: 155.522344ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:21:41.028364Z","caller":"traceutil/trace.go:171","msg":"trace[165452888] transaction","detail":"{read_only:false; response_revision:8642; number_of_response:1; }","duration":"283.75578ms","start":"2026-03-31T05:21:40.744575Z","end":"2026-03-31T05:21:41.02833Z","steps":["trace[165452888] 'process raft request' (duration: 283.485554ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:21:41.209261Z","caller":"traceutil/trace.go:171","msg":"trace[2050971030] linearizableReadLoop","detail":"{readStateIndex:8946; appliedIndex:8944; }","duration":"441.554783ms","start":"2026-03-31T05:21:40.767669Z","end":"2026-03-31T05:21:41.209223Z","steps":["trace[2050971030] 'read index received' (duration: 260.5249ms)","trace[2050971030] 'applied index is now lower than readState.Index' (duration: 181.028113ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:21:41.209387Z","caller":"traceutil/trace.go:171","msg":"trace[802754065] transaction","detail":"{read_only:false; response_revision:8643; number_of_response:1; }","duration":"464.262272ms","start":"2026-03-31T05:21:40.74508Z","end":"2026-03-31T05:21:41.209342Z","steps":["trace[802754065] 'process raft request' (duration: 428.899565ms)","trace[802754065] 'compare' (duration: 35.107232ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:21:41.209441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.754667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:21:41.209531Z","caller":"traceutil/trace.go:171","msg":"trace[6793605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8643; }","duration":"441.916461ms","start":"2026-03-31T05:21:40.767599Z","end":"2026-03-31T05:21:41.209516Z","steps":["trace[6793605] 'agreement among raft nodes before linearized reading' (duration: 441.754887ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:21:41.209575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:21:40.767577Z","time spent":"441.990172ms","remote":"127.0.0.1:56380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T05:21:41.209691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:21:40.745039Z","time spent":"464.508157ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:21:41.209703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.060682ms","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-03-31T05:21:41.209785Z","caller":"traceutil/trace.go:171","msg":"trace[665967949] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8643; }","duration":"387.199605ms","start":"2026-03-31T05:21:40.822566Z","end":"2026-03-31T05:21:41.209766Z","steps":["trace[665967949] 'agreement among raft nodes before linearized reading' (duration: 386.880798ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:21:41.209684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.226918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-config-override\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-03-31T05:21:41.209853Z","caller":"traceutil/trace.go:171","msg":"trace[769131130] range","detail":"{range_begin:/registry/configmaps/openstack/rook-config-override; range_end:; response_count:1; response_revision:8643; }","duration":"174.481963ms","start":"2026-03-31T05:21:41.035352Z","end":"2026-03-31T05:21:41.209833Z","steps":["trace[769131130] 'agreement among raft nodes before linearized reading' (duration: 174.164257ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:21:41.209694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.07177ms","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-03-31T05:21:41.209933Z","caller":"traceutil/trace.go:171","msg":"trace[557002614] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8643; }","duration":"385.370637ms","start":"2026-03-31T05:21:40.824528Z","end":"2026-03-31T05:21:41.209899Z","steps":["trace[557002614] 'agreement among raft nodes before linearized reading' (duration: 384.985289ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:21:41.209853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:21:40.822538Z","time spent":"387.299076ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T05:21:41.209983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:21:40.824518Z","time spent":"385.452008ms","remote":"127.0.0.1:56672","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-03-31T05:21:41.464018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.547714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-03-31T05:21:41.464103Z","caller":"traceutil/trace.go:171","msg":"trace[675223003] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:8646; }","duration":"154.687717ms","start":"2026-03-31T05:21:41.309396Z","end":"2026-03-31T05:21:41.464083Z","steps":["trace[675223003] 'range keys from in-memory index tree' (duration: 154.396751ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:21:42.186937Z","caller":"traceutil/trace.go:171","msg":"trace[1208419643] transaction","detail":"{read_only:false; response_revision:8652; number_of_response:1; }","duration":"184.702229ms","start":"2026-03-31T05:21:42.002205Z","end":"2026-03-31T05:21:42.186907Z","steps":["trace[1208419643] 'process raft request' (duration: 184.497325ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:21:42.205584Z","caller":"traceutil/trace.go:171","msg":"trace[655037736] transaction","detail":"{read_only:false; response_revision:8653; number_of_response:1; }","duration":"124.634441ms","start":"2026-03-31T05:21:42.080931Z","end":"2026-03-31T05:21:42.205566Z","steps":["trace[655037736] 'process raft request' (duration: 124.531749ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:22:17.541644Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002457.wal"} {"level":"info","ts":"2026-03-31T05:23:35.950762Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f170c75a1d97cc","local-member-applied-index":10002,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-31T05:23:35.957825Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10002} {"level":"info","ts":"2026-03-31T05:23:35.957962Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5002} {"level":"info","ts":"2026-03-31T05:25:46.830913Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8258} {"level":"info","ts":"2026-03-31T05:25:46.940583Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8258,"took":"107.800685ms","hash":3357678957,"current-db-size-bytes":57524224,"current-db-size":"58 MB","current-db-size-in-use-bytes":26308608,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-31T05:25:46.94066Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3357678957,"revision":8258,"compact-revision":6032} {"level":"warn","ts":"2026-03-31T05:26:38.554391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.20886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-03-31T05:26:38.554496Z","caller":"traceutil/trace.go:171","msg":"trace[1055123692] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11224; }","duration":"185.357364ms","start":"2026-03-31T05:26:38.369109Z","end":"2026-03-31T05:26:38.554466Z","steps":["trace[1055123692] 'range keys from in-memory index tree' (duration: 184.983746ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:30:46.836782Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10744} {"level":"info","ts":"2026-03-31T05:30:46.926874Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10744,"took":"87.346036ms","hash":4020807034,"current-db-size-bytes":57524224,"current-db-size":"58 MB","current-db-size-in-use-bytes":27299840,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-03-31T05:30:46.927059Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4020807034,"revision":10744,"compact-revision":8258} {"level":"warn","ts":"2026-03-31T05:33:15.276555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.49418ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-31T05:33:15.276779Z","caller":"traceutil/trace.go:171","msg":"trace[1403934757] linearizableReadLoop","detail":"{readStateIndex:14328; appliedIndex:14326; }","duration":"120.165899ms","start":"2026-03-31T05:33:15.156599Z","end":"2026-03-31T05:33:15.276765Z","steps":["trace[1403934757] 'read index received' (duration: 4.458725ms)","trace[1403934757] 'applied index is now lower than readState.Index' (duration: 115.706054ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:33:15.27684Z","caller":"traceutil/trace.go:171","msg":"trace[495444427] transaction","detail":"{read_only:false; response_revision:13858; number_of_response:1; }","duration":"146.466094ms","start":"2026-03-31T05:33:15.130337Z","end":"2026-03-31T05:33:15.276804Z","steps":["trace[495444427] 'process raft request' (duration: 146.32035ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:33:15.276992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.367883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-31T05:33:15.277084Z","caller":"traceutil/trace.go:171","msg":"trace[1912498522] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13858; }","duration":"120.503205ms","start":"2026-03-31T05:33:15.156558Z","end":"2026-03-31T05:33:15.277061Z","steps":["trace[1912498522] 'agreement among raft nodes before linearized reading' (duration: 120.25093ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:33:31.4891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.256006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-31T05:33:31.489231Z","caller":"traceutil/trace.go:171","msg":"trace[553607439] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13955; }","duration":"398.43048ms","start":"2026-03-31T05:33:31.090775Z","end":"2026-03-31T05:33:31.489206Z","steps":["trace[553607439] 'range keys from in-memory index tree' (duration: 398.043382ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:33:31.489291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:33:31.090752Z","time spent":"398.521962ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-31T05:33:31.509617Z","caller":"traceutil/trace.go:171","msg":"trace[1827997415] linearizableReadLoop","detail":"{readStateIndex:14431; appliedIndex:14430; }","duration":"153.157066ms","start":"2026-03-31T05:33:31.356435Z","end":"2026-03-31T05:33:31.509592Z","steps":["trace[1827997415] 'read index received' (duration: 152.993223ms)","trace[1827997415] 'applied index is now lower than readState.Index' (duration: 162.803µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:33:31.509746Z","caller":"traceutil/trace.go:171","msg":"trace[170165621] transaction","detail":"{read_only:false; response_revision:13956; number_of_response:1; }","duration":"175.519849ms","start":"2026-03-31T05:33:31.334189Z","end":"2026-03-31T05:33:31.509709Z","steps":["trace[170165621] 'process raft request' (duration: 175.242583ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:33:31.509917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.457793ms","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-03-31T05:33:31.509968Z","caller":"traceutil/trace.go:171","msg":"trace[1242691059] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13956; }","duration":"153.561416ms","start":"2026-03-31T05:33:31.356388Z","end":"2026-03-31T05:33:31.509949Z","steps":["trace[1242691059] 'agreement among raft nodes before linearized reading' (duration: 153.380062ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:33:40.751597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.773502ms","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-03-31T05:33:40.751693Z","caller":"traceutil/trace.go:171","msg":"trace[145562349] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14124; }","duration":"208.925585ms","start":"2026-03-31T05:33:40.542748Z","end":"2026-03-31T05:33:40.751674Z","steps":["trace[145562349] 'range keys from in-memory index tree' (duration: 208.524707ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:33:40.751591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.050915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:33:40.7519Z","caller":"traceutil/trace.go:171","msg":"trace[1335959415] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:14124; }","duration":"215.416953ms","start":"2026-03-31T05:33:40.536457Z","end":"2026-03-31T05:33:40.751874Z","steps":["trace[1335959415] 'count revisions from in-memory index tree' (duration: 214.930193ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:33:40.897154Z","caller":"traceutil/trace.go:171","msg":"trace[1727201718] linearizableReadLoop","detail":"{readStateIndex:14603; appliedIndex:14602; }","duration":"129.177081ms","start":"2026-03-31T05:33:40.76795Z","end":"2026-03-31T05:33:40.897127Z","steps":["trace[1727201718] 'read index received' (duration: 128.925655ms)","trace[1727201718] 'applied index is now lower than readState.Index' (duration: 250.216µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:33:40.897226Z","caller":"traceutil/trace.go:171","msg":"trace[630759745] transaction","detail":"{read_only:false; response_revision:14125; number_of_response:1; }","duration":"141.835768ms","start":"2026-03-31T05:33:40.755337Z","end":"2026-03-31T05:33:40.897172Z","steps":["trace[630759745] 'process raft request' (duration: 141.599312ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:33:40.897294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.311104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:33:40.897359Z","caller":"traceutil/trace.go:171","msg":"trace[1607324232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14125; }","duration":"129.430246ms","start":"2026-03-31T05:33:40.767917Z","end":"2026-03-31T05:33:40.897348Z","steps":["trace[1607324232] 'agreement among raft nodes before linearized reading' (duration: 129.313613ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:35:04.977201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.297998ms","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-03-31T05:35:04.977303Z","caller":"traceutil/trace.go:171","msg":"trace[1303902364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15135; }","duration":"160.44976ms","start":"2026-03-31T05:35:04.816832Z","end":"2026-03-31T05:35:04.977281Z","steps":["trace[1303902364] 'range keys from in-memory index tree' (duration: 160.100894ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:35:05.335627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.92113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-03-31T05:35:05.33563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.623622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-slcrv\" ","response":"range_response_count:1 size:9796"} {"level":"info","ts":"2026-03-31T05:35:05.33571Z","caller":"traceutil/trace.go:171","msg":"trace[1136909048] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15136; }","duration":"187.056053ms","start":"2026-03-31T05:35:05.148636Z","end":"2026-03-31T05:35:05.335692Z","steps":["trace[1136909048] 'range keys from in-memory index tree' (duration: 186.775597ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:35:05.335746Z","caller":"traceutil/trace.go:171","msg":"trace[2064917001] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-slcrv; range_end:; response_count:1; response_revision:15136; }","duration":"193.795165ms","start":"2026-03-31T05:35:05.141921Z","end":"2026-03-31T05:35:05.335716Z","steps":["trace[2064917001] 'range keys from in-memory index tree' (duration: 193.344127ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:35:05.614993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.132973ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-31T05:35:05.615097Z","caller":"traceutil/trace.go:171","msg":"trace[525154655] linearizableReadLoop","detail":"{readStateIndex:15644; appliedIndex:15643; }","duration":"299.626822ms","start":"2026-03-31T05:35:05.315453Z","end":"2026-03-31T05:35:05.61508Z","steps":["trace[525154655] 'read index received' (duration: 17.335386ms)","trace[525154655] 'applied index is now lower than readState.Index' (duration: 282.289926ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:35:05.61525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.784695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:35:05.61531Z","caller":"traceutil/trace.go:171","msg":"trace[2133805740] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15136; }","duration":"299.878088ms","start":"2026-03-31T05:35:05.315411Z","end":"2026-03-31T05:35:05.615289Z","steps":["trace[2133805740] 'agreement among raft nodes before linearized reading' (duration: 299.784586ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:35:05.615456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.606259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T05:35:05.615499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.456796ms","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-03-31T05:35:05.615527Z","caller":"traceutil/trace.go:171","msg":"trace[215973005] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15136; }","duration":"297.715741ms","start":"2026-03-31T05:35:05.317791Z","end":"2026-03-31T05:35:05.615507Z","steps":["trace[215973005] 'agreement among raft nodes before linearized reading' (duration: 297.607519ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:35:05.615565Z","caller":"traceutil/trace.go:171","msg":"trace[36173036] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15136; }","duration":"296.542507ms","start":"2026-03-31T05:35:05.319009Z","end":"2026-03-31T05:35:05.615551Z","steps":["trace[36173036] 'agreement among raft nodes before linearized reading' (duration: 296.357073ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:35:05.61569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.879575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-03-31T05:35:05.615784Z","caller":"traceutil/trace.go:171","msg":"trace[1275999758] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15136; }","duration":"297.993807ms","start":"2026-03-31T05:35:05.317763Z","end":"2026-03-31T05:35:05.615757Z","steps":["trace[1275999758] 'agreement among raft nodes before linearized reading' (duration: 297.64469ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:35:05.615916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.16233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-03-31T05:35:05.616018Z","caller":"traceutil/trace.go:171","msg":"trace[788107803] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15136; }","duration":"298.262573ms","start":"2026-03-31T05:35:05.31772Z","end":"2026-03-31T05:35:05.615983Z","steps":["trace[788107803] 'agreement among raft nodes before linearized reading' (duration: 297.63953ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:35:05.616279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.643324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769965"} {"level":"info","ts":"2026-03-31T05:35:05.616328Z","caller":"traceutil/trace.go:171","msg":"trace[1437416751] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15136; }","duration":"265.694835ms","start":"2026-03-31T05:35:05.350624Z","end":"2026-03-31T05:35:05.616319Z","steps":["trace[1437416751] 'agreement among raft nodes before linearized reading' (duration: 264.972559ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:35:05.616224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.859494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-03-31T05:35:05.616637Z","caller":"traceutil/trace.go:171","msg":"trace[1613356122] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15136; }","duration":"298.53008ms","start":"2026-03-31T05:35:05.318004Z","end":"2026-03-31T05:35:05.616534Z","steps":["trace[1613356122] 'agreement among raft nodes before linearized reading' (duration: 297.441616ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:35:05.800209Z","caller":"traceutil/trace.go:171","msg":"trace[1459383218] transaction","detail":"{read_only:false; response_revision:15138; number_of_response:1; }","duration":"178.209146ms","start":"2026-03-31T05:35:05.621962Z","end":"2026-03-31T05:35:05.800171Z","steps":["trace[1459383218] 'process raft request' (duration: 135.78749ms)","trace[1459383218] 'compare' (duration: 42.166521ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:35:05.800493Z","caller":"traceutil/trace.go:171","msg":"trace[296561354] linearizableReadLoop","detail":"{readStateIndex:15647; appliedIndex:15644; }","duration":"137.574567ms","start":"2026-03-31T05:35:05.6629Z","end":"2026-03-31T05:35:05.800475Z","steps":["trace[296561354] 'read index received' (duration: 36.048912ms)","trace[296561354] 'applied index is now lower than readState.Index' (duration: 101.523905ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:35:05.800584Z","caller":"traceutil/trace.go:171","msg":"trace[1211345182] transaction","detail":"{read_only:false; response_revision:15139; number_of_response:1; }","duration":"156.110189ms","start":"2026-03-31T05:35:05.644437Z","end":"2026-03-31T05:35:05.800548Z","steps":["trace[1211345182] 'process raft request' (duration: 155.659139ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:35:05.800687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.300828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T05:35:05.800743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.826142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-lm2bd\" ","response":"range_response_count:1 size:22125"} {"level":"info","ts":"2026-03-31T05:35:05.800767Z","caller":"traceutil/trace.go:171","msg":"trace[1245032994] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:15139; }","duration":"134.38818ms","start":"2026-03-31T05:35:05.666359Z","end":"2026-03-31T05:35:05.800747Z","steps":["trace[1245032994] 'agreement among raft nodes before linearized reading' (duration: 134.264107ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:35:05.800808Z","caller":"traceutil/trace.go:171","msg":"trace[794620159] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-lm2bd; range_end:; response_count:1; response_revision:15139; }","duration":"137.939925ms","start":"2026-03-31T05:35:05.662852Z","end":"2026-03-31T05:35:05.800792Z","steps":["trace[794620159] 'agreement among raft nodes before linearized reading' (duration: 137.746761ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:35:05.800841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.368375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T05:35:05.800928Z","caller":"traceutil/trace.go:171","msg":"trace[45636592] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:15139; }","duration":"113.509098ms","start":"2026-03-31T05:35:05.687402Z","end":"2026-03-31T05:35:05.800911Z","steps":["trace[45636592] 'agreement among raft nodes before linearized reading' (duration: 113.363305ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:35:05.801078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.405776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-lm2bd\" ","response":"range_response_count:1 size:22125"} {"level":"info","ts":"2026-03-31T05:35:05.801165Z","caller":"traceutil/trace.go:171","msg":"trace[931469536] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-lm2bd; range_end:; response_count:1; response_revision:15139; }","duration":"129.559138ms","start":"2026-03-31T05:35:05.671587Z","end":"2026-03-31T05:35:05.801146Z","steps":["trace[931469536] 'agreement among raft nodes before linearized reading' (duration: 129.21799ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:35:05.985918Z","caller":"traceutil/trace.go:171","msg":"trace[827026454] transaction","detail":"{read_only:false; response_revision:15140; number_of_response:1; }","duration":"100.596526ms","start":"2026-03-31T05:35:05.885287Z","end":"2026-03-31T05:35:05.985884Z","steps":["trace[827026454] 'process raft request' (duration: 88.445819ms)","trace[827026454] 'compare' (duration: 11.995643ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:35:46.842759Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12822} {"level":"info","ts":"2026-03-31T05:35:46.920193Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12822,"took":"74.931163ms","hash":586345910,"current-db-size-bytes":57524224,"current-db-size":"58 MB","current-db-size-in-use-bytes":30060544,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-31T05:35:46.920258Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":586345910,"revision":12822,"compact-revision":10744} {"level":"info","ts":"2026-03-31T05:40:46.848361Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15349} {"level":"info","ts":"2026-03-31T05:40:46.970903Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15349,"took":"119.84029ms","hash":1086624350,"current-db-size-bytes":57524224,"current-db-size":"58 MB","current-db-size-in-use-bytes":31719424,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-03-31T05:40:46.970967Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1086624350,"revision":15349,"compact-revision":12822} {"level":"info","ts":"2026-03-31T05:40:57.711814Z","caller":"traceutil/trace.go:171","msg":"trace[875413921] transaction","detail":"{read_only:false; response_revision:17773; number_of_response:1; }","duration":"167.047079ms","start":"2026-03-31T05:40:57.544735Z","end":"2026-03-31T05:40:57.711782Z","steps":["trace[875413921] 'process raft request' (duration: 166.801343ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:40:57.734234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.806719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-ovn-metadata-agent-default-5mkkj\" ","response":"range_response_count:1 size:15878"} {"level":"info","ts":"2026-03-31T05:40:57.734334Z","caller":"traceutil/trace.go:171","msg":"trace[2098263486] range","detail":"{range_begin:/registry/pods/openstack/neutron-ovn-metadata-agent-default-5mkkj; range_end:; response_count:1; response_revision:17773; }","duration":"117.955753ms","start":"2026-03-31T05:40:57.616349Z","end":"2026-03-31T05:40:57.734305Z","steps":["trace[2098263486] 'agreement among raft nodes before linearized reading' (duration: 95.504579ms)","trace[2098263486] 'range keys from in-memory index tree' (duration: 22.148407ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:41:05.26853Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938290502987354327,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-31T05:41:05.31589Z","caller":"traceutil/trace.go:171","msg":"trace[181034902] transaction","detail":"{read_only:false; response_revision:17821; number_of_response:1; }","duration":"575.721461ms","start":"2026-03-31T05:41:04.740145Z","end":"2026-03-31T05:41:05.315866Z","steps":["trace[181034902] 'process raft request' (duration: 575.567087ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:41:05.316056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:41:04.740103Z","time spent":"575.865484ms","remote":"127.0.0.1:56574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20981,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:41:05.604665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.223333ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-31T05:41:05.604934Z","caller":"traceutil/trace.go:171","msg":"trace[102072640] linearizableReadLoop","detail":"{readStateIndex:18418; appliedIndex:18416; }","duration":"837.153751ms","start":"2026-03-31T05:41:04.76776Z","end":"2026-03-31T05:41:05.604914Z","steps":["trace[102072640] 'read index received' (duration: 548.001204ms)","trace[102072640] 'applied index is now lower than readState.Index' (duration: 289.151157ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:41:05.605728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.967459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T05:41:05.605774Z","caller":"traceutil/trace.go:171","msg":"trace[1736604583] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17821; }","duration":"838.05222ms","start":"2026-03-31T05:41:04.76771Z","end":"2026-03-31T05:41:05.605763Z","steps":["trace[1736604583] 'agreement among raft nodes before linearized reading' (duration: 837.976998ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:41:05.605815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:41:04.76769Z","time spent":"838.116462ms","remote":"127.0.0.1:56380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-31T05:41:05.606368Z","caller":"traceutil/trace.go:171","msg":"trace[561116072] transaction","detail":"{read_only:false; response_revision:17822; number_of_response:1; }","duration":"215.857299ms","start":"2026-03-31T05:41:05.390495Z","end":"2026-03-31T05:41:05.606352Z","steps":["trace[561116072] 'process raft request' (duration: 214.282776ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:41:05.666765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.937154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-03-31T05:41:05.666869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.097914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-03-31T05:41:05.66688Z","caller":"traceutil/trace.go:171","msg":"trace[1538084955] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:17822; }","duration":"710.098118ms","start":"2026-03-31T05:41:04.956753Z","end":"2026-03-31T05:41:05.666851Z","steps":["trace[1538084955] 'agreement among raft nodes before linearized reading' (duration: 649.650001ms)","trace[1538084955] 'count revisions from in-memory index tree' (duration: 60.285613ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:41:05.666873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.021039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-31T05:41:05.66695Z","caller":"traceutil/trace.go:171","msg":"trace[235681058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17822; }","duration":"849.229067ms","start":"2026-03-31T05:41:04.817699Z","end":"2026-03-31T05:41:05.666928Z","steps":["trace[235681058] 'agreement among raft nodes before linearized reading' (duration: 788.633478ms)","trace[235681058] 'range keys from in-memory index tree' (duration: 60.410505ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:41:05.666957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:41:04.956739Z","time spent":"710.198859ms","remote":"127.0.0.1:56758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":115,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-03-31T05:41:05.66696Z","caller":"traceutil/trace.go:171","msg":"trace[1725006346] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:17822; }","duration":"570.157542ms","start":"2026-03-31T05:41:05.096782Z","end":"2026-03-31T05:41:05.666939Z","steps":["trace[1725006346] 'agreement among raft nodes before linearized reading' (duration: 509.604154ms)","trace[1725006346] 'count revisions from in-memory index tree' (duration: 60.427976ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:41:05.666992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:41:04.817678Z","time spent":"849.307108ms","remote":"127.0.0.1:56672","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-03-31T05:41:05.667009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:41:05.096759Z","time spent":"570.238834ms","remote":"127.0.0.1:56866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-03-31T05:41:05.667425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.279264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7532"} {"level":"info","ts":"2026-03-31T05:41:05.667458Z","caller":"traceutil/trace.go:171","msg":"trace[12638148] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:17822; }","duration":"775.338115ms","start":"2026-03-31T05:41:04.892108Z","end":"2026-03-31T05:41:05.667446Z","steps":["trace[12638148] 'agreement among raft nodes before linearized reading' (duration: 714.307157ms)","trace[12638148] 'range keys from in-memory index tree' (duration: 60.263392ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:41:05.667492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:41:04.892091Z","time spent":"775.390247ms","remote":"127.0.0.1:59836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7554,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"info","ts":"2026-03-31T05:44:11.002838Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f170c75a1d97cc","local-member-applied-index":20003,"local-member-snapshot-index":10002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-31T05:44:11.00905Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20003} {"level":"info","ts":"2026-03-31T05:44:11.009207Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15003} {"level":"info","ts":"2026-03-31T05:45:46.855476Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17659} {"level":"info","ts":"2026-03-31T05:45:46.955034Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17659,"took":"95.749201ms","hash":848988377,"current-db-size-bytes":57524224,"current-db-size":"58 MB","current-db-size-in-use-bytes":34074624,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-03-31T05:45:46.955094Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":848988377,"revision":17659,"compact-revision":15349} {"level":"info","ts":"2026-03-31T05:46:21.409763Z","caller":"traceutil/trace.go:171","msg":"trace[953286255] transaction","detail":"{read_only:false; response_revision:20253; number_of_response:1; }","duration":"121.301689ms","start":"2026-03-31T05:46:21.288282Z","end":"2026-03-31T05:46:21.409583Z","steps":["trace[953286255] 'process raft request' (duration: 120.87566ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:46:26.983938Z","caller":"traceutil/trace.go:171","msg":"trace[1274202535] linearizableReadLoop","detail":"{readStateIndex:20963; appliedIndex:20962; }","duration":"127.921139ms","start":"2026-03-31T05:46:26.855991Z","end":"2026-03-31T05:46:26.983912Z","steps":["trace[1274202535] 'read index received' (duration: 127.749336ms)","trace[1274202535] 'applied index is now lower than readState.Index' (duration: 170.533µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:46:26.984038Z","caller":"traceutil/trace.go:171","msg":"trace[1320207485] transaction","detail":"{read_only:false; response_revision:20282; number_of_response:1; }","duration":"141.351462ms","start":"2026-03-31T05:46:26.842653Z","end":"2026-03-31T05:46:26.984004Z","steps":["trace[1320207485] 'process raft request' (duration: 141.077457ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:46:26.984129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.114083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T05:46:26.984172Z","caller":"traceutil/trace.go:171","msg":"trace[92054403] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:20282; }","duration":"128.225716ms","start":"2026-03-31T05:46:26.855932Z","end":"2026-03-31T05:46:26.984158Z","steps":["trace[92054403] 'agreement among raft nodes before linearized reading' (duration: 128.127884ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:46:27.722619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.661041ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-31T05:46:27.722783Z","caller":"traceutil/trace.go:171","msg":"trace[638325291] transaction","detail":"{read_only:false; response_revision:20285; number_of_response:1; }","duration":"259.6501ms","start":"2026-03-31T05:46:27.463108Z","end":"2026-03-31T05:46:27.722758Z","steps":["trace[638325291] 'process raft request' (duration: 143.725204ms)","trace[638325291] 'compare' (duration: 115.414825ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:46:27.732963Z","caller":"traceutil/trace.go:171","msg":"trace[1642430683] linearizableReadLoop","detail":"{readStateIndex:20967; appliedIndex:20965; }","duration":"219.320119ms","start":"2026-03-31T05:46:27.51362Z","end":"2026-03-31T05:46:27.73294Z","steps":["trace[1642430683] 'read index received' (duration: 93.225558ms)","trace[1642430683] 'applied index is now lower than readState.Index' (duration: 126.093571ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:46:27.733007Z","caller":"traceutil/trace.go:171","msg":"trace[635022276] transaction","detail":"{read_only:false; response_revision:20286; number_of_response:1; }","duration":"268.516327ms","start":"2026-03-31T05:46:27.464471Z","end":"2026-03-31T05:46:27.732987Z","steps":["trace[635022276] 'process raft request' (duration: 268.361644ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:46:27.733207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.574915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-03-31T05:46:27.733297Z","caller":"traceutil/trace.go:171","msg":"trace[1157976147] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20286; }","duration":"219.704997ms","start":"2026-03-31T05:46:27.513567Z","end":"2026-03-31T05:46:27.733272Z","steps":["trace[1157976147] 'agreement among raft nodes before linearized reading' (duration: 219.497832ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:46:27.733322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.299973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-31T05:46:27.733373Z","caller":"traceutil/trace.go:171","msg":"trace[844919466] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:20286; }","duration":"188.404666ms","start":"2026-03-31T05:46:27.544954Z","end":"2026-03-31T05:46:27.733359Z","steps":["trace[844919466] 'agreement among raft nodes before linearized reading' (duration: 188.308194ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:46:27.733346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.576464ms","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-03-31T05:46:27.733443Z","caller":"traceutil/trace.go:171","msg":"trace[1893547225] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20286; }","duration":"219.712467ms","start":"2026-03-31T05:46:27.513709Z","end":"2026-03-31T05:46:27.733421Z","steps":["trace[1893547225] 'agreement among raft nodes before linearized reading' (duration: 219.421161ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:46:27.733358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.474206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T05:46:27.733596Z","caller":"traceutil/trace.go:171","msg":"trace[1831270256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20286; }","duration":"141.748882ms","start":"2026-03-31T05:46:27.591831Z","end":"2026-03-31T05:46:27.733579Z","steps":["trace[1831270256] 'agreement among raft nodes before linearized reading' (duration: 141.384685ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:46:27.882971Z","caller":"traceutil/trace.go:171","msg":"trace[1770354027] transaction","detail":"{read_only:false; response_revision:20287; number_of_response:1; }","duration":"144.736504ms","start":"2026-03-31T05:46:27.738207Z","end":"2026-03-31T05:46:27.882944Z","steps":["trace[1770354027] 'process raft request' (duration: 136.315236ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:46:27.883302Z","caller":"traceutil/trace.go:171","msg":"trace[1055280593] linearizableReadLoop","detail":"{readStateIndex:20970; appliedIndex:20967; }","duration":"141.426084ms","start":"2026-03-31T05:46:27.741852Z","end":"2026-03-31T05:46:27.883278Z","steps":["trace[1055280593] 'read index received' (duration: 132.667369ms)","trace[1055280593] 'applied index is now lower than readState.Index' (duration: 8.757445ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:46:27.883327Z","caller":"traceutil/trace.go:171","msg":"trace[1287487457] transaction","detail":"{read_only:false; response_revision:20288; number_of_response:1; }","duration":"144.418567ms","start":"2026-03-31T05:46:27.738871Z","end":"2026-03-31T05:46:27.88329Z","steps":["trace[1287487457] 'process raft request' (duration: 144.025119ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:46:27.883336Z","caller":"traceutil/trace.go:171","msg":"trace[1344019725] transaction","detail":"{read_only:false; response_revision:20289; number_of_response:1; }","duration":"144.380817ms","start":"2026-03-31T05:46:27.738919Z","end":"2026-03-31T05:46:27.8833Z","steps":["trace[1344019725] 'process raft request' (duration: 144.243554ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:46:27.883524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.359545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T05:46:27.883568Z","caller":"traceutil/trace.go:171","msg":"trace[112258428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20289; }","duration":"115.467177ms","start":"2026-03-31T05:46:27.76809Z","end":"2026-03-31T05:46:27.883557Z","steps":["trace[112258428] 'agreement among raft nodes before linearized reading' (duration: 115.335394ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:46:27.883817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.935925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-03-31T05:46:27.883922Z","caller":"traceutil/trace.go:171","msg":"trace[253633710] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20289; }","duration":"142.091219ms","start":"2026-03-31T05:46:27.741807Z","end":"2026-03-31T05:46:27.883898Z","steps":["trace[253633710] 'agreement among raft nodes before linearized reading' (duration: 141.606678ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:46:28.206967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.342026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-31T05:46:28.207072Z","caller":"traceutil/trace.go:171","msg":"trace[2102364752] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:20290; }","duration":"207.471359ms","start":"2026-03-31T05:46:27.99958Z","end":"2026-03-31T05:46:28.207051Z","steps":["trace[2102364752] 'count revisions from in-memory index tree' (duration: 207.235604ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:50:08.326465Z","caller":"traceutil/trace.go:171","msg":"trace[42729830] transaction","detail":"{read_only:false; response_revision:22521; number_of_response:1; }","duration":"118.8972ms","start":"2026-03-31T05:50:08.207524Z","end":"2026-03-31T05:50:08.326421Z","steps":["trace[42729830] 'process raft request' (duration: 118.603773ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:50:08.43984Z","caller":"traceutil/trace.go:171","msg":"trace[902281849] transaction","detail":"{read_only:false; response_revision:22522; number_of_response:1; }","duration":"182.39383ms","start":"2026-03-31T05:50:08.257413Z","end":"2026-03-31T05:50:08.439807Z","steps":["trace[902281849] 'process raft request' (duration: 182.164275ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:50:08.44018Z","caller":"traceutil/trace.go:171","msg":"trace[108901528] transaction","detail":"{read_only:false; response_revision:22523; number_of_response:1; }","duration":"164.109763ms","start":"2026-03-31T05:50:08.276039Z","end":"2026-03-31T05:50:08.440149Z","steps":["trace[108901528] 'process raft request' (duration: 163.716675ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:50:08.44024Z","caller":"traceutil/trace.go:171","msg":"trace[976076527] transaction","detail":"{read_only:false; response_revision:22524; number_of_response:1; }","duration":"143.08206ms","start":"2026-03-31T05:50:08.297132Z","end":"2026-03-31T05:50:08.440214Z","steps":["trace[976076527] 'process raft request' (duration: 142.886386ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:50:10.556936Z","caller":"traceutil/trace.go:171","msg":"trace[859548437] transaction","detail":"{read_only:false; response_revision:22576; number_of_response:1; }","duration":"158.115368ms","start":"2026-03-31T05:50:10.398803Z","end":"2026-03-31T05:50:10.556918Z","steps":["trace[859548437] 'process raft request' (duration: 157.996065ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:50:10.56591Z","caller":"traceutil/trace.go:171","msg":"trace[336398762] transaction","detail":"{read_only:false; response_revision:22577; number_of_response:1; }","duration":"136.679465ms","start":"2026-03-31T05:50:10.429214Z","end":"2026-03-31T05:50:10.565893Z","steps":["trace[336398762] 'process raft request' (duration: 136.511222ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:50:10.56618Z","caller":"traceutil/trace.go:171","msg":"trace[143796530] transaction","detail":"{read_only:false; response_revision:22578; number_of_response:1; }","duration":"120.300839ms","start":"2026-03-31T05:50:10.445866Z","end":"2026-03-31T05:50:10.566167Z","steps":["trace[143796530] 'process raft request' (duration: 119.986102ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:50:10.566178Z","caller":"traceutil/trace.go:171","msg":"trace[834164003] transaction","detail":"{read_only:false; response_revision:22579; number_of_response:1; }","duration":"120.078894ms","start":"2026-03-31T05:50:10.446078Z","end":"2026-03-31T05:50:10.566156Z","steps":["trace[834164003] 'process raft request' (duration: 119.982532ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:50:46.862723Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20077} {"level":"info","ts":"2026-03-31T05:50:46.977323Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20077,"took":"110.438511ms","hash":3408004210,"current-db-size-bytes":57524224,"current-db-size":"58 MB","current-db-size-in-use-bytes":39993344,"current-db-size-in-use":"40 MB"} {"level":"info","ts":"2026-03-31T05:50:46.977379Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3408004210,"revision":20077,"compact-revision":17659} {"level":"info","ts":"2026-03-31T05:53:30.801186Z","caller":"traceutil/trace.go:171","msg":"trace[2041087008] transaction","detail":"{read_only:false; response_revision:24673; number_of_response:1; }","duration":"156.952511ms","start":"2026-03-31T05:53:30.644195Z","end":"2026-03-31T05:53:30.801147Z","steps":["trace[2041087008] 'process raft request' (duration: 156.697995ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:31.401945Z","caller":"traceutil/trace.go:171","msg":"trace[930213499] transaction","detail":"{read_only:false; response_revision:24675; number_of_response:1; }","duration":"110.074242ms","start":"2026-03-31T05:53:31.291849Z","end":"2026-03-31T05:53:31.401924Z","steps":["trace[930213499] 'process raft request' (duration: 109.916999ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:31.421077Z","caller":"traceutil/trace.go:171","msg":"trace[2006779410] transaction","detail":"{read_only:false; response_revision:24676; number_of_response:1; }","duration":"116.405786ms","start":"2026-03-31T05:53:31.304628Z","end":"2026-03-31T05:53:31.421034Z","steps":["trace[2006779410] 'process raft request' (duration: 116.205082ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:31.893463Z","caller":"traceutil/trace.go:171","msg":"trace[1207789024] transaction","detail":"{read_only:false; response_revision:24677; number_of_response:1; }","duration":"146.132894ms","start":"2026-03-31T05:53:31.747303Z","end":"2026-03-31T05:53:31.893435Z","steps":["trace[1207789024] 'process raft request' (duration: 145.909798ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:31.99769Z","caller":"traceutil/trace.go:171","msg":"trace[1796856587] linearizableReadLoop","detail":"{readStateIndex:25484; appliedIndex:25482; }","duration":"120.3642ms","start":"2026-03-31T05:53:31.877279Z","end":"2026-03-31T05:53:31.997643Z","steps":["trace[1796856587] 'read index received' (duration: 16.08592ms)","trace[1796856587] 'applied index is now lower than readState.Index' (duration: 104.27725ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:53:31.997787Z","caller":"traceutil/trace.go:171","msg":"trace[2026334063] transaction","detail":"{read_only:false; response_revision:24678; number_of_response:1; }","duration":"218.416338ms","start":"2026-03-31T05:53:31.779319Z","end":"2026-03-31T05:53:31.997735Z","steps":["trace[2026334063] 'process raft request' (duration: 218.06941ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:31.998538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.746551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-03-31T05:53:31.998811Z","caller":"traceutil/trace.go:171","msg":"trace[1594725549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24679; }","duration":"116.030847ms","start":"2026-03-31T05:53:31.882753Z","end":"2026-03-31T05:53:31.998784Z","steps":["trace[1594725549] 'agreement among raft nodes before linearized reading' (duration: 115.529577ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:32.000138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.836612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-03-31T05:53:32.000222Z","caller":"traceutil/trace.go:171","msg":"trace[585054719] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24678; }","duration":"122.990735ms","start":"2026-03-31T05:53:31.877209Z","end":"2026-03-31T05:53:32.0002Z","steps":["trace[585054719] 'agreement among raft nodes before linearized reading' (duration: 120.605784ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:36.331424Z","caller":"traceutil/trace.go:171","msg":"trace[1502799435] linearizableReadLoop","detail":"{readStateIndex:25521; appliedIndex:25520; }","duration":"302.00156ms","start":"2026-03-31T05:53:36.0294Z","end":"2026-03-31T05:53:36.331402Z","steps":["trace[1502799435] 'read index received' (duration: 301.799356ms)","trace[1502799435] 'applied index is now lower than readState.Index' (duration: 200.994µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:53:36.331576Z","caller":"traceutil/trace.go:171","msg":"trace[1248261520] transaction","detail":"{read_only:false; response_revision:24713; number_of_response:1; }","duration":"320.813878ms","start":"2026-03-31T05:53:36.010712Z","end":"2026-03-31T05:53:36.331526Z","steps":["trace[1248261520] 'process raft request' (duration: 320.538332ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:36.331657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.802496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-03-31T05:53:36.331726Z","caller":"traceutil/trace.go:171","msg":"trace[1619793451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24713; }","duration":"300.935249ms","start":"2026-03-31T05:53:36.030773Z","end":"2026-03-31T05:53:36.331708Z","steps":["trace[1619793451] 'agreement among raft nodes before linearized reading' (duration: 300.776255ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:36.331766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:53:36.030745Z","time spent":"301.01284ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T05:53:36.331804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:53:36.01067Z","time spent":"321.012062ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:53:36.331885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.530353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-31T05:53:36.331932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.538412ms","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-03-31T05:53:36.331986Z","caller":"traceutil/trace.go:171","msg":"trace[1134785414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24713; }","duration":"102.699137ms","start":"2026-03-31T05:53:36.229263Z","end":"2026-03-31T05:53:36.331962Z","steps":["trace[1134785414] 'agreement among raft nodes before linearized reading' (duration: 102.388651ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:36.331997Z","caller":"traceutil/trace.go:171","msg":"trace[1436749523] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24713; }","duration":"302.635073ms","start":"2026-03-31T05:53:36.02935Z","end":"2026-03-31T05:53:36.331985Z","steps":["trace[1436749523] 'agreement among raft nodes before linearized reading' (duration: 302.161634ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:36.331985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.553948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-31T05:53:36.332027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:53:36.029318Z","time spent":"302.703255ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-31T05:53:36.332047Z","caller":"traceutil/trace.go:171","msg":"trace[664512906] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24713; }","duration":"152.648149ms","start":"2026-03-31T05:53:36.179383Z","end":"2026-03-31T05:53:36.332032Z","steps":["trace[664512906] 'agreement among raft nodes before linearized reading' (duration: 152.264551ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:36.622392Z","caller":"traceutil/trace.go:171","msg":"trace[966144167] transaction","detail":"{read_only:false; response_revision:24720; number_of_response:1; }","duration":"178.336012ms","start":"2026-03-31T05:53:36.44403Z","end":"2026-03-31T05:53:36.622366Z","steps":["trace[966144167] 'process raft request' (duration: 178.297151ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:36.622796Z","caller":"traceutil/trace.go:171","msg":"trace[356849264] transaction","detail":"{read_only:false; response_revision:24719; number_of_response:1; }","duration":"284.658215ms","start":"2026-03-31T05:53:36.338122Z","end":"2026-03-31T05:53:36.62278Z","steps":["trace[356849264] 'process raft request' (duration: 284.162014ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:36.622835Z","caller":"traceutil/trace.go:171","msg":"trace[1329085008] transaction","detail":"{read_only:false; response_revision:24718; number_of_response:1; }","duration":"284.737087ms","start":"2026-03-31T05:53:36.338043Z","end":"2026-03-31T05:53:36.62278Z","steps":["trace[1329085008] 'process raft request' (duration: 284.205086ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:36.622922Z","caller":"traceutil/trace.go:171","msg":"trace[106443442] transaction","detail":"{read_only:false; response_revision:24715; number_of_response:1; }","duration":"285.203406ms","start":"2026-03-31T05:53:36.337614Z","end":"2026-03-31T05:53:36.622817Z","steps":["trace[106443442] 'process raft request' (duration: 271.980567ms)","trace[106443442] 'compare' (duration: 12.421343ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:53:36.623094Z","caller":"traceutil/trace.go:171","msg":"trace[1991241742] transaction","detail":"{read_only:false; response_revision:24717; number_of_response:1; }","duration":"285.130065ms","start":"2026-03-31T05:53:36.337903Z","end":"2026-03-31T05:53:36.623033Z","steps":["trace[1991241742] 'process raft request' (duration: 284.314478ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:36.62285Z","caller":"traceutil/trace.go:171","msg":"trace[599460207] transaction","detail":"{read_only:false; response_revision:24716; number_of_response:1; }","duration":"285.101864ms","start":"2026-03-31T05:53:36.337712Z","end":"2026-03-31T05:53:36.622814Z","steps":["trace[599460207] 'process raft request' (duration: 284.461421ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:37.229273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.229196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-31T05:53:37.229471Z","caller":"traceutil/trace.go:171","msg":"trace[534205226] transaction","detail":"{read_only:false; response_revision:24723; number_of_response:1; }","duration":"225.139369ms","start":"2026-03-31T05:53:37.004312Z","end":"2026-03-31T05:53:37.229452Z","steps":["trace[534205226] 'process raft request' (duration: 225.078688ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:37.229609Z","caller":"traceutil/trace.go:171","msg":"trace[578370547] transaction","detail":"{read_only:false; response_revision:24722; number_of_response:1; }","duration":"251.456554ms","start":"2026-03-31T05:53:36.978113Z","end":"2026-03-31T05:53:37.22957Z","steps":["trace[578370547] 'process raft request' (duration: 103.84221ms)","trace[578370547] 'compare' (duration: 147.062852ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:53:37.313871Z","caller":"traceutil/trace.go:171","msg":"trace[608742511] linearizableReadLoop","detail":"{readStateIndex:25532; appliedIndex:25531; }","duration":"141.465913ms","start":"2026-03-31T05:53:37.17238Z","end":"2026-03-31T05:53:37.313846Z","steps":["trace[608742511] 'read index received' (duration: 141.214168ms)","trace[608742511] 'applied index is now lower than readState.Index' (duration: 250.655µs)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:53:37.314037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.634509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T05:53:37.314011Z","caller":"traceutil/trace.go:171","msg":"trace[948704746] transaction","detail":"{read_only:false; response_revision:24724; number_of_response:1; }","duration":"220.643785ms","start":"2026-03-31T05:53:37.093314Z","end":"2026-03-31T05:53:37.313957Z","steps":["trace[948704746] 'process raft request' (duration: 220.360298ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:37.314098Z","caller":"traceutil/trace.go:171","msg":"trace[31134615] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:24724; }","duration":"141.763791ms","start":"2026-03-31T05:53:37.172315Z","end":"2026-03-31T05:53:37.314079Z","steps":["trace[31134615] 'agreement among raft nodes before linearized reading' (duration: 141.631478ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:37.623532Z","caller":"traceutil/trace.go:171","msg":"trace[1351004783] transaction","detail":"{read_only:false; response_revision:24725; number_of_response:1; }","duration":"199.867156ms","start":"2026-03-31T05:53:37.423643Z","end":"2026-03-31T05:53:37.62351Z","steps":["trace[1351004783] 'process raft request' (duration: 199.690452ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:37.931597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.643163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-31T05:53:37.931706Z","caller":"traceutil/trace.go:171","msg":"trace[1901476923] linearizableReadLoop","detail":"{readStateIndex:25534; appliedIndex:25533; }","duration":"163.522639ms","start":"2026-03-31T05:53:37.768166Z","end":"2026-03-31T05:53:37.931689Z","steps":["trace[1901476923] 'read index received' (duration: 25.574239ms)","trace[1901476923] 'applied index is now lower than readState.Index' (duration: 137.94645ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:53:37.931774Z","caller":"traceutil/trace.go:171","msg":"trace[914950794] transaction","detail":"{read_only:false; response_revision:24726; number_of_response:1; }","duration":"483.52053ms","start":"2026-03-31T05:53:37.44822Z","end":"2026-03-31T05:53:37.93174Z","steps":["trace[914950794] 'process raft request' (duration: 345.625161ms)","trace[914950794] 'compare' (duration: 137.520691ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:53:37.931819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.662941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-31T05:53:37.931933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:53:37.448187Z","time spent":"483.663743ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:53:37.931968Z","caller":"traceutil/trace.go:171","msg":"trace[740130865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24726; }","duration":"163.862066ms","start":"2026-03-31T05:53:37.768091Z","end":"2026-03-31T05:53:37.931953Z","steps":["trace[740130865] 'agreement among raft nodes before linearized reading' (duration: 163.689403ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:37.932149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.008196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-03-31T05:53:37.932267Z","caller":"traceutil/trace.go:171","msg":"trace[639697447] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24726; }","duration":"106.2089ms","start":"2026-03-31T05:53:37.826034Z","end":"2026-03-31T05:53:37.932242Z","steps":["trace[639697447] 'agreement among raft nodes before linearized reading' (duration: 105.832693ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:38.236029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.408837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-31T05:53:38.23614Z","caller":"traceutil/trace.go:171","msg":"trace[55339074] linearizableReadLoop","detail":"{readStateIndex:25535; appliedIndex:25534; }","duration":"242.033476ms","start":"2026-03-31T05:53:37.994089Z","end":"2026-03-31T05:53:38.236122Z","steps":["trace[55339074] 'read index received' (duration: 96.375943ms)","trace[55339074] 'applied index is now lower than readState.Index' (duration: 145.655863ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:53:38.236414Z","caller":"traceutil/trace.go:171","msg":"trace[987474886] transaction","detail":"{read_only:false; response_revision:24727; number_of_response:1; }","duration":"251.922585ms","start":"2026-03-31T05:53:37.984475Z","end":"2026-03-31T05:53:38.236398Z","steps":["trace[987474886] 'process raft request' (duration: 106.060778ms)","trace[987474886] 'compare' (duration: 144.699912ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:53:38.236632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.537476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T05:53:38.236669Z","caller":"traceutil/trace.go:171","msg":"trace[2119750722] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24727; }","duration":"242.611978ms","start":"2026-03-31T05:53:37.994047Z","end":"2026-03-31T05:53:38.236659Z","steps":["trace[2119750722] 'agreement among raft nodes before linearized reading' (duration: 242.546677ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:38.593986Z","caller":"traceutil/trace.go:171","msg":"trace[1717641714] linearizableReadLoop","detail":"{readStateIndex:25536; appliedIndex:25535; }","duration":"188.382634ms","start":"2026-03-31T05:53:38.405572Z","end":"2026-03-31T05:53:38.593954Z","steps":["trace[1717641714] 'read index received' (duration: 135.305124ms)","trace[1717641714] 'applied index is now lower than readState.Index' (duration: 53.07583ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:53:38.594228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.636091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-03-31T05:53:38.594257Z","caller":"traceutil/trace.go:171","msg":"trace[448699353] transaction","detail":"{read_only:false; response_revision:24728; number_of_response:1; }","duration":"256.398119ms","start":"2026-03-31T05:53:38.337828Z","end":"2026-03-31T05:53:38.594226Z","steps":["trace[448699353] 'process raft request' (duration: 203.007542ms)","trace[448699353] 'compare' (duration: 52.980388ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:53:38.594293Z","caller":"traceutil/trace.go:171","msg":"trace[1994862629] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24728; }","duration":"188.759232ms","start":"2026-03-31T05:53:38.405517Z","end":"2026-03-31T05:53:38.594276Z","steps":["trace[1994862629] 'agreement among raft nodes before linearized reading' (duration: 188.550527ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:38.8122Z","caller":"traceutil/trace.go:171","msg":"trace[1697777181] linearizableReadLoop","detail":"{readStateIndex:25537; appliedIndex:25536; }","duration":"184.470581ms","start":"2026-03-31T05:53:38.627504Z","end":"2026-03-31T05:53:38.811975Z","steps":["trace[1697777181] 'read index received' (duration: 143.58699ms)","trace[1697777181] 'applied index is now lower than readState.Index' (duration: 40.882141ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:53:38.81228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.743597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-03-31T05:53:38.812671Z","caller":"traceutil/trace.go:171","msg":"trace[1613938559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24729; }","duration":"185.218818ms","start":"2026-03-31T05:53:38.62744Z","end":"2026-03-31T05:53:38.812659Z","steps":["trace[1613938559] 'agreement among raft nodes before linearized reading' (duration: 184.627044ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:38.812457Z","caller":"traceutil/trace.go:171","msg":"trace[1997291337] transaction","detail":"{read_only:false; response_revision:24729; number_of_response:1; }","duration":"212.021642ms","start":"2026-03-31T05:53:38.600009Z","end":"2026-03-31T05:53:38.812031Z","steps":["trace[1997291337] 'process raft request' (duration: 171.087919ms)","trace[1997291337] 'compare' (duration: 40.527064ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:53:38.823919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.775709ms","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-03-31T05:53:38.823982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.485742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-03-31T05:53:38.824004Z","caller":"traceutil/trace.go:171","msg":"trace[360608142] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24730; }","duration":"195.925872ms","start":"2026-03-31T05:53:38.628058Z","end":"2026-03-31T05:53:38.823983Z","steps":["trace[360608142] 'agreement among raft nodes before linearized reading' (duration: 195.690737ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:38.824044Z","caller":"traceutil/trace.go:171","msg":"trace[1809400066] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24730; }","duration":"193.662446ms","start":"2026-03-31T05:53:38.630364Z","end":"2026-03-31T05:53:38.824026Z","steps":["trace[1809400066] 'agreement among raft nodes before linearized reading' (duration: 193.470641ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:38.824183Z","caller":"traceutil/trace.go:171","msg":"trace[1245487291] transaction","detail":"{read_only:false; response_revision:24730; number_of_response:1; }","duration":"194.553364ms","start":"2026-03-31T05:53:38.629622Z","end":"2026-03-31T05:53:38.824175Z","steps":["trace[1245487291] 'process raft request' (duration: 193.960751ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:38.8245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.27212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-31T05:53:38.824553Z","caller":"traceutil/trace.go:171","msg":"trace[505275817] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24730; }","duration":"196.359822ms","start":"2026-03-31T05:53:38.628183Z","end":"2026-03-31T05:53:38.824543Z","steps":["trace[505275817] 'agreement among raft nodes before linearized reading' (duration: 196.227959ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:38.824735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.245918ms","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-03-31T05:53:38.824816Z","caller":"traceutil/trace.go:171","msg":"trace[1548661102] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24730; }","duration":"194.36456ms","start":"2026-03-31T05:53:38.63042Z","end":"2026-03-31T05:53:38.824784Z","steps":["trace[1548661102] 'agreement among raft nodes before linearized reading' (duration: 194.108625ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:39.144416Z","caller":"traceutil/trace.go:171","msg":"trace[1534067704] transaction","detail":"{read_only:false; response_revision:24732; number_of_response:1; }","duration":"317.093699ms","start":"2026-03-31T05:53:38.827302Z","end":"2026-03-31T05:53:39.144396Z","steps":["trace[1534067704] 'process raft request' (duration: 316.927146ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:39.14456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:53:38.827283Z","time spent":"317.205181ms","remote":"127.0.0.1:56672","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-03-31T05:53:39.144677Z","caller":"traceutil/trace.go:171","msg":"trace[1624711974] transaction","detail":"{read_only:false; response_revision:24733; number_of_response:1; }","duration":"315.274741ms","start":"2026-03-31T05:53:38.829375Z","end":"2026-03-31T05:53:39.144649Z","steps":["trace[1624711974] 'process raft request' (duration: 314.988985ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:39.144797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:53:38.829364Z","time spent":"315.369403ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:53:39.145201Z","caller":"traceutil/trace.go:171","msg":"trace[1757404672] transaction","detail":"{read_only:false; response_revision:24734; number_of_response:1; }","duration":"313.607986ms","start":"2026-03-31T05:53:38.831585Z","end":"2026-03-31T05:53:39.145193Z","steps":["trace[1757404672] 'process raft request' (duration: 313.008763ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:39.145243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:53:38.831564Z","time spent":"313.659326ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:53:39.146062Z","caller":"traceutil/trace.go:171","msg":"trace[373233105] transaction","detail":"{read_only:false; response_revision:24735; number_of_response:1; }","duration":"313.502742ms","start":"2026-03-31T05:53:38.832327Z","end":"2026-03-31T05:53:39.14583Z","steps":["trace[373233105] 'process raft request' (duration: 312.46953ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:39.146267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:53:38.832301Z","time spent":"313.863651ms","remote":"127.0.0.1:56672","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-03-31T05:53:39.206438Z","caller":"traceutil/trace.go:171","msg":"trace[883074061] transaction","detail":"{read_only:false; response_revision:24736; number_of_response:1; }","duration":"275.204245ms","start":"2026-03-31T05:53:38.931203Z","end":"2026-03-31T05:53:39.206407Z","steps":["trace[883074061] 'process raft request' (duration: 275.039792ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:39.775987Z","caller":"traceutil/trace.go:171","msg":"trace[442920406] transaction","detail":"{read_only:false; response_revision:24738; number_of_response:1; }","duration":"146.946949ms","start":"2026-03-31T05:53:39.628963Z","end":"2026-03-31T05:53:39.77591Z","steps":["trace[442920406] 'process raft request' (duration: 64.079112ms)","trace[442920406] 'compare' (duration: 82.602792ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:53:40.082023Z","caller":"traceutil/trace.go:171","msg":"trace[89960620] transaction","detail":"{read_only:false; response_revision:24739; number_of_response:1; }","duration":"141.003115ms","start":"2026-03-31T05:53:39.94099Z","end":"2026-03-31T05:53:40.081993Z","steps":["trace[89960620] 'process raft request' (duration: 138.335139ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:40.265383Z","caller":"traceutil/trace.go:171","msg":"trace[878632423] transaction","detail":"{read_only:false; response_revision:24740; number_of_response:1; }","duration":"161.616129ms","start":"2026-03-31T05:53:40.103747Z","end":"2026-03-31T05:53:40.265363Z","steps":["trace[878632423] 'process raft request' (duration: 158.004753ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:40.586604Z","caller":"traceutil/trace.go:171","msg":"trace[1548604744] linearizableReadLoop","detail":"{readStateIndex:25550; appliedIndex:25549; }","duration":"319.385227ms","start":"2026-03-31T05:53:40.267181Z","end":"2026-03-31T05:53:40.586566Z","steps":["trace[1548604744] 'read index received' (duration: 293.258955ms)","trace[1548604744] 'applied index is now lower than readState.Index' (duration: 26.124102ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:53:40.586722Z","caller":"traceutil/trace.go:171","msg":"trace[1515184157] transaction","detail":"{read_only:false; response_revision:24741; number_of_response:1; }","duration":"343.058617ms","start":"2026-03-31T05:53:40.243628Z","end":"2026-03-31T05:53:40.586687Z","steps":["trace[1515184157] 'process raft request' (duration: 316.848144ms)","trace[1515184157] 'compare' (duration: 25.821375ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:53:40.586885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:53:40.243596Z","time spent":"343.199941ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:53:40.58689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.685674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-03-31T05:53:40.58695Z","caller":"traceutil/trace.go:171","msg":"trace[848378632] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24741; }","duration":"319.792845ms","start":"2026-03-31T05:53:40.267138Z","end":"2026-03-31T05:53:40.586931Z","steps":["trace[848378632] 'agreement among raft nodes before linearized reading' (duration: 319.55416ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:40.587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:53:40.267124Z","time spent":"319.862728ms","remote":"127.0.0.1:56554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-03-31T05:53:40.74489Z","caller":"traceutil/trace.go:171","msg":"trace[1864646217] transaction","detail":"{read_only:false; response_revision:24742; number_of_response:1; }","duration":"146.288375ms","start":"2026-03-31T05:53:40.598566Z","end":"2026-03-31T05:53:40.744855Z","steps":["trace[1864646217] 'process raft request' (duration: 132.643028ms)","trace[1864646217] 'compare' (duration: 13.114666ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:53:40.927089Z","caller":"traceutil/trace.go:171","msg":"trace[456486666] transaction","detail":"{read_only:false; response_revision:24743; number_of_response:1; }","duration":"104.911273ms","start":"2026-03-31T05:53:40.822153Z","end":"2026-03-31T05:53:40.927065Z","steps":["trace[456486666] 'process raft request' (duration: 104.702759ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:40.947442Z","caller":"traceutil/trace.go:171","msg":"trace[727474587] transaction","detail":"{read_only:false; response_revision:24744; number_of_response:1; }","duration":"115.258951ms","start":"2026-03-31T05:53:40.832144Z","end":"2026-03-31T05:53:40.947403Z","steps":["trace[727474587] 'process raft request' (duration: 115.027786ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:41.266549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.065919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-03-31T05:53:41.266644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.426286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-03-31T05:53:41.266675Z","caller":"traceutil/trace.go:171","msg":"trace[1277293832] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24745; }","duration":"117.243193ms","start":"2026-03-31T05:53:41.149402Z","end":"2026-03-31T05:53:41.266645Z","steps":["trace[1277293832] 'range keys from in-memory index tree' (duration: 116.823364ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:41.266721Z","caller":"traceutil/trace.go:171","msg":"trace[152783143] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24745; }","duration":"116.546679ms","start":"2026-03-31T05:53:41.150154Z","end":"2026-03-31T05:53:41.266701Z","steps":["trace[152783143] 'range keys from in-memory index tree' (duration: 116.1929ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:41.266889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.315225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T05:53:41.266931Z","caller":"traceutil/trace.go:171","msg":"trace[480748798] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:24745; }","duration":"199.390086ms","start":"2026-03-31T05:53:41.067526Z","end":"2026-03-31T05:53:41.266916Z","steps":["trace[480748798] 'count revisions from in-memory index tree' (duration: 199.240554ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:41.266977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.769773ms","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-03-31T05:53:41.267004Z","caller":"traceutil/trace.go:171","msg":"trace[301032043] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24745; }","duration":"116.850565ms","start":"2026-03-31T05:53:41.150144Z","end":"2026-03-31T05:53:41.266995Z","steps":["trace[301032043] 'range keys from in-memory index tree' (duration: 116.58607ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:41.267124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.502578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T05:53:41.267142Z","caller":"traceutil/trace.go:171","msg":"trace[57070974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24745; }","duration":"116.541459ms","start":"2026-03-31T05:53:41.150594Z","end":"2026-03-31T05:53:41.267135Z","steps":["trace[57070974] 'range keys from in-memory index tree' (duration: 116.402176ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:41.423961Z","caller":"traceutil/trace.go:171","msg":"trace[2111767468] transaction","detail":"{read_only:false; response_revision:24746; number_of_response:1; }","duration":"209.937519ms","start":"2026-03-31T05:53:41.213997Z","end":"2026-03-31T05:53:41.423935Z","steps":["trace[2111767468] 'process raft request' (duration: 209.663483ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:41.764098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.529665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-31T05:53:41.764321Z","caller":"traceutil/trace.go:171","msg":"trace[1832950154] transaction","detail":"{read_only:false; response_revision:24748; number_of_response:1; }","duration":"491.391505ms","start":"2026-03-31T05:53:41.272911Z","end":"2026-03-31T05:53:41.764303Z","steps":["trace[1832950154] 'process raft request' (duration: 491.287913ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:41.764346Z","caller":"traceutil/trace.go:171","msg":"trace[448500126] transaction","detail":"{read_only:false; response_revision:24747; number_of_response:1; }","duration":"491.502718ms","start":"2026-03-31T05:53:41.272791Z","end":"2026-03-31T05:53:41.764293Z","steps":["trace[448500126] 'process raft request' (duration: 303.492342ms)","trace[448500126] 'compare' (duration: 187.28053ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:53:41.764402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:53:41.272885Z","time spent":"491.484217ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:53:41.764587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:53:41.27277Z","time spent":"491.678961ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":488,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:53:41.764809Z","caller":"traceutil/trace.go:171","msg":"trace[1442110403] transaction","detail":"{read_only:false; response_revision:24749; number_of_response:1; }","duration":"491.767703ms","start":"2026-03-31T05:53:41.273029Z","end":"2026-03-31T05:53:41.764797Z","steps":["trace[1442110403] 'process raft request' (duration: 491.235592ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:41.764853Z","caller":"traceutil/trace.go:171","msg":"trace[1279550071] transaction","detail":"{read_only:false; response_revision:24751; number_of_response:1; }","duration":"344.794173ms","start":"2026-03-31T05:53:41.420052Z","end":"2026-03-31T05:53:41.764846Z","steps":["trace[1279550071] 'process raft request' (duration: 344.465516ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:41.766195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:53:41.420018Z","time spent":"346.087161ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:53:41.764883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:53:41.273023Z","time spent":"491.828464ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:53:41.764793Z","caller":"traceutil/trace.go:171","msg":"trace[742664418] transaction","detail":"{read_only:false; response_revision:24750; number_of_response:1; }","duration":"491.579209ms","start":"2026-03-31T05:53:41.273188Z","end":"2026-03-31T05:53:41.764767Z","steps":["trace[742664418] 'process raft request' (duration: 491.246602ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:41.766503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:53:41.273155Z","time spent":"493.321876ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:53:42.080986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.909471ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-31T05:53:42.081299Z","caller":"traceutil/trace.go:171","msg":"trace[1913531297] transaction","detail":"{read_only:false; response_revision:24752; number_of_response:1; }","duration":"285.013832ms","start":"2026-03-31T05:53:41.796249Z","end":"2026-03-31T05:53:42.081263Z","steps":["trace[1913531297] 'process raft request' (duration: 143.711892ms)","trace[1913531297] 'compare' (duration: 140.491763ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:53:42.377571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.89624ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-31T05:53:42.377841Z","caller":"traceutil/trace.go:171","msg":"trace[1150880755] transaction","detail":"{read_only:false; response_revision:24754; number_of_response:1; }","duration":"142.877963ms","start":"2026-03-31T05:53:42.234943Z","end":"2026-03-31T05:53:42.377821Z","steps":["trace[1150880755] 'process raft request' (duration: 142.801782ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:42.378168Z","caller":"traceutil/trace.go:171","msg":"trace[1232022976] transaction","detail":"{read_only:false; response_revision:24753; number_of_response:1; }","duration":"288.883663ms","start":"2026-03-31T05:53:42.089274Z","end":"2026-03-31T05:53:42.378157Z","steps":["trace[1232022976] 'process raft request' (duration: 103.295729ms)","trace[1232022976] 'compare' (duration: 184.762237ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:53:42.378274Z","caller":"traceutil/trace.go:171","msg":"trace[1057210307] linearizableReadLoop","detail":"{readStateIndex:25562; appliedIndex:25561; }","duration":"265.784677ms","start":"2026-03-31T05:53:42.112482Z","end":"2026-03-31T05:53:42.378267Z","steps":["trace[1057210307] 'read index received' (duration: 80.136061ms)","trace[1057210307] 'applied index is now lower than readState.Index' (duration: 185.647946ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:53:42.378445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.96553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T05:53:42.378479Z","caller":"traceutil/trace.go:171","msg":"trace[1681370500] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:24754; }","duration":"266.037662ms","start":"2026-03-31T05:53:42.11243Z","end":"2026-03-31T05:53:42.378468Z","steps":["trace[1681370500] 'agreement among raft nodes before linearized reading' (duration: 265.980141ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:43.288171Z","caller":"traceutil/trace.go:171","msg":"trace[443888564] linearizableReadLoop","detail":"{readStateIndex:25566; appliedIndex:25565; }","duration":"291.862047ms","start":"2026-03-31T05:53:42.996283Z","end":"2026-03-31T05:53:43.288145Z","steps":["trace[443888564] 'read index received' (duration: 291.827566ms)","trace[443888564] 'applied index is now lower than readState.Index' (duration: 32.961µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:53:43.28829Z","caller":"traceutil/trace.go:171","msg":"trace[1839127223] transaction","detail":"{read_only:false; response_revision:24757; number_of_response:1; }","duration":"335.329804ms","start":"2026-03-31T05:53:42.952927Z","end":"2026-03-31T05:53:43.288257Z","steps":["trace[1839127223] 'process raft request' (duration: 335.076219ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:43.28838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.095202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-03-31T05:53:43.28845Z","caller":"traceutil/trace.go:171","msg":"trace[1526485885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24757; }","duration":"292.198884ms","start":"2026-03-31T05:53:42.996209Z","end":"2026-03-31T05:53:43.288407Z","steps":["trace[1526485885] 'agreement among raft nodes before linearized reading' (duration: 292.059041ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:43.288451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:53:42.952891Z","time spent":"335.472097ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:53:43.307498Z","caller":"traceutil/trace.go:171","msg":"trace[204222774] transaction","detail":"{read_only:false; response_revision:24759; number_of_response:1; }","duration":"250.415012ms","start":"2026-03-31T05:53:43.057049Z","end":"2026-03-31T05:53:43.307464Z","steps":["trace[204222774] 'process raft request' (duration: 250.34893ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:43.308238Z","caller":"traceutil/trace.go:171","msg":"trace[1561555490] transaction","detail":"{read_only:false; response_revision:24758; number_of_response:1; }","duration":"283.298195ms","start":"2026-03-31T05:53:43.02492Z","end":"2026-03-31T05:53:43.308218Z","steps":["trace[1561555490] 'process raft request' (duration: 282.267074ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:43.310487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.033609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T05:53:43.310554Z","caller":"traceutil/trace.go:171","msg":"trace[2078866254] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:24759; }","duration":"190.165552ms","start":"2026-03-31T05:53:43.120374Z","end":"2026-03-31T05:53:43.310539Z","steps":["trace[2078866254] 'agreement among raft nodes before linearized reading' (duration: 190.027549ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:43.310591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.9176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T05:53:43.310692Z","caller":"traceutil/trace.go:171","msg":"trace[1173987436] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:24759; }","duration":"312.084433ms","start":"2026-03-31T05:53:42.998584Z","end":"2026-03-31T05:53:43.310668Z","steps":["trace[1173987436] 'agreement among raft nodes before linearized reading' (duration: 311.884049ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:43.310751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:53:42.99856Z","time spent":"312.179275ms","remote":"127.0.0.1:42980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"info","ts":"2026-03-31T05:53:43.978208Z","caller":"traceutil/trace.go:171","msg":"trace[2036583640] linearizableReadLoop","detail":"{readStateIndex:25571; appliedIndex:25570; }","duration":"151.900245ms","start":"2026-03-31T05:53:43.826269Z","end":"2026-03-31T05:53:43.978169Z","steps":["trace[2036583640] 'read index received' (duration: 151.66966ms)","trace[2036583640] 'applied index is now lower than readState.Index' (duration: 229.155µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:53:43.978305Z","caller":"traceutil/trace.go:171","msg":"trace[1322309160] transaction","detail":"{read_only:false; response_revision:24762; number_of_response:1; }","duration":"206.463715ms","start":"2026-03-31T05:53:43.771807Z","end":"2026-03-31T05:53:43.978271Z","steps":["trace[1322309160] 'process raft request' (duration: 206.075797ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:43.978432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.1302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T05:53:43.978555Z","caller":"traceutil/trace.go:171","msg":"trace[1444958583] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24762; }","duration":"152.269483ms","start":"2026-03-31T05:53:43.826235Z","end":"2026-03-31T05:53:43.978504Z","steps":["trace[1444958583] 'agreement among raft nodes before linearized reading' (duration: 152.097448ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:44.125109Z","caller":"traceutil/trace.go:171","msg":"trace[672691790] transaction","detail":"{read_only:false; response_revision:24763; number_of_response:1; }","duration":"297.975616ms","start":"2026-03-31T05:53:43.827103Z","end":"2026-03-31T05:53:44.125078Z","steps":["trace[672691790] 'process raft request' (duration: 222.677898ms)","trace[672691790] 'compare' (duration: 74.650285ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:53:44.125189Z","caller":"traceutil/trace.go:171","msg":"trace[409703388] transaction","detail":"{read_only:false; response_revision:24764; number_of_response:1; }","duration":"296.341522ms","start":"2026-03-31T05:53:43.828813Z","end":"2026-03-31T05:53:44.125155Z","steps":["trace[409703388] 'process raft request' (duration: 296.110907ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:44.125311Z","caller":"traceutil/trace.go:171","msg":"trace[1166635800] transaction","detail":"{read_only:false; response_revision:24765; number_of_response:1; }","duration":"296.411512ms","start":"2026-03-31T05:53:43.828885Z","end":"2026-03-31T05:53:44.125297Z","steps":["trace[1166635800] 'process raft request' (duration: 296.127927ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:44.125368Z","caller":"traceutil/trace.go:171","msg":"trace[1850014113] transaction","detail":"{read_only:false; response_revision:24766; number_of_response:1; }","duration":"296.028155ms","start":"2026-03-31T05:53:43.829292Z","end":"2026-03-31T05:53:44.125321Z","steps":["trace[1850014113] 'process raft request' (duration: 295.752759ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:46.631601Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938290502987373556,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-31T05:53:46.720106Z","caller":"traceutil/trace.go:171","msg":"trace[342401895] transaction","detail":"{read_only:false; response_revision:24778; number_of_response:1; }","duration":"590.138558ms","start":"2026-03-31T05:53:46.129947Z","end":"2026-03-31T05:53:46.720085Z","steps":["trace[342401895] 'process raft request' (duration: 589.986674ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:46.72025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:53:46.129922Z","time spent":"590.2465ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:53:46.924686Z","caller":"traceutil/trace.go:171","msg":"trace[323920357] transaction","detail":"{read_only:false; response_revision:24779; number_of_response:1; }","duration":"533.660317ms","start":"2026-03-31T05:53:46.390992Z","end":"2026-03-31T05:53:46.924653Z","steps":["trace[323920357] 'process raft request' (duration: 518.018237ms)","trace[323920357] 'compare' (duration: 15.424405ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:53:46.924898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:53:46.390969Z","time spent":"533.83899ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:53:46.925169Z","caller":"traceutil/trace.go:171","msg":"trace[849239915] transaction","detail":"{read_only:false; response_revision:24780; number_of_response:1; }","duration":"314.763789ms","start":"2026-03-31T05:53:46.610385Z","end":"2026-03-31T05:53:46.925149Z","steps":["trace[849239915] 'process raft request' (duration: 314.203507ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:46.925193Z","caller":"traceutil/trace.go:171","msg":"trace[902088901] transaction","detail":"{read_only:false; response_revision:24781; number_of_response:1; }","duration":"103.740959ms","start":"2026-03-31T05:53:46.821433Z","end":"2026-03-31T05:53:46.925174Z","steps":["trace[902088901] 'process raft request' (duration: 103.622436ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:46.925251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:53:46.610333Z","time spent":"314.873891ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:53:46.925329Z","caller":"traceutil/trace.go:171","msg":"trace[1512792587] linearizableReadLoop","detail":"{readStateIndex:25590; appliedIndex:25587; }","duration":"794.446168ms","start":"2026-03-31T05:53:46.130874Z","end":"2026-03-31T05:53:46.925321Z","steps":["trace[1512792587] 'read index received' (duration: 589.076896ms)","trace[1512792587] 'applied index is now lower than readState.Index' (duration: 205.368182ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:53:46.925522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.632622ms","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-03-31T05:53:46.925551Z","caller":"traceutil/trace.go:171","msg":"trace[1573778765] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24781; }","duration":"794.701804ms","start":"2026-03-31T05:53:46.13084Z","end":"2026-03-31T05:53:46.925542Z","steps":["trace[1573778765] 'agreement among raft nodes before linearized reading' (duration: 794.593872ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:46.925575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:53:46.13083Z","time spent":"794.737185ms","remote":"127.0.0.1:56672","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-03-31T05:53:46.925907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.226628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-31T05:53:46.925896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.166407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-31T05:53:46.925982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.357451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T05:53:46.925979Z","caller":"traceutil/trace.go:171","msg":"trace[2095596250] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:24781; }","duration":"478.276929ms","start":"2026-03-31T05:53:46.447685Z","end":"2026-03-31T05:53:46.925962Z","steps":["trace[2095596250] 'agreement among raft nodes before linearized reading' (duration: 478.117836ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:46.926005Z","caller":"traceutil/trace.go:171","msg":"trace[1982816452] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:24781; }","duration":"716.418233ms","start":"2026-03-31T05:53:46.209581Z","end":"2026-03-31T05:53:46.925999Z","steps":["trace[1982816452] 'agreement among raft nodes before linearized reading' (duration: 716.378282ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:46.926025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:53:46.20956Z","time spent":"716.459163ms","remote":"127.0.0.1:34366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true "} {"level":"warn","ts":"2026-03-31T05:53:46.92605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:53:46.447597Z","time spent":"478.437002ms","remote":"127.0.0.1:34560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true "} {"level":"warn","ts":"2026-03-31T05:53:46.926077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.740925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-03-31T05:53:46.926082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.904288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-03-31T05:53:46.92613Z","caller":"traceutil/trace.go:171","msg":"trace[302142741] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24781; }","duration":"794.866117ms","start":"2026-03-31T05:53:46.131249Z","end":"2026-03-31T05:53:46.926115Z","steps":["trace[302142741] 'agreement among raft nodes before linearized reading' (duration: 794.694264ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:46.926149Z","caller":"traceutil/trace.go:171","msg":"trace[14353728] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24781; }","duration":"795.01728ms","start":"2026-03-31T05:53:46.131115Z","end":"2026-03-31T05:53:46.926132Z","steps":["trace[14353728] 'agreement among raft nodes before linearized reading' (duration: 794.851497ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:46.926168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:53:46.13124Z","time spent":"794.917498ms","remote":"127.0.0.1:56672","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-03-31T05:53:46.926181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:53:46.131091Z","time spent":"795.082991ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-31T05:53:46.925894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.128147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T05:53:46.926233Z","caller":"traceutil/trace.go:171","msg":"trace[1501687169] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24781; }","duration":"159.538275ms","start":"2026-03-31T05:53:46.766684Z","end":"2026-03-31T05:53:46.926222Z","steps":["trace[1501687169] 'agreement among raft nodes before linearized reading' (duration: 159.123097ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:46.925937Z","caller":"traceutil/trace.go:171","msg":"trace[1157692592] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:24781; }","duration":"230.291399ms","start":"2026-03-31T05:53:46.695635Z","end":"2026-03-31T05:53:46.925926Z","steps":["trace[1157692592] 'agreement among raft nodes before linearized reading' (duration: 230.238668ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:46.927309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.740045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T05:53:46.927398Z","caller":"traceutil/trace.go:171","msg":"trace[718084608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24781; }","duration":"796.377349ms","start":"2026-03-31T05:53:46.130985Z","end":"2026-03-31T05:53:46.927362Z","steps":["trace[718084608] 'agreement among raft nodes before linearized reading' (duration: 794.48168ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:46.928483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:53:46.130968Z","time spent":"796.559642ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-31T05:53:55.198724Z","caller":"traceutil/trace.go:171","msg":"trace[1707563735] transaction","detail":"{read_only:false; response_revision:24895; number_of_response:1; }","duration":"240.573905ms","start":"2026-03-31T05:53:54.958124Z","end":"2026-03-31T05:53:55.198698Z","steps":["trace[1707563735] 'process raft request' (duration: 240.376311ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:55.20089Z","caller":"traceutil/trace.go:171","msg":"trace[467896452] linearizableReadLoop","detail":"{readStateIndex:25708; appliedIndex:25705; }","duration":"231.741549ms","start":"2026-03-31T05:53:54.969123Z","end":"2026-03-31T05:53:55.200865Z","steps":["trace[467896452] 'read index received' (duration: 229.42573ms)","trace[467896452] 'applied index is now lower than readState.Index' (duration: 2.314779ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:53:55.201002Z","caller":"traceutil/trace.go:171","msg":"trace[1003014548] transaction","detail":"{read_only:false; response_revision:24897; number_of_response:1; }","duration":"235.766544ms","start":"2026-03-31T05:53:54.965222Z","end":"2026-03-31T05:53:55.200989Z","steps":["trace[1003014548] 'process raft request' (duration: 235.59441ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:55.201019Z","caller":"traceutil/trace.go:171","msg":"trace[466437000] transaction","detail":"{read_only:false; response_revision:24896; number_of_response:1; }","duration":"235.798025ms","start":"2026-03-31T05:53:54.96518Z","end":"2026-03-31T05:53:55.200978Z","steps":["trace[466437000] 'process raft request' (duration: 235.511209ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:55.201215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.777309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-03-31T05:53:55.201276Z","caller":"traceutil/trace.go:171","msg":"trace[1227175710] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24897; }","duration":"230.87171ms","start":"2026-03-31T05:53:54.970387Z","end":"2026-03-31T05:53:55.201259Z","steps":["trace[1227175710] 'agreement among raft nodes before linearized reading' (duration: 230.649946ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:55.201292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.862874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a648a83c-4dca-47d2-b53b-c0a9b0a64605\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-03-31T05:53:55.201358Z","caller":"traceutil/trace.go:171","msg":"trace[680151846] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a648a83c-4dca-47d2-b53b-c0a9b0a64605; range_end:; response_count:1; response_revision:24897; }","duration":"151.932045ms","start":"2026-03-31T05:53:55.049403Z","end":"2026-03-31T05:53:55.201335Z","steps":["trace[680151846] 'agreement among raft nodes before linearized reading' (duration: 151.727751ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:55.2014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.942739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-03-31T05:53:55.20142Z","caller":"traceutil/trace.go:171","msg":"trace[732827869] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24897; }","duration":"227.97403ms","start":"2026-03-31T05:53:54.973439Z","end":"2026-03-31T05:53:55.201413Z","steps":["trace[732827869] 'agreement among raft nodes before linearized reading' (duration: 227.919539ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:55.201446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.826609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T05:53:55.201495Z","caller":"traceutil/trace.go:171","msg":"trace[1020448355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24897; }","duration":"221.916281ms","start":"2026-03-31T05:53:54.979567Z","end":"2026-03-31T05:53:55.201484Z","steps":["trace[1020448355] 'agreement among raft nodes before linearized reading' (duration: 221.731057ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:55.20152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.368082ms","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-03-31T05:53:55.20159Z","caller":"traceutil/trace.go:171","msg":"trace[1189690422] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24897; }","duration":"232.490824ms","start":"2026-03-31T05:53:54.96908Z","end":"2026-03-31T05:53:55.201571Z","steps":["trace[1189690422] 'agreement among raft nodes before linearized reading' (duration: 232.172627ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:55.487767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.731787ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-31T05:53:55.487929Z","caller":"traceutil/trace.go:171","msg":"trace[889522481] transaction","detail":"{read_only:false; response_revision:24898; number_of_response:1; }","duration":"282.743504ms","start":"2026-03-31T05:53:55.205167Z","end":"2026-03-31T05:53:55.48791Z","steps":["trace[889522481] 'process raft request' (duration: 134.765852ms)","trace[889522481] 'compare' (duration: 147.608364ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:53:55.488293Z","caller":"traceutil/trace.go:171","msg":"trace[589686265] transaction","detail":"{read_only:false; response_revision:24899; number_of_response:1; }","duration":"282.54905ms","start":"2026-03-31T05:53:55.205716Z","end":"2026-03-31T05:53:55.488265Z","steps":["trace[589686265] 'process raft request' (duration: 282.426838ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:55.488411Z","caller":"traceutil/trace.go:171","msg":"trace[1146801722] linearizableReadLoop","detail":"{readStateIndex:25712; appliedIndex:25708; }","duration":"177.276899ms","start":"2026-03-31T05:53:55.311121Z","end":"2026-03-31T05:53:55.488398Z","steps":["trace[1146801722] 'read index received' (duration: 28.821368ms)","trace[1146801722] 'applied index is now lower than readState.Index' (duration: 148.454821ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:53:55.48841Z","caller":"traceutil/trace.go:171","msg":"trace[1640132433] transaction","detail":"{read_only:false; response_revision:24900; number_of_response:1; }","duration":"282.617482ms","start":"2026-03-31T05:53:55.205768Z","end":"2026-03-31T05:53:55.488385Z","steps":["trace[1640132433] 'process raft request' (duration: 282.468259ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:55.488451Z","caller":"traceutil/trace.go:171","msg":"trace[1733939100] transaction","detail":"{read_only:false; response_revision:24901; number_of_response:1; }","duration":"282.552911ms","start":"2026-03-31T05:53:55.205883Z","end":"2026-03-31T05:53:55.488436Z","steps":["trace[1733939100] 'process raft request' (duration: 282.429818ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:55.488482Z","caller":"traceutil/trace.go:171","msg":"trace[1318139842] transaction","detail":"{read_only:false; response_revision:24902; number_of_response:1; }","duration":"162.611749ms","start":"2026-03-31T05:53:55.325862Z","end":"2026-03-31T05:53:55.488474Z","steps":["trace[1318139842] 'process raft request' (duration: 162.499427ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:55.488568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.431972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T05:53:55.488588Z","caller":"traceutil/trace.go:171","msg":"trace[1399911392] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:24902; }","duration":"177.501804ms","start":"2026-03-31T05:53:55.311078Z","end":"2026-03-31T05:53:55.48858Z","steps":["trace[1399911392] 'agreement among raft nodes before linearized reading' (duration: 177.449533ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:55.4886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.238789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-31T05:53:55.488631Z","caller":"traceutil/trace.go:171","msg":"trace[680097715] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:24902; }","duration":"104.314971ms","start":"2026-03-31T05:53:55.384307Z","end":"2026-03-31T05:53:55.488622Z","steps":["trace[680097715] 'agreement among raft nodes before linearized reading' (duration: 104.254759ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:55.488646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.114036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-03-31T05:53:55.488684Z","caller":"traceutil/trace.go:171","msg":"trace[1603396323] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24902; }","duration":"122.189266ms","start":"2026-03-31T05:53:55.36648Z","end":"2026-03-31T05:53:55.48867Z","steps":["trace[1603396323] 'agreement among raft nodes before linearized reading' (duration: 122.064554ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:55.769147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.739922ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-31T05:53:55.769395Z","caller":"traceutil/trace.go:171","msg":"trace[1439878394] transaction","detail":"{read_only:false; response_revision:24904; number_of_response:1; }","duration":"275.602424ms","start":"2026-03-31T05:53:55.493755Z","end":"2026-03-31T05:53:55.769357Z","steps":["trace[1439878394] 'process raft request' (duration: 159.557016ms)","trace[1439878394] 'compare' (duration: 115.615098ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:53:55.769619Z","caller":"traceutil/trace.go:171","msg":"trace[1355263513] transaction","detail":"{read_only:false; response_revision:24905; number_of_response:1; }","duration":"224.015575ms","start":"2026-03-31T05:53:55.545573Z","end":"2026-03-31T05:53:55.769588Z","steps":["trace[1355263513] 'process raft request' (duration: 223.706617ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:55.859741Z","caller":"traceutil/trace.go:171","msg":"trace[1888374584] transaction","detail":"{read_only:false; response_revision:24906; number_of_response:1; }","duration":"113.605237ms","start":"2026-03-31T05:53:55.746116Z","end":"2026-03-31T05:53:55.859721Z","steps":["trace[1888374584] 'process raft request' (duration: 113.420943ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:56.234146Z","caller":"traceutil/trace.go:171","msg":"trace[1576887686] linearizableReadLoop","detail":"{readStateIndex:25719; appliedIndex:25718; }","duration":"336.089659ms","start":"2026-03-31T05:53:55.898036Z","end":"2026-03-31T05:53:56.234125Z","steps":["trace[1576887686] 'read index received' (duration: 336.000048ms)","trace[1576887686] 'applied index is now lower than readState.Index' (duration: 88.301µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:53:56.234251Z","caller":"traceutil/trace.go:171","msg":"trace[1648592791] transaction","detail":"{read_only:false; response_revision:24907; number_of_response:1; }","duration":"369.357741ms","start":"2026-03-31T05:53:55.86487Z","end":"2026-03-31T05:53:56.234228Z","steps":["trace[1648592791] 'process raft request' (duration: 369.114835ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:56.234308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.246563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T05:53:56.234343Z","caller":"traceutil/trace.go:171","msg":"trace[1163036112] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:24907; }","duration":"336.338045ms","start":"2026-03-31T05:53:55.897995Z","end":"2026-03-31T05:53:56.234333Z","steps":["trace[1163036112] 'agreement among raft nodes before linearized reading' (duration: 336.232233ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:56.234373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:53:55.897972Z","time spent":"336.392496ms","remote":"127.0.0.1:34740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true "} {"level":"warn","ts":"2026-03-31T05:53:56.23438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:53:55.864847Z","time spent":"369.459853ms","remote":"127.0.0.1:56574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3746,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:53:56.26458Z","caller":"traceutil/trace.go:171","msg":"trace[1291905196] transaction","detail":"{read_only:false; response_revision:24908; number_of_response:1; }","duration":"237.304465ms","start":"2026-03-31T05:53:56.027254Z","end":"2026-03-31T05:53:56.264559Z","steps":["trace[1291905196] 'process raft request' (duration: 237.149162ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:56.264768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.715568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-03-31T05:53:56.264818Z","caller":"traceutil/trace.go:171","msg":"trace[1328537506] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24908; }","duration":"241.80797ms","start":"2026-03-31T05:53:56.022996Z","end":"2026-03-31T05:53:56.264803Z","steps":["trace[1328537506] 'agreement among raft nodes before linearized reading' (duration: 241.544675ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:56.417141Z","caller":"traceutil/trace.go:171","msg":"trace[244925958] linearizableReadLoop","detail":"{readStateIndex:25721; appliedIndex:25720; }","duration":"125.388255ms","start":"2026-03-31T05:53:56.291682Z","end":"2026-03-31T05:53:56.41707Z","steps":["trace[244925958] 'read index received' (duration: 123.396693ms)","trace[244925958] 'applied index is now lower than readState.Index' (duration: 1.989862ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:53:56.4172Z","caller":"traceutil/trace.go:171","msg":"trace[2029731555] transaction","detail":"{read_only:false; response_revision:24909; number_of_response:1; }","duration":"129.246576ms","start":"2026-03-31T05:53:56.287922Z","end":"2026-03-31T05:53:56.417168Z","steps":["trace[2029731555] 'process raft request' (duration: 127.218353ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:56.418345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.538379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9602"} {"level":"info","ts":"2026-03-31T05:53:56.418404Z","caller":"traceutil/trace.go:171","msg":"trace[565167421] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24909; }","duration":"126.738023ms","start":"2026-03-31T05:53:56.291652Z","end":"2026-03-31T05:53:56.41839Z","steps":["trace[565167421] 'agreement among raft nodes before linearized reading' (duration: 125.60757ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:56.580372Z","caller":"traceutil/trace.go:171","msg":"trace[575253433] linearizableReadLoop","detail":"{readStateIndex:25722; appliedIndex:25721; }","duration":"156.219156ms","start":"2026-03-31T05:53:56.424127Z","end":"2026-03-31T05:53:56.580347Z","steps":["trace[575253433] 'read index received' (duration: 135.359767ms)","trace[575253433] 'applied index is now lower than readState.Index' (duration: 20.857849ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:53:56.580702Z","caller":"traceutil/trace.go:171","msg":"trace[304441736] transaction","detail":"{read_only:false; response_revision:24910; number_of_response:1; }","duration":"156.988241ms","start":"2026-03-31T05:53:56.423699Z","end":"2026-03-31T05:53:56.580687Z","steps":["trace[304441736] 'process raft request' (duration: 135.872866ms)","trace[304441736] 'compare' (duration: 20.652865ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:53:56.580868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.736226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1446"} {"level":"info","ts":"2026-03-31T05:53:56.580923Z","caller":"traceutil/trace.go:171","msg":"trace[1862453265] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24910; }","duration":"156.826597ms","start":"2026-03-31T05:53:56.424083Z","end":"2026-03-31T05:53:56.580909Z","steps":["trace[1862453265] 'agreement among raft nodes before linearized reading' (duration: 156.730485ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:53:56.772828Z","caller":"traceutil/trace.go:171","msg":"trace[1351371182] linearizableReadLoop","detail":"{readStateIndex:25723; appliedIndex:25722; }","duration":"182.472939ms","start":"2026-03-31T05:53:56.590332Z","end":"2026-03-31T05:53:56.772804Z","steps":["trace[1351371182] 'read index received' (duration: 181.048719ms)","trace[1351371182] 'applied index is now lower than readState.Index' (duration: 1.42069ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:53:56.772874Z","caller":"traceutil/trace.go:171","msg":"trace[1403054408] transaction","detail":"{read_only:false; response_revision:24911; number_of_response:1; }","duration":"189.750793ms","start":"2026-03-31T05:53:56.583098Z","end":"2026-03-31T05:53:56.772849Z","steps":["trace[1403054408] 'process raft request' (duration: 188.267811ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:53:56.773052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.708014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9602"} {"level":"info","ts":"2026-03-31T05:53:56.773103Z","caller":"traceutil/trace.go:171","msg":"trace[575635585] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24911; }","duration":"182.799256ms","start":"2026-03-31T05:53:56.59029Z","end":"2026-03-31T05:53:56.77309Z","steps":["trace[575635585] 'agreement among raft nodes before linearized reading' (duration: 182.629462ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:54:01.529842Z","caller":"traceutil/trace.go:171","msg":"trace[1879316510] transaction","detail":"{read_only:false; response_revision:24967; number_of_response:1; }","duration":"310.674584ms","start":"2026-03-31T05:54:01.219135Z","end":"2026-03-31T05:54:01.52981Z","steps":["trace[1879316510] 'process raft request' (duration: 310.365467ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:54:01.529985Z","caller":"traceutil/trace.go:171","msg":"trace[850570373] transaction","detail":"{read_only:false; response_revision:24968; number_of_response:1; }","duration":"304.286489ms","start":"2026-03-31T05:54:01.225673Z","end":"2026-03-31T05:54:01.52996Z","steps":["trace[850570373] 'process raft request' (duration: 304.056624ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:54:01.530137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:54:01.225642Z","time spent":"304.395411ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:54:01.530184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:54:01.219121Z","time spent":"310.817526ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:54:01.778325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.873017ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-31T05:54:01.778439Z","caller":"traceutil/trace.go:171","msg":"trace[636566732] linearizableReadLoop","detail":"{readStateIndex:25783; appliedIndex:25780; }","duration":"265.381208ms","start":"2026-03-31T05:54:01.513042Z","end":"2026-03-31T05:54:01.778424Z","steps":["trace[636566732] 'read index received' (duration: 16.399746ms)","trace[636566732] 'applied index is now lower than readState.Index' (duration: 248.980302ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:54:01.77865Z","caller":"traceutil/trace.go:171","msg":"trace[1730610229] transaction","detail":"{read_only:false; response_revision:24969; number_of_response:1; }","duration":"271.758582ms","start":"2026-03-31T05:54:01.506879Z","end":"2026-03-31T05:54:01.778637Z","steps":["trace[1730610229] 'process raft request' (duration: 88.482586ms)","trace[1730610229] 'compare' (duration: 182.750464ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:54:01.778904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.449691ms","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-03-31T05:54:01.778909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.388716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-31T05:54:01.778945Z","caller":"traceutil/trace.go:171","msg":"trace[13208910] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24969; }","duration":"239.515633ms","start":"2026-03-31T05:54:01.539422Z","end":"2026-03-31T05:54:01.778938Z","steps":["trace[13208910] 'agreement among raft nodes before linearized reading' (duration: 239.41372ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:54:01.778956Z","caller":"traceutil/trace.go:171","msg":"trace[1230188196] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:24969; }","duration":"217.458567ms","start":"2026-03-31T05:54:01.561487Z","end":"2026-03-31T05:54:01.778946Z","steps":["trace[1230188196] 'agreement among raft nodes before linearized reading' (duration: 217.361205ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:54:01.778973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.989024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-03-31T05:54:01.778925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.095422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-03-31T05:54:01.779016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.95583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T05:54:01.779085Z","caller":"traceutil/trace.go:171","msg":"trace[1686307144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24969; }","duration":"266.039561ms","start":"2026-03-31T05:54:01.51303Z","end":"2026-03-31T05:54:01.77907Z","steps":["trace[1686307144] 'agreement among raft nodes before linearized reading' (duration: 265.820717ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:54:01.779002Z","caller":"traceutil/trace.go:171","msg":"trace[464235214] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24969; }","duration":"224.040006ms","start":"2026-03-31T05:54:01.554952Z","end":"2026-03-31T05:54:01.778992Z","steps":["trace[464235214] 'agreement among raft nodes before linearized reading' (duration: 223.989245ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:54:01.779099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.267965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-03-31T05:54:01.779179Z","caller":"traceutil/trace.go:171","msg":"trace[1355312425] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24969; }","duration":"238.395558ms","start":"2026-03-31T05:54:01.540766Z","end":"2026-03-31T05:54:01.779162Z","steps":["trace[1355312425] 'agreement among raft nodes before linearized reading' (duration: 238.186544ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:54:01.779076Z","caller":"traceutil/trace.go:171","msg":"trace[504190843] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24969; }","duration":"238.246706ms","start":"2026-03-31T05:54:01.540791Z","end":"2026-03-31T05:54:01.779038Z","steps":["trace[504190843] 'agreement among raft nodes before linearized reading' (duration: 238.074632ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:54:27.22138Z","caller":"traceutil/trace.go:171","msg":"trace[1313317964] transaction","detail":"{read_only:false; response_revision:25177; number_of_response:1; }","duration":"317.753693ms","start":"2026-03-31T05:54:26.903593Z","end":"2026-03-31T05:54:27.221347Z","steps":["trace[1313317964] 'process raft request' (duration: 317.522548ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:54:27.221616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:54:26.903579Z","time spent":"317.891456ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:54:28.175307Z","caller":"traceutil/trace.go:171","msg":"trace[891652855] linearizableReadLoop","detail":"{readStateIndex:26001; appliedIndex:26000; }","duration":"257.039561ms","start":"2026-03-31T05:54:27.918237Z","end":"2026-03-31T05:54:28.175277Z","steps":["trace[891652855] 'read index received' (duration: 256.941709ms)","trace[891652855] 'applied index is now lower than readState.Index' (duration: 96.292µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:54:28.175413Z","caller":"traceutil/trace.go:171","msg":"trace[612905594] transaction","detail":"{read_only:false; response_revision:25182; number_of_response:1; }","duration":"300.318705ms","start":"2026-03-31T05:54:27.875052Z","end":"2026-03-31T05:54:28.175371Z","steps":["trace[612905594] 'process raft request' (duration: 300.002378ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:54:28.175485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.222745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-03-31T05:54:28.175784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:54:27.875031Z","time spent":"300.674833ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:54:28.175835Z","caller":"traceutil/trace.go:171","msg":"trace[551331576] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:25182; }","duration":"257.626674ms","start":"2026-03-31T05:54:27.918178Z","end":"2026-03-31T05:54:28.175804Z","steps":["trace[551331576] 'agreement among raft nodes before linearized reading' (duration: 257.205024ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:54:28.544456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.941869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-31T05:54:28.544559Z","caller":"traceutil/trace.go:171","msg":"trace[121950910] linearizableReadLoop","detail":"{readStateIndex:26002; appliedIndex:26001; }","duration":"368.918891ms","start":"2026-03-31T05:54:28.175623Z","end":"2026-03-31T05:54:28.544542Z","steps":["trace[121950910] 'read index received' (duration: 84.753938ms)","trace[121950910] 'applied index is now lower than readState.Index' (duration: 284.163143ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:54:28.544867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.250751ms","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-03-31T05:54:28.544881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.436287ms","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-03-31T05:54:28.544979Z","caller":"traceutil/trace.go:171","msg":"trace[1295423130] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25183; }","duration":"501.53996ms","start":"2026-03-31T05:54:28.043414Z","end":"2026-03-31T05:54:28.544954Z","steps":["trace[1295423130] 'agreement among raft nodes before linearized reading' (duration: 501.279445ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:54:28.544975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.308833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-03-31T05:54:28.545093Z","caller":"traceutil/trace.go:171","msg":"trace[710822934] transaction","detail":"{read_only:false; response_revision:25183; number_of_response:1; }","duration":"613.226485ms","start":"2026-03-31T05:54:27.93185Z","end":"2026-03-31T05:54:28.545076Z","steps":["trace[710822934] 'process raft request' (duration: 328.588791ms)","trace[710822934] 'compare' (duration: 283.669763ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:54:28.545106Z","caller":"traceutil/trace.go:171","msg":"trace[1980626129] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25183; }","duration":"563.466175ms","start":"2026-03-31T05:54:27.981606Z","end":"2026-03-31T05:54:28.545072Z","steps":["trace[1980626129] 'agreement among raft nodes before linearized reading' (duration: 563.109708ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:54:28.545041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:54:28.043363Z","time spent":"501.662273ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-31T05:54:28.544928Z","caller":"traceutil/trace.go:171","msg":"trace[1689410454] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25183; }","duration":"536.360553ms","start":"2026-03-31T05:54:28.008554Z","end":"2026-03-31T05:54:28.544915Z","steps":["trace[1689410454] 'agreement among raft nodes before linearized reading' (duration: 536.18453ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:54:28.545195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:54:27.931831Z","time spent":"613.300408ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:54:28.545239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.323098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-03-31T05:54:28.545272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.121383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-03-31T05:54:28.545321Z","caller":"traceutil/trace.go:171","msg":"trace[1326034312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25183; }","duration":"486.44101ms","start":"2026-03-31T05:54:28.058863Z","end":"2026-03-31T05:54:28.545304Z","steps":["trace[1326034312] 'agreement among raft nodes before linearized reading' (duration: 486.205575ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:54:28.545365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:54:28.058853Z","time spent":"486.506162ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T05:54:28.54526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:54:28.008531Z","time spent":"536.705511ms","remote":"127.0.0.1:56672","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":"info","ts":"2026-03-31T05:54:28.54537Z","caller":"traceutil/trace.go:171","msg":"trace[1053581776] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25183; }","duration":"494.253916ms","start":"2026-03-31T05:54:28.051096Z","end":"2026-03-31T05:54:28.54535Z","steps":["trace[1053581776] 'agreement among raft nodes before linearized reading' (duration: 493.975189ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:54:28.545178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:54:27.981598Z","time spent":"563.565557ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-03-31T05:54:28.545414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:54:28.051085Z","time spent":"494.320227ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-31T05:54:28.545546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.551984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T05:54:28.545583Z","caller":"traceutil/trace.go:171","msg":"trace[807312560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25183; }","duration":"578.589525ms","start":"2026-03-31T05:54:27.966984Z","end":"2026-03-31T05:54:28.545574Z","steps":["trace[807312560] 'agreement among raft nodes before linearized reading' (duration: 577.809148ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:54:28.545608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:54:27.966929Z","time spent":"578.672346ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-31T05:54:28.600998Z","caller":"traceutil/trace.go:171","msg":"trace[448350230] transaction","detail":"{read_only:false; response_revision:25184; number_of_response:1; }","duration":"155.892938ms","start":"2026-03-31T05:54:28.445075Z","end":"2026-03-31T05:54:28.600968Z","steps":["trace[448350230] 'process raft request' (duration: 155.717904ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:54:28.601229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.630403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T05:54:28.601328Z","caller":"traceutil/trace.go:171","msg":"trace[1819720862] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25184; }","duration":"220.789547ms","start":"2026-03-31T05:54:28.380518Z","end":"2026-03-31T05:54:28.601308Z","steps":["trace[1819720862] 'agreement among raft nodes before linearized reading' (duration: 220.600793ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:54:28.601444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.219784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-31T05:54:28.601535Z","caller":"traceutil/trace.go:171","msg":"trace[764480203] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25184; }","duration":"373.385867ms","start":"2026-03-31T05:54:28.228132Z","end":"2026-03-31T05:54:28.601517Z","steps":["trace[764480203] 'agreement among raft nodes before linearized reading' (duration: 372.835505ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:54:28.601569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:54:28.22811Z","time spent":"373.449248ms","remote":"127.0.0.1:39062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-03-31T05:54:29.953375Z","caller":"traceutil/trace.go:171","msg":"trace[1307855876] linearizableReadLoop","detail":"{readStateIndex:26012; appliedIndex:26011; }","duration":"184.752488ms","start":"2026-03-31T05:54:29.768601Z","end":"2026-03-31T05:54:29.953353Z","steps":["trace[1307855876] 'read index received' (duration: 174.189655ms)","trace[1307855876] 'applied index is now lower than readState.Index' (duration: 10.561753ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:54:29.953438Z","caller":"traceutil/trace.go:171","msg":"trace[248121525] transaction","detail":"{read_only:false; response_revision:25193; number_of_response:1; }","duration":"223.514645ms","start":"2026-03-31T05:54:29.72986Z","end":"2026-03-31T05:54:29.953374Z","steps":["trace[248121525] 'process raft request' (duration: 212.695657ms)","trace[248121525] 'compare' (duration: 10.707796ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:54:29.953527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.924671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T05:54:29.953555Z","caller":"traceutil/trace.go:171","msg":"trace[1593586257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25193; }","duration":"185.021363ms","start":"2026-03-31T05:54:29.768523Z","end":"2026-03-31T05:54:29.953545Z","steps":["trace[1593586257] 'agreement among raft nodes before linearized reading' (duration: 184.921831ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:54:30.009288Z","caller":"traceutil/trace.go:171","msg":"trace[461175325] transaction","detail":"{read_only:false; response_revision:25194; number_of_response:1; }","duration":"212.948952ms","start":"2026-03-31T05:54:29.796303Z","end":"2026-03-31T05:54:30.009252Z","steps":["trace[461175325] 'process raft request' (duration: 212.721227ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:54:30.00938Z","caller":"traceutil/trace.go:171","msg":"trace[1395705879] transaction","detail":"{read_only:false; response_revision:25195; number_of_response:1; }","duration":"209.04998ms","start":"2026-03-31T05:54:29.800279Z","end":"2026-03-31T05:54:30.009329Z","steps":["trace[1395705879] 'process raft request' (duration: 208.893457ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:54:30.282511Z","caller":"traceutil/trace.go:171","msg":"trace[2063332631] transaction","detail":"{read_only:false; response_revision:25197; number_of_response:1; }","duration":"102.663935ms","start":"2026-03-31T05:54:30.179807Z","end":"2026-03-31T05:54:30.282471Z","steps":["trace[2063332631] 'process raft request' (duration: 56.43616ms)","trace[2063332631] 'compare' (duration: 46.021711ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:54:30.766516Z","caller":"traceutil/trace.go:171","msg":"trace[1748505670] transaction","detail":"{read_only:false; response_revision:25198; number_of_response:1; }","duration":"214.29952ms","start":"2026-03-31T05:54:30.552183Z","end":"2026-03-31T05:54:30.766483Z","steps":["trace[1748505670] 'process raft request' (duration: 134.786683ms)","trace[1748505670] 'compare' (duration: 79.130009ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:54:31.056416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.319787ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-31T05:54:31.056552Z","caller":"traceutil/trace.go:171","msg":"trace[708582932] linearizableReadLoop","detail":"{readStateIndex:26019; appliedIndex:26017; }","duration":"413.446312ms","start":"2026-03-31T05:54:30.643087Z","end":"2026-03-31T05:54:31.056534Z","steps":["trace[708582932] 'read index received' (duration: 43.818334ms)","trace[708582932] 'applied index is now lower than readState.Index' (duration: 369.626658ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:54:31.056644Z","caller":"traceutil/trace.go:171","msg":"trace[1164217822] transaction","detail":"{read_only:false; response_revision:25199; number_of_response:1; }","duration":"447.304216ms","start":"2026-03-31T05:54:30.609328Z","end":"2026-03-31T05:54:31.056632Z","steps":["trace[1164217822] 'process raft request' (duration: 281.681842ms)","trace[1164217822] 'compare' (duration: 165.193835ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:54:31.056704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:54:30.609306Z","time spent":"447.367968ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:54:31.056828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.728157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-03-31T05:54:31.056885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.123117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-31T05:54:31.056902Z","caller":"traceutil/trace.go:171","msg":"trace[1666751147] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25199; }","duration":"413.84037ms","start":"2026-03-31T05:54:30.643039Z","end":"2026-03-31T05:54:31.056879Z","steps":["trace[1666751147] 'agreement among raft nodes before linearized reading' (duration: 413.651426ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:54:31.05693Z","caller":"traceutil/trace.go:171","msg":"trace[1128862224] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:25199; }","duration":"132.203849ms","start":"2026-03-31T05:54:30.924712Z","end":"2026-03-31T05:54:31.056916Z","steps":["trace[1128862224] 'agreement among raft nodes before linearized reading' (duration: 132.127967ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:54:31.056958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:54:30.64302Z","time spent":"413.928421ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-03-31T05:54:31.056974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.396269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-03-31T05:54:31.056979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.073006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T05:54:31.057034Z","caller":"traceutil/trace.go:171","msg":"trace[1402315667] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25199; }","duration":"411.4781ms","start":"2026-03-31T05:54:30.645539Z","end":"2026-03-31T05:54:31.057017Z","steps":["trace[1402315667] 'agreement among raft nodes before linearized reading' (duration: 411.298986ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:54:31.057049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.027151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-31T05:54:31.057034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.963931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-31T05:54:31.05708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:54:30.645528Z","time spent":"411.544141ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-31T05:54:31.057059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.224025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-03-31T05:54:31.057088Z","caller":"traceutil/trace.go:171","msg":"trace[436287881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25199; }","duration":"412.126103ms","start":"2026-03-31T05:54:30.644951Z","end":"2026-03-31T05:54:31.057077Z","steps":["trace[436287881] 'agreement among raft nodes before linearized reading' (duration: 411.99378ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:54:31.057099Z","caller":"traceutil/trace.go:171","msg":"trace[2098251926] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25199; }","duration":"413.067933ms","start":"2026-03-31T05:54:30.644016Z","end":"2026-03-31T05:54:31.057084Z","steps":["trace[2098251926] 'agreement among raft nodes before linearized reading' (duration: 412.846979ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:54:31.057137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:54:30.644913Z","time spent":"412.216155ms","remote":"127.0.0.1:56672","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-03-31T05:54:31.057138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:54:30.643993Z","time spent":"413.136054ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-31T05:54:31.057141Z","caller":"traceutil/trace.go:171","msg":"trace[1555295438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25199; }","duration":"412.338608ms","start":"2026-03-31T05:54:30.644782Z","end":"2026-03-31T05:54:31.057121Z","steps":["trace[1555295438] 'agreement among raft nodes before linearized reading' (duration: 412.075252ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:54:31.057086Z","caller":"traceutil/trace.go:171","msg":"trace[1531459160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25199; }","duration":"288.242761ms","start":"2026-03-31T05:54:30.768823Z","end":"2026-03-31T05:54:31.057066Z","steps":["trace[1531459160] 'agreement among raft nodes before linearized reading' (duration: 288.068867ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:54:31.057188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:54:30.644776Z","time spent":"412.402479ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T05:54:31.057617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.693766ms","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-03-31T05:54:31.0577Z","caller":"traceutil/trace.go:171","msg":"trace[265159569] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25199; }","duration":"413.787428ms","start":"2026-03-31T05:54:30.643897Z","end":"2026-03-31T05:54:31.057684Z","steps":["trace[265159569] 'agreement among raft nodes before linearized reading' (duration: 412.803897ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:54:31.057736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:54:30.643893Z","time spent":"413.835669ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-31T05:54:31.309596Z","caller":"traceutil/trace.go:171","msg":"trace[384927017] transaction","detail":"{read_only:false; response_revision:25201; number_of_response:1; }","duration":"247.48103ms","start":"2026-03-31T05:54:31.062098Z","end":"2026-03-31T05:54:31.309579Z","steps":["trace[384927017] 'process raft request' (duration: 247.314897ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:54:31.309912Z","caller":"traceutil/trace.go:171","msg":"trace[1194184213] transaction","detail":"{read_only:false; response_revision:25204; number_of_response:1; }","duration":"247.428369ms","start":"2026-03-31T05:54:31.062466Z","end":"2026-03-31T05:54:31.309894Z","steps":["trace[1194184213] 'process raft request' (duration: 247.374058ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:54:31.309952Z","caller":"traceutil/trace.go:171","msg":"trace[216504562] linearizableReadLoop","detail":"{readStateIndex:26025; appliedIndex:26020; }","duration":"127.387817ms","start":"2026-03-31T05:54:31.182555Z","end":"2026-03-31T05:54:31.309943Z","steps":["trace[216504562] 'read index received' (duration: 126.938708ms)","trace[216504562] 'applied index is now lower than readState.Index' (duration: 448.349µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:54:31.309934Z","caller":"traceutil/trace.go:171","msg":"trace[549399350] transaction","detail":"{read_only:false; response_revision:25202; number_of_response:1; }","duration":"247.774096ms","start":"2026-03-31T05:54:31.06212Z","end":"2026-03-31T05:54:31.309894Z","steps":["trace[549399350] 'process raft request' (duration: 247.429089ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:54:31.310029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.460138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T05:54:31.310059Z","caller":"traceutil/trace.go:171","msg":"trace[625568508] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:25205; }","duration":"127.54075ms","start":"2026-03-31T05:54:31.18251Z","end":"2026-03-31T05:54:31.310051Z","steps":["trace[625568508] 'agreement among raft nodes before linearized reading' (duration: 127.466229ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:54:31.310112Z","caller":"traceutil/trace.go:171","msg":"trace[302450154] transaction","detail":"{read_only:false; response_revision:25203; number_of_response:1; }","duration":"247.690624ms","start":"2026-03-31T05:54:31.062412Z","end":"2026-03-31T05:54:31.310103Z","steps":["trace[302450154] 'process raft request' (duration: 247.340987ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:54:31.310245Z","caller":"traceutil/trace.go:171","msg":"trace[218009784] transaction","detail":"{read_only:false; response_revision:25205; number_of_response:1; }","duration":"247.227065ms","start":"2026-03-31T05:54:31.063011Z","end":"2026-03-31T05:54:31.310238Z","steps":["trace[218009784] 'process raft request' (duration: 246.854657ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:54:31.389263Z","caller":"traceutil/trace.go:171","msg":"trace[16357767] transaction","detail":"{read_only:false; response_revision:25206; number_of_response:1; }","duration":"105.948515ms","start":"2026-03-31T05:54:31.283271Z","end":"2026-03-31T05:54:31.389219Z","steps":["trace[16357767] 'process raft request' (duration: 105.68548ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:54:31.591944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.229271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T05:54:31.592083Z","caller":"traceutil/trace.go:171","msg":"trace[630780298] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:25206; }","duration":"124.324923ms","start":"2026-03-31T05:54:31.467682Z","end":"2026-03-31T05:54:31.592007Z","steps":["trace[630780298] 'count revisions from in-memory index tree' (duration: 124.130898ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:54:32.775073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.614595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T05:54:32.775193Z","caller":"traceutil/trace.go:171","msg":"trace[809889615] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:25211; }","duration":"103.788509ms","start":"2026-03-31T05:54:32.671386Z","end":"2026-03-31T05:54:32.775175Z","steps":["trace[809889615] 'count revisions from in-memory index tree' (duration: 103.512023ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:54:33.401133Z","caller":"traceutil/trace.go:171","msg":"trace[1435974436] transaction","detail":"{read_only:false; response_revision:25215; number_of_response:1; }","duration":"184.975142ms","start":"2026-03-31T05:54:33.216114Z","end":"2026-03-31T05:54:33.401089Z","steps":["trace[1435974436] 'process raft request' (duration: 184.679286ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:54:33.530183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.826129ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-31T05:54:33.530377Z","caller":"traceutil/trace.go:171","msg":"trace[1975057518] transaction","detail":"{read_only:false; response_revision:25216; number_of_response:1; }","duration":"136.185953ms","start":"2026-03-31T05:54:33.394147Z","end":"2026-03-31T05:54:33.530333Z","steps":["trace[1975057518] 'process raft request' (duration: 33.135789ms)","trace[1975057518] 'compare' (duration: 102.446322ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:54:33.53045Z","caller":"traceutil/trace.go:171","msg":"trace[1771960599] transaction","detail":"{read_only:false; response_revision:25217; number_of_response:1; }","duration":"124.537896ms","start":"2026-03-31T05:54:33.405892Z","end":"2026-03-31T05:54:33.53043Z","steps":["trace[1771960599] 'process raft request' (duration: 124.417474ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:54:33.530497Z","caller":"traceutil/trace.go:171","msg":"trace[1193992935] linearizableReadLoop","detail":"{readStateIndex:26036; appliedIndex:26035; }","duration":"129.456201ms","start":"2026-03-31T05:54:33.40102Z","end":"2026-03-31T05:54:33.530476Z","steps":["trace[1193992935] 'read index received' (duration: 26.266764ms)","trace[1193992935] 'applied index is now lower than readState.Index' (duration: 103.187927ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:54:33.530665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.653529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-31T05:54:33.530678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.7074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-03-31T05:54:33.530699Z","caller":"traceutil/trace.go:171","msg":"trace[1124278440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25217; }","duration":"215.794142ms","start":"2026-03-31T05:54:33.314896Z","end":"2026-03-31T05:54:33.53069Z","steps":["trace[1124278440] 'agreement among raft nodes before linearized reading' (duration: 215.603098ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:54:33.530706Z","caller":"traceutil/trace.go:171","msg":"trace[1599569105] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25217; }","duration":"215.793842ms","start":"2026-03-31T05:54:33.314902Z","end":"2026-03-31T05:54:33.530696Z","steps":["trace[1599569105] 'agreement among raft nodes before linearized reading' (duration: 215.71814ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:54:33.530749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.447575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-03-31T05:54:33.530807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.990516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-03-31T05:54:33.530833Z","caller":"traceutil/trace.go:171","msg":"trace[68115197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25217; }","duration":"215.563497ms","start":"2026-03-31T05:54:33.315249Z","end":"2026-03-31T05:54:33.530813Z","steps":["trace[68115197] 'agreement among raft nodes before linearized reading' (duration: 215.2399ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:54:33.530869Z","caller":"traceutil/trace.go:171","msg":"trace[514938868] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25217; }","duration":"215.070497ms","start":"2026-03-31T05:54:33.315783Z","end":"2026-03-31T05:54:33.530854Z","steps":["trace[514938868] 'agreement among raft nodes before linearized reading' (duration: 214.916514ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:54:33.53087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.733991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1989"} {"level":"info","ts":"2026-03-31T05:54:33.530907Z","caller":"traceutil/trace.go:171","msg":"trace[332834182] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:25217; }","duration":"124.791872ms","start":"2026-03-31T05:54:33.406104Z","end":"2026-03-31T05:54:33.530896Z","steps":["trace[332834182] 'agreement among raft nodes before linearized reading' (duration: 124.70138ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:54:34.289348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.935888ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-31T05:54:34.289479Z","caller":"traceutil/trace.go:171","msg":"trace[580265331] transaction","detail":"{read_only:false; response_revision:25225; number_of_response:1; }","duration":"206.748311ms","start":"2026-03-31T05:54:34.08271Z","end":"2026-03-31T05:54:34.289458Z","steps":["trace[580265331] 'process raft request' (duration: 97.594809ms)","trace[580265331] 'compare' (duration: 108.749974ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:54:34.500442Z","caller":"traceutil/trace.go:171","msg":"trace[746880975] linearizableReadLoop","detail":"{readStateIndex:26046; appliedIndex:26045; }","duration":"130.834879ms","start":"2026-03-31T05:54:34.36958Z","end":"2026-03-31T05:54:34.500415Z","steps":["trace[746880975] 'read index received' (duration: 100.913578ms)","trace[746880975] 'applied index is now lower than readState.Index' (duration: 29.919711ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:54:34.500483Z","caller":"traceutil/trace.go:171","msg":"trace[510520035] transaction","detail":"{read_only:false; response_revision:25226; number_of_response:1; }","duration":"173.708254ms","start":"2026-03-31T05:54:34.326749Z","end":"2026-03-31T05:54:34.500457Z","steps":["trace[510520035] 'process raft request' (duration: 143.757403ms)","trace[510520035] 'compare' (duration: 29.771587ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:54:34.500568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.979782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T05:54:34.500641Z","caller":"traceutil/trace.go:171","msg":"trace[893160782] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:25226; }","duration":"131.107625ms","start":"2026-03-31T05:54:34.369513Z","end":"2026-03-31T05:54:34.500621Z","steps":["trace[893160782] 'agreement among raft nodes before linearized reading' (duration: 130.986063ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:54:38.246585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.59635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-31T05:54:38.246817Z","caller":"traceutil/trace.go:171","msg":"trace[137964557] transaction","detail":"{read_only:false; response_revision:25251; number_of_response:1; }","duration":"639.556242ms","start":"2026-03-31T05:54:37.607246Z","end":"2026-03-31T05:54:38.246802Z","steps":["trace[137964557] 'process raft request' (duration: 639.4737ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:54:38.246838Z","caller":"traceutil/trace.go:171","msg":"trace[864332152] transaction","detail":"{read_only:false; response_revision:25250; number_of_response:1; }","duration":"639.564832ms","start":"2026-03-31T05:54:37.607245Z","end":"2026-03-31T05:54:38.24681Z","steps":["trace[864332152] 'process raft request' (duration: 119.660915ms)","trace[864332152] 'compare' (duration: 519.279153ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:54:38.246999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:54:37.607231Z","time spent":"639.664064ms","remote":"127.0.0.1:56672","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-03-31T05:54:38.246867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:54:37.607223Z","time spent":"639.620033ms","remote":"127.0.0.1:56672","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-03-31T05:54:38.252203Z","caller":"traceutil/trace.go:171","msg":"trace[1241582298] transaction","detail":"{read_only:false; response_revision:25252; number_of_response:1; }","duration":"644.23992ms","start":"2026-03-31T05:54:37.607954Z","end":"2026-03-31T05:54:38.252194Z","steps":["trace[1241582298] 'process raft request' (duration: 643.868161ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:54:38.25228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:54:37.607945Z","time spent":"644.298741ms","remote":"127.0.0.1:56672","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-03-31T05:54:38.252174Z","caller":"traceutil/trace.go:171","msg":"trace[272893523] transaction","detail":"{read_only:false; response_revision:25253; number_of_response:1; }","duration":"630.071571ms","start":"2026-03-31T05:54:37.622089Z","end":"2026-03-31T05:54:38.25216Z","steps":["trace[272893523] 'process raft request' (duration: 629.852765ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:54:38.252602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:54:37.622074Z","time spent":"630.494309ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:54:38.252642Z","caller":"traceutil/trace.go:171","msg":"trace[1034482897] transaction","detail":"{read_only:false; response_revision:25254; number_of_response:1; }","duration":"210.441189ms","start":"2026-03-31T05:54:38.042193Z","end":"2026-03-31T05:54:38.252634Z","steps":["trace[1034482897] 'process raft request' (duration: 210.357317ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:54:38.25273Z","caller":"traceutil/trace.go:171","msg":"trace[1784166865] transaction","detail":"{read_only:false; response_revision:25255; number_of_response:1; }","duration":"188.374794ms","start":"2026-03-31T05:54:38.064344Z","end":"2026-03-31T05:54:38.252719Z","steps":["trace[1784166865] 'process raft request' (duration: 188.259442ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:54:48.476629Z","caller":"traceutil/trace.go:171","msg":"trace[491629331] transaction","detail":"{read_only:false; response_revision:25337; number_of_response:1; }","duration":"137.396628ms","start":"2026-03-31T05:54:48.339209Z","end":"2026-03-31T05:54:48.476605Z","steps":["trace[491629331] 'process raft request' (duration: 114.591557ms)","trace[491629331] 'compare' (duration: 22.684699ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:54:48.477056Z","caller":"traceutil/trace.go:171","msg":"trace[79718183] transaction","detail":"{read_only:false; response_revision:25338; number_of_response:1; }","duration":"136.470748ms","start":"2026-03-31T05:54:48.340562Z","end":"2026-03-31T05:54:48.477033Z","steps":["trace[79718183] 'process raft request' (duration: 136.375806ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:54:57.290293Z","caller":"traceutil/trace.go:171","msg":"trace[909303671] transaction","detail":"{read_only:false; response_revision:25399; number_of_response:1; }","duration":"423.75043ms","start":"2026-03-31T05:54:56.866519Z","end":"2026-03-31T05:54:57.290269Z","steps":["trace[909303671] 'process raft request' (duration: 374.120995ms)","trace[909303671] 'compare' (duration: 49.500341ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:54:57.290427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:54:56.8665Z","time spent":"423.857921ms","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:55:02.82868Z","caller":"traceutil/trace.go:171","msg":"trace[583254880] transaction","detail":"{read_only:false; response_revision:25455; number_of_response:1; }","duration":"196.041786ms","start":"2026-03-31T05:55:02.632609Z","end":"2026-03-31T05:55:02.828651Z","steps":["trace[583254880] 'process raft request' (duration: 195.927214ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:55:02.828892Z","caller":"traceutil/trace.go:171","msg":"trace[540854845] linearizableReadLoop","detail":"{readStateIndex:26283; appliedIndex:26282; }","duration":"106.133594ms","start":"2026-03-31T05:55:02.722742Z","end":"2026-03-31T05:55:02.828876Z","steps":["trace[540854845] 'read index received' (duration: 106.072843ms)","trace[540854845] 'applied index is now lower than readState.Index' (duration: 59.801µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:55:02.828872Z","caller":"traceutil/trace.go:171","msg":"trace[1288333042] transaction","detail":"{read_only:false; response_revision:25454; number_of_response:1; }","duration":"207.433395ms","start":"2026-03-31T05:55:02.621412Z","end":"2026-03-31T05:55:02.828846Z","steps":["trace[1288333042] 'process raft request' (duration: 206.918214ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:55:02.829032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.255716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-31T05:55:02.82908Z","caller":"traceutil/trace.go:171","msg":"trace[1424342641] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:25456; }","duration":"106.378819ms","start":"2026-03-31T05:55:02.722689Z","end":"2026-03-31T05:55:02.829068Z","steps":["trace[1424342641] 'agreement among raft nodes before linearized reading' (duration: 106.249376ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:55:02.829266Z","caller":"traceutil/trace.go:171","msg":"trace[522939968] transaction","detail":"{read_only:false; response_revision:25456; number_of_response:1; }","duration":"196.409244ms","start":"2026-03-31T05:55:02.632842Z","end":"2026-03-31T05:55:02.829251Z","steps":["trace[522939968] 'process raft request' (duration: 195.7476ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:55:46.868905Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22981} {"level":"info","ts":"2026-03-31T05:55:47.006917Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22981,"took":"133.738245ms","hash":1172061275,"current-db-size-bytes":57524224,"current-db-size":"58 MB","current-db-size-in-use-bytes":35627008,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-03-31T05:55:47.006988Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1172061275,"revision":22981,"compact-revision":20077} {"level":"info","ts":"2026-03-31T05:56:33.205358Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a68.wal"} {"level":"info","ts":"2026-03-31T06:00:46.875901Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25971} {"level":"info","ts":"2026-03-31T06:00:47.035952Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25971,"took":"153.704995ms","hash":3374958888,"current-db-size-bytes":57524224,"current-db-size":"58 MB","current-db-size-in-use-bytes":34918400,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-03-31T06:00:47.036052Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3374958888,"revision":25971,"compact-revision":22981} {"level":"info","ts":"2026-03-31T06:01:34.194697Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f170c75a1d97cc","local-member-applied-index":30004,"local-member-snapshot-index":20003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-31T06:01:34.199861Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30004} {"level":"info","ts":"2026-03-31T06:01:34.200007Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25004} {"level":"info","ts":"2026-03-31T06:05:46.8824Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28733} {"level":"info","ts":"2026-03-31T06:05:47.028301Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28733,"took":"141.65405ms","hash":1667770735,"current-db-size-bytes":57524224,"current-db-size":"58 MB","current-db-size-in-use-bytes":31207424,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-03-31T06:05:47.028391Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1667770735,"revision":28733,"compact-revision":25971} {"level":"info","ts":"2026-03-31T06:10:46.890616Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30993} {"level":"info","ts":"2026-03-31T06:10:47.042243Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30993,"took":"144.317517ms","hash":1938417774,"current-db-size-bytes":57524224,"current-db-size":"58 MB","current-db-size-in-use-bytes":30048256,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-31T06:10:47.042339Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1938417774,"revision":30993,"compact-revision":28733} {"level":"info","ts":"2026-03-31T06:15:46.896754Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33255} {"level":"info","ts":"2026-03-31T06:15:47.045063Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33255,"took":"143.467169ms","hash":2114938475,"current-db-size-bytes":58568704,"current-db-size":"59 MB","current-db-size-in-use-bytes":31948800,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-03-31T06:15:47.045177Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2114938475,"revision":33255,"compact-revision":30993} {"level":"info","ts":"2026-03-31T06:20:46.903796Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35549} {"level":"info","ts":"2026-03-31T06:20:47.053428Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35549,"took":"145.167759ms","hash":1776250773,"current-db-size-bytes":58568704,"current-db-size":"59 MB","current-db-size-in-use-bytes":31694848,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-03-31T06:20:47.053497Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1776250773,"revision":35549,"compact-revision":33255} {"level":"info","ts":"2026-03-31T06:22:58.37289Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f170c75a1d97cc","local-member-applied-index":40005,"local-member-snapshot-index":30004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-31T06:22:58.378764Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40005} {"level":"info","ts":"2026-03-31T06:22:58.378864Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35005} {"level":"info","ts":"2026-03-31T06:25:46.912666Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37780} {"level":"info","ts":"2026-03-31T06:25:47.044975Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37780,"took":"128.065925ms","hash":1490997926,"current-db-size-bytes":58568704,"current-db-size":"59 MB","current-db-size-in-use-bytes":31416320,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-03-31T06:25:47.045053Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1490997926,"revision":37780,"compact-revision":35549}