{"level":"warn","ts":"2026-10-26T21:28:07.594396Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-26T21:28:07.594547Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.161: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://162.253.55.161:2380","--initial-cluster=instance=https://162.253.55.161:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.161:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.161: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-10-26T21:28:07.594652Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-26T21:28:07.594673Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.161:2380"]} {"level":"info","ts":"2026-10-26T21:28:07.594712Z","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-10-26T21:28:07.595888Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.161:2379"]} {"level":"info","ts":"2026-10-26T21:28:07.596031Z","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://162.253.55.161:2380"],"listen-peer-urls":["https://162.253.55.161:2380"],"advertise-client-urls":["https://162.253.55.161:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.161:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.161: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-10-26T21:28:07.600968Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.570835ms"} {"level":"info","ts":"2026-10-26T21:28:07.608206Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"383aa7db69884d67","cluster-id":"2ae15ce520a2ef1e"} {"level":"info","ts":"2026-10-26T21:28:07.608333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 switched to configuration voters=()"} {"level":"info","ts":"2026-10-26T21:28:07.608378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 became follower at term 0"} {"level":"info","ts":"2026-10-26T21:28:07.608398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 383aa7db69884d67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-26T21:28:07.60842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 became follower at term 1"} {"level":"info","ts":"2026-10-26T21:28:07.608487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 switched to configuration voters=(4051735375583399271)"} {"level":"warn","ts":"2026-10-26T21:28:07.615389Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-26T21:28:07.617513Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-26T21:28:07.619537Z","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-10-26T21:28:07.623007Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"383aa7db69884d67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-26T21:28:07.623199Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"383aa7db69884d67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-26T21:28:07.626396Z","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-10-26T21:28:07.626612Z","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-10-26T21:28:07.626637Z","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-10-26T21:28:07.629596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 switched to configuration voters=(4051735375583399271)"} {"level":"info","ts":"2026-10-26T21:28:07.629976Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2ae15ce520a2ef1e","local-member-id":"383aa7db69884d67","added-peer-id":"383aa7db69884d67","added-peer-peer-urls":["https://162.253.55.161:2380"]} {"level":"info","ts":"2026-10-26T21:28:07.633424Z","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-10-26T21:28:07.633567Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.161:2380"} {"level":"info","ts":"2026-10-26T21:28:07.633632Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.161:2380"} {"level":"info","ts":"2026-10-26T21:28:07.634093Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"383aa7db69884d67","initial-advertise-peer-urls":["https://162.253.55.161:2380"],"listen-peer-urls":["https://162.253.55.161:2380"],"advertise-client-urls":["https://162.253.55.161:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.161:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-26T21:28:07.634188Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-26T21:28:08.009032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 is starting a new election at term 1"} {"level":"info","ts":"2026-10-26T21:28:08.009198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-26T21:28:08.009259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 received MsgPreVoteResp from 383aa7db69884d67 at term 1"} {"level":"info","ts":"2026-10-26T21:28:08.009311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 became candidate at term 2"} {"level":"info","ts":"2026-10-26T21:28:08.009346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 received MsgVoteResp from 383aa7db69884d67 at term 2"} {"level":"info","ts":"2026-10-26T21:28:08.009383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"383aa7db69884d67 became leader at term 2"} {"level":"info","ts":"2026-10-26T21:28:08.009423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 383aa7db69884d67 elected leader 383aa7db69884d67 at term 2"} {"level":"info","ts":"2026-10-26T21:28:08.010678Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T21:28:08.012466Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2ae15ce520a2ef1e","local-member-id":"383aa7db69884d67","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T21:28:08.012527Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T21:28:08.0126Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T21:28:08.012756Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T21:28:08.012583Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T21:28:08.012591Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"383aa7db69884d67","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.161:2379]}","request-path":"/0/members/383aa7db69884d67/attributes","cluster-id":"2ae15ce520a2ef1e","publish-timeout":"7s"} {"level":"info","ts":"2026-10-26T21:28:08.013317Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-26T21:28:08.013349Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-26T21:28:08.018376Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-26T21:28:08.019098Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.161:2379"} {"level":"info","ts":"2026-10-26T21:28:55.453947Z","caller":"traceutil/trace.go:171","msg":"trace[720600871] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"109.930685ms","start":"2026-10-26T21:28:55.343995Z","end":"2026-10-26T21:28:55.453925Z","steps":["trace[720600871] 'process raft request' (duration: 109.819022ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:28:55.49316Z","caller":"traceutil/trace.go:171","msg":"trace[2119833383] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"129.389735ms","start":"2026-10-26T21:28:55.363736Z","end":"2026-10-26T21:28:55.493126Z","steps":["trace[2119833383] 'process raft request' (duration: 129.262682ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:28:55.493186Z","caller":"traceutil/trace.go:171","msg":"trace[654540598] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"122.880181ms","start":"2026-10-26T21:28:55.370265Z","end":"2026-10-26T21:28:55.493145Z","steps":["trace[654540598] 'process raft request' (duration: 122.774889ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:28:55.493293Z","caller":"traceutil/trace.go:171","msg":"trace[20332746] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"115.475104ms","start":"2026-10-26T21:28:55.377761Z","end":"2026-10-26T21:28:55.493236Z","steps":["trace[20332746] 'process raft request' (duration: 115.31759ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:28:55.493321Z","caller":"traceutil/trace.go:171","msg":"trace[472645245] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"108.54354ms","start":"2026-10-26T21:28:55.384731Z","end":"2026-10-26T21:28:55.493275Z","steps":["trace[472645245] 'process raft request' (duration: 108.396136ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:28:55.493573Z","caller":"traceutil/trace.go:171","msg":"trace[279260961] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"143.362358ms","start":"2026-10-26T21:28:55.349725Z","end":"2026-10-26T21:28:55.493087Z","steps":["trace[279260961] 'process raft request' (duration: 143.115582ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:28:55.808917Z","caller":"traceutil/trace.go:171","msg":"trace[1740989537] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"107.273217ms","start":"2026-10-26T21:28:55.701572Z","end":"2026-10-26T21:28:55.808846Z","steps":["trace[1740989537] 'process raft request' (duration: 35.733861ms)","trace[1740989537] 'compare' (duration: 71.270189ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:28:56.131686Z","caller":"traceutil/trace.go:171","msg":"trace[1326768398] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"101.243105ms","start":"2026-10-26T21:28:56.03041Z","end":"2026-10-26T21:28:56.131654Z","steps":["trace[1326768398] 'process raft request' (duration: 34.409968ms)","trace[1326768398] 'compare' (duration: 66.607821ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:28:56.212074Z","caller":"traceutil/trace.go:171","msg":"trace[1756062609] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"143.103812ms","start":"2026-10-26T21:28:56.068944Z","end":"2026-10-26T21:28:56.212048Z","steps":["trace[1756062609] 'process raft request' (duration: 142.817345ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:28:56.271137Z","caller":"traceutil/trace.go:171","msg":"trace[1412611867] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"116.12774ms","start":"2026-10-26T21:28:56.154978Z","end":"2026-10-26T21:28:56.271106Z","steps":["trace[1412611867] 'process raft request' (duration: 106.399425ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:28:56.271413Z","caller":"traceutil/trace.go:171","msg":"trace[1578815994] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:623; }","duration":"104.142348ms","start":"2026-10-26T21:28:56.167241Z","end":"2026-10-26T21:28:56.271383Z","steps":["trace[1578815994] 'read index received' (duration: 44.528634ms)","trace[1578815994] 'applied index is now lower than readState.Index' (duration: 59.612084ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:28:56.27183Z","caller":"traceutil/trace.go:171","msg":"trace[622990406] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"116.326355ms","start":"2026-10-26T21:28:56.155103Z","end":"2026-10-26T21:28:56.271429Z","steps":["trace[622990406] 'process raft request' (duration: 115.951276ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:28:56.271898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.956573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-26T21:28:56.272027Z","caller":"traceutil/trace.go:171","msg":"trace[761089230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:601; }","duration":"104.137868ms","start":"2026-10-26T21:28:56.167868Z","end":"2026-10-26T21:28:56.272006Z","steps":["trace[761089230] 'agreement among raft nodes before linearized reading' (duration: 103.925723ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:28:56.271915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.660471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-26T21:28:56.27222Z","caller":"traceutil/trace.go:171","msg":"trace[25485783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:601; }","duration":"104.900677ms","start":"2026-10-26T21:28:56.16719Z","end":"2026-10-26T21:28:56.272091Z","steps":["trace[25485783] 'agreement among raft nodes before linearized reading' (duration: 104.582669ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:29:01.85643Z","caller":"traceutil/trace.go:171","msg":"trace[777765049] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"215.345425ms","start":"2026-10-26T21:29:01.641058Z","end":"2026-10-26T21:29:01.856403Z","steps":["trace[777765049] 'process raft request' (duration: 215.186291ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:29:02.484645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.888631ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-26T21:29:02.484778Z","caller":"traceutil/trace.go:171","msg":"trace[529112653] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:683; }","duration":"250.612015ms","start":"2026-10-26T21:29:02.234149Z","end":"2026-10-26T21:29:02.484761Z","steps":["trace[529112653] 'read index received' (duration: 24.020377ms)","trace[529112653] 'applied index is now lower than readState.Index' (duration: 226.590428ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:29:02.484885Z","caller":"traceutil/trace.go:171","msg":"trace[228805759] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"800.909432ms","start":"2026-10-26T21:29:01.68394Z","end":"2026-10-26T21:29:02.484849Z","steps":["trace[228805759] 'process raft request' (duration: 574.306443ms)","trace[228805759] 'compare' (duration: 225.703946ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:29:02.484945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:29:01.683921Z","time spent":"800.997124ms","remote":"127.0.0.1:42938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T21:29:02.485085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.990882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-26T21:29:02.485175Z","caller":"traceutil/trace.go:171","msg":"trace[404737053] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:655; }","duration":"180.122466ms","start":"2026-10-26T21:29:02.30503Z","end":"2026-10-26T21:29:02.485153Z","steps":["trace[404737053] 'agreement among raft nodes before linearized reading' (duration: 179.864539ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:29:02.485394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.24813ms","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-10-26T21:29:02.485437Z","caller":"traceutil/trace.go:171","msg":"trace[1274182295] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:655; }","duration":"251.333543ms","start":"2026-10-26T21:29:02.234092Z","end":"2026-10-26T21:29:02.485425Z","steps":["trace[1274182295] 'agreement among raft nodes before linearized reading' (duration: 251.183739ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:29:02.485497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.337706ms","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-10-26T21:29:02.485574Z","caller":"traceutil/trace.go:171","msg":"trace[2113195879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:655; }","duration":"179.445399ms","start":"2026-10-26T21:29:02.306109Z","end":"2026-10-26T21:29:02.485555Z","steps":["trace[2113195879] 'agreement among raft nodes before linearized reading' (duration: 179.190152ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:29:02.485651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.046838ms","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-10-26T21:29:02.485864Z","caller":"traceutil/trace.go:171","msg":"trace[1465988139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:655; }","duration":"179.282975ms","start":"2026-10-26T21:29:02.306419Z","end":"2026-10-26T21:29:02.485702Z","steps":["trace[1465988139] 'agreement among raft nodes before linearized reading' (duration: 178.843583ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:29:03.248456Z","caller":"traceutil/trace.go:171","msg":"trace[890721658] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"305.065029ms","start":"2026-10-26T21:29:02.943364Z","end":"2026-10-26T21:29:03.248429Z","steps":["trace[890721658] 'process raft request' (duration: 303.467629ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:29:03.248804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:29:02.94333Z","time spent":"305.199582ms","remote":"127.0.0.1:42682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T21:29:46.933401Z","caller":"traceutil/trace.go:171","msg":"trace[913952203] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"146.255061ms","start":"2026-10-26T21:29:46.787102Z","end":"2026-10-26T21:29:46.933357Z","steps":["trace[913952203] 'process raft request' (duration: 133.183561ms)","trace[913952203] 'compare' (duration: 12.904266ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:30:39.419769Z","caller":"traceutil/trace.go:171","msg":"trace[834099923] linearizableReadLoop","detail":"{readStateIndex:1993; appliedIndex:1992; }","duration":"332.153961ms","start":"2026-10-26T21:30:39.087587Z","end":"2026-10-26T21:30:39.419741Z","steps":["trace[834099923] 'read index received' (duration: 302.088232ms)","trace[834099923] 'applied index is now lower than readState.Index' (duration: 30.064419ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:30:39.419794Z","caller":"traceutil/trace.go:171","msg":"trace[1456977869] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"367.38177ms","start":"2026-10-26T21:30:39.052378Z","end":"2026-10-26T21:30:39.41976Z","steps":["trace[1456977869] 'process raft request' (duration: 337.371862ms)","trace[1456977869] 'compare' (duration: 29.507325ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:30:39.42003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.442758ms","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-10-26T21:30:39.420069Z","caller":"traceutil/trace.go:171","msg":"trace[2132428478] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1915; }","duration":"332.53489ms","start":"2026-10-26T21:30:39.087524Z","end":"2026-10-26T21:30:39.420059Z","steps":["trace[2132428478] 'agreement among raft nodes before linearized reading' (duration: 332.374156ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:30:39.420078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.125114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-26T21:30:39.420153Z","caller":"traceutil/trace.go:171","msg":"trace[465819486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1915; }","duration":"329.222187ms","start":"2026-10-26T21:30:39.090911Z","end":"2026-10-26T21:30:39.420133Z","steps":["trace[465819486] 'agreement among raft nodes before linearized reading' (duration: 329.025342ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:30:39.420199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:30:39.090902Z","time spent":"329.283168ms","remote":"127.0.0.1:42852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-26T21:30:39.420102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:30:39.087502Z","time spent":"332.589712ms","remote":"127.0.0.1:42852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-26T21:30:39.420357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.578971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-10-26T21:30:39.420355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.0773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-dbd23336-50ed-4a4d-b5f6-344990a8daa5\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-26T21:30:39.420389Z","caller":"traceutil/trace.go:171","msg":"trace[2038909793] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1915; }","duration":"202.642983ms","start":"2026-10-26T21:30:39.217737Z","end":"2026-10-26T21:30:39.42038Z","steps":["trace[2038909793] 'agreement among raft nodes before linearized reading' (duration: 202.549511ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:30:39.420447Z","caller":"traceutil/trace.go:171","msg":"trace[227121406] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-dbd23336-50ed-4a4d-b5f6-344990a8daa5; range_end:; response_count:1; response_revision:1915; }","duration":"252.209343ms","start":"2026-10-26T21:30:39.168217Z","end":"2026-10-26T21:30:39.420427Z","steps":["trace[227121406] 'agreement among raft nodes before linearized reading' (duration: 252.017389ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:30:39.420466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.624536ms","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":"warn","ts":"2026-10-26T21:30:39.420504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:30:39.052369Z","time spent":"367.515103ms","remote":"127.0.0.1:42852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T21:30:39.420589Z","caller":"traceutil/trace.go:171","msg":"trace[1050570553] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1915; }","duration":"306.78024ms","start":"2026-10-26T21:30:39.113757Z","end":"2026-10-26T21:30:39.420538Z","steps":["trace[1050570553] 'agreement among raft nodes before linearized reading' (duration: 306.483093ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:30:39.420648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:30:39.113734Z","time spent":"306.896214ms","remote":"127.0.0.1:50960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-10-26T21:30:39.598677Z","caller":"traceutil/trace.go:171","msg":"trace[1307619941] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"173.884277ms","start":"2026-10-26T21:30:39.424768Z","end":"2026-10-26T21:30:39.598652Z","steps":["trace[1307619941] 'process raft request' (duration: 173.709102ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:30:39.598894Z","caller":"traceutil/trace.go:171","msg":"trace[627165625] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"173.130879ms","start":"2026-10-26T21:30:39.425747Z","end":"2026-10-26T21:30:39.598878Z","steps":["trace[627165625] 'process raft request' (duration: 172.865782ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:30:39.599021Z","caller":"traceutil/trace.go:171","msg":"trace[1091024464] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"170.371268ms","start":"2026-10-26T21:30:39.428615Z","end":"2026-10-26T21:30:39.598986Z","steps":["trace[1091024464] 'process raft request' (duration: 170.206374ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:30:39.85071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.290691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-26T21:30:39.850797Z","caller":"traceutil/trace.go:171","msg":"trace[358805050] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1919; }","duration":"146.405214ms","start":"2026-10-26T21:30:39.70437Z","end":"2026-10-26T21:30:39.850775Z","steps":["trace[358805050] 'range keys from in-memory index tree' (duration: 146.018814ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:30:46.023261Z","caller":"traceutil/trace.go:171","msg":"trace[122881915] linearizableReadLoop","detail":"{readStateIndex:2055; appliedIndex:2053; }","duration":"128.931743ms","start":"2026-10-26T21:30:45.894298Z","end":"2026-10-26T21:30:46.02323Z","steps":["trace[122881915] 'read index received' (duration: 78.886901ms)","trace[122881915] 'applied index is now lower than readState.Index' (duration: 50.043492ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:30:46.023411Z","caller":"traceutil/trace.go:171","msg":"trace[1217727641] transaction","detail":"{read_only:false; response_revision:1976; number_of_response:1; }","duration":"131.737574ms","start":"2026-10-26T21:30:45.891623Z","end":"2026-10-26T21:30:46.02336Z","steps":["trace[1217727641] 'process raft request' (duration: 131.453676ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:30:46.023534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.20807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"info","ts":"2026-10-26T21:30:46.023578Z","caller":"traceutil/trace.go:171","msg":"trace[1888206628] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1976; }","duration":"129.290442ms","start":"2026-10-26T21:30:45.894275Z","end":"2026-10-26T21:30:46.023565Z","steps":["trace[1888206628] 'agreement among raft nodes before linearized reading' (duration: 129.079896ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:30:53.031464Z","caller":"traceutil/trace.go:171","msg":"trace[1087898938] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"194.264224ms","start":"2026-10-26T21:30:52.837173Z","end":"2026-10-26T21:30:53.031437Z","steps":["trace[1087898938] 'process raft request' (duration: 194.038138ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:30:53.134924Z","caller":"traceutil/trace.go:171","msg":"trace[522654086] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"112.146486ms","start":"2026-10-26T21:30:53.022747Z","end":"2026-10-26T21:30:53.134893Z","steps":["trace[522654086] 'process raft request' (duration: 52.300428ms)","trace[522654086] 'compare' (duration: 59.724515ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:30:53.15525Z","caller":"traceutil/trace.go:171","msg":"trace[2127993308] transaction","detail":"{read_only:false; response_revision:2031; number_of_response:1; }","duration":"116.914096ms","start":"2026-10-26T21:30:53.038301Z","end":"2026-10-26T21:30:53.155215Z","steps":["trace[2127993308] 'process raft request' (duration: 116.658339ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:30:53.155711Z","caller":"traceutil/trace.go:171","msg":"trace[1287553298] transaction","detail":"{read_only:false; response_revision:2032; number_of_response:1; }","duration":"117.389448ms","start":"2026-10-26T21:30:53.038302Z","end":"2026-10-26T21:30:53.155691Z","steps":["trace[1287553298] 'process raft request' (duration: 116.839134ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:30:53.156032Z","caller":"traceutil/trace.go:171","msg":"trace[157159850] transaction","detail":"{read_only:false; response_revision:2033; number_of_response:1; }","duration":"117.610893ms","start":"2026-10-26T21:30:53.03839Z","end":"2026-10-26T21:30:53.156001Z","steps":["trace[157159850] 'process raft request' (duration: 117.206492ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:30:53.156223Z","caller":"traceutil/trace.go:171","msg":"trace[938453612] transaction","detail":"{read_only:false; response_revision:2034; number_of_response:1; }","duration":"114.836684ms","start":"2026-10-26T21:30:53.041329Z","end":"2026-10-26T21:30:53.156165Z","steps":["trace[938453612] 'process raft request' (duration: 114.581437ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:30:56.301502Z","caller":"traceutil/trace.go:171","msg":"trace[1956174488] linearizableReadLoop","detail":"{readStateIndex:2137; appliedIndex:2136; }","duration":"120.732102ms","start":"2026-10-26T21:30:56.180744Z","end":"2026-10-26T21:30:56.301476Z","steps":["trace[1956174488] 'read index received' (duration: 120.488426ms)","trace[1956174488] 'applied index is now lower than readState.Index' (duration: 240.076µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:30:56.301602Z","caller":"traceutil/trace.go:171","msg":"trace[1957880356] transaction","detail":"{read_only:false; response_revision:2056; number_of_response:1; }","duration":"224.969308ms","start":"2026-10-26T21:30:56.076593Z","end":"2026-10-26T21:30:56.301562Z","steps":["trace[1957880356] 'process raft request' (duration: 224.693281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:30:56.301725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.975048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"info","ts":"2026-10-26T21:30:56.301776Z","caller":"traceutil/trace.go:171","msg":"trace[1139779517] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2056; }","duration":"121.082851ms","start":"2026-10-26T21:30:56.180677Z","end":"2026-10-26T21:30:56.30176Z","steps":["trace[1139779517] 'agreement among raft nodes before linearized reading' (duration: 120.910696ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:31:29.802196Z","caller":"traceutil/trace.go:171","msg":"trace[226210254] transaction","detail":"{read_only:false; response_revision:2237; number_of_response:1; }","duration":"117.925831ms","start":"2026-10-26T21:31:29.68424Z","end":"2026-10-26T21:31:29.802166Z","steps":["trace[226210254] 'process raft request' (duration: 117.766017ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:31:29.849441Z","caller":"traceutil/trace.go:171","msg":"trace[1406595540] transaction","detail":"{read_only:false; response_revision:2238; number_of_response:1; }","duration":"164.369001ms","start":"2026-10-26T21:31:29.685047Z","end":"2026-10-26T21:31:29.849416Z","steps":["trace[1406595540] 'process raft request' (duration: 155.017655ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:31:35.520134Z","caller":"traceutil/trace.go:171","msg":"trace[332613685] transaction","detail":"{read_only:false; response_revision:2267; number_of_response:1; }","duration":"144.141742ms","start":"2026-10-26T21:31:35.375962Z","end":"2026-10-26T21:31:35.520103Z","steps":["trace[332613685] 'process raft request' (duration: 143.939757ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:31:35.783544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.345554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T21:31:35.783645Z","caller":"traceutil/trace.go:171","msg":"trace[1463825219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2267; }","duration":"157.492608ms","start":"2026-10-26T21:31:35.62613Z","end":"2026-10-26T21:31:35.783623Z","steps":["trace[1463825219] 'range keys from in-memory index tree' (duration: 157.206731ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:31:35.783836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.653436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"info","ts":"2026-10-26T21:31:35.783982Z","caller":"traceutil/trace.go:171","msg":"trace[1276482844] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2267; }","duration":"204.853741ms","start":"2026-10-26T21:31:35.579094Z","end":"2026-10-26T21:31:35.783947Z","steps":["trace[1276482844] 'range keys from in-memory index tree' (duration: 204.415451ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:31:35.983495Z","caller":"traceutil/trace.go:171","msg":"trace[2022848400] linearizableReadLoop","detail":"{readStateIndex:2358; appliedIndex:2357; }","duration":"112.924295ms","start":"2026-10-26T21:31:35.870544Z","end":"2026-10-26T21:31:35.983468Z","steps":["trace[2022848400] 'read index received' (duration: 112.706549ms)","trace[2022848400] 'applied index is now lower than readState.Index' (duration: 216.436µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:31:35.983547Z","caller":"traceutil/trace.go:171","msg":"trace[1074261435] transaction","detail":"{read_only:false; response_revision:2268; number_of_response:1; }","duration":"153.683562ms","start":"2026-10-26T21:31:35.829831Z","end":"2026-10-26T21:31:35.983514Z","steps":["trace[1074261435] 'process raft request' (duration: 153.440376ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:31:35.983785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.636871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-26T21:31:35.983828Z","caller":"traceutil/trace.go:171","msg":"trace[1608188818] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2268; }","duration":"107.723324ms","start":"2026-10-26T21:31:35.876094Z","end":"2026-10-26T21:31:35.983817Z","steps":["trace[1608188818] 'agreement among raft nodes before linearized reading' (duration: 107.555209ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:31:35.983821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.223552ms","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-10-26T21:31:35.983901Z","caller":"traceutil/trace.go:171","msg":"trace[1497465066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2268; }","duration":"113.369716ms","start":"2026-10-26T21:31:35.87051Z","end":"2026-10-26T21:31:35.98388Z","steps":["trace[1497465066] 'agreement among raft nodes before linearized reading' (duration: 113.1ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:31:35.984221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.774705ms","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-10-26T21:31:35.984337Z","caller":"traceutil/trace.go:171","msg":"trace[1876104463] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2268; }","duration":"104.204905ms","start":"2026-10-26T21:31:35.880108Z","end":"2026-10-26T21:31:35.984313Z","steps":["trace[1876104463] 'agreement among raft nodes before linearized reading' (duration: 103.61288ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:32:51.178767Z","caller":"traceutil/trace.go:171","msg":"trace[846793107] transaction","detail":"{read_only:false; response_revision:2873; number_of_response:1; }","duration":"197.738686ms","start":"2026-10-26T21:32:50.98099Z","end":"2026-10-26T21:32:51.178729Z","steps":["trace[846793107] 'process raft request' (duration: 197.521251ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:32:51.486548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.248081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-10-26T21:32:51.486626Z","caller":"traceutil/trace.go:171","msg":"trace[45358114] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2873; }","duration":"178.393584ms","start":"2026-10-26T21:32:51.308214Z","end":"2026-10-26T21:32:51.486608Z","steps":["trace[45358114] 'range keys from in-memory index tree' (duration: 178.041375ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:37:28.462693Z","caller":"traceutil/trace.go:171","msg":"trace[844282443] linearizableReadLoop","detail":"{readStateIndex:5224; appliedIndex:5223; }","duration":"175.757522ms","start":"2026-10-26T21:37:28.286905Z","end":"2026-10-26T21:37:28.462663Z","steps":["trace[844282443] 'read index received' (duration: 172.571774ms)","trace[844282443] 'applied index is now lower than readState.Index' (duration: 3.184508ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:37:28.462909Z","caller":"traceutil/trace.go:171","msg":"trace[319776279] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"178.964441ms","start":"2026-10-26T21:37:28.283932Z","end":"2026-10-26T21:37:28.462896Z","steps":["trace[319776279] 'process raft request' (duration: 175.473205ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:37:28.463048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.237344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0.18e232108a9b1b88\" ","response":"range_response_count:1 size:1179"} {"level":"info","ts":"2026-10-26T21:37:28.463137Z","caller":"traceutil/trace.go:171","msg":"trace[1236553852] range","detail":"{range_begin:/registry/events/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0.18e232108a9b1b88; range_end:; response_count:1; response_revision:5026; }","duration":"176.357097ms","start":"2026-10-26T21:37:28.286758Z","end":"2026-10-26T21:37:28.463115Z","steps":["trace[1236553852] 'agreement among raft nodes before linearized reading' (duration: 176.167343ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:37:28.47744Z","caller":"traceutil/trace.go:171","msg":"trace[907450087] transaction","detail":"{read_only:false; response_revision:5028; number_of_response:1; }","duration":"185.204165ms","start":"2026-10-26T21:37:28.292214Z","end":"2026-10-26T21:37:28.477418Z","steps":["trace[907450087] 'process raft request' (duration: 184.99353ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:37:28.477675Z","caller":"traceutil/trace.go:171","msg":"trace[1994535000] transaction","detail":"{read_only:false; response_revision:5029; number_of_response:1; }","duration":"185.42637ms","start":"2026-10-26T21:37:28.292234Z","end":"2026-10-26T21:37:28.477661Z","steps":["trace[1994535000] 'process raft request' (duration: 185.0336ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:37:28.477745Z","caller":"traceutil/trace.go:171","msg":"trace[1474269542] transaction","detail":"{read_only:false; response_revision:5030; number_of_response:1; }","duration":"185.216105ms","start":"2026-10-26T21:37:28.292493Z","end":"2026-10-26T21:37:28.477709Z","steps":["trace[1474269542] 'process raft request' (duration: 184.843176ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:37:28.477875Z","caller":"traceutil/trace.go:171","msg":"trace[1289345480] transaction","detail":"{read_only:false; response_revision:5031; number_of_response:1; }","duration":"184.409495ms","start":"2026-10-26T21:37:28.293443Z","end":"2026-10-26T21:37:28.477853Z","steps":["trace[1289345480] 'process raft request' (duration: 183.963214ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:37:28.478003Z","caller":"traceutil/trace.go:171","msg":"trace[2128499287] transaction","detail":"{read_only:false; response_revision:5033; number_of_response:1; }","duration":"174.294187ms","start":"2026-10-26T21:37:28.303697Z","end":"2026-10-26T21:37:28.477992Z","steps":["trace[2128499287] 'process raft request' (duration: 174.01081ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:37:28.478014Z","caller":"traceutil/trace.go:171","msg":"trace[565558903] transaction","detail":"{read_only:false; response_revision:5027; number_of_response:1; }","duration":"185.991603ms","start":"2026-10-26T21:37:28.291974Z","end":"2026-10-26T21:37:28.477965Z","steps":["trace[565558903] 'process raft request' (duration: 185.077371ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:37:28.478145Z","caller":"traceutil/trace.go:171","msg":"trace[332895364] transaction","detail":"{read_only:false; response_revision:5032; number_of_response:1; }","duration":"182.20216ms","start":"2026-10-26T21:37:28.295924Z","end":"2026-10-26T21:37:28.478126Z","steps":["trace[332895364] 'process raft request' (duration: 181.592245ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:37:28.478261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.856555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-10-26T21:37:28.478339Z","caller":"traceutil/trace.go:171","msg":"trace[1660715886] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:5033; }","duration":"186.962038ms","start":"2026-10-26T21:37:28.291354Z","end":"2026-10-26T21:37:28.478316Z","steps":["trace[1660715886] 'agreement among raft nodes before linearized reading' (duration: 186.654631ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:37:34.748401Z","caller":"traceutil/trace.go:171","msg":"trace[1725231694] linearizableReadLoop","detail":"{readStateIndex:5358; appliedIndex:5357; }","duration":"109.962288ms","start":"2026-10-26T21:37:34.638417Z","end":"2026-10-26T21:37:34.748379Z","steps":["trace[1725231694] 'read index received' (duration: 109.861726ms)","trace[1725231694] 'applied index is now lower than readState.Index' (duration: 99.302µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:37:34.748555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.118042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T21:37:34.74859Z","caller":"traceutil/trace.go:171","msg":"trace[177320657] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:5156; }","duration":"110.187474ms","start":"2026-10-26T21:37:34.638394Z","end":"2026-10-26T21:37:34.748582Z","steps":["trace[177320657] 'agreement among raft nodes before linearized reading' (duration: 110.074891ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:37:34.748544Z","caller":"traceutil/trace.go:171","msg":"trace[288082392] transaction","detail":"{read_only:false; response_revision:5156; number_of_response:1; }","duration":"112.649325ms","start":"2026-10-26T21:37:34.635828Z","end":"2026-10-26T21:37:34.748478Z","steps":["trace[288082392] 'process raft request' (duration: 112.44483ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:37:34.886094Z","caller":"traceutil/trace.go:171","msg":"trace[892166488] transaction","detail":"{read_only:false; response_revision:5163; number_of_response:1; }","duration":"116.222181ms","start":"2026-10-26T21:37:34.769839Z","end":"2026-10-26T21:37:34.886061Z","steps":["trace[892166488] 'process raft request' (duration: 88.741807ms)","trace[892166488] 'compare' (duration: 26.88718ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:37:39.698187Z","caller":"traceutil/trace.go:171","msg":"trace[659089617] linearizableReadLoop","detail":"{readStateIndex:5532; appliedIndex:5531; }","duration":"228.346633ms","start":"2026-10-26T21:37:39.469816Z","end":"2026-10-26T21:37:39.698163Z","steps":["trace[659089617] 'read index received' (duration: 228.20699ms)","trace[659089617] 'applied index is now lower than readState.Index' (duration: 138.663µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:37:39.698269Z","caller":"traceutil/trace.go:171","msg":"trace[427235509] transaction","detail":"{read_only:false; response_revision:5324; number_of_response:1; }","duration":"257.99216ms","start":"2026-10-26T21:37:39.440254Z","end":"2026-10-26T21:37:39.698246Z","steps":["trace[427235509] 'process raft request' (duration: 257.755995ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:37:39.698394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.565668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5454eae9-6df4-4a3a-b83e-74e80366eb66\" ","response":"range_response_count:1 size:3721"} {"level":"info","ts":"2026-10-26T21:37:39.698444Z","caller":"traceutil/trace.go:171","msg":"trace[1841567102] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5454eae9-6df4-4a3a-b83e-74e80366eb66; range_end:; response_count:1; response_revision:5324; }","duration":"228.672781ms","start":"2026-10-26T21:37:39.469757Z","end":"2026-10-26T21:37:39.69843Z","steps":["trace[1841567102] 'agreement among raft nodes before linearized reading' (duration: 228.558958ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:37:39.806634Z","caller":"traceutil/trace.go:171","msg":"trace[853761949] linearizableReadLoop","detail":"{readStateIndex:5533; appliedIndex:5532; }","duration":"108.235476ms","start":"2026-10-26T21:37:39.698363Z","end":"2026-10-26T21:37:39.806598Z","steps":["trace[853761949] 'read index received' (duration: 93.987416ms)","trace[853761949] 'applied index is now lower than readState.Index' (duration: 14.2471ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:37:39.807285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.000373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:787168"} {"level":"info","ts":"2026-10-26T21:37:39.807334Z","caller":"traceutil/trace.go:171","msg":"trace[44141735] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5325; }","duration":"210.101746ms","start":"2026-10-26T21:37:39.597216Z","end":"2026-10-26T21:37:39.807318Z","steps":["trace[44141735] 'agreement among raft nodes before linearized reading' (duration: 209.509491ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:37:39.80789Z","caller":"traceutil/trace.go:171","msg":"trace[2079534168] transaction","detail":"{read_only:false; response_revision:5325; number_of_response:1; }","duration":"239.513648ms","start":"2026-10-26T21:37:39.568362Z","end":"2026-10-26T21:37:39.807876Z","steps":["trace[2079534168] 'process raft request' (duration: 223.942935ms)","trace[2079534168] 'compare' (duration: 14.101757ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:37:40.1846Z","caller":"traceutil/trace.go:171","msg":"trace[285158580] transaction","detail":"{read_only:false; response_revision:5331; number_of_response:1; }","duration":"143.371929ms","start":"2026-10-26T21:37:40.041195Z","end":"2026-10-26T21:37:40.184567Z","steps":["trace[285158580] 'process raft request' (duration: 87.299143ms)","trace[285158580] 'compare' (duration: 55.680416ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:37:40.184612Z","caller":"traceutil/trace.go:171","msg":"trace[1441901448] transaction","detail":"{read_only:false; response_revision:5332; number_of_response:1; }","duration":"108.836971ms","start":"2026-10-26T21:37:40.075748Z","end":"2026-10-26T21:37:40.184585Z","steps":["trace[1441901448] 'process raft request' (duration: 108.734279ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:37:54.09659Z","caller":"traceutil/trace.go:171","msg":"trace[195803619] transaction","detail":"{read_only:false; response_revision:5571; number_of_response:1; }","duration":"141.203225ms","start":"2026-10-26T21:37:53.955353Z","end":"2026-10-26T21:37:54.096557Z","steps":["trace[195803619] 'process raft request' (duration: 141.045761ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:37:54.17297Z","caller":"traceutil/trace.go:171","msg":"trace[760576160] transaction","detail":"{read_only:false; response_revision:5572; number_of_response:1; }","duration":"150.296267ms","start":"2026-10-26T21:37:54.022641Z","end":"2026-10-26T21:37:54.172937Z","steps":["trace[760576160] 'process raft request' (duration: 93.29036ms)","trace[760576160] 'compare' (duration: 56.574956ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:38:02.90804Z","caller":"traceutil/trace.go:171","msg":"trace[579523465] linearizableReadLoop","detail":"{readStateIndex:5960; appliedIndex:5959; }","duration":"136.421218ms","start":"2026-10-26T21:38:02.771587Z","end":"2026-10-26T21:38:02.908009Z","steps":["trace[579523465] 'read index received' (duration: 136.182712ms)","trace[579523465] 'applied index is now lower than readState.Index' (duration: 237.196µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:38:02.908151Z","caller":"traceutil/trace.go:171","msg":"trace[580029787] transaction","detail":"{read_only:false; response_revision:5740; number_of_response:1; }","duration":"159.644757ms","start":"2026-10-26T21:38:02.748477Z","end":"2026-10-26T21:38:02.908122Z","steps":["trace[580029787] 'process raft request' (duration: 159.3273ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:38:02.908379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.765187ms","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-10-26T21:38:02.908493Z","caller":"traceutil/trace.go:171","msg":"trace[661285083] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5740; }","duration":"136.946991ms","start":"2026-10-26T21:38:02.771521Z","end":"2026-10-26T21:38:02.908468Z","steps":["trace[661285083] 'agreement among raft nodes before linearized reading' (duration: 136.623283ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:38:02.90849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.112486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-84tpp\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-26T21:38:02.908582Z","caller":"traceutil/trace.go:171","msg":"trace[1818177068] range","detail":"{range_begin:/registry/pods/openstack/keepalived-84tpp; range_end:; response_count:1; response_revision:5740; }","duration":"133.243609ms","start":"2026-10-26T21:38:02.77532Z","end":"2026-10-26T21:38:02.908564Z","steps":["trace[1818177068] 'agreement among raft nodes before linearized reading' (duration: 132.937432ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:38:03.089014Z","caller":"traceutil/trace.go:171","msg":"trace[2102363641] transaction","detail":"{read_only:false; response_revision:5741; number_of_response:1; }","duration":"174.622255ms","start":"2026-10-26T21:38:02.91435Z","end":"2026-10-26T21:38:03.088972Z","steps":["trace[2102363641] 'process raft request' (duration: 76.162449ms)","trace[2102363641] 'compare' (duration: 98.263991ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:38:08.258654Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2963} {"level":"info","ts":"2026-10-26T21:38:08.344396Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2963,"took":"84.150655ms","hash":2853122801,"current-db-size-bytes":56766464,"current-db-size":"57 MB","current-db-size-in-use-bytes":32100352,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T21:38:08.344469Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2853122801,"revision":2963,"compact-revision":-1} {"level":"info","ts":"2026-10-26T21:38:56.389973Z","caller":"traceutil/trace.go:171","msg":"trace[240622329] transaction","detail":"{read_only:false; response_revision:6262; number_of_response:1; }","duration":"100.06275ms","start":"2026-10-26T21:38:56.289858Z","end":"2026-10-26T21:38:56.389921Z","steps":["trace[240622329] 'process raft request' (duration: 99.879776ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:43:08.266552Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5838} {"level":"info","ts":"2026-10-26T21:43:08.358876Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5838,"took":"90.409448ms","hash":3221507735,"current-db-size-bytes":56766464,"current-db-size":"57 MB","current-db-size-in-use-bytes":26804224,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-26T21:43:08.359095Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3221507735,"revision":5838,"compact-revision":2963} {"level":"info","ts":"2026-10-26T21:44:14.859568Z","caller":"traceutil/trace.go:171","msg":"trace[37365570] transaction","detail":"{read_only:false; response_revision:8610; number_of_response:1; }","duration":"312.209151ms","start":"2026-10-26T21:44:14.547334Z","end":"2026-10-26T21:44:14.859543Z","steps":["trace[37365570] 'process raft request' (duration: 312.067757ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:44:14.859725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:44:14.547307Z","time spent":"312.342485ms","remote":"127.0.0.1:42852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T21:44:28.412971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.608797ms","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-10-26T21:44:28.413071Z","caller":"traceutil/trace.go:171","msg":"trace[2036675660] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8681; }","duration":"125.74179ms","start":"2026-10-26T21:44:28.287305Z","end":"2026-10-26T21:44:28.413047Z","steps":["trace[2036675660] 'range keys from in-memory index tree' (duration: 125.390541ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:44:28.413187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.06938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-26T21:44:28.413261Z","caller":"traceutil/trace.go:171","msg":"trace[1980624801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8681; }","duration":"107.189662ms","start":"2026-10-26T21:44:28.306054Z","end":"2026-10-26T21:44:28.413244Z","steps":["trace[1980624801] 'range keys from in-memory index tree' (duration: 106.934806ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:44:28.413489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.533055ms","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-10-26T21:44:28.413514Z","caller":"traceutil/trace.go:171","msg":"trace[1693986116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8681; }","duration":"102.597357ms","start":"2026-10-26T21:44:28.310909Z","end":"2026-10-26T21:44:28.413506Z","steps":["trace[1693986116] 'range keys from in-memory index tree' (duration: 102.428672ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:44:28.688005Z","caller":"traceutil/trace.go:171","msg":"trace[200579015] transaction","detail":"{read_only:false; response_revision:8682; number_of_response:1; }","duration":"269.69953ms","start":"2026-10-26T21:44:28.418281Z","end":"2026-10-26T21:44:28.687981Z","steps":["trace[200579015] 'process raft request' (duration: 269.531896ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:44:28.752309Z","caller":"traceutil/trace.go:171","msg":"trace[1094813039] transaction","detail":"{read_only:false; response_revision:8683; number_of_response:1; }","duration":"333.928269ms","start":"2026-10-26T21:44:28.418356Z","end":"2026-10-26T21:44:28.752284Z","steps":["trace[1094813039] 'process raft request' (duration: 333.687953ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:44:28.752478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:44:28.418322Z","time spent":"334.060652ms","remote":"127.0.0.1:42852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T21:44:28.75249Z","caller":"traceutil/trace.go:171","msg":"trace[470834047] linearizableReadLoop","detail":"{readStateIndex:8998; appliedIndex:8995; }","duration":"307.753758ms","start":"2026-10-26T21:44:28.444707Z","end":"2026-10-26T21:44:28.752461Z","steps":["trace[470834047] 'read index received' (duration: 243.17033ms)","trace[470834047] 'applied index is now lower than readState.Index' (duration: 64.581178ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:44:28.752577Z","caller":"traceutil/trace.go:171","msg":"trace[2088425949] transaction","detail":"{read_only:false; response_revision:8684; number_of_response:1; }","duration":"334.041362ms","start":"2026-10-26T21:44:28.418507Z","end":"2026-10-26T21:44:28.752549Z","steps":["trace[2088425949] 'process raft request' (duration: 333.736764ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:44:28.752681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.948514ms","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-10-26T21:44:28.752718Z","caller":"traceutil/trace.go:171","msg":"trace[974675469] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:8684; }","duration":"308.039716ms","start":"2026-10-26T21:44:28.444667Z","end":"2026-10-26T21:44:28.752706Z","steps":["trace[974675469] 'agreement among raft nodes before linearized reading' (duration: 307.931973ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:44:28.752761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:44:28.444654Z","time spent":"308.096858ms","remote":"127.0.0.1:39294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-10-26T21:44:28.752738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.456788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T21:44:28.752725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:44:28.418483Z","time spent":"334.160204ms","remote":"127.0.0.1:42852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T21:44:28.752998Z","caller":"traceutil/trace.go:171","msg":"trace[927647622] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8684; }","duration":"126.583571ms","start":"2026-10-26T21:44:28.62622Z","end":"2026-10-26T21:44:28.752804Z","steps":["trace[927647622] 'agreement among raft nodes before linearized reading' (duration: 126.446648ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:44:58.219103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.326583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T21:44:58.219184Z","caller":"traceutil/trace.go:171","msg":"trace[567606897] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:8893; }","duration":"235.461476ms","start":"2026-10-26T21:44:57.983706Z","end":"2026-10-26T21:44:58.219168Z","steps":["trace[567606897] 'count revisions from in-memory index tree' (duration: 235.20053ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:44:58.242449Z","caller":"traceutil/trace.go:171","msg":"trace[1114168386] transaction","detail":"{read_only:false; response_revision:8894; number_of_response:1; }","duration":"234.092652ms","start":"2026-10-26T21:44:58.008337Z","end":"2026-10-26T21:44:58.242429Z","steps":["trace[1114168386] 'process raft request' (duration: 233.762613ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:45:01.249882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.215357ms","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-10-26T21:45:01.25006Z","caller":"traceutil/trace.go:171","msg":"trace[282105470] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8908; }","duration":"127.393672ms","start":"2026-10-26T21:45:01.122626Z","end":"2026-10-26T21:45:01.25002Z","steps":["trace[282105470] 'range keys from in-memory index tree' (duration: 127.179737ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:45:01.250089Z","caller":"traceutil/trace.go:171","msg":"trace[1743894188] transaction","detail":"{read_only:false; response_revision:8909; number_of_response:1; }","duration":"221.111155ms","start":"2026-10-26T21:45:01.028902Z","end":"2026-10-26T21:45:01.250013Z","steps":["trace[1743894188] 'process raft request' (duration: 161.252476ms)","trace[1743894188] 'compare' (duration: 59.676024ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:45:09.010328Z","caller":"traceutil/trace.go:171","msg":"trace[1855424294] transaction","detail":"{read_only:false; response_revision:8949; number_of_response:1; }","duration":"106.994847ms","start":"2026-10-26T21:45:08.903302Z","end":"2026-10-26T21:45:09.010297Z","steps":["trace[1855424294] 'process raft request' (duration: 106.852474ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:45:09.593104Z","caller":"traceutil/trace.go:171","msg":"trace[202114409] linearizableReadLoop","detail":"{readStateIndex:9279; appliedIndex:9278; }","duration":"134.104661ms","start":"2026-10-26T21:45:09.458961Z","end":"2026-10-26T21:45:09.593065Z","steps":["trace[202114409] 'read index received' (duration: 133.764803ms)","trace[202114409] 'applied index is now lower than readState.Index' (duration: 338.648µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:45:09.593205Z","caller":"traceutil/trace.go:171","msg":"trace[1780798722] transaction","detail":"{read_only:false; response_revision:8955; number_of_response:1; }","duration":"135.584768ms","start":"2026-10-26T21:45:09.457595Z","end":"2026-10-26T21:45:09.59318Z","steps":["trace[1780798722] 'process raft request' (duration: 135.182648ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:45:09.594097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.098926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-84tpp\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-26T21:45:09.594177Z","caller":"traceutil/trace.go:171","msg":"trace[671523241] range","detail":"{range_begin:/registry/pods/openstack/keepalived-84tpp; range_end:; response_count:1; response_revision:8955; }","duration":"135.22317ms","start":"2026-10-26T21:45:09.458935Z","end":"2026-10-26T21:45:09.594158Z","steps":["trace[671523241] 'agreement among raft nodes before linearized reading' (duration: 134.369248ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:45:09.898462Z","caller":"traceutil/trace.go:171","msg":"trace[1248171297] transaction","detail":"{read_only:false; response_revision:8956; number_of_response:1; }","duration":"158.474585ms","start":"2026-10-26T21:45:09.739962Z","end":"2026-10-26T21:45:09.898437Z","steps":["trace[1248171297] 'process raft request' (duration: 158.315611ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:45:21.348884Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024c2.wal"} {"level":"info","ts":"2026-10-26T21:45:25.186148Z","caller":"traceutil/trace.go:171","msg":"trace[1050603432] transaction","detail":"{read_only:false; response_revision:9112; number_of_response:1; }","duration":"104.841493ms","start":"2026-10-26T21:45:25.081283Z","end":"2026-10-26T21:45:25.186124Z","steps":["trace[1050603432] 'process raft request' (duration: 104.691629ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:46:16.244581Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"383aa7db69884d67","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T21:46:16.251041Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-26T21:46:16.251157Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-26T21:46:40.859966Z","caller":"traceutil/trace.go:171","msg":"trace[2029302366] transaction","detail":"{read_only:false; response_revision:9823; number_of_response:1; }","duration":"135.884942ms","start":"2026-10-26T21:46:40.724046Z","end":"2026-10-26T21:46:40.859931Z","steps":["trace[2029302366] 'process raft request' (duration: 135.702487ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:48:08.273917Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8025} {"level":"info","ts":"2026-10-26T21:48:08.343362Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8025,"took":"67.453988ms","hash":1348745352,"current-db-size-bytes":56766464,"current-db-size":"57 MB","current-db-size-in-use-bytes":28119040,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-26T21:48:08.343435Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1348745352,"revision":8025,"compact-revision":5838} {"level":"info","ts":"2026-10-26T21:51:59.26014Z","caller":"traceutil/trace.go:171","msg":"trace[275771951] transaction","detail":"{read_only:false; response_revision:12141; number_of_response:1; }","duration":"105.180643ms","start":"2026-10-26T21:51:59.15494Z","end":"2026-10-26T21:51:59.260121Z","steps":["trace[275771951] 'process raft request' (duration: 105.020759ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:52:21.918103Z","caller":"traceutil/trace.go:171","msg":"trace[1166578168] linearizableReadLoop","detail":"{readStateIndex:12694; appliedIndex:12693; }","duration":"131.143324ms","start":"2026-10-26T21:52:21.786939Z","end":"2026-10-26T21:52:21.918082Z","steps":["trace[1166578168] 'read index received' (duration: 52.274479ms)","trace[1166578168] 'applied index is now lower than readState.Index' (duration: 78.862145ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T21:52:21.918355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.40128ms","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-10-26T21:52:21.918398Z","caller":"traceutil/trace.go:171","msg":"trace[1381201123] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12268; }","duration":"131.494343ms","start":"2026-10-26T21:52:21.786889Z","end":"2026-10-26T21:52:21.918383Z","steps":["trace[1381201123] 'agreement among raft nodes before linearized reading' (duration: 131.289778ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:52:21.918789Z","caller":"traceutil/trace.go:171","msg":"trace[369107452] transaction","detail":"{read_only:false; response_revision:12267; number_of_response:1; }","duration":"131.715808ms","start":"2026-10-26T21:52:21.786311Z","end":"2026-10-26T21:52:21.918027Z","steps":["trace[369107452] 'process raft request' (duration: 52.944465ms)","trace[369107452] 'compare' (duration: 78.587118ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:52:22.084915Z","caller":"traceutil/trace.go:171","msg":"trace[1362554666] transaction","detail":"{read_only:false; response_revision:12270; number_of_response:1; }","duration":"128.666522ms","start":"2026-10-26T21:52:21.956175Z","end":"2026-10-26T21:52:22.084842Z","steps":["trace[1362554666] 'process raft request' (duration: 94.419114ms)","trace[1362554666] 'compare' (duration: 34.093884ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:52:22.388506Z","caller":"traceutil/trace.go:171","msg":"trace[452217894] transaction","detail":"{read_only:false; response_revision:12272; number_of_response:1; }","duration":"100.901826ms","start":"2026-10-26T21:52:22.287583Z","end":"2026-10-26T21:52:22.388485Z","steps":["trace[452217894] 'process raft request' (duration: 100.574868ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:53:08.282554Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10420} {"level":"info","ts":"2026-10-26T21:53:08.379667Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10420,"took":"94.582689ms","hash":197357349,"current-db-size-bytes":56766464,"current-db-size":"57 MB","current-db-size-in-use-bytes":26030080,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-26T21:53:08.379743Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":197357349,"revision":10420,"compact-revision":8025} {"level":"info","ts":"2026-10-26T21:58:08.294559Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12596} {"level":"info","ts":"2026-10-26T21:58:08.374341Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12596,"took":"77.300304ms","hash":837215407,"current-db-size-bytes":56766464,"current-db-size":"57 MB","current-db-size-in-use-bytes":27521024,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-26T21:58:08.374412Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":837215407,"revision":12596,"compact-revision":10420} {"level":"warn","ts":"2026-10-26T21:58:16.472752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.226888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-26T21:58:16.472895Z","caller":"traceutil/trace.go:171","msg":"trace[1983395231] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14684; }","duration":"118.422233ms","start":"2026-10-26T21:58:16.354419Z","end":"2026-10-26T21:58:16.472841Z","steps":["trace[1983395231] 'range keys from in-memory index tree' (duration: 117.956791ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:58:39.542755Z","caller":"traceutil/trace.go:171","msg":"trace[1341588793] transaction","detail":"{read_only:false; response_revision:14808; number_of_response:1; }","duration":"136.148906ms","start":"2026-10-26T21:58:39.406583Z","end":"2026-10-26T21:58:39.542731Z","steps":["trace[1341588793] 'process raft request' (duration: 136.109245ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:58:39.542846Z","caller":"traceutil/trace.go:171","msg":"trace[573999587] transaction","detail":"{read_only:false; response_revision:14806; number_of_response:1; }","duration":"142.073014ms","start":"2026-10-26T21:58:39.400734Z","end":"2026-10-26T21:58:39.542807Z","steps":["trace[573999587] 'process raft request' (duration: 92.203247ms)","trace[573999587] 'compare' (duration: 49.254062ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:58:39.542931Z","caller":"traceutil/trace.go:171","msg":"trace[1672171625] transaction","detail":"{read_only:false; response_revision:14807; number_of_response:1; }","duration":"139.10691ms","start":"2026-10-26T21:58:39.403776Z","end":"2026-10-26T21:58:39.542883Z","steps":["trace[1672171625] 'process raft request' (duration: 138.867214ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:58:56.636269Z","caller":"traceutil/trace.go:171","msg":"trace[110821929] transaction","detail":"{read_only:false; response_revision:14911; number_of_response:1; }","duration":"220.377132ms","start":"2026-10-26T21:58:56.415864Z","end":"2026-10-26T21:58:56.636241Z","steps":["trace[110821929] 'process raft request' (duration: 220.212278ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:58:56.811141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.424293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-26T21:58:56.811225Z","caller":"traceutil/trace.go:171","msg":"trace[1700116607] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14911; }","duration":"112.566906ms","start":"2026-10-26T21:58:56.698639Z","end":"2026-10-26T21:58:56.811206Z","steps":["trace[1700116607] 'range keys from in-memory index tree' (duration: 112.271389ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:59:20.428611Z","caller":"traceutil/trace.go:171","msg":"trace[1412099588] linearizableReadLoop","detail":"{readStateIndex:15762; appliedIndex:15761; }","duration":"186.254089ms","start":"2026-10-26T21:59:20.242331Z","end":"2026-10-26T21:59:20.428585Z","steps":["trace[1412099588] 'read index received' (duration: 186.188448ms)","trace[1412099588] 'applied index is now lower than readState.Index' (duration: 64.351µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:59:20.428648Z","caller":"traceutil/trace.go:171","msg":"trace[1261544515] transaction","detail":"{read_only:false; response_revision:15228; number_of_response:1; }","duration":"209.593563ms","start":"2026-10-26T21:59:20.219027Z","end":"2026-10-26T21:59:20.428621Z","steps":["trace[1261544515] 'process raft request' (duration: 209.416049ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:59:20.428963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.605168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-84tpp\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-26T21:59:20.429045Z","caller":"traceutil/trace.go:171","msg":"trace[696611460] range","detail":"{range_begin:/registry/pods/openstack/keepalived-84tpp; range_end:; response_count:1; response_revision:15228; }","duration":"186.730641ms","start":"2026-10-26T21:59:20.242296Z","end":"2026-10-26T21:59:20.429027Z","steps":["trace[696611460] 'agreement among raft nodes before linearized reading' (duration: 186.433744ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T21:59:20.62536Z","caller":"traceutil/trace.go:171","msg":"trace[1910109215] linearizableReadLoop","detail":"{readStateIndex:15763; appliedIndex:15762; }","duration":"196.644419ms","start":"2026-10-26T21:59:20.428694Z","end":"2026-10-26T21:59:20.625338Z","steps":["trace[1910109215] 'read index received' (duration: 196.366302ms)","trace[1910109215] 'applied index is now lower than readState.Index' (duration: 277.107µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T21:59:20.625412Z","caller":"traceutil/trace.go:171","msg":"trace[1325711036] transaction","detail":"{read_only:false; response_revision:15229; number_of_response:1; }","duration":"222.695211ms","start":"2026-10-26T21:59:20.402682Z","end":"2026-10-26T21:59:20.625377Z","steps":["trace[1325711036] 'process raft request' (duration: 222.456105ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:59:20.625526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.290937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T21:59:20.625602Z","caller":"traceutil/trace.go:171","msg":"trace[762769696] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:15229; }","duration":"333.40268ms","start":"2026-10-26T21:59:20.292179Z","end":"2026-10-26T21:59:20.625581Z","steps":["trace[762769696] 'agreement among raft nodes before linearized reading' (duration: 333.233206ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:59:20.625646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:59:20.292166Z","time spent":"333.467682ms","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"warn","ts":"2026-10-26T21:59:20.62567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.280858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-682cad63-8fc2-45cb-871d-6cb90c48b415\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-10-26T21:59:20.62577Z","caller":"traceutil/trace.go:171","msg":"trace[35550416] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-682cad63-8fc2-45cb-871d-6cb90c48b415; range_end:; response_count:1; response_revision:15229; }","duration":"379.414252ms","start":"2026-10-26T21:59:20.246332Z","end":"2026-10-26T21:59:20.625746Z","steps":["trace[35550416] 'agreement among raft nodes before linearized reading' (duration: 379.135345ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T21:59:20.625836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T21:59:20.246322Z","time spent":"379.499583ms","remote":"127.0.0.1:42778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3659,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-682cad63-8fc2-45cb-871d-6cb90c48b415\" "} {"level":"warn","ts":"2026-10-26T21:59:20.62621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.835084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:66 size:633407"} {"level":"info","ts":"2026-10-26T21:59:20.626267Z","caller":"traceutil/trace.go:171","msg":"trace[86615749] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:66; response_revision:15229; }","duration":"192.896855ms","start":"2026-10-26T21:59:20.433358Z","end":"2026-10-26T21:59:20.626255Z","steps":["trace[86615749] 'agreement among raft nodes before linearized reading' (duration: 192.134196ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:00:53.833003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.530521ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T22:00:53.833114Z","caller":"traceutil/trace.go:171","msg":"trace[1304488790] linearizableReadLoop","detail":"{readStateIndex:16663; appliedIndex:16661; }","duration":"471.70147ms","start":"2026-10-26T22:00:53.361396Z","end":"2026-10-26T22:00:53.833098Z","steps":["trace[1304488790] 'read index received' (duration: 5.332663ms)","trace[1304488790] 'applied index is now lower than readState.Index' (duration: 466.367507ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:00:53.833267Z","caller":"traceutil/trace.go:171","msg":"trace[697719947] transaction","detail":"{read_only:false; response_revision:16106; number_of_response:1; }","duration":"485.819003ms","start":"2026-10-26T22:00:53.347434Z","end":"2026-10-26T22:00:53.833253Z","steps":["trace[697719947] 'process raft request' (duration: 313.883502ms)","trace[697719947] 'compare' (duration: 171.283685ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:00:53.833304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.756701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T22:00:53.833361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:00:53.347409Z","time spent":"485.893515ms","remote":"127.0.0.1:42762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18980,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T22:00:53.833417Z","caller":"traceutil/trace.go:171","msg":"trace[1510459614] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16106; }","duration":"471.906985ms","start":"2026-10-26T22:00:53.361482Z","end":"2026-10-26T22:00:53.833389Z","steps":["trace[1510459614] 'agreement among raft nodes before linearized reading' (duration: 471.734671ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:00:53.833455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.08846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-10-26T22:00:53.833477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.864542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-w8v5h\" ","response":"range_response_count:1 size:9796"} {"level":"warn","ts":"2026-10-26T22:00:53.833511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.10404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-10-26T22:00:53.833587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.512599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-26T22:00:53.833592Z","caller":"traceutil/trace.go:171","msg":"trace[2005943848] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-w8v5h; range_end:; response_count:1; response_revision:16106; }","duration":"360.018947ms","start":"2026-10-26T22:00:53.473545Z","end":"2026-10-26T22:00:53.833564Z","steps":["trace[2005943848] 'agreement among raft nodes before linearized reading' (duration: 359.725329ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:00:53.833517Z","caller":"traceutil/trace.go:171","msg":"trace[1219131406] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16106; }","duration":"223.154212ms","start":"2026-10-26T22:00:53.610347Z","end":"2026-10-26T22:00:53.833501Z","steps":["trace[1219131406] 'agreement among raft nodes before linearized reading' (duration: 222.942277ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:00:53.833633Z","caller":"traceutil/trace.go:171","msg":"trace[2135665639] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16106; }","duration":"380.57432ms","start":"2026-10-26T22:00:53.453045Z","end":"2026-10-26T22:00:53.83362Z","steps":["trace[2135665639] 'agreement among raft nodes before linearized reading' (duration: 380.450027ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:00:53.83366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.435258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-10-26T22:00:53.833675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:00:53.453032Z","time spent":"380.632902ms","remote":"127.0.0.1:42810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5545,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-10-26T22:00:53.833685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.467642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T22:00:53.833717Z","caller":"traceutil/trace.go:171","msg":"trace[1498457912] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16106; }","duration":"462.51927ms","start":"2026-10-26T22:00:53.371178Z","end":"2026-10-26T22:00:53.833697Z","steps":["trace[1498457912] 'agreement among raft nodes before linearized reading' (duration: 462.384757ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:00:53.833725Z","caller":"traceutil/trace.go:171","msg":"trace[1315293106] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16106; }","duration":"381.526234ms","start":"2026-10-26T22:00:53.452187Z","end":"2026-10-26T22:00:53.833713Z","steps":["trace[1315293106] 'agreement among raft nodes before linearized reading' (duration: 381.471763ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:00:53.833719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.582033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T22:00:53.833599Z","caller":"traceutil/trace.go:171","msg":"trace[1401576313] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16106; }","duration":"472.219203ms","start":"2026-10-26T22:00:53.361354Z","end":"2026-10-26T22:00:53.833574Z","steps":["trace[1401576313] 'agreement among raft nodes before linearized reading' (duration: 471.994787ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:00:53.833765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:00:53.452176Z","time spent":"381.579755ms","remote":"127.0.0.1:42810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-10-26T22:00:53.833759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.686775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T22:00:53.833635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.016587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-26T22:00:53.833785Z","caller":"traceutil/trace.go:171","msg":"trace[1867896443] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16106; }","duration":"223.685676ms","start":"2026-10-26T22:00:53.610088Z","end":"2026-10-26T22:00:53.833774Z","steps":["trace[1867896443] 'agreement among raft nodes before linearized reading' (duration: 223.595094ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:00:53.833814Z","caller":"traceutil/trace.go:171","msg":"trace[149844497] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:16106; }","duration":"466.765366ms","start":"2026-10-26T22:00:53.367031Z","end":"2026-10-26T22:00:53.833796Z","steps":["trace[149844497] 'agreement among raft nodes before linearized reading' (duration: 466.677904ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:00:53.83382Z","caller":"traceutil/trace.go:171","msg":"trace[1853015613] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16106; }","duration":"446.229363ms","start":"2026-10-26T22:00:53.387576Z","end":"2026-10-26T22:00:53.833805Z","steps":["trace[1853015613] 'agreement among raft nodes before linearized reading' (duration: 445.973636ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:00:53.833867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.676308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-10-26T22:00:53.833869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:00:53.387561Z","time spent":"446.295164ms","remote":"127.0.0.1:42852","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-10-26T22:00:53.833869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:00:53.367009Z","time spent":"466.845109ms","remote":"127.0.0.1:54504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true "} {"level":"info","ts":"2026-10-26T22:00:53.83392Z","caller":"traceutil/trace.go:171","msg":"trace[1233101586] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16106; }","duration":"381.76145ms","start":"2026-10-26T22:00:53.45215Z","end":"2026-10-26T22:00:53.833911Z","steps":["trace[1233101586] 'agreement among raft nodes before linearized reading' (duration: 381.653728ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:00:53.833468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:00:53.361466Z","time spent":"471.989617ms","remote":"127.0.0.1:42810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-10-26T22:00:53.833944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:00:53.452133Z","time spent":"381.804931ms","remote":"127.0.0.1:42758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":690,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-10-26T22:00:53.833975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.946324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-84tpp\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-10-26T22:00:53.833498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.117761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T22:00:53.834021Z","caller":"traceutil/trace.go:171","msg":"trace[1769931808] range","detail":"{range_begin:/registry/pods/openstack/keepalived-84tpp; range_end:; response_count:1; response_revision:16106; }","duration":"332.029416ms","start":"2026-10-26T22:00:53.501979Z","end":"2026-10-26T22:00:53.834008Z","steps":["trace[1769931808] 'agreement among raft nodes before linearized reading' (duration: 331.874412ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:00:53.83406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:00:53.501957Z","time spent":"332.091828ms","remote":"127.0.0.1:42778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-84tpp\" "} {"level":"warn","ts":"2026-10-26T22:00:53.833754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:00:53.37116Z","time spent":"462.585832ms","remote":"127.0.0.1:42810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5545,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-10-26T22:00:53.834181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.151088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T22:00:53.833655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:00:53.473535Z","time spent":"360.105119ms","remote":"127.0.0.1:42778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9818,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-w8v5h\" "} {"level":"info","ts":"2026-10-26T22:00:53.834259Z","caller":"traceutil/trace.go:171","msg":"trace[1153024750] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16106; }","duration":"224.273361ms","start":"2026-10-26T22:00:53.609971Z","end":"2026-10-26T22:00:53.834245Z","steps":["trace[1153024750] 'agreement among raft nodes before linearized reading' (duration: 224.164308ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:00:53.833785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:00:53.361339Z","time spent":"472.439358ms","remote":"127.0.0.1:42758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":690,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"info","ts":"2026-10-26T22:00:53.834061Z","caller":"traceutil/trace.go:171","msg":"trace[2065412074] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16106; }","duration":"223.699897ms","start":"2026-10-26T22:00:53.610341Z","end":"2026-10-26T22:00:53.834041Z","steps":["trace[2065412074] 'agreement among raft nodes before linearized reading' (duration: 223.093381ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:03:08.302244Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14601} {"level":"info","ts":"2026-10-26T22:03:08.380622Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14601,"took":"74.929407ms","hash":2021423268,"current-db-size-bytes":56766464,"current-db-size":"57 MB","current-db-size-in-use-bytes":32190464,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T22:03:08.380692Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2021423268,"revision":14601,"compact-revision":12596} {"level":"warn","ts":"2026-10-26T22:06:46.55147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.1695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-29884200-s4qhx\" ","response":"range_response_count:1 size:7038"} {"level":"info","ts":"2026-10-26T22:06:46.551858Z","caller":"traceutil/trace.go:171","msg":"trace[258675809] transaction","detail":"{read_only:false; response_revision:18769; number_of_response:1; }","duration":"129.503076ms","start":"2026-10-26T22:06:46.422328Z","end":"2026-10-26T22:06:46.551831Z","steps":["trace[258675809] 'process raft request' (duration: 67.288736ms)","trace[258675809] 'compare' (duration: 61.705437ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:06:46.552236Z","caller":"traceutil/trace.go:171","msg":"trace[892174144] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-29884200-s4qhx; range_end:; response_count:1; response_revision:18768; }","duration":"288.96087ms","start":"2026-10-26T22:06:46.263235Z","end":"2026-10-26T22:06:46.552196Z","steps":["trace[892174144] 'range keys from in-memory index tree' (duration: 287.959514ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:06:46.551471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.082971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-c4d99\" ","response":"range_response_count:1 size:20909"} {"level":"info","ts":"2026-10-26T22:06:46.552328Z","caller":"traceutil/trace.go:171","msg":"trace[1852012111] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-c4d99; range_end:; response_count:1; response_revision:18768; }","duration":"181.997214ms","start":"2026-10-26T22:06:46.370322Z","end":"2026-10-26T22:06:46.55232Z","steps":["trace[1852012111] 'range keys from in-memory index tree' (duration: 180.864477ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:06:46.957387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.719649ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T22:06:46.957535Z","caller":"traceutil/trace.go:171","msg":"trace[1852858925] transaction","detail":"{read_only:false; response_revision:18770; number_of_response:1; }","duration":"377.61314ms","start":"2026-10-26T22:06:46.579876Z","end":"2026-10-26T22:06:46.957489Z","steps":["trace[1852858925] 'process raft request' (duration: 201.700046ms)","trace[1852858925] 'compare' (duration: 175.586096ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:06:46.957572Z","caller":"traceutil/trace.go:171","msg":"trace[632219109] transaction","detail":"{read_only:false; response_revision:18771; number_of_response:1; }","duration":"272.939732ms","start":"2026-10-26T22:06:46.684618Z","end":"2026-10-26T22:06:46.957558Z","steps":["trace[632219109] 'process raft request' (duration: 272.87546ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:06:46.957601Z","caller":"traceutil/trace.go:171","msg":"trace[1742842694] linearizableReadLoop","detail":"{readStateIndex:19414; appliedIndex:19413; }","duration":"330.524306ms","start":"2026-10-26T22:06:46.627031Z","end":"2026-10-26T22:06:46.957555Z","steps":["trace[1742842694] 'read index received' (duration: 154.52243ms)","trace[1742842694] 'applied index is now lower than readState.Index' (duration: 175.997066ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:06:46.957652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:06:46.579834Z","time spent":"377.767384ms","remote":"127.0.0.1:42778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":21024,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T22:06:46.957864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.821194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T22:06:46.957924Z","caller":"traceutil/trace.go:171","msg":"trace[1010822470] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18771; }","duration":"330.940297ms","start":"2026-10-26T22:06:46.626968Z","end":"2026-10-26T22:06:46.957908Z","steps":["trace[1010822470] 'agreement among raft nodes before linearized reading' (duration: 330.808653ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:06:46.957926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.148804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-26T22:06:46.957964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:06:46.626948Z","time spent":"331.006558ms","remote":"127.0.0.1:42588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-26T22:06:46.958011Z","caller":"traceutil/trace.go:171","msg":"trace[811832713] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:18771; }","duration":"248.287776ms","start":"2026-10-26T22:06:46.709699Z","end":"2026-10-26T22:06:46.957987Z","steps":["trace[811832713] 'agreement among raft nodes before linearized reading' (duration: 248.111202ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:06:46.958068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.842337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-26T22:06:46.958231Z","caller":"traceutil/trace.go:171","msg":"trace[1215664221] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18771; }","duration":"163.055162ms","start":"2026-10-26T22:06:46.79513Z","end":"2026-10-26T22:06:46.958185Z","steps":["trace[1215664221] 'agreement among raft nodes before linearized reading' (duration: 162.688803ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:06:47.599036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.84059ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-26T22:06:47.599531Z","caller":"traceutil/trace.go:171","msg":"trace[605234125] linearizableReadLoop","detail":"{readStateIndex:19417; appliedIndex:19416; }","duration":"151.366262ms","start":"2026-10-26T22:06:47.448142Z","end":"2026-10-26T22:06:47.599509Z","steps":["trace[605234125] 'read index received' (duration: 26.651µs)","trace[605234125] 'applied index is now lower than readState.Index' (duration: 151.337591ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:06:47.599779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.627928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-65d96\" ","response":"range_response_count:1 size:22132"} {"level":"info","ts":"2026-10-26T22:06:47.599857Z","caller":"traceutil/trace.go:171","msg":"trace[687221808] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-65d96; range_end:; response_count:1; response_revision:18772; }","duration":"151.729521ms","start":"2026-10-26T22:06:47.448115Z","end":"2026-10-26T22:06:47.599845Z","steps":["trace[687221808] 'agreement among raft nodes before linearized reading' (duration: 151.507426ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:07:32.007832Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"383aa7db69884d67","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T22:07:32.014183Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-26T22:07:32.014434Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-26T22:08:08.31368Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17104} {"level":"info","ts":"2026-10-26T22:08:08.417765Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17104,"took":"100.453293ms","hash":1225282240,"current-db-size-bytes":56766464,"current-db-size":"57 MB","current-db-size-in-use-bytes":34123776,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-26T22:08:08.417845Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1225282240,"revision":17104,"compact-revision":14601} {"level":"info","ts":"2026-10-26T22:08:25.404216Z","caller":"traceutil/trace.go:171","msg":"trace[548483276] transaction","detail":"{read_only:false; response_revision:19768; number_of_response:1; }","duration":"101.393277ms","start":"2026-10-26T22:08:25.302802Z","end":"2026-10-26T22:08:25.404195Z","steps":["trace[548483276] 'process raft request' (duration: 101.260674ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:10:04.191322Z","caller":"traceutil/trace.go:171","msg":"trace[1963123973] linearizableReadLoop","detail":"{readStateIndex:21121; appliedIndex:21120; }","duration":"129.517198ms","start":"2026-10-26T22:10:04.061782Z","end":"2026-10-26T22:10:04.191299Z","steps":["trace[1963123973] 'read index received' (duration: 122.469212ms)","trace[1963123973] 'applied index is now lower than readState.Index' (duration: 7.047116ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:10:04.19154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.739864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:1 size:7467"} {"level":"info","ts":"2026-10-26T22:10:04.191576Z","caller":"traceutil/trace.go:171","msg":"trace[1326915012] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:1; response_revision:20426; }","duration":"129.806065ms","start":"2026-10-26T22:10:04.061759Z","end":"2026-10-26T22:10:04.191565Z","steps":["trace[1326915012] 'agreement among raft nodes before linearized reading' (duration: 129.631531ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:10:04.191848Z","caller":"traceutil/trace.go:171","msg":"trace[1083202418] transaction","detail":"{read_only:false; response_revision:20426; number_of_response:1; }","duration":"136.935214ms","start":"2026-10-26T22:10:04.054899Z","end":"2026-10-26T22:10:04.191835Z","steps":["trace[1083202418] 'process raft request' (duration: 129.398145ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:12:19.888517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.836307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-26T22:12:19.888614Z","caller":"traceutil/trace.go:171","msg":"trace[782506095] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21403; }","duration":"142.958161ms","start":"2026-10-26T22:12:19.745637Z","end":"2026-10-26T22:12:19.888595Z","steps":["trace[782506095] 'range keys from in-memory index tree' (duration: 142.637102ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:12:19.8885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.238262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-26T22:12:19.888766Z","caller":"traceutil/trace.go:171","msg":"trace[444706535] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:21403; }","duration":"144.529759ms","start":"2026-10-26T22:12:19.744211Z","end":"2026-10-26T22:12:19.888741Z","steps":["trace[444706535] 'count revisions from in-memory index tree' (duration: 144.15378ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:12:35.902045Z","caller":"traceutil/trace.go:171","msg":"trace[430410867] linearizableReadLoop","detail":"{readStateIndex:22222; appliedIndex:22221; }","duration":"157.084403ms","start":"2026-10-26T22:12:35.744913Z","end":"2026-10-26T22:12:35.901998Z","steps":["trace[430410867] 'read index received' (duration: 156.834707ms)","trace[430410867] 'applied index is now lower than readState.Index' (duration: 248.596µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:12:35.902166Z","caller":"traceutil/trace.go:171","msg":"trace[269490665] transaction","detail":"{read_only:false; response_revision:21487; number_of_response:1; }","duration":"214.706891ms","start":"2026-10-26T22:12:35.687421Z","end":"2026-10-26T22:12:35.902128Z","steps":["trace[269490665] 'process raft request' (duration: 214.329872ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:12:35.902353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.427191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418353"} {"level":"info","ts":"2026-10-26T22:12:35.902397Z","caller":"traceutil/trace.go:171","msg":"trace[614298216] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:21487; }","duration":"157.572345ms","start":"2026-10-26T22:12:35.744813Z","end":"2026-10-26T22:12:35.902385Z","steps":["trace[614298216] 'agreement among raft nodes before linearized reading' (duration: 157.304908ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:12:36.104503Z","caller":"traceutil/trace.go:171","msg":"trace[1956629007] linearizableReadLoop","detail":"{readStateIndex:22223; appliedIndex:22222; }","duration":"103.107675ms","start":"2026-10-26T22:12:36.001371Z","end":"2026-10-26T22:12:36.104479Z","steps":["trace[1956629007] 'read index received' (duration: 76.48559ms)","trace[1956629007] 'applied index is now lower than readState.Index' (duration: 26.620955ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:12:36.104642Z","caller":"traceutil/trace.go:171","msg":"trace[1281961444] transaction","detail":"{read_only:false; response_revision:21488; number_of_response:1; }","duration":"115.132185ms","start":"2026-10-26T22:12:35.989483Z","end":"2026-10-26T22:12:36.104615Z","steps":["trace[1281961444] 'process raft request' (duration: 88.453649ms)","trace[1281961444] 'compare' (duration: 26.339378ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:12:36.104827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.445793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-26T22:12:36.104928Z","caller":"traceutil/trace.go:171","msg":"trace[256244590] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21488; }","duration":"103.593677ms","start":"2026-10-26T22:12:36.001316Z","end":"2026-10-26T22:12:36.10491Z","steps":["trace[256244590] 'agreement among raft nodes before linearized reading' (duration: 103.370341ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:12:36.420568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.84189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T22:12:36.420994Z","caller":"traceutil/trace.go:171","msg":"trace[342495760] transaction","detail":"{read_only:false; response_revision:21490; number_of_response:1; }","duration":"311.905339ms","start":"2026-10-26T22:12:36.10907Z","end":"2026-10-26T22:12:36.420976Z","steps":["trace[342495760] 'process raft request' (duration: 311.634552ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:12:36.420836Z","caller":"traceutil/trace.go:171","msg":"trace[1402585417] transaction","detail":"{read_only:false; response_revision:21489; number_of_response:1; }","duration":"312.066782ms","start":"2026-10-26T22:12:36.10873Z","end":"2026-10-26T22:12:36.420797Z","steps":["trace[1402585417] 'process raft request' (duration: 119.842273ms)","trace[1402585417] 'compare' (duration: 191.634465ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:12:36.421144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:12:36.109049Z","time spent":"312.017751ms","remote":"127.0.0.1:42852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T22:12:36.421148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:12:36.108714Z","time spent":"312.37445ms","remote":"127.0.0.1:42852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T22:12:36.421319Z","caller":"traceutil/trace.go:171","msg":"trace[1966807485] transaction","detail":"{read_only:false; response_revision:21491; number_of_response:1; }","duration":"311.440416ms","start":"2026-10-26T22:12:36.109638Z","end":"2026-10-26T22:12:36.421079Z","steps":["trace[1966807485] 'process raft request' (duration: 311.132639ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:12:36.421416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:12:36.109619Z","time spent":"311.749515ms","remote":"127.0.0.1:42852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T22:12:37.157717Z","caller":"traceutil/trace.go:171","msg":"trace[1649551008] transaction","detail":"{read_only:false; response_revision:21493; number_of_response:1; }","duration":"204.582089ms","start":"2026-10-26T22:12:36.953107Z","end":"2026-10-26T22:12:37.157689Z","steps":["trace[1649551008] 'process raft request' (duration: 204.402454ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:12:37.298041Z","caller":"traceutil/trace.go:171","msg":"trace[991728563] transaction","detail":"{read_only:false; response_revision:21494; number_of_response:1; }","duration":"336.970194ms","start":"2026-10-26T22:12:36.961004Z","end":"2026-10-26T22:12:37.297974Z","steps":["trace[991728563] 'process raft request' (duration: 253.508301ms)","trace[991728563] 'compare' (duration: 83.182476ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:12:37.298238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:12:36.96097Z","time spent":"337.163119ms","remote":"127.0.0.1:42852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T22:12:37.300455Z","caller":"traceutil/trace.go:171","msg":"trace[588058573] linearizableReadLoop","detail":"{readStateIndex:22229; appliedIndex:22227; }","duration":"284.107923ms","start":"2026-10-26T22:12:37.016326Z","end":"2026-10-26T22:12:37.300434Z","steps":["trace[588058573] 'read index received' (duration: 141.303848ms)","trace[588058573] 'applied index is now lower than readState.Index' (duration: 142.802895ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:12:37.300645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.995465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T22:12:37.300697Z","caller":"traceutil/trace.go:171","msg":"trace[1783362467] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:21494; }","duration":"126.097238ms","start":"2026-10-26T22:12:37.174586Z","end":"2026-10-26T22:12:37.300684Z","steps":["trace[1783362467] 'agreement among raft nodes before linearized reading' (duration: 125.997565ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:12:37.300709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.357799ms","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-10-26T22:12:37.300802Z","caller":"traceutil/trace.go:171","msg":"trace[177231199] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21494; }","duration":"284.455792ms","start":"2026-10-26T22:12:37.016316Z","end":"2026-10-26T22:12:37.300772Z","steps":["trace[177231199] 'agreement among raft nodes before linearized reading' (duration: 284.195145ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:13:08.321184Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19610} {"level":"info","ts":"2026-10-26T22:13:08.446783Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19610,"took":"122.273383ms","hash":3914652361,"current-db-size-bytes":56766464,"current-db-size":"57 MB","current-db-size-in-use-bytes":29466624,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T22:13:08.446861Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3914652361,"revision":19610,"compact-revision":17104} {"level":"info","ts":"2026-10-26T22:16:40.791988Z","caller":"traceutil/trace.go:171","msg":"trace[735450630] linearizableReadLoop","detail":"{readStateIndex:24819; appliedIndex:24818; }","duration":"208.526516ms","start":"2026-10-26T22:16:40.583437Z","end":"2026-10-26T22:16:40.791964Z","steps":["trace[735450630] 'read index received' (duration: 208.324411ms)","trace[735450630] 'applied index is now lower than readState.Index' (duration: 200.955µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T22:16:40.792163Z","caller":"traceutil/trace.go:171","msg":"trace[1251589296] transaction","detail":"{read_only:false; response_revision:24008; number_of_response:1; }","duration":"269.14018ms","start":"2026-10-26T22:16:40.522982Z","end":"2026-10-26T22:16:40.792122Z","steps":["trace[1251589296] 'process raft request' (duration: 268.833852ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:16:40.792221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.771043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-10-26T22:16:40.792257Z","caller":"traceutil/trace.go:171","msg":"trace[1980759146] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24008; }","duration":"208.887455ms","start":"2026-10-26T22:16:40.583358Z","end":"2026-10-26T22:16:40.792246Z","steps":["trace[1980759146] 'agreement among raft nodes before linearized reading' (duration: 208.746421ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:16:40.79252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.928767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-26T22:16:40.792685Z","caller":"traceutil/trace.go:171","msg":"trace[2103312790] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24008; }","duration":"167.139623ms","start":"2026-10-26T22:16:40.625512Z","end":"2026-10-26T22:16:40.792652Z","steps":["trace[2103312790] 'agreement among raft nodes before linearized reading' (duration: 166.793844ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:17:55.875957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.092998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T22:17:55.876045Z","caller":"traceutil/trace.go:171","msg":"trace[2028408016] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:24836; }","duration":"112.235742ms","start":"2026-10-26T22:17:55.76379Z","end":"2026-10-26T22:17:55.876025Z","steps":["trace[2028408016] 'count revisions from in-memory index tree' (duration: 112.021827ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:18:08.329041Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21656} {"level":"info","ts":"2026-10-26T22:18:08.449135Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21656,"took":"112.873988ms","hash":582230,"current-db-size-bytes":57610240,"current-db-size":"58 MB","current-db-size-in-use-bytes":43593728,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-10-26T22:18:08.449205Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":582230,"revision":21656,"compact-revision":19610} {"level":"info","ts":"2026-10-26T22:20:00.142707Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068c8.wal"} {"level":"info","ts":"2026-10-26T22:20:03.493649Z","caller":"traceutil/trace.go:171","msg":"trace[1732190449] linearizableReadLoop","detail":"{readStateIndex:26870; appliedIndex:26869; }","duration":"172.960419ms","start":"2026-10-26T22:20:03.320637Z","end":"2026-10-26T22:20:03.493598Z","steps":["trace[1732190449] 'read index received' (duration: 172.012635ms)","trace[1732190449] 'applied index is now lower than readState.Index' (duration: 946.844µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:20:03.493939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.275187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-26T22:20:03.494009Z","caller":"traceutil/trace.go:171","msg":"trace[556027796] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:26007; }","duration":"173.38848ms","start":"2026-10-26T22:20:03.320608Z","end":"2026-10-26T22:20:03.493996Z","steps":["trace[556027796] 'agreement among raft nodes before linearized reading' (duration: 173.154544ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:20:03.49578Z","caller":"traceutil/trace.go:171","msg":"trace[1087148030] transaction","detail":"{read_only:false; response_revision:26007; number_of_response:1; }","duration":"196.25029ms","start":"2026-10-26T22:20:03.299509Z","end":"2026-10-26T22:20:03.495759Z","steps":["trace[1087148030] 'process raft request' (duration: 193.229165ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:20:03.521072Z","caller":"traceutil/trace.go:171","msg":"trace[417795016] transaction","detail":"{read_only:false; response_revision:26008; number_of_response:1; }","duration":"116.630152ms","start":"2026-10-26T22:20:03.404414Z","end":"2026-10-26T22:20:03.521045Z","steps":["trace[417795016] 'process raft request' (duration: 114.957741ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:20:08.447681Z","caller":"traceutil/trace.go:171","msg":"trace[1344624383] transaction","detail":"{read_only:false; response_revision:26048; number_of_response:1; }","duration":"161.833241ms","start":"2026-10-26T22:20:08.285826Z","end":"2026-10-26T22:20:08.447659Z","steps":["trace[1344624383] 'process raft request' (duration: 161.683697ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:20:08.787666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.823981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T22:20:08.787813Z","caller":"traceutil/trace.go:171","msg":"trace[85953166] transaction","detail":"{read_only:false; response_revision:26050; number_of_response:1; }","duration":"283.646122ms","start":"2026-10-26T22:20:08.504149Z","end":"2026-10-26T22:20:08.787795Z","steps":["trace[85953166] 'process raft request' (duration: 127.614546ms)","trace[85953166] 'compare' (duration: 155.680328ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:20:09.136968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.313629ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T22:20:09.137305Z","caller":"traceutil/trace.go:171","msg":"trace[2111351211] transaction","detail":"{read_only:false; response_revision:26051; number_of_response:1; }","duration":"629.663673ms","start":"2026-10-26T22:20:08.507613Z","end":"2026-10-26T22:20:09.137277Z","steps":["trace[2111351211] 'process raft request' (duration: 477.957705ms)","trace[2111351211] 'compare' (duration: 150.9778ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:20:09.137439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:20:08.507599Z","time spent":"629.757845ms","remote":"127.0.0.1:42852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T22:20:09.137605Z","caller":"traceutil/trace.go:171","msg":"trace[1043258809] transaction","detail":"{read_only:false; response_revision:26052; number_of_response:1; }","duration":"626.924084ms","start":"2026-10-26T22:20:08.510672Z","end":"2026-10-26T22:20:09.137596Z","steps":["trace[1043258809] 'process raft request' (duration: 626.722939ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:20:09.13764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:20:08.510658Z","time spent":"626.963266ms","remote":"127.0.0.1:42852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T22:20:09.137719Z","caller":"traceutil/trace.go:171","msg":"trace[1288993316] linearizableReadLoop","detail":"{readStateIndex:26916; appliedIndex:26914; }","duration":"264.927386ms","start":"2026-10-26T22:20:08.872538Z","end":"2026-10-26T22:20:09.137466Z","steps":["trace[1288993316] 'read index received' (duration: 113.044263ms)","trace[1288993316] 'applied index is now lower than readState.Index' (duration: 151.882203ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:20:09.137727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.174642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T22:20:09.137854Z","caller":"traceutil/trace.go:171","msg":"trace[1668259205] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26052; }","duration":"265.335986ms","start":"2026-10-26T22:20:08.872495Z","end":"2026-10-26T22:20:09.137831Z","steps":["trace[1668259205] 'agreement among raft nodes before linearized reading' (duration: 265.179842ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:20:09.137736Z","caller":"traceutil/trace.go:171","msg":"trace[1464339436] transaction","detail":"{read_only:false; response_revision:26053; number_of_response:1; }","duration":"159.296677ms","start":"2026-10-26T22:20:08.97842Z","end":"2026-10-26T22:20:09.137717Z","steps":["trace[1464339436] 'process raft request' (duration: 159.217525ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:20:09.138996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.644472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-26T22:20:09.139069Z","caller":"traceutil/trace.go:171","msg":"trace[380174762] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26054; }","duration":"150.753915ms","start":"2026-10-26T22:20:08.988299Z","end":"2026-10-26T22:20:09.139053Z","steps":["trace[380174762] 'agreement among raft nodes before linearized reading' (duration: 150.5534ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:21:12.397022Z","caller":"traceutil/trace.go:171","msg":"trace[683991691] linearizableReadLoop","detail":"{readStateIndex:27515; appliedIndex:27514; }","duration":"124.077956ms","start":"2026-10-26T22:21:12.272922Z","end":"2026-10-26T22:21:12.397Z","steps":["trace[683991691] 'read index received' (duration: 123.916972ms)","trace[683991691] 'applied index is now lower than readState.Index' (duration: 159.984µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:21:12.397203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.25486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T22:21:12.397248Z","caller":"traceutil/trace.go:171","msg":"trace[623131519] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:26638; }","duration":"124.333931ms","start":"2026-10-26T22:21:12.272897Z","end":"2026-10-26T22:21:12.397231Z","steps":["trace[623131519] 'agreement among raft nodes before linearized reading' (duration: 124.235689ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:21:12.397084Z","caller":"traceutil/trace.go:171","msg":"trace[1773150940] transaction","detail":"{read_only:false; response_revision:26638; number_of_response:1; }","duration":"149.269863ms","start":"2026-10-26T22:21:12.247786Z","end":"2026-10-26T22:21:12.397056Z","steps":["trace[1773150940] 'process raft request' (duration: 149.045807ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T22:21:12.701338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.279649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T22:21:12.701425Z","caller":"traceutil/trace.go:171","msg":"trace[863395979] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:26638; }","duration":"128.423092ms","start":"2026-10-26T22:21:12.572983Z","end":"2026-10-26T22:21:12.701406Z","steps":["trace[863395979] 'count revisions from in-memory index tree' (duration: 128.165576ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:21:13.062Z","caller":"traceutil/trace.go:171","msg":"trace[674774086] transaction","detail":"{read_only:false; response_revision:26640; number_of_response:1; }","duration":"304.134036ms","start":"2026-10-26T22:21:12.757833Z","end":"2026-10-26T22:21:13.061967Z","steps":["trace[674774086] 'process raft request' (duration: 225.789772ms)","trace[674774086] 'compare' (duration: 77.852972ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T22:21:13.062158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T22:21:12.757811Z","time spent":"304.27103ms","remote":"127.0.0.1:42852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T22:21:13.5552Z","caller":"traceutil/trace.go:171","msg":"trace[178320102] transaction","detail":"{read_only:false; response_revision:26643; number_of_response:1; }","duration":"183.61364ms","start":"2026-10-26T22:21:13.371561Z","end":"2026-10-26T22:21:13.555175Z","steps":["trace[178320102] 'process raft request' (duration: 183.364774ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:21:13.555476Z","caller":"traceutil/trace.go:171","msg":"trace[817653128] transaction","detail":"{read_only:false; response_revision:26644; number_of_response:1; }","duration":"182.114693ms","start":"2026-10-26T22:21:13.373343Z","end":"2026-10-26T22:21:13.555458Z","steps":["trace[817653128] 'process raft request' (duration: 181.722374ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T22:23:08.335816Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24936} {"level":"info","ts":"2026-10-26T22:23:08.497581Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24936,"took":"157.427147ms","hash":2780575464,"current-db-size-bytes":58454016,"current-db-size":"58 MB","current-db-size-in-use-bytes":36556800,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-26T22:23:08.497652Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2780575464,"revision":24936,"compact-revision":21656} {"level":"info","ts":"2026-10-26T22:25:14.547357Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"383aa7db69884d67","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T22:25:14.556059Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-26T22:25:14.556251Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-26T22:28:08.345259Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27763} {"level":"info","ts":"2026-10-26T22:28:08.489516Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27763,"took":"139.457639ms","hash":2971654297,"current-db-size-bytes":58454016,"current-db-size":"58 MB","current-db-size-in-use-bytes":33734656,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-26T22:28:08.489607Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2971654297,"revision":27763,"compact-revision":24936} {"level":"info","ts":"2026-10-26T22:33:08.364432Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30362} {"level":"info","ts":"2026-10-26T22:33:08.521613Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30362,"took":"152.352893ms","hash":571303704,"current-db-size-bytes":58454016,"current-db-size":"58 MB","current-db-size-in-use-bytes":30674944,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T22:33:08.521717Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":571303704,"revision":30362,"compact-revision":27763} {"level":"info","ts":"2026-10-26T22:38:08.371739Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32613} {"level":"info","ts":"2026-10-26T22:38:08.53249Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32613,"took":"156.364838ms","hash":82352183,"current-db-size-bytes":58454016,"current-db-size":"58 MB","current-db-size-in-use-bytes":30298112,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T22:38:08.532592Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":82352183,"revision":32613,"compact-revision":30362} {"level":"info","ts":"2026-10-26T22:43:08.383066Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34846} {"level":"info","ts":"2026-10-26T22:43:08.540082Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34846,"took":"152.192544ms","hash":2816239216,"current-db-size-bytes":59138048,"current-db-size":"59 MB","current-db-size-in-use-bytes":31600640,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T22:43:08.540178Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2816239216,"revision":34846,"compact-revision":32613} {"level":"info","ts":"2026-10-26T22:46:41.899716Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"383aa7db69884d67","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T22:46:41.908689Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-26T22:46:41.908827Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-26T22:48:08.39799Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37139} {"level":"info","ts":"2026-10-26T22:48:08.530927Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37139,"took":"128.43989ms","hash":2622809039,"current-db-size-bytes":59138048,"current-db-size":"59 MB","current-db-size-in-use-bytes":32264192,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T22:48:08.53102Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2622809039,"revision":37139,"compact-revision":34846} {"level":"info","ts":"2026-10-26T22:53:08.405216Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39420} {"level":"info","ts":"2026-10-26T22:53:08.542366Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39420,"took":"132.695586ms","hash":2587832775,"current-db-size-bytes":59559936,"current-db-size":"60 MB","current-db-size-in-use-bytes":31621120,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T22:53:08.542448Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2587832775,"revision":39420,"compact-revision":37139}