{"level":"warn","ts":"2026-08-28T06:09:25.088161Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-28T06:09:25.088254Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.138:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.138:2380","--initial-cluster=instance=https://162.253.55.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.138:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-28T06:09:25.088335Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-28T06:09:25.088361Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.138:2380"]} {"level":"info","ts":"2026-08-28T06:09:25.088388Z","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-08-28T06:09:25.089382Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.138:2379"]} {"level":"info","ts":"2026-08-28T06:09:25.0895Z","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.138:2380"],"listen-peer-urls":["https://162.253.55.138:2380"],"advertise-client-urls":["https://162.253.55.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.138:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-28T06:09:25.097364Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.574892ms"} {"level":"info","ts":"2026-08-28T06:09:25.110091Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ee8e5a779fc25cea","cluster-id":"8f8e3b77da0cbcef"} {"level":"info","ts":"2026-08-28T06:09:25.110198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea switched to configuration voters=()"} {"level":"info","ts":"2026-08-28T06:09:25.110222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea became follower at term 0"} {"level":"info","ts":"2026-08-28T06:09:25.110231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ee8e5a779fc25cea [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-28T06:09:25.110243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea became follower at term 1"} {"level":"info","ts":"2026-08-28T06:09:25.110282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea switched to configuration voters=(17189776297547685098)"} {"level":"warn","ts":"2026-08-28T06:09:25.124946Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-28T06:09:25.126925Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-28T06:09:25.128621Z","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-08-28T06:09:25.130729Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ee8e5a779fc25cea","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-28T06:09:25.13119Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ee8e5a779fc25cea","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-28T06:09:25.131227Z","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-08-28T06:09:25.131446Z","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-08-28T06:09:25.131483Z","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-08-28T06:09:25.134081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea switched to configuration voters=(17189776297547685098)"} {"level":"info","ts":"2026-08-28T06:09:25.134252Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8f8e3b77da0cbcef","local-member-id":"ee8e5a779fc25cea","added-peer-id":"ee8e5a779fc25cea","added-peer-peer-urls":["https://162.253.55.138:2380"]} {"level":"info","ts":"2026-08-28T06:09:25.135367Z","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-08-28T06:09:25.135504Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.138:2380"} {"level":"info","ts":"2026-08-28T06:09:25.135569Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.138:2380"} {"level":"info","ts":"2026-08-28T06:09:25.135732Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ee8e5a779fc25cea","initial-advertise-peer-urls":["https://162.253.55.138:2380"],"listen-peer-urls":["https://162.253.55.138:2380"],"advertise-client-urls":["https://162.253.55.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-28T06:09:25.135801Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-28T06:09:26.111003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea is starting a new election at term 1"} {"level":"info","ts":"2026-08-28T06:09:26.111077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea became pre-candidate at term 1"} {"level":"info","ts":"2026-08-28T06:09:26.111099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea received MsgPreVoteResp from ee8e5a779fc25cea at term 1"} {"level":"info","ts":"2026-08-28T06:09:26.111128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea became candidate at term 2"} {"level":"info","ts":"2026-08-28T06:09:26.111139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea received MsgVoteResp from ee8e5a779fc25cea at term 2"} {"level":"info","ts":"2026-08-28T06:09:26.111154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea became leader at term 2"} {"level":"info","ts":"2026-08-28T06:09:26.111166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ee8e5a779fc25cea elected leader ee8e5a779fc25cea at term 2"} {"level":"info","ts":"2026-08-28T06:09:26.113539Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T06:09:26.114488Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ee8e5a779fc25cea","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.138:2379]}","request-path":"/0/members/ee8e5a779fc25cea/attributes","cluster-id":"8f8e3b77da0cbcef","publish-timeout":"7s"} {"level":"info","ts":"2026-08-28T06:09:26.114499Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-28T06:09:26.114561Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-28T06:09:26.114798Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-28T06:09:26.114857Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-28T06:09:26.115148Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8f8e3b77da0cbcef","local-member-id":"ee8e5a779fc25cea","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T06:09:26.115368Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T06:09:26.115413Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T06:09:26.118583Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.138:2379"} {"level":"info","ts":"2026-08-28T06:09:26.118624Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-28T06:10:02.662228Z","caller":"traceutil/trace.go:171","msg":"trace[579336804] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"114.152593ms","start":"2026-08-28T06:10:02.548053Z","end":"2026-08-28T06:10:02.662205Z","steps":["trace[579336804] 'process raft request' (duration: 113.878267ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:10:02.662293Z","caller":"traceutil/trace.go:171","msg":"trace[1416345865] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"102.159129ms","start":"2026-08-28T06:10:02.560108Z","end":"2026-08-28T06:10:02.662267Z","steps":["trace[1416345865] 'process raft request' (duration: 101.994085ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:10:08.906598Z","caller":"traceutil/trace.go:171","msg":"trace[2146237452] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"118.100934ms","start":"2026-08-28T06:10:08.788473Z","end":"2026-08-28T06:10:08.906574Z","steps":["trace[2146237452] 'process raft request' (duration: 103.365606ms)","trace[2146237452] 'compare' (duration: 14.619855ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T06:10:09.477777Z","caller":"traceutil/trace.go:171","msg":"trace[2056124271] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"237.60746ms","start":"2026-08-28T06:10:09.240136Z","end":"2026-08-28T06:10:09.477743Z","steps":["trace[2056124271] 'process raft request' (duration: 237.385914ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:10:10.192951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.742315ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T06:10:10.193095Z","caller":"traceutil/trace.go:171","msg":"trace[1903821400] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:617; }","duration":"818.811093ms","start":"2026-08-28T06:10:09.374266Z","end":"2026-08-28T06:10:10.193077Z","steps":["trace[1903821400] 'read index received' (duration: 103.306934ms)","trace[1903821400] 'applied index is now lower than readState.Index' (duration: 715.503059ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T06:10:10.193202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.308524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T06:10:10.193224Z","caller":"traceutil/trace.go:171","msg":"trace[985721771] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"749.357205ms","start":"2026-08-28T06:10:09.443859Z","end":"2026-08-28T06:10:10.193216Z","steps":["trace[985721771] 'agreement among raft nodes before linearized reading' (duration: 749.309824ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:10:10.193197Z","caller":"traceutil/trace.go:171","msg":"trace[935930114] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"856.293052ms","start":"2026-08-28T06:10:09.336864Z","end":"2026-08-28T06:10:10.193157Z","steps":["trace[935930114] 'process raft request' (duration: 554.796081ms)","trace[935930114] 'compare' (duration: 300.400027ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T06:10:10.193255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T06:10:09.443846Z","time spent":"749.401726ms","remote":"127.0.0.1:48696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-28T06:10:10.193308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.006798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.138\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-28T06:10:10.19336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T06:10:09.336833Z","time spent":"856.433695ms","remote":"127.0.0.1:48780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T06:10:10.193341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.837796ms","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-08-28T06:10:10.193387Z","caller":"traceutil/trace.go:171","msg":"trace[1517726258] range","detail":"{range_begin:/registry/masterleases/162.253.55.138; range_end:; response_count:1; response_revision:597; }","duration":"819.136302ms","start":"2026-08-28T06:10:09.37423Z","end":"2026-08-28T06:10:10.193366Z","steps":["trace[1517726258] 'agreement among raft nodes before linearized reading' (duration: 818.934967ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:10:10.193421Z","caller":"traceutil/trace.go:171","msg":"trace[1375433950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:597; }","duration":"241.967219ms","start":"2026-08-28T06:10:09.951434Z","end":"2026-08-28T06:10:10.193401Z","steps":["trace[1375433950] 'agreement among raft nodes before linearized reading' (duration: 241.770345ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:10:10.193436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T06:10:09.374211Z","time spent":"819.217193ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.138\" "} {"level":"warn","ts":"2026-08-28T06:10:10.193564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.157344ms","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-08-28T06:10:10.193595Z","caller":"traceutil/trace.go:171","msg":"trace[1538711356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:597; }","duration":"242.219505ms","start":"2026-08-28T06:10:09.951367Z","end":"2026-08-28T06:10:10.193587Z","steps":["trace[1538711356] 'agreement among raft nodes before linearized reading' (duration: 242.104272ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:10:10.193663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.031459ms","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-08-28T06:10:10.193697Z","caller":"traceutil/trace.go:171","msg":"trace[1775119519] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:597; }","duration":"435.099631ms","start":"2026-08-28T06:10:09.758586Z","end":"2026-08-28T06:10:10.193686Z","steps":["trace[1775119519] 'agreement among raft nodes before linearized reading' (duration: 435.002269ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:10:10.193719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T06:10:09.758561Z","time spent":"435.152201ms","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-28T06:11:49.796701Z","caller":"traceutil/trace.go:171","msg":"trace[1107322356] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"145.43739ms","start":"2026-08-28T06:11:49.651234Z","end":"2026-08-28T06:11:49.796671Z","steps":["trace[1107322356] 'process raft request' (duration: 144.932217ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:11:50.030109Z","caller":"traceutil/trace.go:171","msg":"trace[410578764] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"253.183471ms","start":"2026-08-28T06:11:49.776897Z","end":"2026-08-28T06:11:50.03008Z","steps":["trace[410578764] 'process raft request' (duration: 203.156429ms)","trace[410578764] 'compare' (duration: 49.865927ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T06:11:50.030328Z","caller":"traceutil/trace.go:171","msg":"trace[553915246] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"131.250823ms","start":"2026-08-28T06:11:49.899046Z","end":"2026-08-28T06:11:50.030297Z","steps":["trace[553915246] 'process raft request' (duration: 131.14541ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:11:50.030347Z","caller":"traceutil/trace.go:171","msg":"trace[853981200] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"172.005871ms","start":"2026-08-28T06:11:49.858319Z","end":"2026-08-28T06:11:50.030325Z","steps":["trace[853981200] 'process raft request' (duration: 171.716954ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:12:02.869465Z","caller":"traceutil/trace.go:171","msg":"trace[1660251351] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"152.950304ms","start":"2026-08-28T06:12:02.716469Z","end":"2026-08-28T06:12:02.86942Z","steps":["trace[1660251351] 'process raft request' (duration: 96.887322ms)","trace[1660251351] 'compare' (duration: 55.904337ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T06:12:39.194819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.487554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-28T06:12:39.194926Z","caller":"traceutil/trace.go:171","msg":"trace[1930129822] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2186; }","duration":"126.662599ms","start":"2026-08-28T06:12:39.06824Z","end":"2026-08-28T06:12:39.194903Z","steps":["trace[1930129822] 'range keys from in-memory index tree' (duration: 126.2898ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:12:39.19513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.13175ms","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-08-28T06:12:39.195204Z","caller":"traceutil/trace.go:171","msg":"trace[675335779] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2186; }","duration":"114.246603ms","start":"2026-08-28T06:12:39.080935Z","end":"2026-08-28T06:12:39.195182Z","steps":["trace[675335779] 'range keys from in-memory index tree' (duration: 113.981688ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:12:39.386308Z","caller":"traceutil/trace.go:171","msg":"trace[1885886758] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"186.723387ms","start":"2026-08-28T06:12:39.19955Z","end":"2026-08-28T06:12:39.386274Z","steps":["trace[1885886758] 'process raft request' (duration: 186.513122ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:12:39.386322Z","caller":"traceutil/trace.go:171","msg":"trace[1717646857] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"186.632465ms","start":"2026-08-28T06:12:39.199657Z","end":"2026-08-28T06:12:39.386289Z","steps":["trace[1717646857] 'process raft request' (duration: 186.531023ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:12:39.386312Z","caller":"traceutil/trace.go:171","msg":"trace[1340035548] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"186.628165ms","start":"2026-08-28T06:12:39.199656Z","end":"2026-08-28T06:12:39.386285Z","steps":["trace[1340035548] 'process raft request' (duration: 186.570464ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:12:39.411853Z","caller":"traceutil/trace.go:171","msg":"trace[1243528669] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"129.706548ms","start":"2026-08-28T06:12:39.282104Z","end":"2026-08-28T06:12:39.41181Z","steps":["trace[1243528669] 'process raft request' (duration: 129.532344ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:12:45.503404Z","caller":"traceutil/trace.go:171","msg":"trace[1227710778] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"210.138474ms","start":"2026-08-28T06:12:45.293235Z","end":"2026-08-28T06:12:45.503373Z","steps":["trace[1227710778] 'process raft request' (duration: 209.9571ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:12:45.535652Z","caller":"traceutil/trace.go:171","msg":"trace[907798416] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"163.065504ms","start":"2026-08-28T06:12:45.372548Z","end":"2026-08-28T06:12:45.535614Z","steps":["trace[907798416] 'process raft request' (duration: 162.834988ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:12:45.535829Z","caller":"traceutil/trace.go:171","msg":"trace[1314539436] linearizableReadLoop","detail":"{readStateIndex:2308; appliedIndex:2305; }","duration":"127.267242ms","start":"2026-08-28T06:12:45.408539Z","end":"2026-08-28T06:12:45.535806Z","steps":["trace[1314539436] 'read index received' (duration: 94.737354ms)","trace[1314539436] 'applied index is now lower than readState.Index' (duration: 32.529118ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T06:12:45.535879Z","caller":"traceutil/trace.go:171","msg":"trace[2101567090] transaction","detail":"{read_only:false; response_revision:2219; number_of_response:1; }","duration":"163.253389ms","start":"2026-08-28T06:12:45.372598Z","end":"2026-08-28T06:12:45.535852Z","steps":["trace[2101567090] 'process raft request' (duration: 162.923311ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:12:45.536054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.483707ms","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-08-28T06:12:45.536113Z","caller":"traceutil/trace.go:171","msg":"trace[1672588939] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2220; }","duration":"127.575258ms","start":"2026-08-28T06:12:45.408525Z","end":"2026-08-28T06:12:45.5361Z","steps":["trace[1672588939] 'agreement among raft nodes before linearized reading' (duration: 127.411085ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:12:45.536262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.704121ms","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-08-28T06:12:45.53626Z","caller":"traceutil/trace.go:171","msg":"trace[499022545] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"108.806089ms","start":"2026-08-28T06:12:45.427434Z","end":"2026-08-28T06:12:45.53624Z","steps":["trace[499022545] 'process raft request' (duration: 108.246885ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:12:45.536419Z","caller":"traceutil/trace.go:171","msg":"trace[809533639] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2220; }","duration":"127.846655ms","start":"2026-08-28T06:12:45.408508Z","end":"2026-08-28T06:12:45.536355Z","steps":["trace[809533639] 'agreement among raft nodes before linearized reading' (duration: 127.537488ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:12:45.53639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.910977ms","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-08-28T06:12:45.53655Z","caller":"traceutil/trace.go:171","msg":"trace[577434306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2220; }","duration":"125.103162ms","start":"2026-08-28T06:12:45.411417Z","end":"2026-08-28T06:12:45.53652Z","steps":["trace[577434306] 'agreement among raft nodes before linearized reading' (duration: 124.696913ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:12:45.757828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.131912ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T06:12:45.758065Z","caller":"traceutil/trace.go:171","msg":"trace[1453548591] transaction","detail":"{read_only:false; response_revision:2223; number_of_response:1; }","duration":"217.045303ms","start":"2026-08-28T06:12:45.541001Z","end":"2026-08-28T06:12:45.758047Z","steps":["trace[1453548591] 'process raft request' (duration: 217.005022ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:12:45.758239Z","caller":"traceutil/trace.go:171","msg":"trace[1954200974] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"218.040375ms","start":"2026-08-28T06:12:45.540059Z","end":"2026-08-28T06:12:45.7581Z","steps":["trace[1954200974] 'process raft request' (duration: 79.525635ms)","trace[1954200974] 'compare' (duration: 138.03319ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T06:12:45.75833Z","caller":"traceutil/trace.go:171","msg":"trace[1464111953] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"217.337629ms","start":"2026-08-28T06:12:45.540971Z","end":"2026-08-28T06:12:45.758309Z","steps":["trace[1464111953] 'process raft request' (duration: 216.980761ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:13:25.241131Z","caller":"traceutil/trace.go:171","msg":"trace[588680763] transaction","detail":"{read_only:false; response_revision:2612; number_of_response:1; }","duration":"168.900952ms","start":"2026-08-28T06:13:25.072186Z","end":"2026-08-28T06:13:25.241087Z","steps":["trace[588680763] 'process raft request' (duration: 168.648015ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:17:13.554669Z","caller":"traceutil/trace.go:171","msg":"trace[2138192897] linearizableReadLoop","detail":"{readStateIndex:4197; appliedIndex:4196; }","duration":"161.985365ms","start":"2026-08-28T06:17:13.392652Z","end":"2026-08-28T06:17:13.554637Z","steps":["trace[2138192897] 'read index received' (duration: 161.740029ms)","trace[2138192897] 'applied index is now lower than readState.Index' (duration: 244.606µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T06:17:13.55478Z","caller":"traceutil/trace.go:171","msg":"trace[1885780059] transaction","detail":"{read_only:false; response_revision:4040; number_of_response:1; }","duration":"200.00265ms","start":"2026-08-28T06:17:13.354751Z","end":"2026-08-28T06:17:13.554754Z","steps":["trace[1885780059] 'process raft request' (duration: 199.704992ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:17:13.554945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.274241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-f8bhq\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-28T06:17:13.554981Z","caller":"traceutil/trace.go:171","msg":"trace[865072917] range","detail":"{range_begin:/registry/pods/openstack/keepalived-f8bhq; range_end:; response_count:1; response_revision:4040; }","duration":"162.395634ms","start":"2026-08-28T06:17:13.392575Z","end":"2026-08-28T06:17:13.55497Z","steps":["trace[865072917] 'agreement among raft nodes before linearized reading' (duration: 162.21708ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:17:13.55501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.437839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T06:17:13.555064Z","caller":"traceutil/trace.go:171","msg":"trace[1934134904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4040; }","duration":"109.540011ms","start":"2026-08-28T06:17:13.445506Z","end":"2026-08-28T06:17:13.555046Z","steps":["trace[1934134904] 'agreement among raft nodes before linearized reading' (duration: 109.454479ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:18:44.052541Z","caller":"traceutil/trace.go:171","msg":"trace[1918930924] transaction","detail":"{read_only:false; response_revision:5104; number_of_response:1; }","duration":"103.428089ms","start":"2026-08-28T06:18:43.949082Z","end":"2026-08-28T06:18:44.05251Z","steps":["trace[1918930924] 'process raft request' (duration: 102.961708ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:18:47.613399Z","caller":"traceutil/trace.go:171","msg":"trace[1315000053] transaction","detail":"{read_only:false; response_revision:5247; number_of_response:1; }","duration":"148.167087ms","start":"2026-08-28T06:18:47.465202Z","end":"2026-08-28T06:18:47.613369Z","steps":["trace[1315000053] 'process raft request' (duration: 147.940102ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:18:47.62571Z","caller":"traceutil/trace.go:171","msg":"trace[624136584] transaction","detail":"{read_only:false; response_revision:5248; number_of_response:1; }","duration":"158.304791ms","start":"2026-08-28T06:18:47.467368Z","end":"2026-08-28T06:18:47.625672Z","steps":["trace[624136584] 'process raft request' (duration: 157.985713ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:18:50.217314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.571877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-62a83e14-312e-4951-9460-c27bbff7af03\" ","response":"range_response_count:1 size:3907"} {"level":"info","ts":"2026-08-28T06:18:50.217432Z","caller":"traceutil/trace.go:171","msg":"trace[916506831] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-62a83e14-312e-4951-9460-c27bbff7af03; range_end:; response_count:1; response_revision:5292; }","duration":"171.752621ms","start":"2026-08-28T06:18:50.045651Z","end":"2026-08-28T06:18:50.217403Z","steps":["trace[916506831] 'range keys from in-memory index tree' (duration: 171.33717ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:18:50.21743Z","caller":"traceutil/trace.go:171","msg":"trace[846262760] linearizableReadLoop","detail":"{readStateIndex:5499; appliedIndex:5498; }","duration":"137.895672ms","start":"2026-08-28T06:18:50.0795Z","end":"2026-08-28T06:18:50.217396Z","steps":["trace[846262760] 'read index received' (duration: 131.221628ms)","trace[846262760] 'applied index is now lower than readState.Index' (duration: 6.672654ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T06:18:50.217442Z","caller":"traceutil/trace.go:171","msg":"trace[535982091] transaction","detail":"{read_only:false; response_revision:5293; number_of_response:1; }","duration":"147.643216ms","start":"2026-08-28T06:18:50.069763Z","end":"2026-08-28T06:18:50.217407Z","steps":["trace[535982091] 'process raft request' (duration: 141.046334ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:18:50.21769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.162857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-f8bhq\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-28T06:18:50.217735Z","caller":"traceutil/trace.go:171","msg":"trace[579933253] range","detail":"{range_begin:/registry/pods/openstack/keepalived-f8bhq; range_end:; response_count:1; response_revision:5293; }","duration":"138.24354ms","start":"2026-08-28T06:18:50.079478Z","end":"2026-08-28T06:18:50.217722Z","steps":["trace[579933253] 'agreement among raft nodes before linearized reading' (duration: 138.043415ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:19:03.071838Z","caller":"traceutil/trace.go:171","msg":"trace[1141644447] linearizableReadLoop","detail":"{readStateIndex:5764; appliedIndex:5763; }","duration":"160.337327ms","start":"2026-08-28T06:19:02.91146Z","end":"2026-08-28T06:19:03.071797Z","steps":["trace[1141644447] 'read index received' (duration: 103.532751ms)","trace[1141644447] 'applied index is now lower than readState.Index' (duration: 56.802946ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T06:19:03.071934Z","caller":"traceutil/trace.go:171","msg":"trace[1149420418] transaction","detail":"{read_only:false; response_revision:5549; number_of_response:1; }","duration":"163.118471ms","start":"2026-08-28T06:19:02.908775Z","end":"2026-08-28T06:19:03.071894Z","steps":["trace[1149420418] 'process raft request' (duration: 106.143221ms)","trace[1149420418] 'compare' (duration: 56.50717ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T06:19:03.072222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.724876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-08-28T06:19:03.072339Z","caller":"traceutil/trace.go:171","msg":"trace[127277735] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:5549; }","duration":"160.941241ms","start":"2026-08-28T06:19:02.91137Z","end":"2026-08-28T06:19:03.072311Z","steps":["trace[127277735] 'agreement among raft nodes before linearized reading' (duration: 160.624074ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:19:03.108039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.819859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T06:19:03.108205Z","caller":"traceutil/trace.go:171","msg":"trace[1114078649] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:5552; }","duration":"161.060275ms","start":"2026-08-28T06:19:02.947117Z","end":"2026-08-28T06:19:03.108177Z","steps":["trace[1114078649] 'agreement among raft nodes before linearized reading' (duration: 160.489872ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:19:03.225541Z","caller":"traceutil/trace.go:171","msg":"trace[37258202] linearizableReadLoop","detail":"{readStateIndex:5770; appliedIndex:5768; }","duration":"101.244738ms","start":"2026-08-28T06:19:03.124262Z","end":"2026-08-28T06:19:03.225507Z","steps":["trace[37258202] 'read index received' (duration: 18.25168ms)","trace[37258202] 'applied index is now lower than readState.Index' (duration: 82.991638ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T06:19:03.225713Z","caller":"traceutil/trace.go:171","msg":"trace[946544136] transaction","detail":"{read_only:false; response_revision:5555; number_of_response:1; }","duration":"103.045729ms","start":"2026-08-28T06:19:03.122647Z","end":"2026-08-28T06:19:03.225693Z","steps":["trace[946544136] 'process raft request' (duration: 65.012915ms)","trace[946544136] 'compare' (duration: 37.660356ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T06:19:03.225948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.652607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-08-28T06:19:03.226065Z","caller":"traceutil/trace.go:171","msg":"trace[1237020077] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5555; }","duration":"101.812032ms","start":"2026-08-28T06:19:03.12423Z","end":"2026-08-28T06:19:03.226042Z","steps":["trace[1237020077] 'agreement among raft nodes before linearized reading' (duration: 101.513185ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:19:03.256058Z","caller":"traceutil/trace.go:171","msg":"trace[1917311207] transaction","detail":"{read_only:false; response_revision:5556; number_of_response:1; }","duration":"106.97671ms","start":"2026-08-28T06:19:03.149052Z","end":"2026-08-28T06:19:03.256029Z","steps":["trace[1917311207] 'process raft request' (duration: 106.702764ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:19:03.256366Z","caller":"traceutil/trace.go:171","msg":"trace[887999322] transaction","detail":"{read_only:false; response_revision:5557; number_of_response:1; }","duration":"106.940649ms","start":"2026-08-28T06:19:03.149322Z","end":"2026-08-28T06:19:03.256263Z","steps":["trace[887999322] 'process raft request' (duration: 106.575521ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:19:03.25654Z","caller":"traceutil/trace.go:171","msg":"trace[1096745679] transaction","detail":"{read_only:false; response_revision:5558; number_of_response:1; }","duration":"107.060752ms","start":"2026-08-28T06:19:03.149441Z","end":"2026-08-28T06:19:03.256501Z","steps":["trace[1096745679] 'process raft request' (duration: 106.692884ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:19:03.256666Z","caller":"traceutil/trace.go:171","msg":"trace[79304834] transaction","detail":"{read_only:false; response_revision:5559; number_of_response:1; }","duration":"107.043691ms","start":"2026-08-28T06:19:03.149592Z","end":"2026-08-28T06:19:03.256635Z","steps":["trace[79304834] 'process raft request' (duration: 106.778225ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:19:03.256868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.918769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-08-28T06:19:03.256924Z","caller":"traceutil/trace.go:171","msg":"trace[1993847360] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5560; }","duration":"107.028722ms","start":"2026-08-28T06:19:03.149879Z","end":"2026-08-28T06:19:03.256908Z","steps":["trace[1993847360] 'agreement among raft nodes before linearized reading' (duration: 106.860687ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:19:03.257042Z","caller":"traceutil/trace.go:171","msg":"trace[1825719924] transaction","detail":"{read_only:false; response_revision:5560; number_of_response:1; }","duration":"107.331958ms","start":"2026-08-28T06:19:03.149695Z","end":"2026-08-28T06:19:03.257027Z","steps":["trace[1825719924] 'process raft request' (duration: 106.734774ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:19:03.257325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.434066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:110789"} {"level":"info","ts":"2026-08-28T06:19:03.257389Z","caller":"traceutil/trace.go:171","msg":"trace[754485810] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:5560; }","duration":"132.536708ms","start":"2026-08-28T06:19:03.124839Z","end":"2026-08-28T06:19:03.257376Z","steps":["trace[754485810] 'agreement among raft nodes before linearized reading' (duration: 132.16048ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:19:03.831619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.895162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-28T06:19:03.831719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.561999ms","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-08-28T06:19:03.831785Z","caller":"traceutil/trace.go:171","msg":"trace[1642152022] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:5560; }","duration":"288.161778ms","start":"2026-08-28T06:19:03.543586Z","end":"2026-08-28T06:19:03.831748Z","steps":["trace[1642152022] 'count revisions from in-memory index tree' (duration: 287.801289ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:19:03.831805Z","caller":"traceutil/trace.go:171","msg":"trace[1689399248] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5560; }","duration":"142.717233ms","start":"2026-08-28T06:19:03.689067Z","end":"2026-08-28T06:19:03.831784Z","steps":["trace[1689399248] 'range keys from in-memory index tree' (duration: 142.353184ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:19:03.831786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.355336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-28T06:19:03.83184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.175702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-08-28T06:19:03.831913Z","caller":"traceutil/trace.go:171","msg":"trace[965751532] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5560; }","duration":"386.5343ms","start":"2026-08-28T06:19:03.445348Z","end":"2026-08-28T06:19:03.831882Z","steps":["trace[965751532] 'range keys from in-memory index tree' (duration: 386.179992ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:19:03.831937Z","caller":"traceutil/trace.go:171","msg":"trace[995655094] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:5560; }","duration":"506.282854ms","start":"2026-08-28T06:19:03.325631Z","end":"2026-08-28T06:19:03.831914Z","steps":["trace[995655094] 'range keys from in-memory index tree' (duration: 506.020927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:19:03.83204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T06:19:03.325599Z","time spent":"506.428986ms","remote":"127.0.0.1:48858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":1017,"request content":"key:\"/registry/services/specs/monitoring/prometheus-operated\" "} {"level":"warn","ts":"2026-08-28T06:19:03.832071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.515166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-08-28T06:19:03.832087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.644512ms","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-08-28T06:19:03.832173Z","caller":"traceutil/trace.go:171","msg":"trace[823045970] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5560; }","duration":"135.68575ms","start":"2026-08-28T06:19:03.696467Z","end":"2026-08-28T06:19:03.832153Z","steps":["trace[823045970] 'range keys from in-memory index tree' (duration: 135.24017ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:19:03.832195Z","caller":"traceutil/trace.go:171","msg":"trace[675235670] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5560; }","duration":"426.787025ms","start":"2026-08-28T06:19:03.405392Z","end":"2026-08-28T06:19:03.832179Z","steps":["trace[675235670] 'range keys from in-memory index tree' (duration: 426.624951ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:19:03.832035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T06:19:03.445333Z","time spent":"386.686683ms","remote":"127.0.0.1:48686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-28T06:19:03.832465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.386158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4867"} {"level":"info","ts":"2026-08-28T06:19:03.83252Z","caller":"traceutil/trace.go:171","msg":"trace[873228617] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5560; }","duration":"503.46339ms","start":"2026-08-28T06:19:03.329036Z","end":"2026-08-28T06:19:03.832499Z","steps":["trace[873228617] 'range keys from in-memory index tree' (duration: 503.216514ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:19:03.832584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T06:19:03.329024Z","time spent":"503.550112ms","remote":"127.0.0.1:39344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":4891,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" "} {"level":"info","ts":"2026-08-28T06:19:04.040489Z","caller":"traceutil/trace.go:171","msg":"trace[637797356] linearizableReadLoop","detail":"{readStateIndex:5777; appliedIndex:5775; }","duration":"202.332793ms","start":"2026-08-28T06:19:03.838122Z","end":"2026-08-28T06:19:04.040455Z","steps":["trace[637797356] 'read index received' (duration: 64.411582ms)","trace[637797356] 'applied index is now lower than readState.Index' (duration: 137.919791ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T06:19:04.040532Z","caller":"traceutil/trace.go:171","msg":"trace[478639649] transaction","detail":"{read_only:false; response_revision:5562; number_of_response:1; }","duration":"203.336657ms","start":"2026-08-28T06:19:03.837143Z","end":"2026-08-28T06:19:04.040479Z","steps":["trace[478639649] 'process raft request' (duration: 150.037621ms)","trace[478639649] 'compare' (duration: 52.997959ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T06:19:04.040744Z","caller":"traceutil/trace.go:171","msg":"trace[1547542464] transaction","detail":"{read_only:false; response_revision:5563; number_of_response:1; }","duration":"202.319052ms","start":"2026-08-28T06:19:03.838376Z","end":"2026-08-28T06:19:04.040695Z","steps":["trace[1547542464] 'process raft request' (duration: 202.012516ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:19:04.040862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.476626ms","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-08-28T06:19:04.040904Z","caller":"traceutil/trace.go:171","msg":"trace[1924343453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5563; }","duration":"202.565689ms","start":"2026-08-28T06:19:03.838324Z","end":"2026-08-28T06:19:04.04089Z","steps":["trace[1924343453] 'agreement among raft nodes before linearized reading' (duration: 202.360503ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:19:04.04092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.756813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-08-28T06:19:04.041026Z","caller":"traceutil/trace.go:171","msg":"trace[1464213467] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5563; }","duration":"202.906966ms","start":"2026-08-28T06:19:03.838092Z","end":"2026-08-28T06:19:04.040999Z","steps":["trace[1464213467] 'agreement among raft nodes before linearized reading' (duration: 202.575638ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:19:04.041155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.604148ms","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-08-28T06:19:04.041194Z","caller":"traceutil/trace.go:171","msg":"trace[44812551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5563; }","duration":"198.684739ms","start":"2026-08-28T06:19:03.842498Z","end":"2026-08-28T06:19:04.041182Z","steps":["trace[44812551] 'agreement among raft nodes before linearized reading' (duration: 198.485345ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:19:04.0414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.548738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:110789"} {"level":"info","ts":"2026-08-28T06:19:04.041454Z","caller":"traceutil/trace.go:171","msg":"trace[16573472] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:5563; }","duration":"202.641061ms","start":"2026-08-28T06:19:03.838801Z","end":"2026-08-28T06:19:04.041442Z","steps":["trace[16573472] 'agreement among raft nodes before linearized reading' (duration: 202.247682ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:19:13.493307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.858967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-23d6b79a-5cef-45e9-a87d-9ed88110a722\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-28T06:19:13.49347Z","caller":"traceutil/trace.go:171","msg":"trace[66308360] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-23d6b79a-5cef-45e9-a87d-9ed88110a722; range_end:; response_count:1; response_revision:5742; }","duration":"180.069232ms","start":"2026-08-28T06:19:13.313364Z","end":"2026-08-28T06:19:13.493433Z","steps":["trace[66308360] 'range keys from in-memory index tree' (duration: 179.674793ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:19:13.493436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.751785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-08-28T06:19:13.493894Z","caller":"traceutil/trace.go:171","msg":"trace[434370319] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5742; }","duration":"237.208246ms","start":"2026-08-28T06:19:13.256624Z","end":"2026-08-28T06:19:13.493832Z","steps":["trace[434370319] 'agreement among raft nodes before linearized reading' (duration: 53.200014ms)","trace[434370319] 'range keys from in-memory index tree' (duration: 183.396508ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T06:19:13.681587Z","caller":"traceutil/trace.go:171","msg":"trace[520555200] transaction","detail":"{read_only:false; response_revision:5744; number_of_response:1; }","duration":"103.658374ms","start":"2026-08-28T06:19:13.57787Z","end":"2026-08-28T06:19:13.681529Z","steps":["trace[520555200] 'process raft request' (duration: 43.644284ms)","trace[520555200] 'compare' (duration: 59.852407ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T06:19:13.863098Z","caller":"traceutil/trace.go:171","msg":"trace[137569507] transaction","detail":"{read_only:false; response_revision:5745; number_of_response:1; }","duration":"155.335073ms","start":"2026-08-28T06:19:13.707727Z","end":"2026-08-28T06:19:13.863062Z","steps":["trace[137569507] 'process raft request' (duration: 125.566877ms)","trace[137569507] 'compare' (duration: 29.641623ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T06:19:24.661829Z","caller":"traceutil/trace.go:171","msg":"trace[190974180] transaction","detail":"{read_only:false; response_revision:5865; number_of_response:1; }","duration":"100.804799ms","start":"2026-08-28T06:19:24.560989Z","end":"2026-08-28T06:19:24.661794Z","steps":["trace[190974180] 'process raft request' (duration: 100.581364ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:19:26.150004Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2965} {"level":"info","ts":"2026-08-28T06:19:26.249759Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2965,"took":"96.443767ms","hash":432967605,"current-db-size-bytes":55885824,"current-db-size":"56 MB","current-db-size-in-use-bytes":32288768,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-28T06:19:26.249841Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":432967605,"revision":2965,"compact-revision":-1} {"level":"info","ts":"2026-08-28T06:24:26.158696Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5873} {"level":"info","ts":"2026-08-28T06:24:26.251835Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5873,"took":"89.377708ms","hash":334761219,"current-db-size-bytes":55885824,"current-db-size":"56 MB","current-db-size-in-use-bytes":25927680,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-28T06:24:26.251919Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":334761219,"revision":5873,"compact-revision":2965} {"level":"info","ts":"2026-08-28T06:25:35.004207Z","caller":"traceutil/trace.go:171","msg":"trace[436886089] transaction","detail":"{read_only:false; response_revision:8613; number_of_response:1; }","duration":"153.293515ms","start":"2026-08-28T06:25:34.850865Z","end":"2026-08-28T06:25:35.004158Z","steps":["trace[436886089] 'process raft request' (duration: 153.023039ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:25:35.004303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.833792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-28T06:25:35.004381Z","caller":"traceutil/trace.go:171","msg":"trace[231459096] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8613; }","duration":"132.979475ms","start":"2026-08-28T06:25:34.871384Z","end":"2026-08-28T06:25:35.004364Z","steps":["trace[231459096] 'agreement among raft nodes before linearized reading' (duration: 132.839352ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:25:35.004172Z","caller":"traceutil/trace.go:171","msg":"trace[1390384534] linearizableReadLoop","detail":"{readStateIndex:8925; appliedIndex:8923; }","duration":"132.662209ms","start":"2026-08-28T06:25:34.871443Z","end":"2026-08-28T06:25:35.004105Z","steps":["trace[1390384534] 'read index received' (duration: 45.771519ms)","trace[1390384534] 'applied index is now lower than readState.Index' (duration: 86.88929ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T06:25:45.955822Z","caller":"traceutil/trace.go:171","msg":"trace[941234956] transaction","detail":"{read_only:false; response_revision:8666; number_of_response:1; }","duration":"122.105894ms","start":"2026-08-28T06:25:45.833688Z","end":"2026-08-28T06:25:45.955794Z","steps":["trace[941234956] 'process raft request' (duration: 121.92383ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:25:46.77333Z","caller":"traceutil/trace.go:171","msg":"trace[1580338520] transaction","detail":"{read_only:false; response_revision:8673; number_of_response:1; }","duration":"299.434866ms","start":"2026-08-28T06:25:46.473868Z","end":"2026-08-28T06:25:46.773303Z","steps":["trace[1580338520] 'process raft request' (duration: 299.265772ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:25:46.787936Z","caller":"traceutil/trace.go:171","msg":"trace[2063968034] transaction","detail":"{read_only:false; response_revision:8674; number_of_response:1; }","duration":"314.011513ms","start":"2026-08-28T06:25:46.473887Z","end":"2026-08-28T06:25:46.787899Z","steps":["trace[2063968034] 'process raft request' (duration: 313.708486ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:25:46.788184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T06:25:46.473857Z","time spent":"314.183617ms","remote":"127.0.0.1:48940","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-08-28T06:26:11.414796Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023b2.wal"} {"level":"warn","ts":"2026-08-28T06:26:16.749901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.641237ms","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-08-28T06:26:16.750015Z","caller":"traceutil/trace.go:171","msg":"trace[1969389851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8884; }","duration":"326.821651ms","start":"2026-08-28T06:26:16.423168Z","end":"2026-08-28T06:26:16.74999Z","steps":["trace[1969389851] 'range keys from in-memory index tree' (duration: 326.272858ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:26:16.750061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T06:26:16.423143Z","time spent":"326.908754ms","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-28T06:26:16.750665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.293048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-28T06:26:16.750815Z","caller":"traceutil/trace.go:171","msg":"trace[244073071] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8884; }","duration":"348.700317ms","start":"2026-08-28T06:26:16.402054Z","end":"2026-08-28T06:26:16.750754Z","steps":["trace[244073071] 'range keys from in-memory index tree' (duration: 347.344466ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:26:16.750934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T06:26:16.402026Z","time spent":"348.888391ms","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-28T06:26:19.626949Z","caller":"traceutil/trace.go:171","msg":"trace[246206483] linearizableReadLoop","detail":"{readStateIndex:9225; appliedIndex:9224; }","duration":"182.290396ms","start":"2026-08-28T06:26:19.444632Z","end":"2026-08-28T06:26:19.626922Z","steps":["trace[246206483] 'read index received' (duration: 182.250915ms)","trace[246206483] 'applied index is now lower than readState.Index' (duration: 37.821µs)"],"step_count":2} {"level":"warn","ts":"2026-08-28T06:26:19.627161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.514233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-28T06:26:19.627203Z","caller":"traceutil/trace.go:171","msg":"trace[669455480] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8902; }","duration":"182.615365ms","start":"2026-08-28T06:26:19.444574Z","end":"2026-08-28T06:26:19.62719Z","steps":["trace[669455480] 'agreement among raft nodes before linearized reading' (duration: 182.452192ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:26:19.638905Z","caller":"traceutil/trace.go:171","msg":"trace[79047344] transaction","detail":"{read_only:false; response_revision:8903; number_of_response:1; }","duration":"108.48768ms","start":"2026-08-28T06:26:19.53039Z","end":"2026-08-28T06:26:19.638878Z","steps":["trace[79047344] 'process raft request' (duration: 108.298905ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:26:30.880724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.575947ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T06:26:30.880893Z","caller":"traceutil/trace.go:171","msg":"trace[768454208] transaction","detail":"{read_only:false; response_revision:8972; number_of_response:1; }","duration":"200.270893ms","start":"2026-08-28T06:26:30.680596Z","end":"2026-08-28T06:26:30.880867Z","steps":["trace[768454208] 'process raft request' (duration: 99.175834ms)","trace[768454208] 'compare' (duration: 100.319302ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T06:27:35.501565Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ee8e5a779fc25cea","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-28T06:27:35.510602Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-28T06:27:35.510908Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-28T06:29:26.167213Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8021} {"level":"info","ts":"2026-08-28T06:29:26.237039Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8021,"took":"66.893816ms","hash":1652839587,"current-db-size-bytes":55885824,"current-db-size":"56 MB","current-db-size-in-use-bytes":27820032,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-28T06:29:26.237123Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1652839587,"revision":8021,"compact-revision":5873} {"level":"info","ts":"2026-08-28T06:33:37.301138Z","caller":"traceutil/trace.go:171","msg":"trace[318804878] transaction","detail":"{read_only:false; response_revision:12244; number_of_response:1; }","duration":"158.963564ms","start":"2026-08-28T06:33:37.142133Z","end":"2026-08-28T06:33:37.301097Z","steps":["trace[318804878] 'process raft request' (duration: 158.690138ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:33:37.451082Z","caller":"traceutil/trace.go:171","msg":"trace[825180119] linearizableReadLoop","detail":"{readStateIndex:12676; appliedIndex:12675; }","duration":"128.191152ms","start":"2026-08-28T06:33:37.322818Z","end":"2026-08-28T06:33:37.451009Z","steps":["trace[825180119] 'read index received' (duration: 104.944975ms)","trace[825180119] 'applied index is now lower than readState.Index' (duration: 23.244527ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T06:33:37.45113Z","caller":"traceutil/trace.go:171","msg":"trace[950776482] transaction","detail":"{read_only:false; response_revision:12246; number_of_response:1; }","duration":"145.299647ms","start":"2026-08-28T06:33:37.305801Z","end":"2026-08-28T06:33:37.451101Z","steps":["trace[950776482] 'process raft request' (duration: 121.944738ms)","trace[950776482] 'compare' (duration: 23.142985ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T06:33:37.451406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.56338ms","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-08-28T06:33:37.45149Z","caller":"traceutil/trace.go:171","msg":"trace[1912007524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12246; }","duration":"128.690854ms","start":"2026-08-28T06:33:37.32278Z","end":"2026-08-28T06:33:37.451471Z","steps":["trace[1912007524] 'agreement among raft nodes before linearized reading' (duration: 128.421068ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:33:38.397843Z","caller":"traceutil/trace.go:171","msg":"trace[441049298] transaction","detail":"{read_only:false; response_revision:12251; number_of_response:1; }","duration":"210.021293ms","start":"2026-08-28T06:33:38.187799Z","end":"2026-08-28T06:33:38.39782Z","steps":["trace[441049298] 'process raft request' (duration: 209.866699ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:33:38.496083Z","caller":"traceutil/trace.go:171","msg":"trace[1648193149] linearizableReadLoop","detail":"{readStateIndex:12682; appliedIndex:12680; }","duration":"135.347517ms","start":"2026-08-28T06:33:38.360709Z","end":"2026-08-28T06:33:38.496056Z","steps":["trace[1648193149] 'read index received' (duration: 37.041125ms)","trace[1648193149] 'applied index is now lower than readState.Index' (duration: 98.305112ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T06:33:38.49615Z","caller":"traceutil/trace.go:171","msg":"trace[709729422] transaction","detail":"{read_only:false; response_revision:12252; number_of_response:1; }","duration":"207.369672ms","start":"2026-08-28T06:33:38.288757Z","end":"2026-08-28T06:33:38.496127Z","steps":["trace[709729422] 'process raft request' (duration: 198.286853ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:33:38.496555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.776418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-f8bhq\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-28T06:33:38.496639Z","caller":"traceutil/trace.go:171","msg":"trace[450408173] range","detail":"{range_begin:/registry/pods/openstack/keepalived-f8bhq; range_end:; response_count:1; response_revision:12252; }","duration":"135.969962ms","start":"2026-08-28T06:33:38.360647Z","end":"2026-08-28T06:33:38.496617Z","steps":["trace[450408173] 'agreement among raft nodes before linearized reading' (duration: 135.48224ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:34:26.174679Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10406} {"level":"info","ts":"2026-08-28T06:34:26.267207Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10406,"took":"90.192404ms","hash":1880444570,"current-db-size-bytes":55885824,"current-db-size":"56 MB","current-db-size-in-use-bytes":25829376,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-28T06:34:26.2673Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1880444570,"revision":10406,"compact-revision":8021} {"level":"info","ts":"2026-08-28T06:39:26.181573Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12586} {"level":"info","ts":"2026-08-28T06:39:26.265431Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12586,"took":"81.570262ms","hash":1914284514,"current-db-size-bytes":55885824,"current-db-size":"56 MB","current-db-size-in-use-bytes":27906048,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-28T06:39:26.265518Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1914284514,"revision":12586,"compact-revision":10406} {"level":"info","ts":"2026-08-28T06:40:39.71083Z","caller":"traceutil/trace.go:171","msg":"trace[2569455] transaction","detail":"{read_only:false; response_revision:15009; number_of_response:1; }","duration":"133.746216ms","start":"2026-08-28T06:40:39.577051Z","end":"2026-08-28T06:40:39.710797Z","steps":["trace[2569455] 'process raft request' (duration: 133.443589ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:40:39.711028Z","caller":"traceutil/trace.go:171","msg":"trace[1991376518] linearizableReadLoop","detail":"{readStateIndex:15540; appliedIndex:15537; }","duration":"118.828292ms","start":"2026-08-28T06:40:39.592155Z","end":"2026-08-28T06:40:39.710983Z","steps":["trace[1991376518] 'read index received' (duration: 3.848449ms)","trace[1991376518] 'applied index is now lower than readState.Index' (duration: 114.977163ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T06:40:39.711106Z","caller":"traceutil/trace.go:171","msg":"trace[1709838546] transaction","detail":"{read_only:false; response_revision:15010; number_of_response:1; }","duration":"127.43077ms","start":"2026-08-28T06:40:39.583643Z","end":"2026-08-28T06:40:39.711074Z","steps":["trace[1709838546] 'process raft request' (duration: 127.095163ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:40:39.711291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.104378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-28T06:40:39.711323Z","caller":"traceutil/trace.go:171","msg":"trace[1635881426] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15010; }","duration":"119.184621ms","start":"2026-08-28T06:40:39.592128Z","end":"2026-08-28T06:40:39.711312Z","steps":["trace[1635881426] 'agreement among raft nodes before linearized reading' (duration: 118.981596ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:40:39.711925Z","caller":"traceutil/trace.go:171","msg":"trace[839331019] transaction","detail":"{read_only:false; response_revision:15011; number_of_response:1; }","duration":"114.691107ms","start":"2026-08-28T06:40:39.597209Z","end":"2026-08-28T06:40:39.7119Z","steps":["trace[839331019] 'process raft request' (duration: 114.571254ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:40:49.072527Z","caller":"traceutil/trace.go:171","msg":"trace[1400905915] transaction","detail":"{read_only:false; response_revision:15059; number_of_response:1; }","duration":"123.123391ms","start":"2026-08-28T06:40:48.949344Z","end":"2026-08-28T06:40:49.072467Z","steps":["trace[1400905915] 'process raft request' (duration: 94.937151ms)","trace[1400905915] 'compare' (duration: 27.979355ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T06:40:57.696629Z","caller":"traceutil/trace.go:171","msg":"trace[1249048813] linearizableReadLoop","detail":"{readStateIndex:15652; appliedIndex:15651; }","duration":"250.545051ms","start":"2026-08-28T06:40:57.446057Z","end":"2026-08-28T06:40:57.696602Z","steps":["trace[1249048813] 'read index received' (duration: 250.321686ms)","trace[1249048813] 'applied index is now lower than readState.Index' (duration: 219.655µs)"],"step_count":2} {"level":"warn","ts":"2026-08-28T06:40:57.696844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.775926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T06:40:57.696909Z","caller":"traceutil/trace.go:171","msg":"trace[1763972738] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15118; }","duration":"250.9117ms","start":"2026-08-28T06:40:57.445984Z","end":"2026-08-28T06:40:57.696896Z","steps":["trace[1763972738] 'agreement among raft nodes before linearized reading' (duration: 250.755956ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:40:57.697322Z","caller":"traceutil/trace.go:171","msg":"trace[620324933] transaction","detail":"{read_only:false; response_revision:15118; number_of_response:1; }","duration":"406.54715ms","start":"2026-08-28T06:40:57.290763Z","end":"2026-08-28T06:40:57.697311Z","steps":["trace[620324933] 'process raft request' (duration: 405.675461ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:40:57.697418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T06:40:57.290726Z","time spent":"406.630392ms","remote":"127.0.0.1:48844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12483,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T06:41:07.804253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.212641ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-28T06:41:07.804908Z","caller":"traceutil/trace.go:171","msg":"trace[614144463] transaction","detail":"{read_only:false; response_revision:15287; number_of_response:1; }","duration":"241.023672ms","start":"2026-08-28T06:41:07.563786Z","end":"2026-08-28T06:41:07.804809Z","steps":["trace[614144463] 'process raft request' (duration: 134.117385ms)","trace[614144463] 'compare' (duration: 105.894894ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T06:41:08.042152Z","caller":"traceutil/trace.go:171","msg":"trace[1381941851] transaction","detail":"{read_only:false; response_revision:15288; number_of_response:1; }","duration":"227.464419ms","start":"2026-08-28T06:41:07.814648Z","end":"2026-08-28T06:41:08.042113Z","steps":["trace[1381941851] 'process raft request' (duration: 178.326005ms)","trace[1381941851] 'compare' (duration: 48.815567ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T06:41:08.042466Z","caller":"traceutil/trace.go:171","msg":"trace[1280500298] transaction","detail":"{read_only:false; response_revision:15289; number_of_response:1; }","duration":"226.343293ms","start":"2026-08-28T06:41:07.816103Z","end":"2026-08-28T06:41:08.042446Z","steps":["trace[1280500298] 'process raft request' (duration: 225.955605ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:41:08.042489Z","caller":"traceutil/trace.go:171","msg":"trace[108118371] linearizableReadLoop","detail":"{readStateIndex:15826; appliedIndex:15824; }","duration":"199.733419ms","start":"2026-08-28T06:41:07.842569Z","end":"2026-08-28T06:41:08.042302Z","steps":["trace[108118371] 'read index received' (duration: 150.419681ms)","trace[108118371] 'applied index is now lower than readState.Index' (duration: 49.307138ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T06:41:08.042952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.361123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-08-28T06:41:08.043011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.746871ms","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-08-28T06:41:08.043039Z","caller":"traceutil/trace.go:171","msg":"trace[1623481430] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15289; }","duration":"200.489286ms","start":"2026-08-28T06:41:07.842529Z","end":"2026-08-28T06:41:08.043018Z","steps":["trace[1623481430] 'agreement among raft nodes before linearized reading' (duration: 200.2395ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:41:08.043092Z","caller":"traceutil/trace.go:171","msg":"trace[848784406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15289; }","duration":"138.857524ms","start":"2026-08-28T06:41:07.904215Z","end":"2026-08-28T06:41:08.043073Z","steps":["trace[848784406] 'agreement among raft nodes before linearized reading' (duration: 138.609859ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:41:08.043025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.249797ms","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-08-28T06:41:08.04323Z","caller":"traceutil/trace.go:171","msg":"trace[1548104244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15289; }","duration":"183.486745ms","start":"2026-08-28T06:41:07.859703Z","end":"2026-08-28T06:41:08.04319Z","steps":["trace[1548104244] 'agreement among raft nodes before linearized reading' (duration: 183.087985ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:41:08.043145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.971604ms","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-08-28T06:41:08.04337Z","caller":"traceutil/trace.go:171","msg":"trace[480727065] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15289; }","duration":"148.244061ms","start":"2026-08-28T06:41:07.895076Z","end":"2026-08-28T06:41:08.04332Z","steps":["trace[480727065] 'agreement among raft nodes before linearized reading' (duration: 147.895302ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:42:49.706873Z","caller":"traceutil/trace.go:171","msg":"trace[115116862] linearizableReadLoop","detail":"{readStateIndex:16960; appliedIndex:16958; }","duration":"107.774707ms","start":"2026-08-28T06:42:49.599072Z","end":"2026-08-28T06:42:49.706847Z","steps":["trace[115116862] 'read index received' (duration: 39.085433ms)","trace[115116862] 'applied index is now lower than readState.Index' (duration: 68.688354ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T06:42:49.707038Z","caller":"traceutil/trace.go:171","msg":"trace[1895118483] transaction","detail":"{read_only:false; response_revision:16388; number_of_response:1; }","duration":"146.999151ms","start":"2026-08-28T06:42:49.560027Z","end":"2026-08-28T06:42:49.707026Z","steps":["trace[1895118483] 'process raft request' (duration: 146.705375ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:42:49.707302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.210748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-f8bhq\" ","response":"range_response_count:1 size:6525"} {"level":"info","ts":"2026-08-28T06:42:49.706912Z","caller":"traceutil/trace.go:171","msg":"trace[1417503225] transaction","detail":"{read_only:false; response_revision:16387; number_of_response:1; }","duration":"170.481434ms","start":"2026-08-28T06:42:49.536378Z","end":"2026-08-28T06:42:49.70686Z","steps":["trace[1417503225] 'process raft request' (duration: 101.813869ms)","trace[1417503225] 'compare' (duration: 68.269936ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T06:42:49.707356Z","caller":"traceutil/trace.go:171","msg":"trace[690966800] range","detail":"{range_begin:/registry/pods/openstack/keepalived-f8bhq; range_end:; response_count:1; response_revision:16388; }","duration":"108.318421ms","start":"2026-08-28T06:42:49.599025Z","end":"2026-08-28T06:42:49.707343Z","steps":["trace[690966800] 'agreement among raft nodes before linearized reading' (duration: 108.077084ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:42:50.688766Z","caller":"traceutil/trace.go:171","msg":"trace[2099889911] transaction","detail":"{read_only:false; response_revision:16390; number_of_response:1; }","duration":"208.653465ms","start":"2026-08-28T06:42:50.480087Z","end":"2026-08-28T06:42:50.68874Z","steps":["trace[2099889911] 'process raft request' (duration: 208.508262ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:42:50.812155Z","caller":"traceutil/trace.go:171","msg":"trace[244154528] transaction","detail":"{read_only:false; response_revision:16391; number_of_response:1; }","duration":"325.594343ms","start":"2026-08-28T06:42:50.486529Z","end":"2026-08-28T06:42:50.812123Z","steps":["trace[244154528] 'process raft request' (duration: 324.984249ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:42:50.812307Z","caller":"traceutil/trace.go:171","msg":"trace[1026733946] linearizableReadLoop","detail":"{readStateIndex:16964; appliedIndex:16961; }","duration":"297.345582ms","start":"2026-08-28T06:42:50.51493Z","end":"2026-08-28T06:42:50.812275Z","steps":["trace[1026733946] 'read index received' (duration: 173.624697ms)","trace[1026733946] 'applied index is now lower than readState.Index' (duration: 123.719675ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T06:42:50.812431Z","caller":"traceutil/trace.go:171","msg":"trace[526316282] transaction","detail":"{read_only:false; response_revision:16392; number_of_response:1; }","duration":"324.439656ms","start":"2026-08-28T06:42:50.48798Z","end":"2026-08-28T06:42:50.81242Z","steps":["trace[526316282] 'process raft request' (duration: 324.090008ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:42:50.812544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T06:42:50.487945Z","time spent":"324.514588ms","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T06:42:50.812357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T06:42:50.486515Z","time spent":"325.733676ms","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T06:42:50.812578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.147306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T06:42:50.812661Z","caller":"traceutil/trace.go:171","msg":"trace[1372031295] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16392; }","duration":"297.263129ms","start":"2026-08-28T06:42:50.515379Z","end":"2026-08-28T06:42:50.812642Z","steps":["trace[1372031295] 'agreement among raft nodes before linearized reading' (duration: 297.113306ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:42:50.812775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.88214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-28T06:42:50.812899Z","caller":"traceutil/trace.go:171","msg":"trace[307904960] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:16392; }","duration":"274.025993ms","start":"2026-08-28T06:42:50.538835Z","end":"2026-08-28T06:42:50.812861Z","steps":["trace[307904960] 'agreement among raft nodes before linearized reading' (duration: 273.705496ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:42:50.812985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.220387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T06:42:50.813022Z","caller":"traceutil/trace.go:171","msg":"trace[237046410] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16392; }","duration":"277.256107ms","start":"2026-08-28T06:42:50.535756Z","end":"2026-08-28T06:42:50.813012Z","steps":["trace[237046410] 'agreement among raft nodes before linearized reading' (duration: 277.203406ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:42:50.813077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.246287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-28T06:42:50.813092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.351383ms","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-08-28T06:42:50.813129Z","caller":"traceutil/trace.go:171","msg":"trace[1223262033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16392; }","duration":"160.379681ms","start":"2026-08-28T06:42:50.652737Z","end":"2026-08-28T06:42:50.813116Z","steps":["trace[1223262033] 'agreement among raft nodes before linearized reading' (duration: 159.958231ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:42:50.813132Z","caller":"traceutil/trace.go:171","msg":"trace[820931583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16392; }","duration":"200.392534ms","start":"2026-08-28T06:42:50.612729Z","end":"2026-08-28T06:42:50.813121Z","steps":["trace[820931583] 'agreement among raft nodes before linearized reading' (duration: 200.2418ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:42:50.813235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.42549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5522"} {"level":"info","ts":"2026-08-28T06:42:50.813267Z","caller":"traceutil/trace.go:171","msg":"trace[1559104071] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16392; }","duration":"293.483462ms","start":"2026-08-28T06:42:50.519775Z","end":"2026-08-28T06:42:50.813259Z","steps":["trace[1559104071] 'agreement among raft nodes before linearized reading' (duration: 293.365649ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:42:50.813284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.34206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T06:42:50.813308Z","caller":"traceutil/trace.go:171","msg":"trace[667526419] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16392; }","duration":"297.383872ms","start":"2026-08-28T06:42:50.515916Z","end":"2026-08-28T06:42:50.8133Z","steps":["trace[667526419] 'agreement among raft nodes before linearized reading' (duration: 297.337021ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:42:50.813326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.682971ms","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-08-28T06:42:50.813389Z","caller":"traceutil/trace.go:171","msg":"trace[766563600] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16392; }","duration":"249.779083ms","start":"2026-08-28T06:42:50.563599Z","end":"2026-08-28T06:42:50.813378Z","steps":["trace[766563600] 'agreement among raft nodes before linearized reading' (duration: 249.596658ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:42:50.813442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.862143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-08-28T06:42:50.813473Z","caller":"traceutil/trace.go:171","msg":"trace[1683550282] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16392; }","duration":"297.908824ms","start":"2026-08-28T06:42:50.515556Z","end":"2026-08-28T06:42:50.813465Z","steps":["trace[1683550282] 'agreement among raft nodes before linearized reading' (duration: 297.823003ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:42:50.81352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.707871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5522"} {"level":"info","ts":"2026-08-28T06:42:50.813555Z","caller":"traceutil/trace.go:171","msg":"trace[1841621220] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16392; }","duration":"294.763702ms","start":"2026-08-28T06:42:50.518783Z","end":"2026-08-28T06:42:50.813547Z","steps":["trace[1841621220] 'agreement among raft nodes before linearized reading' (duration: 294.67151ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:42:50.813704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.409053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T06:42:50.813735Z","caller":"traceutil/trace.go:171","msg":"trace[1603188909] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16392; }","duration":"297.455504ms","start":"2026-08-28T06:42:50.516271Z","end":"2026-08-28T06:42:50.813726Z","steps":["trace[1603188909] 'agreement among raft nodes before linearized reading' (duration: 297.410183ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:42:50.813865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.936648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T06:42:50.813916Z","caller":"traceutil/trace.go:171","msg":"trace[1919769339] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16392; }","duration":"299.0535ms","start":"2026-08-28T06:42:50.514854Z","end":"2026-08-28T06:42:50.813908Z","steps":["trace[1919769339] 'agreement among raft nodes before linearized reading' (duration: 298.991099ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:42:50.814056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.541808ms","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-08-28T06:42:50.814117Z","caller":"traceutil/trace.go:171","msg":"trace[882520573] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16392; }","duration":"155.637351ms","start":"2026-08-28T06:42:50.658461Z","end":"2026-08-28T06:42:50.814099Z","steps":["trace[882520573] 'agreement among raft nodes before linearized reading' (duration: 155.487217ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:42:50.959513Z","caller":"traceutil/trace.go:171","msg":"trace[549263879] transaction","detail":"{read_only:false; response_revision:16394; number_of_response:1; }","duration":"140.259836ms","start":"2026-08-28T06:42:50.819233Z","end":"2026-08-28T06:42:50.959493Z","steps":["trace[549263879] 'process raft request' (duration: 140.073122ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:42:50.959851Z","caller":"traceutil/trace.go:171","msg":"trace[1074340616] transaction","detail":"{read_only:false; response_revision:16395; number_of_response:1; }","duration":"140.41187ms","start":"2026-08-28T06:42:50.819393Z","end":"2026-08-28T06:42:50.959805Z","steps":["trace[1074340616] 'process raft request' (duration: 140.053672ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:42:50.959904Z","caller":"traceutil/trace.go:171","msg":"trace[1456277306] transaction","detail":"{read_only:false; response_revision:16396; number_of_response:1; }","duration":"140.334858ms","start":"2026-08-28T06:42:50.819523Z","end":"2026-08-28T06:42:50.959858Z","steps":["trace[1456277306] 'process raft request' (duration: 140.174845ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:44:26.187655Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14588} {"level":"info","ts":"2026-08-28T06:44:26.274073Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14588,"took":"83.347234ms","hash":1987953834,"current-db-size-bytes":55885824,"current-db-size":"56 MB","current-db-size-in-use-bytes":31485952,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-28T06:44:26.274165Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1987953834,"revision":14588,"compact-revision":12586} {"level":"info","ts":"2026-08-28T06:48:34.807965Z","caller":"traceutil/trace.go:171","msg":"trace[1792902771] linearizableReadLoop","detail":"{readStateIndex:19564; appliedIndex:19563; }","duration":"108.640469ms","start":"2026-08-28T06:48:34.699294Z","end":"2026-08-28T06:48:34.807935Z","steps":["trace[1792902771] 'read index received' (duration: 33.660427ms)","trace[1792902771] 'applied index is now lower than readState.Index' (duration: 74.979332ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T06:48:34.807998Z","caller":"traceutil/trace.go:171","msg":"trace[450643513] transaction","detail":"{read_only:false; response_revision:18909; number_of_response:1; }","duration":"108.915256ms","start":"2026-08-28T06:48:34.699024Z","end":"2026-08-28T06:48:34.807939Z","steps":["trace[450643513] 'process raft request' (duration: 33.966764ms)","trace[450643513] 'compare' (duration: 74.838588ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T06:48:34.808149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.837455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-08-28T06:48:34.808172Z","caller":"traceutil/trace.go:171","msg":"trace[1911021728] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18909; }","duration":"108.905856ms","start":"2026-08-28T06:48:34.699259Z","end":"2026-08-28T06:48:34.808165Z","steps":["trace[1911021728] 'agreement among raft nodes before linearized reading' (duration: 108.788963ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:48:34.808252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.528009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-28T06:48:34.808312Z","caller":"traceutil/trace.go:171","msg":"trace[569283067] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18909; }","duration":"102.623542ms","start":"2026-08-28T06:48:34.705673Z","end":"2026-08-28T06:48:34.808296Z","steps":["trace[569283067] 'agreement among raft nodes before linearized reading' (duration: 102.492529ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:48:35.179075Z","caller":"traceutil/trace.go:171","msg":"trace[1927371567] transaction","detail":"{read_only:false; response_revision:18914; number_of_response:1; }","duration":"150.328254ms","start":"2026-08-28T06:48:35.028723Z","end":"2026-08-28T06:48:35.179052Z","steps":["trace[1927371567] 'process raft request' (duration: 150.269673ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:48:35.179089Z","caller":"traceutil/trace.go:171","msg":"trace[1121942576] transaction","detail":"{read_only:false; response_revision:18913; number_of_response:1; }","duration":"331.719024ms","start":"2026-08-28T06:48:34.847333Z","end":"2026-08-28T06:48:35.179052Z","steps":["trace[1121942576] 'process raft request' (duration: 253.618009ms)","trace[1121942576] 'compare' (duration: 77.571712ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T06:48:35.179228Z","caller":"traceutil/trace.go:171","msg":"trace[1291507850] linearizableReadLoop","detail":"{readStateIndex:19568; appliedIndex:19567; }","duration":"275.983605ms","start":"2026-08-28T06:48:34.903218Z","end":"2026-08-28T06:48:35.179202Z","steps":["trace[1291507850] 'read index received' (duration: 197.749378ms)","trace[1291507850] 'applied index is now lower than readState.Index' (duration: 78.231697ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T06:48:35.179256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T06:48:34.847313Z","time spent":"331.856187ms","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T06:48:35.179383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.14416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-28T06:48:35.179422Z","caller":"traceutil/trace.go:171","msg":"trace[2026700998] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:18914; }","duration":"276.226641ms","start":"2026-08-28T06:48:34.903184Z","end":"2026-08-28T06:48:35.179411Z","steps":["trace[2026700998] 'agreement among raft nodes before linearized reading' (duration: 276.133279ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:48:35.179476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.022612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-08-28T06:48:35.179511Z","caller":"traceutil/trace.go:171","msg":"trace[656838683] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:18914; }","duration":"110.108704ms","start":"2026-08-28T06:48:35.069389Z","end":"2026-08-28T06:48:35.179498Z","steps":["trace[656838683] 'agreement among raft nodes before linearized reading' (duration: 109.94807ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:48:35.521422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.356321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T06:48:35.521525Z","caller":"traceutil/trace.go:171","msg":"trace[1856986946] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:18914; }","duration":"204.509554ms","start":"2026-08-28T06:48:35.316995Z","end":"2026-08-28T06:48:35.521505Z","steps":["trace[1856986946] 'range keys from in-memory index tree' (duration: 204.239108ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:48:36.194089Z","caller":"traceutil/trace.go:171","msg":"trace[1298721148] transaction","detail":"{read_only:false; response_revision:18918; number_of_response:1; }","duration":"146.834481ms","start":"2026-08-28T06:48:36.047205Z","end":"2026-08-28T06:48:36.19404Z","steps":["trace[1298721148] 'process raft request' (duration: 146.580096ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:49:16.60309Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ee8e5a779fc25cea","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-28T06:49:16.610889Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-28T06:49:16.61102Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-28T06:49:26.194753Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17013} {"level":"info","ts":"2026-08-28T06:49:26.294603Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17013,"took":"96.286584ms","hash":2007444631,"current-db-size-bytes":55885824,"current-db-size":"56 MB","current-db-size-in-use-bytes":33681408,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-28T06:49:26.294689Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2007444631,"revision":17013,"compact-revision":14588} {"level":"info","ts":"2026-08-28T06:54:26.20439Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19564} {"level":"info","ts":"2026-08-28T06:54:26.321549Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19564,"took":"113.864411ms","hash":2641537709,"current-db-size-bytes":55885824,"current-db-size":"56 MB","current-db-size-in-use-bytes":30310400,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-28T06:54:26.32162Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2641537709,"revision":19564,"compact-revision":17013} {"level":"warn","ts":"2026-08-28T06:54:39.439397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.476965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T06:54:39.439498Z","caller":"traceutil/trace.go:171","msg":"trace[1935047989] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:21714; }","duration":"114.614597ms","start":"2026-08-28T06:54:39.324861Z","end":"2026-08-28T06:54:39.439476Z","steps":["trace[1935047989] 'count revisions from in-memory index tree' (duration: 114.413043ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:54:39.439411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.62011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T06:54:39.439734Z","caller":"traceutil/trace.go:171","msg":"trace[890082195] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21714; }","duration":"202.997479ms","start":"2026-08-28T06:54:39.236727Z","end":"2026-08-28T06:54:39.439724Z","steps":["trace[890082195] 'range keys from in-memory index tree' (duration: 202.515648ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:54:39.439826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.929251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-28T06:54:39.439931Z","caller":"traceutil/trace.go:171","msg":"trace[620490406] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:21714; }","duration":"117.087085ms","start":"2026-08-28T06:54:39.322824Z","end":"2026-08-28T06:54:39.439911Z","steps":["trace[620490406] 'count revisions from in-memory index tree' (duration: 116.818288ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:54:39.440106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.40248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:19264"} {"level":"info","ts":"2026-08-28T06:54:39.440244Z","caller":"traceutil/trace.go:171","msg":"trace[611377789] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:21714; }","duration":"184.768328ms","start":"2026-08-28T06:54:39.255447Z","end":"2026-08-28T06:54:39.440215Z","steps":["trace[611377789] 'range keys from in-memory index tree' (duration: 184.093703ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:54:49.785082Z","caller":"traceutil/trace.go:171","msg":"trace[158420882] transaction","detail":"{read_only:false; response_revision:21767; number_of_response:1; }","duration":"160.517878ms","start":"2026-08-28T06:54:49.624539Z","end":"2026-08-28T06:54:49.785057Z","steps":["trace[158420882] 'process raft request' (duration: 160.373765ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:54:49.789262Z","caller":"traceutil/trace.go:171","msg":"trace[531956377] transaction","detail":"{read_only:false; response_revision:21768; number_of_response:1; }","duration":"128.465128ms","start":"2026-08-28T06:54:49.66078Z","end":"2026-08-28T06:54:49.789245Z","steps":["trace[531956377] 'process raft request' (duration: 128.354475ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:54:49.789443Z","caller":"traceutil/trace.go:171","msg":"trace[1435951027] transaction","detail":"{read_only:false; response_revision:21769; number_of_response:1; }","duration":"113.504742ms","start":"2026-08-28T06:54:49.675929Z","end":"2026-08-28T06:54:49.789434Z","steps":["trace[1435951027] 'process raft request' (duration: 113.279837ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:54:49.789582Z","caller":"traceutil/trace.go:171","msg":"trace[468870706] linearizableReadLoop","detail":"{readStateIndex:22518; appliedIndex:22516; }","duration":"124.011916ms","start":"2026-08-28T06:54:49.665543Z","end":"2026-08-28T06:54:49.789554Z","steps":["trace[468870706] 'read index received' (duration: 119.384309ms)","trace[468870706] 'applied index is now lower than readState.Index' (duration: 4.625837ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T06:54:49.78971Z","caller":"traceutil/trace.go:171","msg":"trace[686735940] transaction","detail":"{read_only:false; response_revision:21770; number_of_response:1; }","duration":"103.104862ms","start":"2026-08-28T06:54:49.686595Z","end":"2026-08-28T06:54:49.7897Z","steps":["trace[686735940] 'process raft request' (duration: 102.785485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:54:49.789752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.222151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T06:54:49.789803Z","caller":"traceutil/trace.go:171","msg":"trace[760542564] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21770; }","duration":"124.330873ms","start":"2026-08-28T06:54:49.665457Z","end":"2026-08-28T06:54:49.789788Z","steps":["trace[760542564] 'agreement among raft nodes before linearized reading' (duration: 124.19622ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:54:55.322643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.62975ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-08-28T06:54:55.322754Z","caller":"traceutil/trace.go:171","msg":"trace[146750510] linearizableReadLoop","detail":"{readStateIndex:22546; appliedIndex:22545; }","duration":"228.123759ms","start":"2026-08-28T06:54:55.094609Z","end":"2026-08-28T06:54:55.322733Z","steps":["trace[146750510] 'read index received' (duration: 51.379887ms)","trace[146750510] 'applied index is now lower than readState.Index' (duration: 176.742542ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T06:54:55.323077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.353942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-28T06:54:55.323244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.56596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-28T06:54:55.323294Z","caller":"traceutil/trace.go:171","msg":"trace[634111167] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:21795; }","duration":"228.724814ms","start":"2026-08-28T06:54:55.094554Z","end":"2026-08-28T06:54:55.323279Z","steps":["trace[634111167] 'agreement among raft nodes before linearized reading' (duration: 228.266063ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:54:55.323354Z","caller":"traceutil/trace.go:171","msg":"trace[1267731776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21795; }","duration":"143.457084ms","start":"2026-08-28T06:54:55.17966Z","end":"2026-08-28T06:54:55.323117Z","steps":["trace[1267731776] 'agreement among raft nodes before linearized reading' (duration: 143.248729ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:54:55.478712Z","caller":"traceutil/trace.go:171","msg":"trace[1874304583] transaction","detail":"{read_only:false; response_revision:21796; number_of_response:1; }","duration":"150.533857ms","start":"2026-08-28T06:54:55.328143Z","end":"2026-08-28T06:54:55.478676Z","steps":["trace[1874304583] 'process raft request' (duration: 150.332292ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:54:55.959879Z","caller":"traceutil/trace.go:171","msg":"trace[543777053] linearizableReadLoop","detail":"{readStateIndex:22548; appliedIndex:22547; }","duration":"149.058093ms","start":"2026-08-28T06:54:55.810767Z","end":"2026-08-28T06:54:55.959825Z","steps":["trace[543777053] 'read index received' (duration: 148.661295ms)","trace[543777053] 'applied index is now lower than readState.Index' (duration: 395.808µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T06:54:55.959937Z","caller":"traceutil/trace.go:171","msg":"trace[1075344323] transaction","detail":"{read_only:false; response_revision:21797; number_of_response:1; }","duration":"150.831824ms","start":"2026-08-28T06:54:55.809071Z","end":"2026-08-28T06:54:55.959903Z","steps":["trace[1075344323] 'process raft request' (duration: 150.369254ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:54:55.960245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.457732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-28T06:54:55.960739Z","caller":"traceutil/trace.go:171","msg":"trace[816580249] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21797; }","duration":"150.013315ms","start":"2026-08-28T06:54:55.810709Z","end":"2026-08-28T06:54:55.960723Z","steps":["trace[816580249] 'agreement among raft nodes before linearized reading' (duration: 149.325809ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:54:56.121882Z","caller":"traceutil/trace.go:171","msg":"trace[1412707461] transaction","detail":"{read_only:false; response_revision:21798; number_of_response:1; }","duration":"311.104118ms","start":"2026-08-28T06:54:55.81075Z","end":"2026-08-28T06:54:56.121854Z","steps":["trace[1412707461] 'process raft request' (duration: 234.663322ms)","trace[1412707461] 'compare' (duration: 76.023465ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T06:54:56.12201Z","caller":"traceutil/trace.go:171","msg":"trace[1092123728] transaction","detail":"{read_only:false; response_revision:21800; number_of_response:1; }","duration":"156.854374ms","start":"2026-08-28T06:54:55.965131Z","end":"2026-08-28T06:54:56.121986Z","steps":["trace[1092123728] 'process raft request' (duration: 156.748531ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:54:56.12201Z","caller":"traceutil/trace.go:171","msg":"trace[251133091] transaction","detail":"{read_only:false; response_revision:21799; number_of_response:1; }","duration":"308.266012ms","start":"2026-08-28T06:54:55.813694Z","end":"2026-08-28T06:54:56.12196Z","steps":["trace[251133091] 'process raft request' (duration: 308.055296ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:54:56.122072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T06:54:55.810724Z","time spent":"311.276651ms","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T06:54:56.122164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T06:54:55.813676Z","time spent":"308.399325ms","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T06:54:56.55095Z","caller":"traceutil/trace.go:171","msg":"trace[486742609] transaction","detail":"{read_only:false; response_revision:21801; number_of_response:1; }","duration":"164.213733ms","start":"2026-08-28T06:54:56.38671Z","end":"2026-08-28T06:54:56.550924Z","steps":["trace[486742609] 'process raft request' (duration: 163.963168ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:59:07.522086Z","caller":"traceutil/trace.go:171","msg":"trace[322817251] transaction","detail":"{read_only:false; response_revision:24350; number_of_response:1; }","duration":"115.710522ms","start":"2026-08-28T06:59:07.406353Z","end":"2026-08-28T06:59:07.522064Z","steps":["trace[322817251] 'process raft request' (duration: 115.528258ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:59:07.926775Z","caller":"traceutil/trace.go:171","msg":"trace[798759812] transaction","detail":"{read_only:false; response_revision:24353; number_of_response:1; }","duration":"343.77832ms","start":"2026-08-28T06:59:07.582969Z","end":"2026-08-28T06:59:07.926747Z","steps":["trace[798759812] 'process raft request' (duration: 343.604516ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T06:59:07.92696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T06:59:07.582936Z","time spent":"343.919744ms","remote":"127.0.0.1:48940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T06:59:07.936798Z","caller":"traceutil/trace.go:171","msg":"trace[78214547] transaction","detail":"{read_only:false; response_revision:24354; number_of_response:1; }","duration":"225.203562ms","start":"2026-08-28T06:59:07.711566Z","end":"2026-08-28T06:59:07.93677Z","steps":["trace[78214547] 'process raft request' (duration: 224.986347ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:59:07.937104Z","caller":"traceutil/trace.go:171","msg":"trace[685719954] transaction","detail":"{read_only:false; response_revision:24355; number_of_response:1; }","duration":"137.305061ms","start":"2026-08-28T06:59:07.799771Z","end":"2026-08-28T06:59:07.937076Z","steps":["trace[685719954] 'process raft request' (duration: 136.930272ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:59:07.937194Z","caller":"traceutil/trace.go:171","msg":"trace[863372544] linearizableReadLoop","detail":"{readStateIndex:25184; appliedIndex:25181; }","duration":"111.407484ms","start":"2026-08-28T06:59:07.825752Z","end":"2026-08-28T06:59:07.93716Z","steps":["trace[863372544] 'read index received' (duration: 100.916741ms)","trace[863372544] 'applied index is now lower than readState.Index' (duration: 10.489273ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T06:59:07.937376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.611918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-28T06:59:07.937453Z","caller":"traceutil/trace.go:171","msg":"trace[401380392] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:24356; }","duration":"111.740491ms","start":"2026-08-28T06:59:07.825694Z","end":"2026-08-28T06:59:07.937434Z","steps":["trace[401380392] 'agreement among raft nodes before linearized reading' (duration: 111.605988ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T06:59:26.210722Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21645} {"level":"info","ts":"2026-08-28T06:59:26.336803Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21645,"took":"122.329946ms","hash":2168348066,"current-db-size-bytes":55885824,"current-db-size":"56 MB","current-db-size-in-use-bytes":42184704,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-08-28T06:59:26.336888Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2168348066,"revision":21645,"compact-revision":19564} {"level":"info","ts":"2026-08-28T07:00:29.384399Z","caller":"traceutil/trace.go:171","msg":"trace[1066839801] linearizableReadLoop","detail":"{readStateIndex:26173; appliedIndex:26172; }","duration":"117.998677ms","start":"2026-08-28T07:00:29.266374Z","end":"2026-08-28T07:00:29.384372Z","steps":["trace[1066839801] 'read index received' (duration: 37.410994ms)","trace[1066839801] 'applied index is now lower than readState.Index' (duration: 80.586903ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T07:00:29.384635Z","caller":"traceutil/trace.go:171","msg":"trace[1653119011] transaction","detail":"{read_only:false; response_revision:25322; number_of_response:1; }","duration":"122.677794ms","start":"2026-08-28T07:00:29.26189Z","end":"2026-08-28T07:00:29.384568Z","steps":["trace[1653119011] 'process raft request' (duration: 94.664957ms)","trace[1653119011] 'compare' (duration: 27.688869ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T07:00:29.384992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.418195ms","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-08-28T07:00:29.38511Z","caller":"traceutil/trace.go:171","msg":"trace[1950057152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25322; }","duration":"118.788785ms","start":"2026-08-28T07:00:29.2663Z","end":"2026-08-28T07:00:29.385089Z","steps":["trace[1950057152] 'agreement among raft nodes before linearized reading' (duration: 118.198081ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T07:03:20.811826Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006b62.wal"} {"level":"info","ts":"2026-08-28T07:04:26.217343Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24521} {"level":"info","ts":"2026-08-28T07:04:26.378249Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24521,"took":"156.632745ms","hash":1341272153,"current-db-size-bytes":55885824,"current-db-size":"56 MB","current-db-size-in-use-bytes":33239040,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-28T07:04:26.378313Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1341272153,"revision":24521,"compact-revision":21645} {"level":"info","ts":"2026-08-28T07:08:47.099567Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ee8e5a779fc25cea","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-28T07:08:47.106134Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-28T07:08:47.106262Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-28T07:09:26.225619Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27079} {"level":"info","ts":"2026-08-28T07:09:26.357745Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27079,"took":"128.804591ms","hash":2833235914,"current-db-size-bytes":55885824,"current-db-size":"56 MB","current-db-size-in-use-bytes":29724672,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-28T07:09:26.357818Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2833235914,"revision":27079,"compact-revision":24521}