{"level":"warn","ts":"2026-04-22T06:07:37.550306Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T06:07:37.550447Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.33: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.33:2380","--initial-cluster=instance=https://199.204.45.33:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.33:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.33:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-22T06:07:37.550546Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T06:07:37.550562Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-04-22T06:07:37.550594Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-22T06:07:37.551542Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"]} {"level":"info","ts":"2026-04-22T06:07:37.551648Z","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.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.33:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-22T06:07:37.55526Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.286424ms"} {"level":"info","ts":"2026-04-22T06:07:37.567525Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e5729930cf7e1f","cluster-id":"14aeb8c3ec66b749"} {"level":"info","ts":"2026-04-22T06:07:37.567608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T06:07:37.56763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 0"} {"level":"info","ts":"2026-04-22T06:07:37.56764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e5729930cf7e1f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-22T06:07:37.56765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 1"} {"level":"info","ts":"2026-04-22T06:07:37.567697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"warn","ts":"2026-04-22T06:07:37.573539Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T06:07:37.575269Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T06:07:37.576706Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-22T06:07:37.579594Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e5729930cf7e1f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-22T06:07:37.579814Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e5729930cf7e1f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-22T06:07:37.581395Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T06:07:37.58156Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T06:07:37.581609Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T06:07:37.582389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"info","ts":"2026-04-22T06:07:37.582532Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","added-peer-id":"f1e5729930cf7e1f","added-peer-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-04-22T06:07:37.585483Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-22T06:07:37.585631Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-04-22T06:07:37.585773Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-04-22T06:07:37.58581Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e5729930cf7e1f","initial-advertise-peer-urls":["https://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-22T06:07:37.585856Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T06:07:38.368597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T06:07:38.36866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T06:07:38.368698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgPreVoteResp from f1e5729930cf7e1f at term 1"} {"level":"info","ts":"2026-04-22T06:07:38.368718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became candidate at term 2"} {"level":"info","ts":"2026-04-22T06:07:38.368729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgVoteResp from f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-04-22T06:07:38.368745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became leader at term 2"} {"level":"info","ts":"2026-04-22T06:07:38.368758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e5729930cf7e1f elected leader f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-04-22T06:07:38.369963Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T06:07:38.370711Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e5729930cf7e1f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.33:2379]}","request-path":"/0/members/f1e5729930cf7e1f/attributes","cluster-id":"14aeb8c3ec66b749","publish-timeout":"7s"} {"level":"info","ts":"2026-04-22T06:07:38.370875Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T06:07:38.370937Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T06:07:38.371439Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T06:07:38.371634Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T06:07:38.37169Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T06:07:38.37321Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.33:2379"} {"level":"info","ts":"2026-04-22T06:07:38.373303Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T06:07:38.373787Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T06:07:38.374839Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-22T06:07:41.452413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.507465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/system::leader-locking-kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:07:41.45257Z","caller":"traceutil/trace.go:171","msg":"trace[379641904] range","detail":"{range_begin:/registry/roles/kube-system/system::leader-locking-kube-controller-manager; range_end:; response_count:0; response_revision:224; }","duration":"121.7145ms","start":"2026-04-22T06:07:41.330828Z","end":"2026-04-22T06:07:41.452543Z","steps":["trace[379641904] 'range keys from in-memory index tree' (duration: 121.390263ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:57.477813Z","caller":"traceutil/trace.go:171","msg":"trace[1087385959] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"314.227253ms","start":"2026-04-22T06:07:57.163549Z","end":"2026-04-22T06:07:57.477777Z","steps":["trace[1087385959] 'process raft request' (duration: 314.022629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:57.478526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:07:57.163535Z","time spent":"314.392078ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:07:57.857428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.28858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:07:57.857511Z","caller":"traceutil/trace.go:171","msg":"trace[1255745844] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:452; }","duration":"211.432043ms","start":"2026-04-22T06:07:57.646062Z","end":"2026-04-22T06:07:57.857494Z","steps":["trace[1255745844] 'range keys from in-memory index tree' (duration: 211.196507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:08.995202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.245761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:08:08.995332Z","caller":"traceutil/trace.go:171","msg":"trace[1649244201] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"315.466062ms","start":"2026-04-22T06:08:08.679846Z","end":"2026-04-22T06:08:08.995312Z","steps":["trace[1649244201] 'process raft request' (duration: 178.858263ms)","trace[1649244201] 'compare' (duration: 136.086736ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:08:08.995452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:08.679799Z","time spent":"315.601215ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:08:08.996779Z","caller":"traceutil/trace.go:171","msg":"trace[1475897454] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"310.338538ms","start":"2026-04-22T06:08:08.686399Z","end":"2026-04-22T06:08:08.996738Z","steps":["trace[1475897454] 'process raft request' (duration: 309.625282ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:08.996815Z","caller":"traceutil/trace.go:171","msg":"trace[777946091] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"204.310245ms","start":"2026-04-22T06:08:08.792484Z","end":"2026-04-22T06:08:08.996794Z","steps":["trace[777946091] 'process raft request' (duration: 204.207823ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:08.996815Z","caller":"traceutil/trace.go:171","msg":"trace[1422430734] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"310.293328ms","start":"2026-04-22T06:08:08.686483Z","end":"2026-04-22T06:08:08.996776Z","steps":["trace[1422430734] 'process raft request' (duration: 309.822867ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:08.99687Z","caller":"traceutil/trace.go:171","msg":"trace[946843946] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"303.570869ms","start":"2026-04-22T06:08:08.69325Z","end":"2026-04-22T06:08:08.996821Z","steps":["trace[946843946] 'process raft request' (duration: 303.230841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:08.996982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:08.686378Z","time spent":"310.487662ms","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:08:08.997082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:08.693233Z","time spent":"303.738912ms","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:08:08.997302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:08.686454Z","time spent":"310.458621ms","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:08:15.222561Z","caller":"traceutil/trace.go:171","msg":"trace[457193004] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"139.846974ms","start":"2026-04-22T06:08:15.082687Z","end":"2026-04-22T06:08:15.222534Z","steps":["trace[457193004] 'read index received' (duration: 138.549335ms)","trace[457193004] 'applied index is now lower than readState.Index' (duration: 1.296339ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:08:15.222661Z","caller":"traceutil/trace.go:171","msg":"trace[1285325268] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"187.619437ms","start":"2026-04-22T06:08:15.034992Z","end":"2026-04-22T06:08:15.222612Z","steps":["trace[1285325268] 'process raft request' (duration: 186.42438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:15.222987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.275813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-22T06:08:15.223065Z","caller":"traceutil/trace.go:171","msg":"trace[236393637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:620; }","duration":"140.422706ms","start":"2026-04-22T06:08:15.082624Z","end":"2026-04-22T06:08:15.223047Z","steps":["trace[236393637] 'agreement among raft nodes before linearized reading' (duration: 140.013868ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:15.457139Z","caller":"traceutil/trace.go:171","msg":"trace[54202140] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"226.242058ms","start":"2026-04-22T06:08:15.230867Z","end":"2026-04-22T06:08:15.457109Z","steps":["trace[54202140] 'process raft request' (duration: 226.023473ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:15.537261Z","caller":"traceutil/trace.go:171","msg":"trace[1452230649] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"253.373648ms","start":"2026-04-22T06:08:15.283867Z","end":"2026-04-22T06:08:15.53724Z","steps":["trace[1452230649] 'process raft request' (duration: 253.269895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:15.797076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.59172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:08:15.797131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.50279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:08:15.797226Z","caller":"traceutil/trace.go:171","msg":"trace[167043511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"150.650932ms","start":"2026-04-22T06:08:15.646551Z","end":"2026-04-22T06:08:15.797202Z","steps":["trace[167043511] 'range keys from in-memory index tree' (duration: 150.427687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:15.797201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.312209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-22T06:08:15.797319Z","caller":"traceutil/trace.go:171","msg":"trace[2051330037] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:622; }","duration":"191.496463ms","start":"2026-04-22T06:08:15.605793Z","end":"2026-04-22T06:08:15.79729Z","steps":["trace[2051330037] 'range keys from in-memory index tree' (duration: 191.066563ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:15.79717Z","caller":"traceutil/trace.go:171","msg":"trace[816545879] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:622; }","duration":"247.740234ms","start":"2026-04-22T06:08:15.549396Z","end":"2026-04-22T06:08:15.797137Z","steps":["trace[816545879] 'range keys from in-memory index tree' (duration: 247.432287ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:16.596052Z","caller":"traceutil/trace.go:171","msg":"trace[121450384] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"315.909906ms","start":"2026-04-22T06:08:16.280095Z","end":"2026-04-22T06:08:16.596005Z","steps":["trace[121450384] 'read index received' (duration: 314.980605ms)","trace[121450384] 'applied index is now lower than readState.Index' (duration: 928.331µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:08:16.596117Z","caller":"traceutil/trace.go:171","msg":"trace[1139803580] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"394.618102ms","start":"2026-04-22T06:08:16.201459Z","end":"2026-04-22T06:08:16.596077Z","steps":["trace[1139803580] 'process raft request' (duration: 393.883536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:16.59629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:16.201419Z","time spent":"394.777825ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:08:16.596302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.191983ms","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-04-22T06:08:16.59652Z","caller":"traceutil/trace.go:171","msg":"trace[170582079] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:625; }","duration":"316.478799ms","start":"2026-04-22T06:08:16.280022Z","end":"2026-04-22T06:08:16.596501Z","steps":["trace[170582079] 'agreement among raft nodes before linearized reading' (duration: 316.134172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:16.596568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:16.279993Z","time spent":"316.56414ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T06:08:16.596554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.839385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-22T06:08:16.59664Z","caller":"traceutil/trace.go:171","msg":"trace[1996258742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"281.990239ms","start":"2026-04-22T06:08:16.314631Z","end":"2026-04-22T06:08:16.596622Z","steps":["trace[1996258742] 'agreement among raft nodes before linearized reading' (duration: 281.742723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:09:47.684546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.781357ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:09:47.684663Z","caller":"traceutil/trace.go:171","msg":"trace[1349162542] linearizableReadLoop","detail":"{readStateIndex:1900; appliedIndex:1899; }","duration":"446.323993ms","start":"2026-04-22T06:09:47.238321Z","end":"2026-04-22T06:09:47.684645Z","steps":["trace[1349162542] 'read index received' (duration: 197.31782ms)","trace[1349162542] 'applied index is now lower than readState.Index' (duration: 249.004803ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:09:47.684756Z","caller":"traceutil/trace.go:171","msg":"trace[1852621732] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"466.289215ms","start":"2026-04-22T06:09:47.218456Z","end":"2026-04-22T06:09:47.684745Z","steps":["trace[1852621732] 'process raft request' (duration: 217.20761ms)","trace[1852621732] 'compare' (duration: 248.640204ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:09:47.684818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:09:47.218426Z","time spent":"466.362766ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:09:47.684822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.770612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:09:47.684916Z","caller":"traceutil/trace.go:171","msg":"trace[87300639] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1846; }","duration":"273.947025ms","start":"2026-04-22T06:09:47.410941Z","end":"2026-04-22T06:09:47.684888Z","steps":["trace[87300639] 'agreement among raft nodes before linearized reading' (duration: 273.812152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:09:47.68523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.918965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f4afa161-8b99-43a2-a065-24b025cc9027\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-04-22T06:09:47.685245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.598923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T06:09:47.685271Z","caller":"traceutil/trace.go:171","msg":"trace[859618084] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f4afa161-8b99-43a2-a065-24b025cc9027; range_end:; response_count:1; response_revision:1846; }","duration":"447.012187ms","start":"2026-04-22T06:09:47.238248Z","end":"2026-04-22T06:09:47.68526Z","steps":["trace[859618084] 'agreement among raft nodes before linearized reading' (duration: 446.877754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:09:47.685261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.086912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f4afa161-8b99-43a2-a065-24b025cc9027\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-22T06:09:47.685282Z","caller":"traceutil/trace.go:171","msg":"trace[1661096087] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1846; }","duration":"102.690934ms","start":"2026-04-22T06:09:47.58258Z","end":"2026-04-22T06:09:47.685271Z","steps":["trace[1661096087] 'agreement among raft nodes before linearized reading' (duration: 102.587752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:09:47.685302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:09:47.238216Z","time spent":"447.078689ms","remote":"127.0.0.1:59756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f4afa161-8b99-43a2-a065-24b025cc9027\" "} {"level":"info","ts":"2026-04-22T06:09:47.685321Z","caller":"traceutil/trace.go:171","msg":"trace[1710029612] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f4afa161-8b99-43a2-a065-24b025cc9027; range_end:; response_count:1; response_revision:1846; }","duration":"196.200994ms","start":"2026-04-22T06:09:47.4891Z","end":"2026-04-22T06:09:47.685301Z","steps":["trace[1710029612] 'agreement among raft nodes before linearized reading' (duration: 196.03527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:09:47.685435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.1295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a898ea6306d3c7\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-04-22T06:09:47.685525Z","caller":"traceutil/trace.go:171","msg":"trace[1835197377] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a898ea6306d3c7; range_end:; response_count:1; response_revision:1846; }","duration":"204.238022ms","start":"2026-04-22T06:09:47.481267Z","end":"2026-04-22T06:09:47.685505Z","steps":["trace[1835197377] 'agreement among raft nodes before linearized reading' (duration: 203.927926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:09:47.925728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.470869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:09:47.92591Z","caller":"traceutil/trace.go:171","msg":"trace[833212740] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"233.754396ms","start":"2026-04-22T06:09:47.692126Z","end":"2026-04-22T06:09:47.92588Z","steps":["trace[833212740] 'process raft request' (duration: 94.046992ms)","trace[833212740] 'compare' (duration: 139.376967ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:09:55.166946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.99283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T06:09:55.167304Z","caller":"traceutil/trace.go:171","msg":"trace[952412532] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1938; }","duration":"122.445371ms","start":"2026-04-22T06:09:55.04484Z","end":"2026-04-22T06:09:55.167286Z","steps":["trace[952412532] 'count revisions from in-memory index tree' (duration: 121.770346ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:29.535686Z","caller":"traceutil/trace.go:171","msg":"trace[1929267409] linearizableReadLoop","detail":"{readStateIndex:2254; appliedIndex:2253; }","duration":"113.79788ms","start":"2026-04-22T06:10:29.421856Z","end":"2026-04-22T06:10:29.535654Z","steps":["trace[1929267409] 'read index received' (duration: 110.337144ms)","trace[1929267409] 'applied index is now lower than readState.Index' (duration: 3.459006ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:10:29.535919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.038937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-04-22T06:10:29.535962Z","caller":"traceutil/trace.go:171","msg":"trace[1835475842] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2183; }","duration":"114.129938ms","start":"2026-04-22T06:10:29.42182Z","end":"2026-04-22T06:10:29.53595Z","steps":["trace[1835475842] 'agreement among raft nodes before linearized reading' (duration: 113.959474ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:29.535824Z","caller":"traceutil/trace.go:171","msg":"trace[930178996] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"122.239019ms","start":"2026-04-22T06:10:29.413542Z","end":"2026-04-22T06:10:29.535781Z","steps":["trace[930178996] 'process raft request' (duration: 118.67537ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:29.988249Z","caller":"traceutil/trace.go:171","msg":"trace[1640926097] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"161.222399ms","start":"2026-04-22T06:10:29.826996Z","end":"2026-04-22T06:10:29.988219Z","steps":["trace[1640926097] 'process raft request' (duration: 161.029615ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:09.250252Z","caller":"traceutil/trace.go:171","msg":"trace[1191229498] linearizableReadLoop","detail":"{readStateIndex:2591; appliedIndex:2590; }","duration":"325.486496ms","start":"2026-04-22T06:11:08.924733Z","end":"2026-04-22T06:11:09.250219Z","steps":["trace[1191229498] 'read index received' (duration: 323.402169ms)","trace[1191229498] 'applied index is now lower than readState.Index' (duration: 2.083107ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:11:09.250407Z","caller":"traceutil/trace.go:171","msg":"trace[1052263327] transaction","detail":"{read_only:false; response_revision:2502; number_of_response:1; }","duration":"331.48236ms","start":"2026-04-22T06:11:08.918913Z","end":"2026-04-22T06:11:09.250395Z","steps":["trace[1052263327] 'process raft request' (duration: 329.25552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:09.250535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:08.918901Z","time spent":"331.535151ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:11:09.250653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.208875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-22T06:11:09.250714Z","caller":"traceutil/trace.go:171","msg":"trace[1070524263] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2502; }","duration":"323.337487ms","start":"2026-04-22T06:11:08.92736Z","end":"2026-04-22T06:11:09.250697Z","steps":["trace[1070524263] 'agreement among raft nodes before linearized reading' (duration: 323.142213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:09.250755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:08.927326Z","time spent":"323.419879ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-22T06:11:09.251065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.329464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-22T06:11:09.251105Z","caller":"traceutil/trace.go:171","msg":"trace[2080745670] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2502; }","duration":"326.378156ms","start":"2026-04-22T06:11:08.924713Z","end":"2026-04-22T06:11:09.251091Z","steps":["trace[2080745670] 'agreement among raft nodes before linearized reading' (duration: 326.278724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:09.251124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:08.924704Z","time spent":"326.415277ms","remote":"127.0.0.1:40640","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-04-22T06:11:43.547659Z","caller":"traceutil/trace.go:171","msg":"trace[1944491226] transaction","detail":"{read_only:false; response_revision:2739; number_of_response:1; }","duration":"103.149838ms","start":"2026-04-22T06:11:43.444477Z","end":"2026-04-22T06:11:43.547627Z","steps":["trace[1944491226] 'process raft request' (duration: 102.990965ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:12:39.964212Z","caller":"traceutil/trace.go:171","msg":"trace[2064112208] linearizableReadLoop","detail":"{readStateIndex:3419; appliedIndex:3418; }","duration":"130.365329ms","start":"2026-04-22T06:12:39.833815Z","end":"2026-04-22T06:12:39.96418Z","steps":["trace[2064112208] 'read index received' (duration: 119.364572ms)","trace[2064112208] 'applied index is now lower than readState.Index' (duration: 10.999347ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:12:39.964335Z","caller":"traceutil/trace.go:171","msg":"trace[1945291755] transaction","detail":"{read_only:false; response_revision:3291; number_of_response:1; }","duration":"134.853169ms","start":"2026-04-22T06:12:39.829445Z","end":"2026-04-22T06:12:39.964298Z","steps":["trace[1945291755] 'process raft request' (duration: 123.716739ms)","trace[1945291755] 'compare' (duration: 10.820003ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:12:39.964396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.545113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T06:12:39.964439Z","caller":"traceutil/trace.go:171","msg":"trace[1116064633] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:3291; }","duration":"130.638315ms","start":"2026-04-22T06:12:39.833791Z","end":"2026-04-22T06:12:39.964429Z","steps":["trace[1116064633] 'agreement among raft nodes before linearized reading' (duration: 130.515652ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:12:39.985958Z","caller":"traceutil/trace.go:171","msg":"trace[802570358] transaction","detail":"{read_only:false; response_revision:3292; number_of_response:1; }","duration":"149.771585ms","start":"2026-04-22T06:12:39.836158Z","end":"2026-04-22T06:12:39.985929Z","steps":["trace[802570358] 'process raft request' (duration: 149.606211ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:00.994821Z","caller":"traceutil/trace.go:171","msg":"trace[162744955] transaction","detail":"{read_only:false; response_revision:4446; number_of_response:1; }","duration":"184.941308ms","start":"2026-04-22T06:14:00.80986Z","end":"2026-04-22T06:14:00.994801Z","steps":["trace[162744955] 'process raft request' (duration: 184.784515ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:01.013053Z","caller":"traceutil/trace.go:171","msg":"trace[1451085923] transaction","detail":"{read_only:false; response_revision:4447; number_of_response:1; }","duration":"169.419088ms","start":"2026-04-22T06:14:00.843595Z","end":"2026-04-22T06:14:01.013014Z","steps":["trace[1451085923] 'process raft request' (duration: 169.199163ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:01.187575Z","caller":"traceutil/trace.go:171","msg":"trace[504265671] transaction","detail":"{read_only:false; response_revision:4449; number_of_response:1; }","duration":"101.476967ms","start":"2026-04-22T06:14:01.086073Z","end":"2026-04-22T06:14:01.18755Z","steps":["trace[504265671] 'process raft request' (duration: 101.297613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:01.785382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.358628ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-22T06:14:01.789533Z","caller":"traceutil/trace.go:171","msg":"trace[689709537] linearizableReadLoop","detail":"{readStateIndex:4704; appliedIndex:4701; }","duration":"188.867337ms","start":"2026-04-22T06:14:01.600646Z","end":"2026-04-22T06:14:01.789513Z","steps":["trace[689709537] 'read index received' (duration: 54.223493ms)","trace[689709537] 'applied index is now lower than readState.Index' (duration: 134.642754ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:14:01.789787Z","caller":"traceutil/trace.go:171","msg":"trace[1691282391] transaction","detail":"{read_only:false; response_revision:4515; number_of_response:1; }","duration":"213.303458ms","start":"2026-04-22T06:14:01.576456Z","end":"2026-04-22T06:14:01.789759Z","steps":["trace[1691282391] 'process raft request' (duration: 212.695504ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:01.789823Z","caller":"traceutil/trace.go:171","msg":"trace[795965079] transaction","detail":"{read_only:false; response_revision:4516; number_of_response:1; }","duration":"194.652517ms","start":"2026-04-22T06:14:01.59516Z","end":"2026-04-22T06:14:01.789813Z","steps":["trace[795965079] 'process raft request' (duration: 194.236298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:01.790004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.306067ms","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-04-22T06:14:01.790414Z","caller":"traceutil/trace.go:171","msg":"trace[1782045773] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:4516; }","duration":"189.802458ms","start":"2026-04-22T06:14:01.600589Z","end":"2026-04-22T06:14:01.790392Z","steps":["trace[1782045773] 'agreement among raft nodes before linearized reading' (duration: 189.286226ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:04.6524Z","caller":"traceutil/trace.go:171","msg":"trace[320045185] transaction","detail":"{read_only:false; response_revision:4570; number_of_response:1; }","duration":"194.032383ms","start":"2026-04-22T06:14:04.458335Z","end":"2026-04-22T06:14:04.652368Z","steps":["trace[320045185] 'process raft request' (duration: 193.822228ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:04.72736Z","caller":"traceutil/trace.go:171","msg":"trace[782982811] linearizableReadLoop","detail":"{readStateIndex:4761; appliedIndex:4759; }","duration":"162.578605ms","start":"2026-04-22T06:14:04.564761Z","end":"2026-04-22T06:14:04.72734Z","steps":["trace[782982811] 'read index received' (duration: 87.458381ms)","trace[782982811] 'applied index is now lower than readState.Index' (duration: 75.119474ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:14:04.727506Z","caller":"traceutil/trace.go:171","msg":"trace[1738405279] transaction","detail":"{read_only:false; response_revision:4571; number_of_response:1; }","duration":"268.633424ms","start":"2026-04-22T06:14:04.458863Z","end":"2026-04-22T06:14:04.727496Z","steps":["trace[1738405279] 'process raft request' (duration: 268.318096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:04.727758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.984153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-22T06:14:04.727794Z","caller":"traceutil/trace.go:171","msg":"trace[435208274] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4571; }","duration":"163.086346ms","start":"2026-04-22T06:14:04.564701Z","end":"2026-04-22T06:14:04.727787Z","steps":["trace[435208274] 'agreement among raft nodes before linearized reading' (duration: 162.946203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:04.727992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.807371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c28ee77f-1201-454c-971d-f59dfc47829d\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-04-22T06:14:04.728074Z","caller":"traceutil/trace.go:171","msg":"trace[324898207] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c28ee77f-1201-454c-971d-f59dfc47829d; range_end:; response_count:1; response_revision:4571; }","duration":"139.961404ms","start":"2026-04-22T06:14:04.588093Z","end":"2026-04-22T06:14:04.728054Z","steps":["trace[324898207] 'agreement among raft nodes before linearized reading' (duration: 139.690868ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:06.771947Z","caller":"traceutil/trace.go:171","msg":"trace[379286537] transaction","detail":"{read_only:false; response_revision:4611; number_of_response:1; }","duration":"148.572899ms","start":"2026-04-22T06:14:06.623344Z","end":"2026-04-22T06:14:06.771917Z","steps":["trace[379286537] 'process raft request' (duration: 148.398635ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:06.772712Z","caller":"traceutil/trace.go:171","msg":"trace[1236057346] linearizableReadLoop","detail":"{readStateIndex:4802; appliedIndex:4802; }","duration":"127.493594ms","start":"2026-04-22T06:14:06.645201Z","end":"2026-04-22T06:14:06.772694Z","steps":["trace[1236057346] 'read index received' (duration: 127.487844ms)","trace[1236057346] 'applied index is now lower than readState.Index' (duration: 4.51µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:14:06.77291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.336597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-22T06:14:06.772963Z","caller":"traceutil/trace.go:171","msg":"trace[960178715] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4611; }","duration":"114.425699ms","start":"2026-04-22T06:14:06.658522Z","end":"2026-04-22T06:14:06.772948Z","steps":["trace[960178715] 'agreement among raft nodes before linearized reading' (duration: 114.257786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:06.773212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.004955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:14:06.773256Z","caller":"traceutil/trace.go:171","msg":"trace[996972267] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4611; }","duration":"128.074907ms","start":"2026-04-22T06:14:06.645171Z","end":"2026-04-22T06:14:06.773246Z","steps":["trace[996972267] 'agreement among raft nodes before linearized reading' (duration: 128.006815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:07.048508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.25356ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:14:07.048663Z","caller":"traceutil/trace.go:171","msg":"trace[1794985566] transaction","detail":"{read_only:false; response_revision:4612; number_of_response:1; }","duration":"267.913117ms","start":"2026-04-22T06:14:06.780724Z","end":"2026-04-22T06:14:07.048637Z","steps":["trace[1794985566] 'process raft request' (duration: 155.394511ms)","trace[1794985566] 'compare' (duration: 111.995864ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:14:07.129349Z","caller":"traceutil/trace.go:171","msg":"trace[649153207] transaction","detail":"{read_only:false; response_revision:4613; number_of_response:1; }","duration":"348.581375ms","start":"2026-04-22T06:14:06.78073Z","end":"2026-04-22T06:14:07.129311Z","steps":["trace[649153207] 'process raft request' (duration: 348.33601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:07.129759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:14:06.780709Z","time spent":"348.75564ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:14:07.129986Z","caller":"traceutil/trace.go:171","msg":"trace[970100677] transaction","detail":"{read_only:false; response_revision:4614; number_of_response:1; }","duration":"349.182058ms","start":"2026-04-22T06:14:06.780764Z","end":"2026-04-22T06:14:07.129946Z","steps":["trace[970100677] 'process raft request' (duration: 348.493963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:07.130159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:14:06.780738Z","time spent":"349.322902ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:14:07.130396Z","caller":"traceutil/trace.go:171","msg":"trace[1392131417] transaction","detail":"{read_only:false; response_revision:4616; number_of_response:1; }","duration":"349.186139ms","start":"2026-04-22T06:14:06.781188Z","end":"2026-04-22T06:14:07.130374Z","steps":["trace[1392131417] 'process raft request' (duration: 348.990004ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:07.130402Z","caller":"traceutil/trace.go:171","msg":"trace[409759977] transaction","detail":"{read_only:false; response_revision:4615; number_of_response:1; }","duration":"349.25598ms","start":"2026-04-22T06:14:06.781089Z","end":"2026-04-22T06:14:07.130345Z","steps":["trace[409759977] 'process raft request' (duration: 348.629926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:07.130481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:14:06.781177Z","time spent":"349.26368ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:14:07.130519Z","caller":"traceutil/trace.go:171","msg":"trace[837691894] linearizableReadLoop","detail":"{readStateIndex:4808; appliedIndex:4803; }","duration":"158.936561ms","start":"2026-04-22T06:14:06.971572Z","end":"2026-04-22T06:14:07.130508Z","steps":["trace[837691894] 'read index received' (duration: 157.518129ms)","trace[837691894] 'applied index is now lower than readState.Index' (duration: 1.417372ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:14:07.130584Z","caller":"traceutil/trace.go:171","msg":"trace[1146990012] transaction","detail":"{read_only:false; response_revision:4617; number_of_response:1; }","duration":"349.045475ms","start":"2026-04-22T06:14:06.781531Z","end":"2026-04-22T06:14:07.130577Z","steps":["trace[1146990012] 'process raft request' (duration: 348.759359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:07.130627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:14:06.781496Z","time spent":"349.102027ms","remote":"127.0.0.1:59748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":991,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:14:07.130639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:14:06.781078Z","time spent":"349.441374ms","remote":"127.0.0.1:40642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1407,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:14:07.130656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.081555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:14:07.130708Z","caller":"traceutil/trace.go:171","msg":"trace[1117014961] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:4617; }","duration":"159.179196ms","start":"2026-04-22T06:14:06.971514Z","end":"2026-04-22T06:14:07.130694Z","steps":["trace[1117014961] 'agreement among raft nodes before linearized reading' (duration: 159.061753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:07.351569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.837783ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-04-22T06:14:07.352079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:14:07.036338Z","time spent":"315.733955ms","remote":"127.0.0.1:59646","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-22T06:14:07.376914Z","caller":"traceutil/trace.go:171","msg":"trace[1173839018] linearizableReadLoop","detail":"{readStateIndex:4811; appliedIndex:4808; }","duration":"246.342471ms","start":"2026-04-22T06:14:07.130542Z","end":"2026-04-22T06:14:07.376885Z","steps":["trace[1173839018] 'read index received' (duration: 76.209668ms)","trace[1173839018] 'applied index is now lower than readState.Index' (duration: 170.131623ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:14:07.377081Z","caller":"traceutil/trace.go:171","msg":"trace[719271475] transaction","detail":"{read_only:false; response_revision:4618; number_of_response:1; }","duration":"340.460142ms","start":"2026-04-22T06:14:07.036606Z","end":"2026-04-22T06:14:07.377066Z","steps":["trace[719271475] 'process raft request' (duration: 315.447949ms)","trace[719271475] 'compare' (duration: 24.572274ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:14:07.377245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:14:07.036592Z","time spent":"340.529794ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:14:07.377404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.56558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/ipmi-exporter\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:14:07.377487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.508748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-04-22T06:14:07.377548Z","caller":"traceutil/trace.go:171","msg":"trace[193100954] range","detail":"{range_begin:/registry/configmaps/monitoring/ipmi-exporter; range_end:; response_count:0; response_revision:4619; }","duration":"247.778184ms","start":"2026-04-22T06:14:07.129737Z","end":"2026-04-22T06:14:07.377515Z","steps":["trace[193100954] 'agreement among raft nodes before linearized reading' (duration: 247.536288ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:07.377597Z","caller":"traceutil/trace.go:171","msg":"trace[214451494] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4619; }","duration":"113.711063ms","start":"2026-04-22T06:14:07.263858Z","end":"2026-04-22T06:14:07.377569Z","steps":["trace[214451494] 'agreement among raft nodes before linearized reading' (duration: 113.293802ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:17.565243Z","caller":"traceutil/trace.go:171","msg":"trace[1191642852] transaction","detail":"{read_only:false; response_revision:4802; number_of_response:1; }","duration":"165.159923ms","start":"2026-04-22T06:14:17.400051Z","end":"2026-04-22T06:14:17.565211Z","steps":["trace[1191642852] 'process raft request' (duration: 164.879276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:17.718229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.573586ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:14:17.7185Z","caller":"traceutil/trace.go:171","msg":"trace[1636018421] linearizableReadLoop","detail":"{readStateIndex:5003; appliedIndex:5001; }","duration":"210.402982ms","start":"2026-04-22T06:14:17.508075Z","end":"2026-04-22T06:14:17.718478Z","steps":["trace[1636018421] 'read index received' (duration: 56.869122ms)","trace[1636018421] 'applied index is now lower than readState.Index' (duration: 153.53178ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:14:17.718519Z","caller":"traceutil/trace.go:171","msg":"trace[1473057485] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"151.248038ms","start":"2026-04-22T06:14:17.56725Z","end":"2026-04-22T06:14:17.718498Z","steps":["trace[1473057485] 'process raft request' (duration: 151.142226ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:17.718487Z","caller":"traceutil/trace.go:171","msg":"trace[329600038] transaction","detail":"{read_only:false; response_revision:4803; number_of_response:1; }","duration":"298.837775ms","start":"2026-04-22T06:14:17.419596Z","end":"2026-04-22T06:14:17.718434Z","steps":["trace[329600038] 'process raft request' (duration: 162.916571ms)","trace[329600038] 'compare' (duration: 135.182608ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:14:17.718744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.674078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-04-22T06:14:17.71879Z","caller":"traceutil/trace.go:171","msg":"trace[165473670] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4804; }","duration":"210.75756ms","start":"2026-04-22T06:14:17.508019Z","end":"2026-04-22T06:14:17.718776Z","steps":["trace[165473670] 'agreement among raft nodes before linearized reading' (duration: 210.573805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:17.718902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.143251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-22T06:14:17.718948Z","caller":"traceutil/trace.go:171","msg":"trace[1602388757] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4804; }","duration":"196.220162ms","start":"2026-04-22T06:14:17.522714Z","end":"2026-04-22T06:14:17.718934Z","steps":["trace[1602388757] 'agreement among raft nodes before linearized reading' (duration: 196.101019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:17.719048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.130191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:14:17.719077Z","caller":"traceutil/trace.go:171","msg":"trace[1663379061] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:4804; }","duration":"157.192132ms","start":"2026-04-22T06:14:17.561875Z","end":"2026-04-22T06:14:17.719067Z","steps":["trace[1663379061] 'agreement among raft nodes before linearized reading' (duration: 157.143891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:17.719105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.624221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:14:17.719199Z","caller":"traceutil/trace.go:171","msg":"trace[1085298759] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:4804; }","duration":"192.737593ms","start":"2026-04-22T06:14:17.526442Z","end":"2026-04-22T06:14:17.71918Z","steps":["trace[1085298759] 'agreement among raft nodes before linearized reading' (duration: 192.621931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:18.290353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.164492ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:14:18.29049Z","caller":"traceutil/trace.go:171","msg":"trace[1848804121] linearizableReadLoop","detail":"{readStateIndex:5013; appliedIndex:5012; }","duration":"299.789006ms","start":"2026-04-22T06:14:17.990679Z","end":"2026-04-22T06:14:18.290468Z","steps":["trace[1848804121] 'read index received' (duration: 63.361898ms)","trace[1848804121] 'applied index is now lower than readState.Index' (duration: 236.425128ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:14:18.290545Z","caller":"traceutil/trace.go:171","msg":"trace[259579523] transaction","detail":"{read_only:false; response_revision:4813; number_of_response:1; }","duration":"442.850891ms","start":"2026-04-22T06:14:17.847661Z","end":"2026-04-22T06:14:18.290512Z","steps":["trace[259579523] 'process raft request' (duration: 206.386822ms)","trace[259579523] 'compare' (duration: 235.964407ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:14:18.290678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.992691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:14:18.290751Z","caller":"traceutil/trace.go:171","msg":"trace[1695857432] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:4813; }","duration":"300.090223ms","start":"2026-04-22T06:14:17.990626Z","end":"2026-04-22T06:14:18.290716Z","steps":["trace[1695857432] 'agreement among raft nodes before linearized reading' (duration: 299.96932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:18.290745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:14:17.847639Z","time spent":"442.989763ms","remote":"127.0.0.1:59756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4982,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:14:18.290813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:14:17.990601Z","time spent":"300.196165ms","remote":"127.0.0.1:43126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-04-22T06:14:18.291512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.980842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:14:18.291565Z","caller":"traceutil/trace.go:171","msg":"trace[761099342] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:4813; }","duration":"273.048844ms","start":"2026-04-22T06:14:18.018494Z","end":"2026-04-22T06:14:18.291543Z","steps":["trace[761099342] 'agreement among raft nodes before linearized reading' (duration: 272.962603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:18.291551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.289847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6m6bl\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-04-22T06:14:18.291619Z","caller":"traceutil/trace.go:171","msg":"trace[1458039289] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6m6bl; range_end:; response_count:1; response_revision:4813; }","duration":"284.373519ms","start":"2026-04-22T06:14:18.007231Z","end":"2026-04-22T06:14:18.291604Z","steps":["trace[1458039289] 'agreement among raft nodes before linearized reading' (duration: 283.884527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:18.291583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.34151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T06:14:18.291958Z","caller":"traceutil/trace.go:171","msg":"trace[185837748] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:4813; }","duration":"174.796929ms","start":"2026-04-22T06:14:18.11714Z","end":"2026-04-22T06:14:18.291937Z","steps":["trace[185837748] 'agreement among raft nodes before linearized reading' (duration: 174.299938ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:25.278909Z","caller":"traceutil/trace.go:171","msg":"trace[2096541242] transaction","detail":"{read_only:false; response_revision:4984; number_of_response:1; }","duration":"128.245831ms","start":"2026-04-22T06:14:25.150609Z","end":"2026-04-22T06:14:25.278855Z","steps":["trace[2096541242] 'process raft request' (duration: 128.007445ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:26.02276Z","caller":"traceutil/trace.go:171","msg":"trace[272636069] linearizableReadLoop","detail":"{readStateIndex:5194; appliedIndex:5193; }","duration":"174.40664ms","start":"2026-04-22T06:14:25.848331Z","end":"2026-04-22T06:14:26.022738Z","steps":["trace[272636069] 'read index received' (duration: 174.153064ms)","trace[272636069] 'applied index is now lower than readState.Index' (duration: 252.606µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:14:26.022872Z","caller":"traceutil/trace.go:171","msg":"trace[736851443] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"184.808226ms","start":"2026-04-22T06:14:25.837999Z","end":"2026-04-22T06:14:26.022807Z","steps":["trace[736851443] 'process raft request' (duration: 184.57013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:26.023042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.686376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-22T06:14:26.023091Z","caller":"traceutil/trace.go:171","msg":"trace[910101703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4989; }","duration":"174.773869ms","start":"2026-04-22T06:14:25.848306Z","end":"2026-04-22T06:14:26.02308Z","steps":["trace[910101703] 'agreement among raft nodes before linearized reading' (duration: 174.611385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:26.023307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.090899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-04-22T06:14:26.023419Z","caller":"traceutil/trace.go:171","msg":"trace[347032384] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4989; }","duration":"105.219572ms","start":"2026-04-22T06:14:25.918178Z","end":"2026-04-22T06:14:26.023397Z","steps":["trace[347032384] 'agreement among raft nodes before linearized reading' (duration: 104.953925ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:15:13.644313Z","caller":"traceutil/trace.go:171","msg":"trace[2143269780] transaction","detail":"{read_only:false; response_revision:5510; number_of_response:1; }","duration":"101.971734ms","start":"2026-04-22T06:15:13.542294Z","end":"2026-04-22T06:15:13.644266Z","steps":["trace[2143269780] 'process raft request' (duration: 101.651186ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:17:38.400601Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3286} {"level":"info","ts":"2026-04-22T06:17:38.493771Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3286,"took":"91.48055ms","hash":246708974,"current-db-size-bytes":60915712,"current-db-size":"61 MB","current-db-size-in-use-bytes":34041856,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-22T06:17:38.493832Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":246708974,"revision":3286,"compact-revision":-1} {"level":"info","ts":"2026-04-22T06:20:18.561694Z","caller":"traceutil/trace.go:171","msg":"trace[1342043445] linearizableReadLoop","detail":"{readStateIndex:8427; appliedIndex:8426; }","duration":"134.043643ms","start":"2026-04-22T06:20:18.427615Z","end":"2026-04-22T06:20:18.561659Z","steps":["trace[1342043445] 'read index received' (duration: 133.819498ms)","trace[1342043445] 'applied index is now lower than readState.Index' (duration: 222.665µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:20:18.561783Z","caller":"traceutil/trace.go:171","msg":"trace[891507364] transaction","detail":"{read_only:false; response_revision:8069; number_of_response:1; }","duration":"138.899524ms","start":"2026-04-22T06:20:18.422828Z","end":"2026-04-22T06:20:18.561727Z","steps":["trace[891507364] 'process raft request' (duration: 138.637208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:18.561983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.35352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6m6bl\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-04-22T06:20:18.562073Z","caller":"traceutil/trace.go:171","msg":"trace[1727656908] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6m6bl; range_end:; response_count:1; response_revision:8069; }","duration":"134.502944ms","start":"2026-04-22T06:20:18.427552Z","end":"2026-04-22T06:20:18.562055Z","steps":["trace[1727656908] 'agreement among raft nodes before linearized reading' (duration: 134.194976ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:18.739619Z","caller":"traceutil/trace.go:171","msg":"trace[443084976] linearizableReadLoop","detail":"{readStateIndex:8428; appliedIndex:8427; }","duration":"171.432469ms","start":"2026-04-22T06:20:18.568144Z","end":"2026-04-22T06:20:18.739576Z","steps":["trace[443084976] 'read index received' (duration: 158.059957ms)","trace[443084976] 'applied index is now lower than readState.Index' (duration: 13.370552ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:20:18.739823Z","caller":"traceutil/trace.go:171","msg":"trace[867514320] transaction","detail":"{read_only:false; response_revision:8070; number_of_response:1; }","duration":"305.179467ms","start":"2026-04-22T06:20:18.434628Z","end":"2026-04-22T06:20:18.739807Z","steps":["trace[867514320] 'process raft request' (duration: 291.642461ms)","trace[867514320] 'compare' (duration: 13.023265ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:20:18.739984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:18.434604Z","time spent":"305.254009ms","remote":"127.0.0.1:59756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8264,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:20:18.739956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.017872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T06:20:18.740069Z","caller":"traceutil/trace.go:171","msg":"trace[47173345] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:8070; }","duration":"160.203446ms","start":"2026-04-22T06:20:18.579839Z","end":"2026-04-22T06:20:18.740042Z","steps":["trace[47173345] 'agreement among raft nodes before linearized reading' (duration: 160.001491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:18.740631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.443353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:24 size:203484"} {"level":"info","ts":"2026-04-22T06:20:18.740758Z","caller":"traceutil/trace.go:171","msg":"trace[1325053184] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:24; response_revision:8070; }","duration":"172.597266ms","start":"2026-04-22T06:20:18.568126Z","end":"2026-04-22T06:20:18.740724Z","steps":["trace[1325053184] 'agreement among raft nodes before linearized reading' (duration: 171.849079ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:19.365348Z","caller":"traceutil/trace.go:171","msg":"trace[643746776] transaction","detail":"{read_only:false; response_revision:8076; number_of_response:1; }","duration":"163.847968ms","start":"2026-04-22T06:20:19.201467Z","end":"2026-04-22T06:20:19.365315Z","steps":["trace[643746776] 'process raft request' (duration: 163.616613ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:19.396627Z","caller":"traceutil/trace.go:171","msg":"trace[1363894725] transaction","detail":"{read_only:false; response_revision:8077; number_of_response:1; }","duration":"168.411452ms","start":"2026-04-22T06:20:19.228189Z","end":"2026-04-22T06:20:19.396601Z","steps":["trace[1363894725] 'process raft request' (duration: 168.180807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.457754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.899779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-22T06:20:19.457881Z","caller":"traceutil/trace.go:171","msg":"trace[596646996] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8077; }","duration":"107.059923ms","start":"2026-04-22T06:20:19.35079Z","end":"2026-04-22T06:20:19.45785Z","steps":["trace[596646996] 'agreement among raft nodes before linearized reading' (duration: 106.773497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.457904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.050493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T06:20:19.457989Z","caller":"traceutil/trace.go:171","msg":"trace[360185813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8077; }","duration":"107.197646ms","start":"2026-04-22T06:20:19.350773Z","end":"2026-04-22T06:20:19.457971Z","steps":["trace[360185813] 'agreement among raft nodes before linearized reading' (duration: 106.94497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.457891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.829444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-22T06:20:19.458092Z","caller":"traceutil/trace.go:171","msg":"trace[28313081] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8077; }","duration":"106.08792ms","start":"2026-04-22T06:20:19.351982Z","end":"2026-04-22T06:20:19.45807Z","steps":["trace[28313081] 'agreement among raft nodes before linearized reading' (duration: 105.678241ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:19.639694Z","caller":"traceutil/trace.go:171","msg":"trace[236272192] transaction","detail":"{read_only:false; response_revision:8079; number_of_response:1; }","duration":"176.925195ms","start":"2026-04-22T06:20:19.462744Z","end":"2026-04-22T06:20:19.639669Z","steps":["trace[236272192] 'process raft request' (duration: 104.520526ms)","trace[236272192] 'compare' (duration: 72.176953ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:20:19.640099Z","caller":"traceutil/trace.go:171","msg":"trace[1385387731] transaction","detail":"{read_only:false; response_revision:8080; number_of_response:1; }","duration":"177.139029ms","start":"2026-04-22T06:20:19.462951Z","end":"2026-04-22T06:20:19.64009Z","steps":["trace[1385387731] 'process raft request' (duration: 176.683169ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:19.64019Z","caller":"traceutil/trace.go:171","msg":"trace[105265637] transaction","detail":"{read_only:false; response_revision:8081; number_of_response:1; }","duration":"175.136593ms","start":"2026-04-22T06:20:19.465047Z","end":"2026-04-22T06:20:19.640184Z","steps":["trace[105265637] 'process raft request' (duration: 174.896818ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:19.640239Z","caller":"traceutil/trace.go:171","msg":"trace[1616069131] transaction","detail":"{read_only:false; response_revision:8082; number_of_response:1; }","duration":"169.661229ms","start":"2026-04-22T06:20:19.47054Z","end":"2026-04-22T06:20:19.640202Z","steps":["trace[1616069131] 'process raft request' (duration: 169.556817ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:23.369714Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002189.wal"} {"level":"info","ts":"2026-04-22T06:20:26.36852Z","caller":"traceutil/trace.go:171","msg":"trace[1043196382] transaction","detail":"{read_only:false; response_revision:8252; number_of_response:1; }","duration":"108.778453ms","start":"2026-04-22T06:20:26.259713Z","end":"2026-04-22T06:20:26.368492Z","steps":["trace[1043196382] 'process raft request' (duration: 108.574728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:28.234552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.524882ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:20:28.234702Z","caller":"traceutil/trace.go:171","msg":"trace[1018363933] transaction","detail":"{read_only:false; response_revision:8258; number_of_response:1; }","duration":"545.739691ms","start":"2026-04-22T06:20:27.688922Z","end":"2026-04-22T06:20:28.234661Z","steps":["trace[1018363933] 'process raft request' (duration: 396.982924ms)","trace[1018363933] 'compare' (duration: 148.387569ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:20:28.234802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:27.688903Z","time spent":"545.852684ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:20:28.321634Z","caller":"traceutil/trace.go:171","msg":"trace[2004044549] transaction","detail":"{read_only:false; response_revision:8260; number_of_response:1; }","duration":"631.83331ms","start":"2026-04-22T06:20:27.689766Z","end":"2026-04-22T06:20:28.321599Z","steps":["trace[2004044549] 'process raft request' (duration: 631.671766ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:28.32176Z","caller":"traceutil/trace.go:171","msg":"trace[964736856] linearizableReadLoop","detail":"{readStateIndex:8624; appliedIndex:8620; }","duration":"169.110367ms","start":"2026-04-22T06:20:28.152633Z","end":"2026-04-22T06:20:28.321743Z","steps":["trace[964736856] 'read index received' (duration: 168.683527ms)","trace[964736856] 'applied index is now lower than readState.Index' (duration: 425.49µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:20:28.32182Z","caller":"traceutil/trace.go:171","msg":"trace[1424162183] transaction","detail":"{read_only:false; response_revision:8262; number_of_response:1; }","duration":"332.375762ms","start":"2026-04-22T06:20:27.989397Z","end":"2026-04-22T06:20:28.321772Z","steps":["trace[1424162183] 'process raft request' (duration: 332.132486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:28.321863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:27.68974Z","time spent":"632.009374ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:20:28.321947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.318081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-22T06:20:28.321946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:27.989373Z","time spent":"332.517685ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:20:28.321976Z","caller":"traceutil/trace.go:171","msg":"trace[1802620995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8262; }","duration":"169.394834ms","start":"2026-04-22T06:20:28.152571Z","end":"2026-04-22T06:20:28.321966Z","steps":["trace[1802620995] 'agreement among raft nodes before linearized reading' (duration: 169.24819ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:28.321631Z","caller":"traceutil/trace.go:171","msg":"trace[2011885364] transaction","detail":"{read_only:false; response_revision:8259; number_of_response:1; }","duration":"631.859791ms","start":"2026-04-22T06:20:27.689716Z","end":"2026-04-22T06:20:28.321575Z","steps":["trace[2011885364] 'process raft request' (duration: 631.579375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:28.322112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:27.689696Z","time spent":"632.370583ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:20:28.321626Z","caller":"traceutil/trace.go:171","msg":"trace[1489763478] transaction","detail":"{read_only:false; response_revision:8261; number_of_response:1; }","duration":"619.115363ms","start":"2026-04-22T06:20:27.702452Z","end":"2026-04-22T06:20:28.321568Z","steps":["trace[1489763478] 'process raft request' (duration: 619.030071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:28.322633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:27.702425Z","time spent":"620.074325ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:20:40.596677Z","caller":"traceutil/trace.go:171","msg":"trace[1336155517] linearizableReadLoop","detail":"{readStateIndex:8705; appliedIndex:8704; }","duration":"364.595652ms","start":"2026-04-22T06:20:40.232054Z","end":"2026-04-22T06:20:40.59665Z","steps":["trace[1336155517] 'read index received' (duration: 364.416908ms)","trace[1336155517] 'applied index is now lower than readState.Index' (duration: 177.344µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:20:40.596768Z","caller":"traceutil/trace.go:171","msg":"trace[1625576163] transaction","detail":"{read_only:false; response_revision:8338; number_of_response:1; }","duration":"465.142138ms","start":"2026-04-22T06:20:40.131573Z","end":"2026-04-22T06:20:40.596715Z","steps":["trace[1625576163] 'process raft request' (duration: 464.920233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:40.596917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.825517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:40.596961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.458248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T06:20:40.597Z","caller":"traceutil/trace.go:171","msg":"trace[2054444952] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:8338; }","duration":"364.989441ms","start":"2026-04-22T06:20:40.231989Z","end":"2026-04-22T06:20:40.596979Z","steps":["trace[2054444952] 'agreement among raft nodes before linearized reading' (duration: 364.810637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:40.597027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.26252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-22T06:20:40.597031Z","caller":"traceutil/trace.go:171","msg":"trace[680149507] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:8338; }","duration":"113.54767ms","start":"2026-04-22T06:20:40.483467Z","end":"2026-04-22T06:20:40.597014Z","steps":["trace[680149507] 'agreement among raft nodes before linearized reading' (duration: 113.356925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:40.597027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:40.131535Z","time spent":"465.356063ms","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16428,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:20:40.597049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:40.231959Z","time spent":"365.076223ms","remote":"127.0.0.1:47080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-22T06:20:40.597061Z","caller":"traceutil/trace.go:171","msg":"trace[1371785523] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8338; }","duration":"142.31264ms","start":"2026-04-22T06:20:40.454736Z","end":"2026-04-22T06:20:40.597049Z","steps":["trace[1371785523] 'agreement among raft nodes before linearized reading' (duration: 142.231809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:40.597151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.073004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-22T06:20:40.5972Z","caller":"traceutil/trace.go:171","msg":"trace[816409324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8338; }","duration":"168.121315ms","start":"2026-04-22T06:20:40.429063Z","end":"2026-04-22T06:20:40.597184Z","steps":["trace[816409324] 'agreement among raft nodes before linearized reading' (duration: 167.963472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:40.596944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.818557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-22T06:20:40.597214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.055184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T06:20:40.597274Z","caller":"traceutil/trace.go:171","msg":"trace[114264637] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8338; }","duration":"259.169255ms","start":"2026-04-22T06:20:40.338095Z","end":"2026-04-22T06:20:40.597264Z","steps":["trace[114264637] 'agreement among raft nodes before linearized reading' (duration: 258.716545ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:40.597316Z","caller":"traceutil/trace.go:171","msg":"trace[1573593071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8338; }","duration":"168.223688ms","start":"2026-04-22T06:20:40.429067Z","end":"2026-04-22T06:20:40.59729Z","steps":["trace[1573593071] 'agreement among raft nodes before linearized reading' (duration: 167.917871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:40.597216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.065564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-22T06:20:40.597408Z","caller":"traceutil/trace.go:171","msg":"trace[1411313531] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8338; }","duration":"168.294469ms","start":"2026-04-22T06:20:40.429098Z","end":"2026-04-22T06:20:40.597393Z","steps":["trace[1411313531] 'agreement among raft nodes before linearized reading' (duration: 167.967842ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:43.146016Z","caller":"traceutil/trace.go:171","msg":"trace[1896579165] transaction","detail":"{read_only:false; response_revision:8362; number_of_response:1; }","duration":"158.156259ms","start":"2026-04-22T06:20:42.987838Z","end":"2026-04-22T06:20:43.145994Z","steps":["trace[1896579165] 'process raft request' (duration: 86.256312ms)","trace[1896579165] 'compare' (duration: 71.782844ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:21:07.199079Z","caller":"traceutil/trace.go:171","msg":"trace[1223784503] transaction","detail":"{read_only:false; response_revision:8575; number_of_response:1; }","duration":"152.101573ms","start":"2026-04-22T06:21:07.046948Z","end":"2026-04-22T06:21:07.199049Z","steps":["trace[1223784503] 'process raft request' (duration: 149.816902ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:21:07.200677Z","caller":"traceutil/trace.go:171","msg":"trace[1852796553] linearizableReadLoop","detail":"{readStateIndex:8955; appliedIndex:8953; }","duration":"149.877353ms","start":"2026-04-22T06:21:07.050781Z","end":"2026-04-22T06:21:07.200659Z","steps":["trace[1852796553] 'read index received' (duration: 146.123678ms)","trace[1852796553] 'applied index is now lower than readState.Index' (duration: 3.752545ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:21:07.200848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.049406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6m6bl\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-04-22T06:21:07.200881Z","caller":"traceutil/trace.go:171","msg":"trace[744535794] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6m6bl; range_end:; response_count:1; response_revision:8575; }","duration":"150.119588ms","start":"2026-04-22T06:21:07.050751Z","end":"2026-04-22T06:21:07.20087Z","steps":["trace[744535794] 'agreement among raft nodes before linearized reading' (duration: 149.974475ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:21:09.036712Z","caller":"traceutil/trace.go:171","msg":"trace[1533937937] linearizableReadLoop","detail":"{readStateIndex:8960; appliedIndex:8959; }","duration":"298.302172ms","start":"2026-04-22T06:21:08.738378Z","end":"2026-04-22T06:21:09.03668Z","steps":["trace[1533937937] 'read index received' (duration: 298.114627ms)","trace[1533937937] 'applied index is now lower than readState.Index' (duration: 186.435µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:21:09.036793Z","caller":"traceutil/trace.go:171","msg":"trace[674107606] transaction","detail":"{read_only:false; response_revision:8580; number_of_response:1; }","duration":"439.667701ms","start":"2026-04-22T06:21:08.597096Z","end":"2026-04-22T06:21:09.036764Z","steps":["trace[674107606] 'process raft request' (duration: 439.415056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:09.036877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.479085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:21:09.036919Z","caller":"traceutil/trace.go:171","msg":"trace[348324578] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:8580; }","duration":"298.568558ms","start":"2026-04-22T06:21:08.738337Z","end":"2026-04-22T06:21:09.036905Z","steps":["trace[348324578] 'agreement among raft nodes before linearized reading' (duration: 298.460455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:09.03694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:21:08.597076Z","time spent":"439.784285ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:21:09.297885Z","caller":"traceutil/trace.go:171","msg":"trace[532147938] linearizableReadLoop","detail":"{readStateIndex:8961; appliedIndex:8960; }","duration":"261.08746ms","start":"2026-04-22T06:21:09.036778Z","end":"2026-04-22T06:21:09.297866Z","steps":["trace[532147938] 'read index received' (duration: 214.666939ms)","trace[532147938] 'applied index is now lower than readState.Index' (duration: 46.419841ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:21:09.297945Z","caller":"traceutil/trace.go:171","msg":"trace[1470286121] transaction","detail":"{read_only:false; response_revision:8581; number_of_response:1; }","duration":"552.68384ms","start":"2026-04-22T06:21:08.745224Z","end":"2026-04-22T06:21:09.297908Z","steps":["trace[1470286121] 'process raft request' (duration: 506.288819ms)","trace[1470286121] 'compare' (duration: 46.272448ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:21:09.298046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.596335ms","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-04-22T06:21:09.298055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.52185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-22T06:21:09.298058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.326465ms","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-04-22T06:21:09.298058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.062067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"warn","ts":"2026-04-22T06:21:09.298106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.659945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:21:09.298102Z","caller":"traceutil/trace.go:171","msg":"trace[340977168] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8581; }","duration":"485.608242ms","start":"2026-04-22T06:21:08.812481Z","end":"2026-04-22T06:21:09.298089Z","steps":["trace[340977168] 'agreement among raft nodes before linearized reading' (duration: 485.449078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:09.298068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.282139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-22T06:21:09.298114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.481296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T06:21:09.298134Z","caller":"traceutil/trace.go:171","msg":"trace[1382932420] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8581; }","duration":"264.183919ms","start":"2026-04-22T06:21:09.033933Z","end":"2026-04-22T06:21:09.298117Z","steps":["trace[1382932420] 'agreement among raft nodes before linearized reading' (duration: 263.964704ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:21:09.298159Z","caller":"traceutil/trace.go:171","msg":"trace[1335403141] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8581; }","duration":"487.429063ms","start":"2026-04-22T06:21:08.810709Z","end":"2026-04-22T06:21:09.298138Z","steps":["trace[1335403141] 'agreement among raft nodes before linearized reading' (duration: 487.165187ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:21:09.298189Z","caller":"traceutil/trace.go:171","msg":"trace[1513084256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8581; }","duration":"503.561968ms","start":"2026-04-22T06:21:08.794611Z","end":"2026-04-22T06:21:09.298173Z","steps":["trace[1513084256] 'agreement among raft nodes before linearized reading' (duration: 503.317132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:09.298207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:21:08.81068Z","time spent":"487.518644ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T06:21:09.298231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:21:08.794534Z","time spent":"503.686761ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-22T06:21:09.298125Z","caller":"traceutil/trace.go:171","msg":"trace[1510082278] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:8581; }","duration":"110.737836ms","start":"2026-04-22T06:21:09.187382Z","end":"2026-04-22T06:21:09.29812Z","steps":["trace[1510082278] 'agreement among raft nodes before linearized reading' (duration: 110.704776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:09.298087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:21:08.745185Z","time spent":"552.829813ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:21:09.298132Z","caller":"traceutil/trace.go:171","msg":"trace[511848852] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8581; }","duration":"485.427518ms","start":"2026-04-22T06:21:08.812684Z","end":"2026-04-22T06:21:09.298112Z","steps":["trace[511848852] 'agreement among raft nodes before linearized reading' (duration: 485.186393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:09.298462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:21:08.812674Z","time spent":"485.776016ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T06:21:09.298143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:21:08.812465Z","time spent":"485.668884ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-22T06:21:09.298071Z","caller":"traceutil/trace.go:171","msg":"trace[2053781853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8581; }","duration":"517.656137ms","start":"2026-04-22T06:21:08.780405Z","end":"2026-04-22T06:21:09.298061Z","steps":["trace[2053781853] 'agreement among raft nodes before linearized reading' (duration: 517.567095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:09.298585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:21:08.780387Z","time spent":"518.186658ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-22T06:21:21.786847Z","caller":"traceutil/trace.go:171","msg":"trace[780580086] transaction","detail":"{read_only:false; response_revision:8710; number_of_response:1; }","duration":"117.456659ms","start":"2026-04-22T06:21:21.669351Z","end":"2026-04-22T06:21:21.786808Z","steps":["trace[780580086] 'process raft request' (duration: 117.185483ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:21:22.051143Z","caller":"traceutil/trace.go:171","msg":"trace[1010955680] linearizableReadLoop","detail":"{readStateIndex:9100; appliedIndex:9099; }","duration":"181.996609ms","start":"2026-04-22T06:21:21.869124Z","end":"2026-04-22T06:21:22.05112Z","steps":["trace[1010955680] 'read index received' (duration: 181.818395ms)","trace[1010955680] 'applied index is now lower than readState.Index' (duration: 177.454µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:21:22.051182Z","caller":"traceutil/trace.go:171","msg":"trace[1622914186] transaction","detail":"{read_only:false; response_revision:8712; number_of_response:1; }","duration":"213.351839ms","start":"2026-04-22T06:21:21.837804Z","end":"2026-04-22T06:21:22.051156Z","steps":["trace[1622914186] 'process raft request' (duration: 213.173745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:22.051285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.150653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-22T06:21:22.051331Z","caller":"traceutil/trace.go:171","msg":"trace[481707529] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8712; }","duration":"182.266315ms","start":"2026-04-22T06:21:21.869053Z","end":"2026-04-22T06:21:22.051319Z","steps":["trace[481707529] 'agreement among raft nodes before linearized reading' (duration: 182.159173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:23.198282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.590562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:21:23.198378Z","caller":"traceutil/trace.go:171","msg":"trace[1719587414] linearizableReadLoop","detail":"{readStateIndex:9105; appliedIndex:9104; }","duration":"384.228248ms","start":"2026-04-22T06:21:22.814135Z","end":"2026-04-22T06:21:23.198363Z","steps":["trace[1719587414] 'read index received' (duration: 46.472442ms)","trace[1719587414] 'applied index is now lower than readState.Index' (duration: 337.754226ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:21:23.198978Z","caller":"traceutil/trace.go:171","msg":"trace[1877464685] transaction","detail":"{read_only:false; response_revision:8716; number_of_response:1; }","duration":"558.110272ms","start":"2026-04-22T06:21:22.640856Z","end":"2026-04-22T06:21:23.198967Z","steps":["trace[1877464685] 'process raft request' (duration: 219.759543ms)","trace[1877464685] 'compare' (duration: 337.48171ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:21:23.199036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:21:22.640831Z","time spent":"558.177584ms","remote":"127.0.0.1:59788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2999,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:21:23.199401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.21222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-22T06:21:23.199499Z","caller":"traceutil/trace.go:171","msg":"trace[1182506961] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8716; }","duration":"385.396164ms","start":"2026-04-22T06:21:22.814078Z","end":"2026-04-22T06:21:23.199475Z","steps":["trace[1182506961] 'agreement among raft nodes before linearized reading' (duration: 385.101417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:23.19955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:21:22.814059Z","time spent":"385.479645ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-04-22T06:21:23.593124Z","caller":"traceutil/trace.go:171","msg":"trace[2129747836] linearizableReadLoop","detail":"{readStateIndex:9106; appliedIndex:9105; }","duration":"318.317476ms","start":"2026-04-22T06:21:23.274768Z","end":"2026-04-22T06:21:23.593085Z","steps":["trace[2129747836] 'read index received' (duration: 317.301862ms)","trace[2129747836] 'applied index is now lower than readState.Index' (duration: 1.014274ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:21:23.593207Z","caller":"traceutil/trace.go:171","msg":"trace[1959579760] transaction","detail":"{read_only:false; response_revision:8717; number_of_response:1; }","duration":"389.835424ms","start":"2026-04-22T06:21:23.203337Z","end":"2026-04-22T06:21:23.593172Z","steps":["trace[1959579760] 'process raft request' (duration: 388.847532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:23.593413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:21:23.203307Z","time spent":"389.980168ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:21:23.593598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.786956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-w9bqv\" ","response":"range_response_count:1 size:4614"} {"level":"info","ts":"2026-04-22T06:21:23.593787Z","caller":"traceutil/trace.go:171","msg":"trace[2023123249] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-w9bqv; range_end:; response_count:1; response_revision:8717; }","duration":"319.018601ms","start":"2026-04-22T06:21:23.27473Z","end":"2026-04-22T06:21:23.593749Z","steps":["trace[2023123249] 'agreement among raft nodes before linearized reading' (duration: 318.555891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:23.593936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:21:23.274712Z","time spent":"319.208785ms","remote":"127.0.0.1:59756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4637,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-w9bqv\" "} {"level":"warn","ts":"2026-04-22T06:21:23.608092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.007142ms","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-04-22T06:21:23.608121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.296324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-22T06:21:23.608146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.256601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-22T06:21:23.608183Z","caller":"traceutil/trace.go:171","msg":"trace[1995347299] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8718; }","duration":"128.418427ms","start":"2026-04-22T06:21:23.47975Z","end":"2026-04-22T06:21:23.608168Z","steps":["trace[1995347299] 'agreement among raft nodes before linearized reading' (duration: 128.220152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:23.60821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.978269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-22T06:21:23.60824Z","caller":"traceutil/trace.go:171","msg":"trace[1256596285] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8718; }","duration":"129.02227ms","start":"2026-04-22T06:21:23.479212Z","end":"2026-04-22T06:21:23.608234Z","steps":["trace[1256596285] 'agreement among raft nodes before linearized reading' (duration: 128.954588ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:21:23.608263Z","caller":"traceutil/trace.go:171","msg":"trace[2035065315] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8718; }","duration":"150.376964ms","start":"2026-04-22T06:21:23.457847Z","end":"2026-04-22T06:21:23.608224Z","steps":["trace[2035065315] 'agreement among raft nodes before linearized reading' (duration: 150.068127ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:21:23.60814Z","caller":"traceutil/trace.go:171","msg":"trace[872374679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8718; }","duration":"126.079864ms","start":"2026-04-22T06:21:23.482049Z","end":"2026-04-22T06:21:23.608129Z","steps":["trace[872374679] 'agreement among raft nodes before linearized reading' (duration: 125.948871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:23.608518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.560988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T06:21:23.608565Z","caller":"traceutil/trace.go:171","msg":"trace[632032115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8718; }","duration":"131.630289ms","start":"2026-04-22T06:21:23.476924Z","end":"2026-04-22T06:21:23.608554Z","steps":["trace[632032115] 'agreement among raft nodes before linearized reading' (duration: 131.467175ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:21:23.608603Z","caller":"traceutil/trace.go:171","msg":"trace[142890014] transaction","detail":"{read_only:false; response_revision:8718; number_of_response:1; }","duration":"332.546918ms","start":"2026-04-22T06:21:23.276041Z","end":"2026-04-22T06:21:23.608588Z","steps":["trace[142890014] 'process raft request' (duration: 331.666808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:23.609091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:21:23.27603Z","time spent":"332.617559ms","remote":"127.0.0.1:59646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T06:21:23.662519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.03841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6m6bl\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-04-22T06:21:23.662591Z","caller":"traceutil/trace.go:171","msg":"trace[1051560557] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6m6bl; range_end:; response_count:1; response_revision:8718; }","duration":"171.146123ms","start":"2026-04-22T06:21:23.491428Z","end":"2026-04-22T06:21:23.662574Z","steps":["trace[1051560557] 'agreement among raft nodes before linearized reading' (duration: 117.422468ms)","trace[1051560557] 'range keys from in-memory index tree' (duration: 53.47976ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:21:23.720407Z","caller":"traceutil/trace.go:171","msg":"trace[1168717298] transaction","detail":"{read_only:false; response_revision:8722; number_of_response:1; }","duration":"105.854416ms","start":"2026-04-22T06:21:23.614516Z","end":"2026-04-22T06:21:23.720371Z","steps":["trace[1168717298] 'process raft request' (duration: 105.765334ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:21:23.720407Z","caller":"traceutil/trace.go:171","msg":"trace[646661190] transaction","detail":"{read_only:false; response_revision:8721; number_of_response:1; }","duration":"106.01459ms","start":"2026-04-22T06:21:23.61434Z","end":"2026-04-22T06:21:23.720355Z","steps":["trace[646661190] 'process raft request' (duration: 105.778165ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:21:23.720652Z","caller":"traceutil/trace.go:171","msg":"trace[953578397] transaction","detail":"{read_only:false; response_revision:8723; number_of_response:1; }","duration":"105.941988ms","start":"2026-04-22T06:21:23.614698Z","end":"2026-04-22T06:21:23.72064Z","steps":["trace[953578397] 'process raft request' (duration: 105.625761ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:21:24.270966Z","caller":"traceutil/trace.go:171","msg":"trace[843245827] transaction","detail":"{read_only:false; response_revision:8730; number_of_response:1; }","duration":"101.488977ms","start":"2026-04-22T06:21:24.16944Z","end":"2026-04-22T06:21:24.270929Z","steps":["trace[843245827] 'process raft request' (duration: 101.223321ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:21:25.845811Z","caller":"traceutil/trace.go:171","msg":"trace[357119002] transaction","detail":"{read_only:false; response_revision:8731; number_of_response:1; }","duration":"173.281592ms","start":"2026-04-22T06:21:25.672509Z","end":"2026-04-22T06:21:25.845791Z","steps":["trace[357119002] 'process raft request' (duration: 173.15436ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:21:26.058309Z","caller":"traceutil/trace.go:171","msg":"trace[1471632558] linearizableReadLoop","detail":"{readStateIndex:9121; appliedIndex:9119; }","duration":"333.588182ms","start":"2026-04-22T06:21:25.724695Z","end":"2026-04-22T06:21:26.058283Z","steps":["trace[1471632558] 'read index received' (duration: 121.031751ms)","trace[1471632558] 'applied index is now lower than readState.Index' (duration: 212.555181ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:21:26.058357Z","caller":"traceutil/trace.go:171","msg":"trace[1469471737] transaction","detail":"{read_only:false; response_revision:8733; number_of_response:1; }","duration":"242.112461ms","start":"2026-04-22T06:21:25.816209Z","end":"2026-04-22T06:21:26.058322Z","steps":["trace[1469471737] 'process raft request' (duration: 241.956576ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:21:26.058348Z","caller":"traceutil/trace.go:171","msg":"trace[146162215] transaction","detail":"{read_only:false; response_revision:8732; number_of_response:1; }","duration":"384.363121ms","start":"2026-04-22T06:21:25.673952Z","end":"2026-04-22T06:21:26.058316Z","steps":["trace[146162215] 'process raft request' (duration: 383.808997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:26.058604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.887898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-22T06:21:26.058668Z","caller":"traceutil/trace.go:171","msg":"trace[1853484819] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8733; }","duration":"333.998841ms","start":"2026-04-22T06:21:25.724651Z","end":"2026-04-22T06:21:26.05865Z","steps":["trace[1853484819] 'agreement among raft nodes before linearized reading' (duration: 333.808377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:26.058676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.160081ms","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-04-22T06:21:26.058716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:21:25.724636Z","time spent":"334.067032ms","remote":"127.0.0.1:40640","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-04-22T06:21:26.058732Z","caller":"traceutil/trace.go:171","msg":"trace[1260695928] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8733; }","duration":"333.250123ms","start":"2026-04-22T06:21:25.725467Z","end":"2026-04-22T06:21:26.058717Z","steps":["trace[1260695928] 'agreement among raft nodes before linearized reading' (duration: 333.068578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:26.058776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:21:25.725455Z","time spent":"333.313185ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T06:21:26.058895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.397091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6m6bl\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-04-22T06:21:26.058947Z","caller":"traceutil/trace.go:171","msg":"trace[479453600] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6m6bl; range_end:; response_count:1; response_revision:8733; }","duration":"316.470533ms","start":"2026-04-22T06:21:25.742462Z","end":"2026-04-22T06:21:26.058933Z","steps":["trace[479453600] 'agreement among raft nodes before linearized reading' (duration: 316.34047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:26.058977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:21:25.74245Z","time spent":"316.519224ms","remote":"127.0.0.1:59756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6549,"request content":"key:\"/registry/pods/openstack/keepalived-6m6bl\" "} {"level":"warn","ts":"2026-04-22T06:21:26.058895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.074051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:21:26.059075Z","caller":"traceutil/trace.go:171","msg":"trace[668268001] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:8733; }","duration":"148.293656ms","start":"2026-04-22T06:21:25.910757Z","end":"2026-04-22T06:21:26.05905Z","steps":["trace[668268001] 'agreement among raft nodes before linearized reading' (duration: 148.083911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:26.058918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.163879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-22T06:21:26.059183Z","caller":"traceutil/trace.go:171","msg":"trace[1605607905] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8733; }","duration":"260.486805ms","start":"2026-04-22T06:21:25.798674Z","end":"2026-04-22T06:21:26.05916Z","steps":["trace[1605607905] 'agreement among raft nodes before linearized reading' (duration: 259.886842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:26.059116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.577321ms","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-04-22T06:21:26.059137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.034465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"warn","ts":"2026-04-22T06:21:26.058916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:21:25.673927Z","time spent":"384.667627ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:21:26.059262Z","caller":"traceutil/trace.go:171","msg":"trace[1252480616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8733; }","duration":"333.778994ms","start":"2026-04-22T06:21:25.725464Z","end":"2026-04-22T06:21:26.059243Z","steps":["trace[1252480616] 'agreement among raft nodes before linearized reading' (duration: 333.355335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:26.059446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:21:25.725449Z","time spent":"333.978449ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-22T06:21:26.05946Z","caller":"traceutil/trace.go:171","msg":"trace[1170521580] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8733; }","duration":"260.209019ms","start":"2026-04-22T06:21:25.799042Z","end":"2026-04-22T06:21:26.059251Z","steps":["trace[1170521580] 'agreement among raft nodes before linearized reading' (duration: 259.80196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:26.401838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.405146ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:21:26.401976Z","caller":"traceutil/trace.go:171","msg":"trace[383259097] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8738; }","duration":"147.554659ms","start":"2026-04-22T06:21:26.254401Z","end":"2026-04-22T06:21:26.401956Z","steps":["trace[383259097] 'range keys from in-memory index tree' (duration: 147.387135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:26.402188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.336546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:21:26.402515Z","caller":"traceutil/trace.go:171","msg":"trace[793549361] transaction","detail":"{read_only:false; response_revision:8739; number_of_response:1; }","duration":"261.268173ms","start":"2026-04-22T06:21:26.141202Z","end":"2026-04-22T06:21:26.402471Z","steps":["trace[793549361] 'process raft request' (duration: 25.497927ms)","trace[793549361] 'compare' (duration: 234.914257ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:21:26.402781Z","caller":"traceutil/trace.go:171","msg":"trace[1041047361] transaction","detail":"{read_only:false; response_revision:8741; number_of_response:1; }","duration":"245.307611ms","start":"2026-04-22T06:21:26.157439Z","end":"2026-04-22T06:21:26.402746Z","steps":["trace[1041047361] 'process raft request' (duration: 244.973524ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:21:26.402948Z","caller":"traceutil/trace.go:171","msg":"trace[68269984] transaction","detail":"{read_only:false; response_revision:8740; number_of_response:1; }","duration":"259.235778ms","start":"2026-04-22T06:21:26.143619Z","end":"2026-04-22T06:21:26.402855Z","steps":["trace[68269984] 'process raft request' (duration: 258.690105ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:22:33.440036Z","caller":"traceutil/trace.go:171","msg":"trace[646947161] transaction","detail":"{read_only:false; response_revision:9460; number_of_response:1; }","duration":"120.292363ms","start":"2026-04-22T06:22:33.319708Z","end":"2026-04-22T06:22:33.44Z","steps":["trace[646947161] 'process raft request' (duration: 120.081889ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:22:33.707169Z","caller":"traceutil/trace.go:171","msg":"trace[2092150348] linearizableReadLoop","detail":"{readStateIndex:9882; appliedIndex:9881; }","duration":"262.595535ms","start":"2026-04-22T06:22:33.44453Z","end":"2026-04-22T06:22:33.707126Z","steps":["trace[2092150348] 'read index received' (duration: 181.567041ms)","trace[2092150348] 'applied index is now lower than readState.Index' (duration: 81.024304ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:22:33.707314Z","caller":"traceutil/trace.go:171","msg":"trace[1184201051] transaction","detail":"{read_only:false; response_revision:9461; number_of_response:1; }","duration":"383.976933ms","start":"2026-04-22T06:22:33.323305Z","end":"2026-04-22T06:22:33.707282Z","steps":["trace[1184201051] 'process raft request' (duration: 302.890527ms)","trace[1184201051] 'compare' (duration: 80.614065ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:22:33.707536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.979823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-04-22T06:22:33.707595Z","caller":"traceutil/trace.go:171","msg":"trace[1483470980] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9461; }","duration":"263.106546ms","start":"2026-04-22T06:22:33.444468Z","end":"2026-04-22T06:22:33.707575Z","steps":["trace[1483470980] 'agreement among raft nodes before linearized reading' (duration: 262.81054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:22:33.707546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:22:33.323285Z","time spent":"384.160176ms","remote":"127.0.0.1:59678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:22:33.961224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.859042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:22:33.961342Z","caller":"traceutil/trace.go:171","msg":"trace[1475434344] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:9463; }","duration":"142.053407ms","start":"2026-04-22T06:22:33.819256Z","end":"2026-04-22T06:22:33.96131Z","steps":["trace[1475434344] 'count revisions from in-memory index tree' (duration: 141.628867ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:22:38.408236Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6629} {"level":"info","ts":"2026-04-22T06:22:38.544798Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6629,"took":"134.116007ms","hash":2458998715,"current-db-size-bytes":60915712,"current-db-size":"61 MB","current-db-size-in-use-bytes":26746880,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-22T06:22:38.544864Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2458998715,"revision":6629,"compact-revision":3286} {"level":"info","ts":"2026-04-22T06:22:45.883527Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f1e5729930cf7e1f","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-22T06:22:45.889748Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-22T06:22:45.889855Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-22T06:24:53.516559Z","caller":"traceutil/trace.go:171","msg":"trace[517012562] transaction","detail":"{read_only:false; response_revision:10762; number_of_response:1; }","duration":"344.996004ms","start":"2026-04-22T06:24:53.171532Z","end":"2026-04-22T06:24:53.516528Z","steps":["trace[517012562] 'process raft request' (duration: 344.711208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:24:53.516786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:24:53.17151Z","time spent":"345.152088ms","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16842,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:24:53.517324Z","caller":"traceutil/trace.go:171","msg":"trace[520135050] linearizableReadLoop","detail":"{readStateIndex:11248; appliedIndex:11246; }","duration":"214.142665ms","start":"2026-04-22T06:24:53.303146Z","end":"2026-04-22T06:24:53.517289Z","steps":["trace[520135050] 'read index received' (duration: 213.147402ms)","trace[520135050] 'applied index is now lower than readState.Index' (duration: 993.683µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:24:53.517421Z","caller":"traceutil/trace.go:171","msg":"trace[467382019] transaction","detail":"{read_only:false; response_revision:10763; number_of_response:1; }","duration":"261.863304ms","start":"2026-04-22T06:24:53.255515Z","end":"2026-04-22T06:24:53.517378Z","steps":["trace[467382019] 'process raft request' (duration: 261.624859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:24:53.517772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.839746ms","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-04-22T06:24:53.517869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.729898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-22T06:24:53.517886Z","caller":"traceutil/trace.go:171","msg":"trace[2139526327] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:10763; }","duration":"182.99864ms","start":"2026-04-22T06:24:53.334864Z","end":"2026-04-22T06:24:53.517863Z","steps":["trace[2139526327] 'agreement among raft nodes before linearized reading' (duration: 182.647112ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:24:53.517918Z","caller":"traceutil/trace.go:171","msg":"trace[1075652176] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10763; }","duration":"176.843831ms","start":"2026-04-22T06:24:53.34106Z","end":"2026-04-22T06:24:53.517903Z","steps":["trace[1075652176] 'agreement among raft nodes before linearized reading' (duration: 176.582115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:24:53.517922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.981561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-22T06:24:53.51804Z","caller":"traceutil/trace.go:171","msg":"trace[1846364159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10763; }","duration":"180.150945ms","start":"2026-04-22T06:24:53.337858Z","end":"2026-04-22T06:24:53.518009Z","steps":["trace[1846364159] 'agreement among raft nodes before linearized reading' (duration: 179.858239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:24:53.518795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.646118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T06:24:53.518888Z","caller":"traceutil/trace.go:171","msg":"trace[1395440317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10763; }","duration":"215.788401ms","start":"2026-04-22T06:24:53.303085Z","end":"2026-04-22T06:24:53.518873Z","steps":["trace[1395440317] 'agreement among raft nodes before linearized reading' (duration: 214.342279ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:24:56.660492Z","caller":"traceutil/trace.go:171","msg":"trace[566869289] transaction","detail":"{read_only:false; response_revision:10794; number_of_response:1; }","duration":"107.625556ms","start":"2026-04-22T06:24:56.552829Z","end":"2026-04-22T06:24:56.660454Z","steps":["trace[566869289] 'process raft request' (duration: 107.395231ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:27:38.416289Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9508} {"level":"info","ts":"2026-04-22T06:27:38.507092Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9508,"took":"87.989621ms","hash":1211260957,"current-db-size-bytes":60915712,"current-db-size":"61 MB","current-db-size-in-use-bytes":25546752,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-22T06:27:38.507164Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1211260957,"revision":9508,"compact-revision":6629}