{"level":"warn","ts":"2026-10-26T19:25:00.349741Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-26T19:25:00.349874Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.4: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.4:2380","--initial-cluster=instance=https://162.253.55.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.4:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-26T19:25:00.349968Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-26T19:25:00.349985Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.4:2380"]} {"level":"info","ts":"2026-10-26T19:25:00.35001Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-26T19:25:00.350944Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.4:2379"]} {"level":"info","ts":"2026-10-26T19:25:00.351103Z","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.4:2380"],"listen-peer-urls":["https://162.253.55.4:2380"],"advertise-client-urls":["https://162.253.55.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.4:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-26T19:25:00.36387Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"12.469322ms"} {"level":"info","ts":"2026-10-26T19:25:00.39057Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"caafea9f270da559","cluster-id":"271ff399bb4addea"} {"level":"info","ts":"2026-10-26T19:25:00.390707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 switched to configuration voters=()"} {"level":"info","ts":"2026-10-26T19:25:00.390744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 became follower at term 0"} {"level":"info","ts":"2026-10-26T19:25:00.390771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft caafea9f270da559 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-26T19:25:00.390802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 became follower at term 1"} {"level":"info","ts":"2026-10-26T19:25:00.390866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 switched to configuration voters=(14605150085861713241)"} {"level":"warn","ts":"2026-10-26T19:25:00.408625Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-26T19:25:00.411852Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-26T19:25:00.413392Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-26T19:25:00.416511Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"caafea9f270da559","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-26T19:25:00.416802Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"caafea9f270da559","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-26T19:25:00.417013Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-26T19:25:00.41714Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-26T19:25:00.41716Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-26T19:25:00.418152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 switched to configuration voters=(14605150085861713241)"} {"level":"info","ts":"2026-10-26T19:25:00.418329Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"271ff399bb4addea","local-member-id":"caafea9f270da559","added-peer-id":"caafea9f270da559","added-peer-peer-urls":["https://162.253.55.4:2380"]} {"level":"info","ts":"2026-10-26T19:25:00.422632Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-26T19:25:00.422762Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.4:2380"} {"level":"info","ts":"2026-10-26T19:25:00.422846Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.4:2380"} {"level":"info","ts":"2026-10-26T19:25:00.42296Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"caafea9f270da559","initial-advertise-peer-urls":["https://162.253.55.4:2380"],"listen-peer-urls":["https://162.253.55.4:2380"],"advertise-client-urls":["https://162.253.55.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-26T19:25:00.423036Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-26T19:25:01.294935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 is starting a new election at term 1"} {"level":"info","ts":"2026-10-26T19:25:01.295026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-26T19:25:01.295053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 received MsgPreVoteResp from caafea9f270da559 at term 1"} {"level":"info","ts":"2026-10-26T19:25:01.295076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 became candidate at term 2"} {"level":"info","ts":"2026-10-26T19:25:01.295088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 received MsgVoteResp from caafea9f270da559 at term 2"} {"level":"info","ts":"2026-10-26T19:25:01.295105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 became leader at term 2"} {"level":"info","ts":"2026-10-26T19:25:01.295119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: caafea9f270da559 elected leader caafea9f270da559 at term 2"} {"level":"info","ts":"2026-10-26T19:25:01.296563Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"caafea9f270da559","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.4:2379]}","request-path":"/0/members/caafea9f270da559/attributes","cluster-id":"271ff399bb4addea","publish-timeout":"7s"} {"level":"info","ts":"2026-10-26T19:25:01.296614Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T19:25:01.296671Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:25:01.296661Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T19:25:01.29717Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-26T19:25:01.297274Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-26T19:25:01.298636Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"271ff399bb4addea","local-member-id":"caafea9f270da559","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:25:01.2988Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:25:01.298847Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:25:01.299258Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-26T19:25:01.301799Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.4:2379"} {"level":"info","ts":"2026-10-26T19:25:17.736329Z","caller":"traceutil/trace.go:171","msg":"trace[484291773] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"166.773995ms","start":"2026-10-26T19:25:17.569519Z","end":"2026-10-26T19:25:17.736293Z","steps":["trace[484291773] 'process raft request' (duration: 166.569249ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:25:53.530653Z","caller":"traceutil/trace.go:171","msg":"trace[1281763157] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"281.525551ms","start":"2026-10-26T19:25:53.249089Z","end":"2026-10-26T19:25:53.530615Z","steps":["trace[1281763157] 'process raft request' (duration: 281.233033ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:25:53.577256Z","caller":"traceutil/trace.go:171","msg":"trace[988488466] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"242.732994ms","start":"2026-10-26T19:25:53.334461Z","end":"2026-10-26T19:25:53.577194Z","steps":["trace[988488466] 'process raft request' (duration: 242.006774ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:25:53.986262Z","caller":"traceutil/trace.go:171","msg":"trace[1678320044] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:675; }","duration":"109.978997ms","start":"2026-10-26T19:25:53.876258Z","end":"2026-10-26T19:25:53.986237Z","steps":["trace[1678320044] 'read index received' (duration: 109.73831ms)","trace[1678320044] 'applied index is now lower than readState.Index' (duration: 239.616µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:25:53.9863Z","caller":"traceutil/trace.go:171","msg":"trace[1537142006] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"332.038126ms","start":"2026-10-26T19:25:53.654234Z","end":"2026-10-26T19:25:53.986272Z","steps":["trace[1537142006] 'process raft request' (duration: 331.822911ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:25:53.986567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.292365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nrlf4\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-10-26T19:25:53.986688Z","caller":"traceutil/trace.go:171","msg":"trace[710755518] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nrlf4; range_end:; response_count:1; response_revision:650; }","duration":"110.459959ms","start":"2026-10-26T19:25:53.876207Z","end":"2026-10-26T19:25:53.986667Z","steps":["trace[710755518] 'agreement among raft nodes before linearized reading' (duration: 110.12927ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:25:53.986918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:25:53.654194Z","time spent":"332.315884ms","remote":"127.0.0.1:34922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T19:26:41.594597Z","caller":"traceutil/trace.go:171","msg":"trace[1242864093] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"339.618874ms","start":"2026-10-26T19:26:41.254936Z","end":"2026-10-26T19:26:41.594555Z","steps":["trace[1242864093] 'process raft request' (duration: 339.036628ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:26:41.595099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:26:41.254892Z","time spent":"339.83819ms","remote":"127.0.0.1:34842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:26:41.623642Z","caller":"traceutil/trace.go:171","msg":"trace[274567076] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"256.952361ms","start":"2026-10-26T19:26:41.36646Z","end":"2026-10-26T19:26:41.623413Z","steps":["trace[274567076] 'process raft request' (duration: 256.806527ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:03.48615Z","caller":"traceutil/trace.go:171","msg":"trace[819238130] transaction","detail":"{read_only:false; response_revision:1477; number_of_response:1; }","duration":"130.188485ms","start":"2026-10-26T19:27:03.355929Z","end":"2026-10-26T19:27:03.486117Z","steps":["trace[819238130] 'process raft request' (duration: 129.978389ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:29.523292Z","caller":"traceutil/trace.go:171","msg":"trace[758629441] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"101.429377ms","start":"2026-10-26T19:27:29.421836Z","end":"2026-10-26T19:27:29.523266Z","steps":["trace[758629441] 'process raft request' (duration: 101.246132ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:29.964015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.584628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:27:29.964247Z","caller":"traceutil/trace.go:171","msg":"trace[1699957790] linearizableReadLoop","detail":"{readStateIndex:1972; appliedIndex:1970; }","duration":"121.965768ms","start":"2026-10-26T19:27:29.842256Z","end":"2026-10-26T19:27:29.964222Z","steps":["trace[1699957790] 'read index received' (duration: 108.808189ms)","trace[1699957790] 'applied index is now lower than readState.Index' (duration: 13.156459ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:27:29.964326Z","caller":"traceutil/trace.go:171","msg":"trace[1724562634] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"137.496875ms","start":"2026-10-26T19:27:29.826794Z","end":"2026-10-26T19:27:29.964291Z","steps":["trace[1724562634] 'process raft request' (duration: 137.361771ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:29.964403Z","caller":"traceutil/trace.go:171","msg":"trace[2058112273] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"424.10737ms","start":"2026-10-26T19:27:29.540247Z","end":"2026-10-26T19:27:29.964354Z","steps":["trace[2058112273] 'process raft request' (duration: 277.851353ms)","trace[2058112273] 'compare' (duration: 145.444753ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:27:29.964582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:27:29.540201Z","time spent":"424.288145ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11470,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:27:29.964614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.328739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-26T19:27:29.964747Z","caller":"traceutil/trace.go:171","msg":"trace[439060342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1897; }","duration":"122.515514ms","start":"2026-10-26T19:27:29.842198Z","end":"2026-10-26T19:27:29.964714Z","steps":["trace[439060342] 'agreement among raft nodes before linearized reading' (duration: 122.119703ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:30.320841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.330764ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-26T19:27:30.320987Z","caller":"traceutil/trace.go:171","msg":"trace[1749378976] linearizableReadLoop","detail":"{readStateIndex:1980; appliedIndex:1979; }","duration":"199.362265ms","start":"2026-10-26T19:27:30.121601Z","end":"2026-10-26T19:27:30.320963Z","steps":["trace[1749378976] 'read index received' (duration: 7.796354ms)","trace[1749378976] 'applied index is now lower than readState.Index' (duration: 191.563791ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:27:30.32131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.216353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-26T19:27:30.321377Z","caller":"traceutil/trace.go:171","msg":"trace[1562340067] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1904; }","duration":"165.344686ms","start":"2026-10-26T19:27:30.156007Z","end":"2026-10-26T19:27:30.321352Z","steps":["trace[1562340067] 'agreement among raft nodes before linearized reading' (duration: 165.100209ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:30.32138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.735096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-26T19:27:30.321495Z","caller":"traceutil/trace.go:171","msg":"trace[1428847145] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1904; }","duration":"199.955492ms","start":"2026-10-26T19:27:30.12152Z","end":"2026-10-26T19:27:30.321476Z","steps":["trace[1428847145] 'agreement among raft nodes before linearized reading' (duration: 199.575711ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:30.321658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.687864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0aac5c37-8a2e-4bab-b86e-4452e026416f\" ","response":"range_response_count:1 size:3745"} {"level":"info","ts":"2026-10-26T19:27:30.321811Z","caller":"traceutil/trace.go:171","msg":"trace[1917328360] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0aac5c37-8a2e-4bab-b86e-4452e026416f; range_end:; response_count:1; response_revision:1904; }","duration":"142.841799ms","start":"2026-10-26T19:27:30.178899Z","end":"2026-10-26T19:27:30.321741Z","steps":["trace[1917328360] 'agreement among raft nodes before linearized reading' (duration: 142.559591ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:44.202898Z","caller":"traceutil/trace.go:171","msg":"trace[446787188] transaction","detail":"{read_only:false; response_revision:2021; number_of_response:1; }","duration":"181.908412ms","start":"2026-10-26T19:27:44.020945Z","end":"2026-10-26T19:27:44.202854Z","steps":["trace[446787188] 'process raft request' (duration: 181.648045ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:28:21.037633Z","caller":"traceutil/trace.go:171","msg":"trace[1151713341] transaction","detail":"{read_only:false; response_revision:2231; number_of_response:1; }","duration":"105.12923ms","start":"2026-10-26T19:28:20.932471Z","end":"2026-10-26T19:28:21.0376Z","steps":["trace[1151713341] 'process raft request' (duration: 104.951315ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:28:26.860683Z","caller":"traceutil/trace.go:171","msg":"trace[2068221559] linearizableReadLoop","detail":"{readStateIndex:2348; appliedIndex:2347; }","duration":"121.378865ms","start":"2026-10-26T19:28:26.739273Z","end":"2026-10-26T19:28:26.860652Z","steps":["trace[2068221559] 'read index received' (duration: 121.269072ms)","trace[2068221559] 'applied index is now lower than readState.Index' (duration: 108.253µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:28:26.860761Z","caller":"traceutil/trace.go:171","msg":"trace[526113166] transaction","detail":"{read_only:false; response_revision:2259; number_of_response:1; }","duration":"133.398274ms","start":"2026-10-26T19:28:26.727342Z","end":"2026-10-26T19:28:26.86074Z","steps":["trace[526113166] 'process raft request' (duration: 133.120577ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:28:26.860959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.660033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-26T19:28:26.861017Z","caller":"traceutil/trace.go:171","msg":"trace[1673425300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2259; }","duration":"121.762475ms","start":"2026-10-26T19:28:26.739239Z","end":"2026-10-26T19:28:26.861001Z","steps":["trace[1673425300] 'agreement among raft nodes before linearized reading' (duration: 121.57159ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:28:27.041353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.29025ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:28:27.041478Z","caller":"traceutil/trace.go:171","msg":"trace[191937427] linearizableReadLoop","detail":"{readStateIndex:2350; appliedIndex:2348; }","duration":"149.880066ms","start":"2026-10-26T19:28:26.891584Z","end":"2026-10-26T19:28:27.041464Z","steps":["trace[191937427] 'read index received' (duration: 27.323078ms)","trace[191937427] 'applied index is now lower than readState.Index' (duration: 122.555668ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:28:27.04163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.04461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:28:27.041631Z","caller":"traceutil/trace.go:171","msg":"trace[2050375569] transaction","detail":"{read_only:false; response_revision:2260; number_of_response:1; }","duration":"213.682904ms","start":"2026-10-26T19:28:26.827927Z","end":"2026-10-26T19:28:27.04161Z","steps":["trace[2050375569] 'process raft request' (duration: 91.052124ms)","trace[2050375569] 'compare' (duration: 122.195338ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:28:27.041658Z","caller":"traceutil/trace.go:171","msg":"trace[304107675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2261; }","duration":"150.113962ms","start":"2026-10-26T19:28:26.891535Z","end":"2026-10-26T19:28:27.041649Z","steps":["trace[304107675] 'agreement among raft nodes before linearized reading' (duration: 150.05506ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:28:27.041647Z","caller":"traceutil/trace.go:171","msg":"trace[429167225] transaction","detail":"{read_only:false; response_revision:2261; number_of_response:1; }","duration":"176.428033ms","start":"2026-10-26T19:28:26.86516Z","end":"2026-10-26T19:28:27.041588Z","steps":["trace[429167225] 'process raft request' (duration: 176.265579ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:28:27.200746Z","caller":"traceutil/trace.go:171","msg":"trace[1790278242] transaction","detail":"{read_only:false; response_revision:2262; number_of_response:1; }","duration":"132.665544ms","start":"2026-10-26T19:28:27.068042Z","end":"2026-10-26T19:28:27.200707Z","steps":["trace[1790278242] 'process raft request' (duration: 132.404077ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:29:24.055762Z","caller":"traceutil/trace.go:171","msg":"trace[2087284163] linearizableReadLoop","detail":"{readStateIndex:2847; appliedIndex:2846; }","duration":"163.217559ms","start":"2026-10-26T19:29:23.892509Z","end":"2026-10-26T19:29:24.055726Z","steps":["trace[2087284163] 'read index received' (duration: 162.87062ms)","trace[2087284163] 'applied index is now lower than readState.Index' (duration: 345.079µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:29:24.055871Z","caller":"traceutil/trace.go:171","msg":"trace[773478645] transaction","detail":"{read_only:false; response_revision:2738; number_of_response:1; }","duration":"202.814429ms","start":"2026-10-26T19:29:23.852999Z","end":"2026-10-26T19:29:24.055813Z","steps":["trace[773478645] 'process raft request' (duration: 202.49589ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:29:24.055959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.432316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:29:24.05602Z","caller":"traceutil/trace.go:171","msg":"trace[2075267575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2738; }","duration":"163.544009ms","start":"2026-10-26T19:29:23.892459Z","end":"2026-10-26T19:29:24.056003Z","steps":["trace[2075267575] 'agreement among raft nodes before linearized reading' (duration: 163.426316ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:29:57.893597Z","caller":"traceutil/trace.go:171","msg":"trace[147777310] transaction","detail":"{read_only:false; response_revision:2941; number_of_response:1; }","duration":"115.204017ms","start":"2026-10-26T19:29:57.778351Z","end":"2026-10-26T19:29:57.893555Z","steps":["trace[147777310] 'process raft request' (duration: 114.95305ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:29:58.092196Z","caller":"traceutil/trace.go:171","msg":"trace[320004503] transaction","detail":"{read_only:false; response_revision:2942; number_of_response:1; }","duration":"162.535171ms","start":"2026-10-26T19:29:57.929607Z","end":"2026-10-26T19:29:58.092142Z","steps":["trace[320004503] 'process raft request' (duration: 160.268398ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:33:20.518349Z","caller":"traceutil/trace.go:171","msg":"trace[1691126280] linearizableReadLoop","detail":"{readStateIndex:4373; appliedIndex:4371; }","duration":"261.778037ms","start":"2026-10-26T19:33:20.256539Z","end":"2026-10-26T19:33:20.518317Z","steps":["trace[1691126280] 'read index received' (duration: 3.950139ms)","trace[1691126280] 'applied index is now lower than readState.Index' (duration: 257.826558ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:33:20.518517Z","caller":"traceutil/trace.go:171","msg":"trace[1367684880] transaction","detail":"{read_only:false; response_revision:4208; number_of_response:1; }","duration":"284.249919ms","start":"2026-10-26T19:33:20.234254Z","end":"2026-10-26T19:33:20.518504Z","steps":["trace[1367684880] 'process raft request' (duration: 247.681827ms)","trace[1367684880] 'compare' (duration: 36.048238ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:33:20.518596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.664054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:33:20.518679Z","caller":"traceutil/trace.go:171","msg":"trace[980282607] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:4208; }","duration":"179.802948ms","start":"2026-10-26T19:33:20.338855Z","end":"2026-10-26T19:33:20.518658Z","steps":["trace[980282607] 'agreement among raft nodes before linearized reading' (duration: 179.669895ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:20.518863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.2548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-26T19:33:20.518999Z","caller":"traceutil/trace.go:171","msg":"trace[331023476] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4208; }","duration":"262.489967ms","start":"2026-10-26T19:33:20.256477Z","end":"2026-10-26T19:33:20.518967Z","steps":["trace[331023476] 'agreement among raft nodes before linearized reading' (duration: 262.084746ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:48.071449Z","caller":"traceutil/trace.go:171","msg":"trace[1866159337] linearizableReadLoop","detail":"{readStateIndex:5344; appliedIndex:5343; }","duration":"183.932037ms","start":"2026-10-26T19:34:47.887471Z","end":"2026-10-26T19:34:48.071403Z","steps":["trace[1866159337] 'read index received' (duration: 180.503403ms)","trace[1866159337] 'applied index is now lower than readState.Index' (duration: 3.427124ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:34:48.071914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.40217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/kube-prometheus-stack-kubelet\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:34:48.072053Z","caller":"traceutil/trace.go:171","msg":"trace[177294011] range","detail":"{range_begin:/registry/services/specs/kube-system/kube-prometheus-stack-kubelet; range_end:; response_count:0; response_revision:5141; }","duration":"184.583765ms","start":"2026-10-26T19:34:47.887434Z","end":"2026-10-26T19:34:48.072018Z","steps":["trace[177294011] 'agreement among raft nodes before linearized reading' (duration: 184.308697ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:48.071942Z","caller":"traceutil/trace.go:171","msg":"trace[971979561] transaction","detail":"{read_only:false; response_revision:5141; number_of_response:1; }","duration":"229.823962ms","start":"2026-10-26T19:34:47.841921Z","end":"2026-10-26T19:34:48.071745Z","steps":["trace[971979561] 'process raft request' (duration: 226.030817ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:48.078578Z","caller":"traceutil/trace.go:171","msg":"trace[1212817281] transaction","detail":"{read_only:false; response_revision:5142; number_of_response:1; }","duration":"133.071005ms","start":"2026-10-26T19:34:47.945463Z","end":"2026-10-26T19:34:48.078534Z","steps":["trace[1212817281] 'process raft request' (duration: 132.664284ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:48.078643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.400365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:34:48.078726Z","caller":"traceutil/trace.go:171","msg":"trace[530228566] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5144; }","duration":"186.556749ms","start":"2026-10-26T19:34:47.892147Z","end":"2026-10-26T19:34:48.078704Z","steps":["trace[530228566] 'agreement among raft nodes before linearized reading' (duration: 186.390675ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:48.079067Z","caller":"traceutil/trace.go:171","msg":"trace[1405242713] transaction","detail":"{read_only:false; response_revision:5144; number_of_response:1; }","duration":"105.866727ms","start":"2026-10-26T19:34:47.97318Z","end":"2026-10-26T19:34:48.079047Z","steps":["trace[1405242713] 'process raft request' (duration: 105.27742ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:48.079138Z","caller":"traceutil/trace.go:171","msg":"trace[891396056] transaction","detail":"{read_only:false; response_revision:5143; number_of_response:1; }","duration":"123.245486ms","start":"2026-10-26T19:34:47.95587Z","end":"2026-10-26T19:34:48.079116Z","steps":["trace[891396056] 'process raft request' (duration: 122.482865ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:48.079334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.867328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-10-26T19:34:48.07939Z","caller":"traceutil/trace.go:171","msg":"trace[2017457837] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5144; }","duration":"133.984351ms","start":"2026-10-26T19:34:47.945391Z","end":"2026-10-26T19:34:48.079376Z","steps":["trace[2017457837] 'agreement among raft nodes before linearized reading' (duration: 133.777775ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:48.079374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.784879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-10-26T19:34:48.079496Z","caller":"traceutil/trace.go:171","msg":"trace[905858098] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5144; }","duration":"148.983394ms","start":"2026-10-26T19:34:47.93048Z","end":"2026-10-26T19:34:48.079463Z","steps":["trace[905858098] 'agreement among raft nodes before linearized reading' (duration: 148.580383ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:57.225515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.124364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:34:57.225863Z","caller":"traceutil/trace.go:171","msg":"trace[1440086571] transaction","detail":"{read_only:false; response_revision:5461; number_of_response:1; }","duration":"350.432724ms","start":"2026-10-26T19:34:56.875361Z","end":"2026-10-26T19:34:57.225794Z","steps":["trace[1440086571] 'process raft request' (duration: 88.907469ms)","trace[1440086571] 'compare' (duration: 260.97201ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:34:57.225985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:34:56.875336Z","time spent":"350.597839ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4934,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:34:57.226044Z","caller":"traceutil/trace.go:171","msg":"trace[293113887] linearizableReadLoop","detail":"{readStateIndex:5678; appliedIndex:5674; }","duration":"336.314715ms","start":"2026-10-26T19:34:56.88971Z","end":"2026-10-26T19:34:57.226025Z","steps":["trace[293113887] 'read index received' (duration: 74.654197ms)","trace[293113887] 'applied index is now lower than readState.Index' (duration: 261.658678ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:34:57.226144Z","caller":"traceutil/trace.go:171","msg":"trace[1083286818] transaction","detail":"{read_only:false; response_revision:5464; number_of_response:1; }","duration":"348.290495ms","start":"2026-10-26T19:34:56.877839Z","end":"2026-10-26T19:34:57.226129Z","steps":["trace[1083286818] 'process raft request' (duration: 348.032208ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:57.226228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:34:56.8778Z","time spent":"348.382998ms","remote":"127.0.0.1:35012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5068,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:34:57.226147Z","caller":"traceutil/trace.go:171","msg":"trace[1590703185] transaction","detail":"{read_only:false; response_revision:5465; number_of_response:1; }","duration":"288.55685ms","start":"2026-10-26T19:34:56.93758Z","end":"2026-10-26T19:34:57.226137Z","steps":["trace[1590703185] 'process raft request' (duration: 288.369084ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:57.226317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.836796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-26T19:34:57.226278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.542871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:34:57.226357Z","caller":"traceutil/trace.go:171","msg":"trace[1224813363] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5465; }","duration":"113.936808ms","start":"2026-10-26T19:34:57.112411Z","end":"2026-10-26T19:34:57.226348Z","steps":["trace[1224813363] 'agreement among raft nodes before linearized reading' (duration: 113.787384ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:57.22632Z","caller":"traceutil/trace.go:171","msg":"trace[13543240] transaction","detail":"{read_only:false; response_revision:5462; number_of_response:1; }","duration":"350.567207ms","start":"2026-10-26T19:34:56.87571Z","end":"2026-10-26T19:34:57.226277Z","steps":["trace[13543240] 'process raft request' (duration: 349.979091ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:57.226387Z","caller":"traceutil/trace.go:171","msg":"trace[1583298308] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5465; }","duration":"336.708976ms","start":"2026-10-26T19:34:56.889652Z","end":"2026-10-26T19:34:57.226361Z","steps":["trace[1583298308] 'agreement among raft nodes before linearized reading' (duration: 336.50648ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:57.226413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.144822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-02db23bc-69e2-430c-81d4-3d02e480cde4\" ","response":"range_response_count:1 size:3599"} {"level":"warn","ts":"2026-10-26T19:34:57.226439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:34:56.889638Z","time spent":"336.793729ms","remote":"127.0.0.1:34634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-26T19:34:57.22648Z","caller":"traceutil/trace.go:171","msg":"trace[1385469513] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-02db23bc-69e2-430c-81d4-3d02e480cde4; range_end:; response_count:1; response_revision:5465; }","duration":"327.260076ms","start":"2026-10-26T19:34:56.899203Z","end":"2026-10-26T19:34:57.226463Z","steps":["trace[1385469513] 'agreement among raft nodes before linearized reading' (duration: 326.991818ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:57.226517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:34:56.899186Z","time spent":"327.322148ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3622,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-02db23bc-69e2-430c-81d4-3d02e480cde4\" "} {"level":"warn","ts":"2026-10-26T19:34:57.226525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:34:56.875683Z","time spent":"350.747753ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T19:34:57.226655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.78174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-02db23bc-69e2-430c-81d4-3d02e480cde4\" ","response":"range_response_count:1 size:3599"} {"level":"info","ts":"2026-10-26T19:34:57.226702Z","caller":"traceutil/trace.go:171","msg":"trace[344928113] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-02db23bc-69e2-430c-81d4-3d02e480cde4; range_end:; response_count:1; response_revision:5465; }","duration":"315.826521ms","start":"2026-10-26T19:34:56.910862Z","end":"2026-10-26T19:34:57.226688Z","steps":["trace[344928113] 'agreement among raft nodes before linearized reading' (duration: 315.709688ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:57.22673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:34:56.910763Z","time spent":"315.960125ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3622,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-02db23bc-69e2-430c-81d4-3d02e480cde4\" "} {"level":"warn","ts":"2026-10-26T19:34:57.227036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.266481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:787130"} {"level":"info","ts":"2026-10-26T19:34:57.227285Z","caller":"traceutil/trace.go:171","msg":"trace[975574335] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5465; }","duration":"284.392055ms","start":"2026-10-26T19:34:56.942692Z","end":"2026-10-26T19:34:57.227084Z","steps":["trace[975574335] 'agreement among raft nodes before linearized reading' (duration: 283.660835ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:57.227095Z","caller":"traceutil/trace.go:171","msg":"trace[123809458] transaction","detail":"{read_only:false; response_revision:5463; number_of_response:1; }","duration":"349.255461ms","start":"2026-10-26T19:34:56.876905Z","end":"2026-10-26T19:34:57.22616Z","steps":["trace[123809458] 'process raft request' (duration: 348.85911ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:57.227414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:34:56.876881Z","time spent":"350.470635ms","remote":"127.0.0.1:34842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:34:59.349011Z","caller":"traceutil/trace.go:171","msg":"trace[1834162000] linearizableReadLoop","detail":"{readStateIndex:5696; appliedIndex:5694; }","duration":"109.097035ms","start":"2026-10-26T19:34:59.239894Z","end":"2026-10-26T19:34:59.348991Z","steps":["trace[1834162000] 'read index received' (duration: 83.392758ms)","trace[1834162000] 'applied index is now lower than readState.Index' (duration: 25.701307ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:34:59.34913Z","caller":"traceutil/trace.go:171","msg":"trace[1063087800] transaction","detail":"{read_only:false; response_revision:5482; number_of_response:1; }","duration":"111.899193ms","start":"2026-10-26T19:34:59.237212Z","end":"2026-10-26T19:34:59.349112Z","steps":["trace[1063087800] 'process raft request' (duration: 111.669686ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:59.349232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.324551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-02db23bc-69e2-430c-81d4-3d02e480cde4\" ","response":"range_response_count:1 size:3905"} {"level":"info","ts":"2026-10-26T19:34:59.349275Z","caller":"traceutil/trace.go:171","msg":"trace[1145653606] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-02db23bc-69e2-430c-81d4-3d02e480cde4; range_end:; response_count:1; response_revision:5482; }","duration":"109.402474ms","start":"2026-10-26T19:34:59.239859Z","end":"2026-10-26T19:34:59.349261Z","steps":["trace[1145653606] 'agreement among raft nodes before linearized reading' (duration: 109.242279ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:59.430344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.108727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-10-26T19:34:59.430371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.491765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-26T19:34:59.430431Z","caller":"traceutil/trace.go:171","msg":"trace[736899035] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5483; }","duration":"141.226881ms","start":"2026-10-26T19:34:59.289184Z","end":"2026-10-26T19:34:59.430411Z","steps":["trace[736899035] 'agreement among raft nodes before linearized reading' (duration: 141.007425ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:59.430439Z","caller":"traceutil/trace.go:171","msg":"trace[1271654023] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5483; }","duration":"142.593178ms","start":"2026-10-26T19:34:59.287829Z","end":"2026-10-26T19:34:59.430422Z","steps":["trace[1271654023] 'agreement among raft nodes before linearized reading' (duration: 142.422393ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:35:01.329794Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2960} {"level":"warn","ts":"2026-10-26T19:35:01.517286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.019451ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:35:01.517481Z","caller":"traceutil/trace.go:171","msg":"trace[605776413] transaction","detail":"{read_only:false; response_revision:5543; number_of_response:1; }","duration":"118.830874ms","start":"2026-10-26T19:35:01.398621Z","end":"2026-10-26T19:35:01.517452Z","steps":["trace[605776413] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-cp-lock; req_size:416; } (duration: 113.748083ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:35:01.519717Z","caller":"traceutil/trace.go:171","msg":"trace[1408298765] transaction","detail":"{read_only:false; response_revision:5544; number_of_response:1; }","duration":"113.875607ms","start":"2026-10-26T19:35:01.405788Z","end":"2026-10-26T19:35:01.519663Z","steps":["trace[1408298765] 'process raft request' (duration: 111.647706ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:35:01.536936Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2960,"took":"205.765538ms","hash":845085853,"current-db-size-bytes":54308864,"current-db-size":"54 MB","current-db-size-in-use-bytes":30502912,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T19:35:01.537015Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":845085853,"revision":2960,"compact-revision":-1} {"level":"info","ts":"2026-10-26T19:35:11.263313Z","caller":"traceutil/trace.go:171","msg":"trace[1933976886] transaction","detail":"{read_only:false; response_revision:5708; number_of_response:1; }","duration":"124.14645ms","start":"2026-10-26T19:35:11.139129Z","end":"2026-10-26T19:35:11.263275Z","steps":["trace[1933976886] 'process raft request' (duration: 95.330166ms)","trace[1933976886] 'compare' (duration: 28.568537ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:35:11.799623Z","caller":"traceutil/trace.go:171","msg":"trace[2053887654] transaction","detail":"{read_only:false; response_revision:5717; number_of_response:1; }","duration":"193.314065ms","start":"2026-10-26T19:35:11.606276Z","end":"2026-10-26T19:35:11.79959Z","steps":["trace[2053887654] 'process raft request' (duration: 193.224043ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:35:11.800048Z","caller":"traceutil/trace.go:171","msg":"trace[1752647618] transaction","detail":"{read_only:false; response_revision:5716; number_of_response:1; }","duration":"194.353135ms","start":"2026-10-26T19:35:11.605683Z","end":"2026-10-26T19:35:11.800036Z","steps":["trace[1752647618] 'process raft request' (duration: 193.635075ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:35:11.800266Z","caller":"traceutil/trace.go:171","msg":"trace[1205758952] transaction","detail":"{read_only:false; response_revision:5718; number_of_response:1; }","duration":"193.907782ms","start":"2026-10-26T19:35:11.606341Z","end":"2026-10-26T19:35:11.800249Z","steps":["trace[1205758952] 'process raft request' (duration: 193.206213ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:35:18.317557Z","caller":"traceutil/trace.go:171","msg":"trace[392837218] transaction","detail":"{read_only:false; response_revision:5791; number_of_response:1; }","duration":"103.556843ms","start":"2026-10-26T19:35:18.213958Z","end":"2026-10-26T19:35:18.317515Z","steps":["trace[392837218] 'process raft request' (duration: 82.668277ms)","trace[392837218] 'compare' (duration: 19.653902ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:35:20.009414Z","caller":"traceutil/trace.go:171","msg":"trace[2138158296] linearizableReadLoop","detail":"{readStateIndex:6078; appliedIndex:6077; }","duration":"162.280781ms","start":"2026-10-26T19:35:19.847101Z","end":"2026-10-26T19:35:20.009382Z","steps":["trace[2138158296] 'read index received' (duration: 162.209489ms)","trace[2138158296] 'applied index is now lower than readState.Index' (duration: 69.562µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:35:20.009534Z","caller":"traceutil/trace.go:171","msg":"trace[476873881] transaction","detail":"{read_only:false; response_revision:5854; number_of_response:1; }","duration":"163.798473ms","start":"2026-10-26T19:35:19.845696Z","end":"2026-10-26T19:35:20.009494Z","steps":["trace[476873881] 'process raft request' (duration: 163.489834ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:35:20.009796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.676992ms","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-10-26T19:35:20.009922Z","caller":"traceutil/trace.go:171","msg":"trace[1083522071] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5854; }","duration":"162.820126ms","start":"2026-10-26T19:35:19.847079Z","end":"2026-10-26T19:35:20.009899Z","steps":["trace[1083522071] 'agreement among raft nodes before linearized reading' (duration: 162.467146ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:35:20.023495Z","caller":"traceutil/trace.go:171","msg":"trace[1296979435] transaction","detail":"{read_only:false; response_revision:5855; number_of_response:1; }","duration":"175.822394ms","start":"2026-10-26T19:35:19.847641Z","end":"2026-10-26T19:35:20.023463Z","steps":["trace[1296979435] 'process raft request' (duration: 175.604868ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:35:20.023577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.350864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e08bdeb2-3fd5-4846-9843-67692b9b3d39\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-10-26T19:35:20.023637Z","caller":"traceutil/trace.go:171","msg":"trace[692820124] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e08bdeb2-3fd5-4846-9843-67692b9b3d39; range_end:; response_count:1; response_revision:5855; }","duration":"157.425207ms","start":"2026-10-26T19:35:19.866195Z","end":"2026-10-26T19:35:20.02362Z","steps":["trace[692820124] 'agreement among raft nodes before linearized reading' (duration: 157.306394ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:35:20.023672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.333098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:35:20.023764Z","caller":"traceutil/trace.go:171","msg":"trace[1201138210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5855; }","duration":"131.554034ms","start":"2026-10-26T19:35:19.892188Z","end":"2026-10-26T19:35:20.023742Z","steps":["trace[1201138210] 'agreement among raft nodes before linearized reading' (duration: 131.289427ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:35:20.024008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.126266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-26T19:35:20.02406Z","caller":"traceutil/trace.go:171","msg":"trace[1804313953] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5855; }","duration":"166.740374ms","start":"2026-10-26T19:35:19.857307Z","end":"2026-10-26T19:35:20.024047Z","steps":["trace[1804313953] 'agreement among raft nodes before linearized reading' (duration: 166.056255ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:35:20.376387Z","caller":"traceutil/trace.go:171","msg":"trace[854329931] transaction","detail":"{read_only:false; response_revision:5858; number_of_response:1; }","duration":"209.45397ms","start":"2026-10-26T19:35:20.1669Z","end":"2026-10-26T19:35:20.376354Z","steps":["trace[854329931] 'process raft request' (duration: 209.284126ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:35:20.541142Z","caller":"traceutil/trace.go:171","msg":"trace[1900518353] linearizableReadLoop","detail":"{readStateIndex:6083; appliedIndex:6081; }","duration":"347.241777ms","start":"2026-10-26T19:35:20.193874Z","end":"2026-10-26T19:35:20.541115Z","steps":["trace[1900518353] 'read index received' (duration: 182.355614ms)","trace[1900518353] 'applied index is now lower than readState.Index' (duration: 164.885163ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:35:20.541251Z","caller":"traceutil/trace.go:171","msg":"trace[1785435911] transaction","detail":"{read_only:false; response_revision:5859; number_of_response:1; }","duration":"216.883105ms","start":"2026-10-26T19:35:20.32434Z","end":"2026-10-26T19:35:20.541223Z","steps":["trace[1785435911] 'process raft request' (duration: 216.617767ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:35:20.54138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.494143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-10-26T19:35:20.541424Z","caller":"traceutil/trace.go:171","msg":"trace[736098819] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5859; }","duration":"347.642297ms","start":"2026-10-26T19:35:20.193771Z","end":"2026-10-26T19:35:20.541414Z","steps":["trace[736098819] 'agreement among raft nodes before linearized reading' (duration: 347.444901ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:35:20.541465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:35:20.193741Z","time spent":"347.711919ms","remote":"127.0.0.1:34734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1804,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"warn","ts":"2026-10-26T19:35:22.408008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.482683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:35:22.408179Z","caller":"traceutil/trace.go:171","msg":"trace[121489232] transaction","detail":"{read_only:false; response_revision:5905; number_of_response:1; }","duration":"142.942448ms","start":"2026-10-26T19:35:22.26521Z","end":"2026-10-26T19:35:22.408153Z","steps":["trace[121489232] 'process raft request' (duration: 40.215988ms)","trace[121489232] 'compare' (duration: 102.238356ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:40:01.337555Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5540} {"level":"info","ts":"2026-10-26T19:40:01.453761Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5540,"took":"113.369436ms","hash":2678679270,"current-db-size-bytes":54308864,"current-db-size":"54 MB","current-db-size-in-use-bytes":29143040,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-26T19:40:01.453878Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2678679270,"revision":5540,"compact-revision":2960} {"level":"info","ts":"2026-10-26T19:41:39.401931Z","caller":"traceutil/trace.go:171","msg":"trace[1793258244] transaction","detail":"{read_only:false; response_revision:8781; number_of_response:1; }","duration":"112.433696ms","start":"2026-10-26T19:41:39.289468Z","end":"2026-10-26T19:41:39.401902Z","steps":["trace[1793258244] 'process raft request' (duration: 112.21571ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:41:39.403285Z","caller":"traceutil/trace.go:171","msg":"trace[679873082] transaction","detail":"{read_only:false; response_revision:8783; number_of_response:1; }","duration":"107.579973ms","start":"2026-10-26T19:41:39.295646Z","end":"2026-10-26T19:41:39.403226Z","steps":["trace[679873082] 'process raft request' (duration: 107.493531ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:41:39.403397Z","caller":"traceutil/trace.go:171","msg":"trace[122567769] transaction","detail":"{read_only:false; response_revision:8782; number_of_response:1; }","duration":"109.021072ms","start":"2026-10-26T19:41:39.29436Z","end":"2026-10-26T19:41:39.403381Z","steps":["trace[122567769] 'process raft request' (duration: 108.513228ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:41:51.676858Z","caller":"traceutil/trace.go:171","msg":"trace[619027115] transaction","detail":"{read_only:false; response_revision:8844; number_of_response:1; }","duration":"197.89178ms","start":"2026-10-26T19:41:51.478915Z","end":"2026-10-26T19:41:51.676807Z","steps":["trace[619027115] 'process raft request' (duration: 137.98751ms)","trace[619027115] 'compare' (duration: 59.752686ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:41:51.842441Z","caller":"traceutil/trace.go:171","msg":"trace[223099809] linearizableReadLoop","detail":"{readStateIndex:9168; appliedIndex:9166; }","duration":"334.256505ms","start":"2026-10-26T19:41:51.508149Z","end":"2026-10-26T19:41:51.842406Z","steps":["trace[223099809] 'read index received' (duration: 108.825347ms)","trace[223099809] 'applied index is now lower than readState.Index' (duration: 225.429608ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:41:51.842611Z","caller":"traceutil/trace.go:171","msg":"trace[340815884] transaction","detail":"{read_only:false; response_revision:8845; number_of_response:1; }","duration":"362.529883ms","start":"2026-10-26T19:41:51.480031Z","end":"2026-10-26T19:41:51.842561Z","steps":["trace[340815884] 'process raft request' (duration: 357.538206ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:41:51.842858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:41:51.480019Z","time spent":"362.692788ms","remote":"127.0.0.1:34842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:41:51.842908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.736938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dbxsr\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-10-26T19:41:51.843044Z","caller":"traceutil/trace.go:171","msg":"trace[633039587] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dbxsr; range_end:; response_count:1; response_revision:8845; }","duration":"334.862151ms","start":"2026-10-26T19:41:51.508104Z","end":"2026-10-26T19:41:51.842966Z","steps":["trace[633039587] 'agreement among raft nodes before linearized reading' (duration: 334.611664ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:41:51.843092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:41:51.508091Z","time spent":"334.990794ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-dbxsr\" "} {"level":"info","ts":"2026-10-26T19:41:52.509266Z","caller":"traceutil/trace.go:171","msg":"trace[499336668] linearizableReadLoop","detail":"{readStateIndex:9170; appliedIndex:9169; }","duration":"208.221945ms","start":"2026-10-26T19:41:52.301012Z","end":"2026-10-26T19:41:52.509234Z","steps":["trace[499336668] 'read index received' (duration: 207.950057ms)","trace[499336668] 'applied index is now lower than readState.Index' (duration: 270.548µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:41:52.509334Z","caller":"traceutil/trace.go:171","msg":"trace[823150755] transaction","detail":"{read_only:false; response_revision:8847; number_of_response:1; }","duration":"217.621094ms","start":"2026-10-26T19:41:52.291658Z","end":"2026-10-26T19:41:52.509279Z","steps":["trace[823150755] 'process raft request' (duration: 217.377757ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:41:52.509558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.518343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-26T19:41:52.509606Z","caller":"traceutil/trace.go:171","msg":"trace[1079192749] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8847; }","duration":"208.643757ms","start":"2026-10-26T19:41:52.300949Z","end":"2026-10-26T19:41:52.509592Z","steps":["trace[1079192749] 'agreement among raft nodes before linearized reading' (duration: 208.458311ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:41:52.509745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.797388ms","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-10-26T19:41:52.509848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.577715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-26T19:41:52.509904Z","caller":"traceutil/trace.go:171","msg":"trace[143370446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8847; }","duration":"202.015283ms","start":"2026-10-26T19:41:52.307859Z","end":"2026-10-26T19:41:52.509874Z","steps":["trace[143370446] 'agreement among raft nodes before linearized reading' (duration: 201.614462ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:41:52.509994Z","caller":"traceutil/trace.go:171","msg":"trace[1108710986] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8847; }","duration":"167.828173ms","start":"2026-10-26T19:41:52.342132Z","end":"2026-10-26T19:41:52.50996Z","steps":["trace[1108710986] 'agreement among raft nodes before linearized reading' (duration: 167.424062ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:41:52.691388Z","caller":"traceutil/trace.go:171","msg":"trace[170066933] transaction","detail":"{read_only:false; response_revision:8849; number_of_response:1; }","duration":"176.153581ms","start":"2026-10-26T19:41:52.515201Z","end":"2026-10-26T19:41:52.691354Z","steps":["trace[170066933] 'process raft request' (duration: 175.899624ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:41:52.691835Z","caller":"traceutil/trace.go:171","msg":"trace[482170171] transaction","detail":"{read_only:false; response_revision:8850; number_of_response:1; }","duration":"176.48353ms","start":"2026-10-26T19:41:52.515292Z","end":"2026-10-26T19:41:52.691776Z","steps":["trace[482170171] 'process raft request' (duration: 176.012067ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:41:52.691849Z","caller":"traceutil/trace.go:171","msg":"trace[1239341019] transaction","detail":"{read_only:false; response_revision:8851; number_of_response:1; }","duration":"175.884334ms","start":"2026-10-26T19:41:52.515896Z","end":"2026-10-26T19:41:52.69178Z","steps":["trace[1239341019] 'process raft request' (duration: 175.75104ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:42:22.745176Z","caller":"traceutil/trace.go:171","msg":"trace[423283491] transaction","detail":"{read_only:false; response_revision:9063; number_of_response:1; }","duration":"113.800185ms","start":"2026-10-26T19:42:22.631333Z","end":"2026-10-26T19:42:22.745134Z","steps":["trace[423283491] 'process raft request' (duration: 113.435415ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:42:22.97274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.783934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-26T19:42:22.972851Z","caller":"traceutil/trace.go:171","msg":"trace[293561556] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9064; }","duration":"113.964068ms","start":"2026-10-26T19:42:22.858866Z","end":"2026-10-26T19:42:22.97283Z","steps":["trace[293561556] 'range keys from in-memory index tree' (duration: 113.63413ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:42:22.97274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.468003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-10-26T19:42:22.973035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.444667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-26T19:42:22.973143Z","caller":"traceutil/trace.go:171","msg":"trace[685112656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9064; }","duration":"104.60412ms","start":"2026-10-26T19:42:22.868514Z","end":"2026-10-26T19:42:22.973118Z","steps":["trace[685112656] 'range keys from in-memory index tree' (duration: 104.20884ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:42:22.973035Z","caller":"traceutil/trace.go:171","msg":"trace[1735652912] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9064; }","duration":"110.802461ms","start":"2026-10-26T19:42:22.862198Z","end":"2026-10-26T19:42:22.973Z","steps":["trace[1735652912] 'range keys from in-memory index tree' (duration: 110.265636ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:42:26.980231Z","caller":"traceutil/trace.go:171","msg":"trace[1896519839] linearizableReadLoop","detail":"{readStateIndex:9417; appliedIndex:9416; }","duration":"185.628212ms","start":"2026-10-26T19:42:26.79457Z","end":"2026-10-26T19:42:26.980199Z","steps":["trace[1896519839] 'read index received' (duration: 185.430617ms)","trace[1896519839] 'applied index is now lower than readState.Index' (duration: 196.555µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:42:26.980337Z","caller":"traceutil/trace.go:171","msg":"trace[107673342] transaction","detail":"{read_only:false; response_revision:9085; number_of_response:1; }","duration":"223.389712ms","start":"2026-10-26T19:42:26.756912Z","end":"2026-10-26T19:42:26.980301Z","steps":["trace[107673342] 'process raft request' (duration: 223.113484ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:42:26.980543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.945281ms","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-10-26T19:42:26.980613Z","caller":"traceutil/trace.go:171","msg":"trace[2147419913] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9085; }","duration":"186.080825ms","start":"2026-10-26T19:42:26.794508Z","end":"2026-10-26T19:42:26.980589Z","steps":["trace[2147419913] 'agreement among raft nodes before linearized reading' (duration: 185.823348ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:42:35.153859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.281352ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:42:35.154194Z","caller":"traceutil/trace.go:171","msg":"trace[2019190927] transaction","detail":"{read_only:false; response_revision:9129; number_of_response:1; }","duration":"147.533033ms","start":"2026-10-26T19:42:35.006633Z","end":"2026-10-26T19:42:35.154166Z","steps":["trace[2019190927] 'process raft request' (duration: 147.368319ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:42:35.154258Z","caller":"traceutil/trace.go:171","msg":"trace[1844483881] linearizableReadLoop","detail":"{readStateIndex:9462; appliedIndex:9461; }","duration":"262.040696ms","start":"2026-10-26T19:42:34.892163Z","end":"2026-10-26T19:42:35.154204Z","steps":["trace[1844483881] 'read index received' (duration: 153.415945ms)","trace[1844483881] 'applied index is now lower than readState.Index' (duration: 108.620171ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:42:35.154319Z","caller":"traceutil/trace.go:171","msg":"trace[1210343966] transaction","detail":"{read_only:false; response_revision:9128; number_of_response:1; }","duration":"294.50362ms","start":"2026-10-26T19:42:34.859777Z","end":"2026-10-26T19:42:35.154281Z","steps":["trace[1210343966] 'process raft request' (duration: 185.644212ms)","trace[1210343966] 'compare' (duration: 107.947063ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:42:35.154463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.279873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:42:35.154505Z","caller":"traceutil/trace.go:171","msg":"trace[1703764126] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9129; }","duration":"262.384406ms","start":"2026-10-26T19:42:34.892107Z","end":"2026-10-26T19:42:35.154491Z","steps":["trace[1703764126] 'agreement among raft nodes before linearized reading' (duration: 262.282523ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:42:35.557886Z","caller":"traceutil/trace.go:171","msg":"trace[1289503275] transaction","detail":"{read_only:false; response_revision:9134; number_of_response:1; }","duration":"103.46212ms","start":"2026-10-26T19:42:35.454389Z","end":"2026-10-26T19:42:35.557851Z","steps":["trace[1289503275] 'process raft request' (duration: 103.247294ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:42:42.586174Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002556.wal"} {"level":"info","ts":"2026-10-26T19:43:33.561554Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"caafea9f270da559","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T19:43:33.568586Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-26T19:43:33.568769Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-10-26T19:44:10.025232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.914079ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:44:10.025422Z","caller":"traceutil/trace.go:171","msg":"trace[31899581] transaction","detail":"{read_only:false; response_revision:10015; number_of_response:1; }","duration":"232.029483ms","start":"2026-10-26T19:44:09.793368Z","end":"2026-10-26T19:44:10.025397Z","steps":["trace[31899581] 'process raft request' (duration: 44.827585ms)","trace[31899581] 'compare' (duration: 186.715784ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:44:10.053357Z","caller":"traceutil/trace.go:171","msg":"trace[1853448777] linearizableReadLoop","detail":"{readStateIndex:10378; appliedIndex:10376; }","duration":"257.951207ms","start":"2026-10-26T19:44:09.79537Z","end":"2026-10-26T19:44:10.053321Z","steps":["trace[1853448777] 'read index received' (duration: 42.82044ms)","trace[1853448777] 'applied index is now lower than readState.Index' (duration: 215.129807ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:44:10.05358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.193413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-26T19:44:10.053639Z","caller":"traceutil/trace.go:171","msg":"trace[984536934] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10016; }","duration":"258.291136ms","start":"2026-10-26T19:44:09.795329Z","end":"2026-10-26T19:44:10.05362Z","steps":["trace[984536934] 'agreement among raft nodes before linearized reading' (duration: 258.108931ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:44:10.053778Z","caller":"traceutil/trace.go:171","msg":"trace[488346114] transaction","detail":"{read_only:false; response_revision:10016; number_of_response:1; }","duration":"258.743959ms","start":"2026-10-26T19:44:09.794963Z","end":"2026-10-26T19:44:10.053707Z","steps":["trace[488346114] 'process raft request' (duration: 258.196224ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:45:01.346565Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8004} {"level":"info","ts":"2026-10-26T19:45:01.448263Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8004,"took":"98.517904ms","hash":3974160862,"current-db-size-bytes":54308864,"current-db-size":"54 MB","current-db-size-in-use-bytes":25735168,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-26T19:45:01.448349Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3974160862,"revision":8004,"compact-revision":5540} {"level":"warn","ts":"2026-10-26T19:49:52.286919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.511526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-26T19:49:52.287513Z","caller":"traceutil/trace.go:171","msg":"trace[469921936] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12469; }","duration":"177.168644ms","start":"2026-10-26T19:49:52.110305Z","end":"2026-10-26T19:49:52.287474Z","steps":["trace[469921936] 'range keys from in-memory index tree' (duration: 176.209577ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:49:52.64658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.809867ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:49:52.646739Z","caller":"traceutil/trace.go:171","msg":"trace[348242661] linearizableReadLoop","detail":"{readStateIndex:12911; appliedIndex:12910; }","duration":"233.225412ms","start":"2026-10-26T19:49:52.413488Z","end":"2026-10-26T19:49:52.646714Z","steps":["trace[348242661] 'read index received' (duration: 90.822µs)","trace[348242661] 'applied index is now lower than readState.Index' (duration: 233.13211ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:49:52.646883Z","caller":"traceutil/trace.go:171","msg":"trace[942833182] transaction","detail":"{read_only:false; response_revision:12471; number_of_response:1; }","duration":"347.074817ms","start":"2026-10-26T19:49:52.299768Z","end":"2026-10-26T19:49:52.646843Z","steps":["trace[942833182] 'process raft request' (duration: 86.876199ms)","trace[942833182] 'compare' (duration: 259.513429ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:49:52.647117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:49:52.299738Z","time spent":"347.257432ms","remote":"127.0.0.1:34842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:49:52.647216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.726997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12430"} {"level":"info","ts":"2026-10-26T19:49:52.647328Z","caller":"traceutil/trace.go:171","msg":"trace[1168960432] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:12471; }","duration":"233.886171ms","start":"2026-10-26T19:49:52.41342Z","end":"2026-10-26T19:49:52.647306Z","steps":["trace[1168960432] 'agreement among raft nodes before linearized reading' (duration: 233.358686ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:49:52.974685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.152211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:6011"} {"level":"info","ts":"2026-10-26T19:49:52.974806Z","caller":"traceutil/trace.go:171","msg":"trace[355493235] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:12471; }","duration":"224.327976ms","start":"2026-10-26T19:49:52.75045Z","end":"2026-10-26T19:49:52.974777Z","steps":["trace[355493235] 'range keys from in-memory index tree' (duration: 223.889134ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:49:53.138278Z","caller":"traceutil/trace.go:171","msg":"trace[1824463892] transaction","detail":"{read_only:false; response_revision:12472; number_of_response:1; }","duration":"146.640371ms","start":"2026-10-26T19:49:52.9916Z","end":"2026-10-26T19:49:53.138241Z","steps":["trace[1824463892] 'process raft request' (duration: 146.371963ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:49:53.16321Z","caller":"traceutil/trace.go:171","msg":"trace[917315585] transaction","detail":"{read_only:false; response_revision:12473; number_of_response:1; }","duration":"133.775696ms","start":"2026-10-26T19:49:53.029394Z","end":"2026-10-26T19:49:53.163169Z","steps":["trace[917315585] 'process raft request' (duration: 133.502918ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:50:01.355478Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10372} {"level":"info","ts":"2026-10-26T19:50:01.444963Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10372,"took":"86.158451ms","hash":2893517514,"current-db-size-bytes":54308864,"current-db-size":"54 MB","current-db-size-in-use-bytes":25669632,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-26T19:50:01.445133Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2893517514,"revision":10372,"compact-revision":8004} {"level":"info","ts":"2026-10-26T19:55:01.366355Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12536} {"level":"info","ts":"2026-10-26T19:55:01.451891Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12536,"took":"82.671358ms","hash":3616842078,"current-db-size-bytes":54308864,"current-db-size":"54 MB","current-db-size-in-use-bytes":28033024,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-26T19:55:01.451971Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3616842078,"revision":12536,"compact-revision":10372} {"level":"info","ts":"2026-10-26T19:55:54.026932Z","caller":"traceutil/trace.go:171","msg":"trace[2024927579] transaction","detail":"{read_only:false; response_revision:14917; number_of_response:1; }","duration":"132.126866ms","start":"2026-10-26T19:55:53.894778Z","end":"2026-10-26T19:55:54.026905Z","steps":["trace[2024927579] 'process raft request' (duration: 58.681384ms)","trace[2024927579] 'compare' (duration: 73.220196ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:55:57.394504Z","caller":"traceutil/trace.go:171","msg":"trace[1940596082] linearizableReadLoop","detail":"{readStateIndex:15468; appliedIndex:15467; }","duration":"157.704834ms","start":"2026-10-26T19:55:57.236764Z","end":"2026-10-26T19:55:57.394469Z","steps":["trace[1940596082] 'read index received' (duration: 157.431486ms)","trace[1940596082] 'applied index is now lower than readState.Index' (duration: 271.728µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:55:57.394647Z","caller":"traceutil/trace.go:171","msg":"trace[493914440] transaction","detail":"{read_only:false; response_revision:14940; number_of_response:1; }","duration":"167.265929ms","start":"2026-10-26T19:55:57.22737Z","end":"2026-10-26T19:55:57.394636Z","steps":["trace[493914440] 'process raft request' (duration: 166.910179ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:55:57.395209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.292248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12430"} {"level":"warn","ts":"2026-10-26T19:55:57.395342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.560437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-26T19:55:57.395397Z","caller":"traceutil/trace.go:171","msg":"trace[356742870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14940; }","duration":"158.685971ms","start":"2026-10-26T19:55:57.236697Z","end":"2026-10-26T19:55:57.395383Z","steps":["trace[356742870] 'agreement among raft nodes before linearized reading' (duration: 158.484056ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:55:57.395534Z","caller":"traceutil/trace.go:171","msg":"trace[587589501] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14940; }","duration":"129.512533ms","start":"2026-10-26T19:55:57.265801Z","end":"2026-10-26T19:55:57.395313Z","steps":["trace[587589501] 'agreement among raft nodes before linearized reading' (duration: 129.157404ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:55:57.395718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.64792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dbxsr\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-10-26T19:55:57.395749Z","caller":"traceutil/trace.go:171","msg":"trace[301879391] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dbxsr; range_end:; response_count:1; response_revision:14940; }","duration":"119.680141ms","start":"2026-10-26T19:55:57.27606Z","end":"2026-10-26T19:55:57.39574Z","steps":["trace[301879391] 'agreement among raft nodes before linearized reading' (duration: 119.576318ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:56:19.758318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.299291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dbxsr\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-10-26T19:56:19.758419Z","caller":"traceutil/trace.go:171","msg":"trace[1405197444] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dbxsr; range_end:; response_count:1; response_revision:15056; }","duration":"110.481147ms","start":"2026-10-26T19:56:19.647915Z","end":"2026-10-26T19:56:19.758396Z","steps":["trace[1405197444] 'range keys from in-memory index tree' (duration: 109.999363ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:56:38.590684Z","caller":"traceutil/trace.go:171","msg":"trace[259880704] linearizableReadLoop","detail":"{readStateIndex:15705; appliedIndex:15704; }","duration":"131.936821ms","start":"2026-10-26T19:56:38.458698Z","end":"2026-10-26T19:56:38.590635Z","steps":["trace[259880704] 'read index received' (duration: 124.483885ms)","trace[259880704] 'applied index is now lower than readState.Index' (duration: 7.451176ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:56:38.590765Z","caller":"traceutil/trace.go:171","msg":"trace[52507122] transaction","detail":"{read_only:false; response_revision:15167; number_of_response:1; }","duration":"177.043158ms","start":"2026-10-26T19:56:38.413654Z","end":"2026-10-26T19:56:38.590697Z","steps":["trace[52507122] 'process raft request' (duration: 169.526111ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:56:38.591035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.27387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-b6kk8\" ","response":"range_response_count:1 size:12356"} {"level":"info","ts":"2026-10-26T19:56:38.591124Z","caller":"traceutil/trace.go:171","msg":"trace[237197273] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-b6kk8; range_end:; response_count:1; response_revision:15167; }","duration":"132.450395ms","start":"2026-10-26T19:56:38.458657Z","end":"2026-10-26T19:56:38.591107Z","steps":["trace[237197273] 'agreement among raft nodes before linearized reading' (duration: 132.214158ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:56:39.286121Z","caller":"traceutil/trace.go:171","msg":"trace[1010720242] transaction","detail":"{read_only:false; response_revision:15170; number_of_response:1; }","duration":"150.313699ms","start":"2026-10-26T19:56:39.13578Z","end":"2026-10-26T19:56:39.286093Z","steps":["trace[1010720242] 'process raft request' (duration: 150.136254ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:56:40.714966Z","caller":"traceutil/trace.go:171","msg":"trace[1922938169] transaction","detail":"{read_only:false; response_revision:15182; number_of_response:1; }","duration":"211.325607ms","start":"2026-10-26T19:56:40.503598Z","end":"2026-10-26T19:56:40.714923Z","steps":["trace[1922938169] 'process raft request' (duration: 155.326797ms)","trace[1922938169] 'compare' (duration: 55.684061ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:56:40.754123Z","caller":"traceutil/trace.go:171","msg":"trace[814580602] linearizableReadLoop","detail":"{readStateIndex:15722; appliedIndex:15720; }","duration":"159.35988ms","start":"2026-10-26T19:56:40.59473Z","end":"2026-10-26T19:56:40.754089Z","steps":["trace[814580602] 'read index received' (duration: 64.210847ms)","trace[814580602] 'applied index is now lower than readState.Index' (duration: 95.148053ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:56:40.754226Z","caller":"traceutil/trace.go:171","msg":"trace[525964827] transaction","detail":"{read_only:false; response_revision:15183; number_of_response:1; }","duration":"243.199959ms","start":"2026-10-26T19:56:40.510973Z","end":"2026-10-26T19:56:40.754173Z","steps":["trace[525964827] 'process raft request' (duration: 242.953532ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:56:40.754353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.606976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-26T19:56:40.754391Z","caller":"traceutil/trace.go:171","msg":"trace[1537714522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15183; }","duration":"159.71408ms","start":"2026-10-26T19:56:40.594666Z","end":"2026-10-26T19:56:40.75438Z","steps":["trace[1537714522] 'agreement among raft nodes before linearized reading' (duration: 159.539905ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:57:04.770879Z","caller":"traceutil/trace.go:171","msg":"trace[1560818535] transaction","detail":"{read_only:false; response_revision:15498; number_of_response:1; }","duration":"112.477352ms","start":"2026-10-26T19:57:04.658319Z","end":"2026-10-26T19:57:04.770797Z","steps":["trace[1560818535] 'process raft request' (duration: 112.170814ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:57:05.103209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.765825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-26T19:57:05.103315Z","caller":"traceutil/trace.go:171","msg":"trace[1222783076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15498; }","duration":"153.912039ms","start":"2026-10-26T19:57:04.949374Z","end":"2026-10-26T19:57:05.103286Z","steps":["trace[1222783076] 'range keys from in-memory index tree' (duration: 153.590741ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:57:05.103583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.55446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.4\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-10-26T19:57:05.103682Z","caller":"traceutil/trace.go:171","msg":"trace[309690468] range","detail":"{range_begin:/registry/masterleases/162.253.55.4; range_end:; response_count:1; response_revision:15498; }","duration":"230.715434ms","start":"2026-10-26T19:57:04.872945Z","end":"2026-10-26T19:57:05.103661Z","steps":["trace[309690468] 'range keys from in-memory index tree' (duration: 230.327673ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:57:05.103806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.818776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8e52b541-6283-418d-9099-e89ec12eb6fb\" ","response":"range_response_count:1 size:3635"} {"level":"info","ts":"2026-10-26T19:57:05.103922Z","caller":"traceutil/trace.go:171","msg":"trace[1317942072] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8e52b541-6283-418d-9099-e89ec12eb6fb; range_end:; response_count:1; response_revision:15498; }","duration":"223.011681ms","start":"2026-10-26T19:57:04.880887Z","end":"2026-10-26T19:57:05.103899Z","steps":["trace[1317942072] 'range keys from in-memory index tree' (duration: 222.595839ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:57:05.243847Z","caller":"traceutil/trace.go:171","msg":"trace[9402931] transaction","detail":"{read_only:false; response_revision:15501; number_of_response:1; }","duration":"134.328367ms","start":"2026-10-26T19:57:05.109462Z","end":"2026-10-26T19:57:05.24379Z","steps":["trace[9402931] 'process raft request' (duration: 134.040469ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:57:05.244113Z","caller":"traceutil/trace.go:171","msg":"trace[1608315775] transaction","detail":"{read_only:false; response_revision:15502; number_of_response:1; }","duration":"131.316563ms","start":"2026-10-26T19:57:05.112765Z","end":"2026-10-26T19:57:05.244082Z","steps":["trace[1608315775] 'process raft request' (duration: 130.948793ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:57:05.244173Z","caller":"traceutil/trace.go:171","msg":"trace[2101381693] transaction","detail":"{read_only:false; response_revision:15500; number_of_response:1; }","duration":"134.219014ms","start":"2026-10-26T19:57:05.109354Z","end":"2026-10-26T19:57:05.243573Z","steps":["trace[2101381693] 'process raft request' (duration: 133.765892ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:57:58.224905Z","caller":"traceutil/trace.go:171","msg":"trace[939808526] transaction","detail":"{read_only:false; response_revision:16136; number_of_response:1; }","duration":"106.183407ms","start":"2026-10-26T19:57:58.118385Z","end":"2026-10-26T19:57:58.224569Z","steps":["trace[939808526] 'process raft request' (duration: 105.9239ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:58:17.286635Z","caller":"traceutil/trace.go:171","msg":"trace[1103138431] transaction","detail":"{read_only:false; response_revision:16236; number_of_response:1; }","duration":"117.561604ms","start":"2026-10-26T19:58:17.169041Z","end":"2026-10-26T19:58:17.286602Z","steps":["trace[1103138431] 'process raft request' (duration: 117.353728ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:58:18.097802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.307802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:58:18.097944Z","caller":"traceutil/trace.go:171","msg":"trace[1610270103] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:16243; }","duration":"144.539138ms","start":"2026-10-26T19:58:17.953376Z","end":"2026-10-26T19:58:18.097915Z","steps":["trace[1610270103] 'agreement among raft nodes before linearized reading' (duration: 144.279471ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:58:18.097843Z","caller":"traceutil/trace.go:171","msg":"trace[982182296] transaction","detail":"{read_only:false; response_revision:16242; number_of_response:1; }","duration":"105.919662ms","start":"2026-10-26T19:58:17.991831Z","end":"2026-10-26T19:58:18.09775Z","steps":["trace[982182296] 'process raft request' (duration: 105.451459ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:58:18.097949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.864496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-xww8p\" ","response":"range_response_count:1 size:22123"} {"level":"info","ts":"2026-10-26T19:58:18.098138Z","caller":"traceutil/trace.go:171","msg":"trace[818908838] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-xww8p; range_end:; response_count:1; response_revision:16243; }","duration":"133.095713ms","start":"2026-10-26T19:58:17.964995Z","end":"2026-10-26T19:58:18.098091Z","steps":["trace[818908838] 'agreement among raft nodes before linearized reading' (duration: 132.568808ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:58:18.469617Z","caller":"traceutil/trace.go:171","msg":"trace[402915872] transaction","detail":"{read_only:false; response_revision:16244; number_of_response:1; }","duration":"132.981769ms","start":"2026-10-26T19:58:18.3366Z","end":"2026-10-26T19:58:18.469581Z","steps":["trace[402915872] 'process raft request' (duration: 129.980636ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:00:01.376172Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14571} {"level":"info","ts":"2026-10-26T20:00:01.465373Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14571,"took":"85.462218ms","hash":1586739483,"current-db-size-bytes":54308864,"current-db-size":"54 MB","current-db-size-in-use-bytes":31121408,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T20:00:01.46544Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1586739483,"revision":14571,"compact-revision":12536} {"level":"warn","ts":"2026-10-26T20:04:49.373226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.278946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:04:49.373361Z","caller":"traceutil/trace.go:171","msg":"trace[2060780396] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:19067; }","duration":"133.487853ms","start":"2026-10-26T20:04:49.239845Z","end":"2026-10-26T20:04:49.373333Z","steps":["trace[2060780396] 'count revisions from in-memory index tree' (duration: 133.082701ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:04:57.013403Z","caller":"traceutil/trace.go:171","msg":"trace[603774781] linearizableReadLoop","detail":"{readStateIndex:19802; appliedIndex:19801; }","duration":"118.63582ms","start":"2026-10-26T20:04:56.894737Z","end":"2026-10-26T20:04:57.013373Z","steps":["trace[603774781] 'read index received' (duration: 118.461325ms)","trace[603774781] 'applied index is now lower than readState.Index' (duration: 172.965µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:04:57.013533Z","caller":"traceutil/trace.go:171","msg":"trace[1370745369] transaction","detail":"{read_only:false; response_revision:19132; number_of_response:1; }","duration":"118.884517ms","start":"2026-10-26T20:04:56.894616Z","end":"2026-10-26T20:04:57.013501Z","steps":["trace[1370745369] 'process raft request' (duration: 118.551798ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:04:57.013667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.913969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:04:57.01377Z","caller":"traceutil/trace.go:171","msg":"trace[323623316] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19132; }","duration":"119.077863ms","start":"2026-10-26T20:04:56.894671Z","end":"2026-10-26T20:04:57.013749Z","steps":["trace[323623316] 'agreement among raft nodes before linearized reading' (duration: 118.855497ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:04:57.376591Z","caller":"traceutil/trace.go:171","msg":"trace[1026992173] linearizableReadLoop","detail":"{readStateIndex:19803; appliedIndex:19802; }","duration":"119.274419ms","start":"2026-10-26T20:04:57.257287Z","end":"2026-10-26T20:04:57.376561Z","steps":["trace[1026992173] 'read index received' (duration: 119.028353ms)","trace[1026992173] 'applied index is now lower than readState.Index' (duration: 244.236µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:04:57.376988Z","caller":"traceutil/trace.go:171","msg":"trace[1542180698] transaction","detail":"{read_only:false; response_revision:19133; number_of_response:1; }","duration":"263.748785ms","start":"2026-10-26T20:04:57.1132Z","end":"2026-10-26T20:04:57.376948Z","steps":["trace[1542180698] 'process raft request' (duration: 263.145929ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:04:57.377557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.242746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-lmhgl\" ","response":"range_response_count:1 size:20901"} {"level":"info","ts":"2026-10-26T20:04:57.377773Z","caller":"traceutil/trace.go:171","msg":"trace[1491709134] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-lmhgl; range_end:; response_count:1; response_revision:19133; }","duration":"120.523523ms","start":"2026-10-26T20:04:57.257227Z","end":"2026-10-26T20:04:57.377751Z","steps":["trace[1491709134] 'agreement among raft nodes before linearized reading' (duration: 119.451484ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:04:57.562415Z","caller":"traceutil/trace.go:171","msg":"trace[418770795] linearizableReadLoop","detail":"{readStateIndex:19804; appliedIndex:19803; }","duration":"147.432768ms","start":"2026-10-26T20:04:57.414885Z","end":"2026-10-26T20:04:57.562318Z","steps":["trace[418770795] 'read index received' (duration: 146.935774ms)","trace[418770795] 'applied index is now lower than readState.Index' (duration: 495.724µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:04:57.562446Z","caller":"traceutil/trace.go:171","msg":"trace[1415284941] transaction","detail":"{read_only:false; response_revision:19134; number_of_response:1; }","duration":"162.795173ms","start":"2026-10-26T20:04:57.39961Z","end":"2026-10-26T20:04:57.562405Z","steps":["trace[1415284941] 'process raft request' (duration: 162.426352ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:04:57.562751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.828739ms","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-10-26T20:04:57.56289Z","caller":"traceutil/trace.go:171","msg":"trace[327279495] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19134; }","duration":"148.034044ms","start":"2026-10-26T20:04:57.414835Z","end":"2026-10-26T20:04:57.562869Z","steps":["trace[327279495] 'agreement among raft nodes before linearized reading' (duration: 147.682375ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:05:01.385549Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16900} {"level":"info","ts":"2026-10-26T20:05:01.50325Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16900,"took":"109.717845ms","hash":2398282780,"current-db-size-bytes":54308864,"current-db-size":"54 MB","current-db-size-in-use-bytes":32227328,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T20:05:01.503334Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2398282780,"revision":16900,"compact-revision":14571} {"level":"info","ts":"2026-10-26T20:05:05.895502Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"caafea9f270da559","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T20:05:05.937355Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-26T20:05:05.937467Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-26T20:10:01.395844Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19179} {"level":"info","ts":"2026-10-26T20:10:01.520996Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19179,"took":"120.723129ms","hash":1683206532,"current-db-size-bytes":54308864,"current-db-size":"54 MB","current-db-size-in-use-bytes":34349056,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-26T20:10:01.521083Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1683206532,"revision":19179,"compact-revision":16900} {"level":"info","ts":"2026-10-26T20:10:45.111877Z","caller":"traceutil/trace.go:171","msg":"trace[539619356] transaction","detail":"{read_only:false; response_revision:21876; number_of_response:1; }","duration":"115.460504ms","start":"2026-10-26T20:10:44.99639Z","end":"2026-10-26T20:10:45.111851Z","steps":["trace[539619356] 'process raft request' (duration: 115.28929ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:10:50.65755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.662764ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-26T20:10:50.657674Z","caller":"traceutil/trace.go:171","msg":"trace[828351954] linearizableReadLoop","detail":"{readStateIndex:22667; appliedIndex:22666; }","duration":"305.063824ms","start":"2026-10-26T20:10:50.352592Z","end":"2026-10-26T20:10:50.657656Z","steps":["trace[828351954] 'read index received' (duration: 141.259836ms)","trace[828351954] 'applied index is now lower than readState.Index' (duration: 163.802098ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:10:50.657905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.314761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12431"} {"level":"info","ts":"2026-10-26T20:10:50.657946Z","caller":"traceutil/trace.go:171","msg":"trace[151261484] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21905; }","duration":"305.391423ms","start":"2026-10-26T20:10:50.352541Z","end":"2026-10-26T20:10:50.657932Z","steps":["trace[151261484] 'agreement among raft nodes before linearized reading' (duration: 305.216738ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:10:50.657974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:10:50.352526Z","time spent":"305.440865ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12454,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-26T20:10:50.658275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.381177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/heat-cfn-8464b798f4-b2hxj.18e22d3a5e7ed194\" ","response":"range_response_count:1 size:905"} {"level":"info","ts":"2026-10-26T20:10:50.658313Z","caller":"traceutil/trace.go:171","msg":"trace[912293055] range","detail":"{range_begin:/registry/events/openstack/heat-cfn-8464b798f4-b2hxj.18e22d3a5e7ed194; range_end:; response_count:1; response_revision:21905; }","duration":"295.453229ms","start":"2026-10-26T20:10:50.362848Z","end":"2026-10-26T20:10:50.658301Z","steps":["trace[912293055] 'agreement among raft nodes before linearized reading' (duration: 295.352296ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:10:50.658773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.277709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:10:50.658835Z","caller":"traceutil/trace.go:171","msg":"trace[2145098815] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:21905; }","duration":"264.374042ms","start":"2026-10-26T20:10:50.39445Z","end":"2026-10-26T20:10:50.658824Z","steps":["trace[2145098815] 'agreement among raft nodes before linearized reading' (duration: 264.290879ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:11:06.13271Z","caller":"traceutil/trace.go:171","msg":"trace[1765018253] transaction","detail":"{read_only:false; response_revision:22007; number_of_response:1; }","duration":"119.353622ms","start":"2026-10-26T20:11:06.013312Z","end":"2026-10-26T20:11:06.132665Z","steps":["trace[1765018253] 'process raft request' (duration: 119.135886ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:11:11.542678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.651498ms","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-10-26T20:11:11.542808Z","caller":"traceutil/trace.go:171","msg":"trace[1024454385] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:22031; }","duration":"124.852244ms","start":"2026-10-26T20:11:11.417919Z","end":"2026-10-26T20:11:11.542772Z","steps":["trace[1024454385] 'range keys from in-memory index tree' (duration: 124.474333ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:11:11.701526Z","caller":"traceutil/trace.go:171","msg":"trace[2048925812] transaction","detail":"{read_only:false; response_revision:22032; number_of_response:1; }","duration":"124.508534ms","start":"2026-10-26T20:11:11.576987Z","end":"2026-10-26T20:11:11.701495Z","steps":["trace[2048925812] 'process raft request' (duration: 124.322339ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:11:11.72985Z","caller":"traceutil/trace.go:171","msg":"trace[2114374069] transaction","detail":"{read_only:false; response_revision:22033; number_of_response:1; }","duration":"125.80315ms","start":"2026-10-26T20:11:11.604007Z","end":"2026-10-26T20:11:11.729811Z","steps":["trace[2114374069] 'process raft request' (duration: 125.612984ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:11:11.871557Z","caller":"traceutil/trace.go:171","msg":"trace[1170397543] transaction","detail":"{read_only:false; response_revision:22034; number_of_response:1; }","duration":"133.572314ms","start":"2026-10-26T20:11:11.737948Z","end":"2026-10-26T20:11:11.87152Z","steps":["trace[1170397543] 'process raft request' (duration: 114.534419ms)","trace[1170397543] 'compare' (duration: 18.680225ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:11:12.01286Z","caller":"traceutil/trace.go:171","msg":"trace[1454380522] linearizableReadLoop","detail":"{readStateIndex:22801; appliedIndex:22800; }","duration":"120.587846ms","start":"2026-10-26T20:11:11.892239Z","end":"2026-10-26T20:11:12.012827Z","steps":["trace[1454380522] 'read index received' (duration: 120.283128ms)","trace[1454380522] 'applied index is now lower than readState.Index' (duration: 303.008µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:11:12.013004Z","caller":"traceutil/trace.go:171","msg":"trace[1314958803] transaction","detail":"{read_only:false; response_revision:22035; number_of_response:1; }","duration":"135.933509ms","start":"2026-10-26T20:11:11.877055Z","end":"2026-10-26T20:11:12.012988Z","steps":["trace[1314958803] 'process raft request' (duration: 135.367593ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:11:12.013341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.058479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:11:12.013423Z","caller":"traceutil/trace.go:171","msg":"trace[934456623] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22035; }","duration":"121.211673ms","start":"2026-10-26T20:11:11.892189Z","end":"2026-10-26T20:11:12.013401Z","steps":["trace[934456623] 'agreement among raft nodes before linearized reading' (duration: 120.916535ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:11:12.055587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.918464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:11:12.055707Z","caller":"traceutil/trace.go:171","msg":"trace[1767856589] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:22036; }","duration":"117.10625ms","start":"2026-10-26T20:11:11.938571Z","end":"2026-10-26T20:11:12.055678Z","steps":["trace[1767856589] 'agreement among raft nodes before linearized reading' (duration: 116.891834ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:11:12.05572Z","caller":"traceutil/trace.go:171","msg":"trace[1757685962] transaction","detail":"{read_only:false; response_revision:22036; number_of_response:1; }","duration":"157.961977ms","start":"2026-10-26T20:11:11.897725Z","end":"2026-10-26T20:11:12.055687Z","steps":["trace[1757685962] 'process raft request' (duration: 157.560605ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:11:12.055744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.363181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7841"} {"level":"info","ts":"2026-10-26T20:11:12.055877Z","caller":"traceutil/trace.go:171","msg":"trace[1646084080] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:22036; }","duration":"103.538995ms","start":"2026-10-26T20:11:11.952311Z","end":"2026-10-26T20:11:12.05585Z","steps":["trace[1646084080] 'agreement among raft nodes before linearized reading' (duration: 103.158745ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:11:12.05615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.701662ms","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-10-26T20:11:12.05647Z","caller":"traceutil/trace.go:171","msg":"trace[1433207320] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22036; }","duration":"161.068572ms","start":"2026-10-26T20:11:11.895377Z","end":"2026-10-26T20:11:12.056446Z","steps":["trace[1433207320] 'agreement among raft nodes before linearized reading' (duration: 160.210559ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:11:12.280505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.21059ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T20:11:12.280661Z","caller":"traceutil/trace.go:171","msg":"trace[681237359] linearizableReadLoop","detail":"{readStateIndex:22803; appliedIndex:22802; }","duration":"172.5804ms","start":"2026-10-26T20:11:12.108053Z","end":"2026-10-26T20:11:12.280634Z","steps":["trace[681237359] 'read index received' (duration: 58.160604ms)","trace[681237359] 'applied index is now lower than readState.Index' (duration: 114.417656ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:11:12.28081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.723584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-26T20:11:12.280876Z","caller":"traceutil/trace.go:171","msg":"trace[1993392517] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:22037; }","duration":"172.859928ms","start":"2026-10-26T20:11:12.107996Z","end":"2026-10-26T20:11:12.280856Z","steps":["trace[1993392517] 'agreement among raft nodes before linearized reading' (duration: 172.715634ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:11:12.281083Z","caller":"traceutil/trace.go:171","msg":"trace[995271169] transaction","detail":"{read_only:false; response_revision:22037; number_of_response:1; }","duration":"219.637957ms","start":"2026-10-26T20:11:12.061412Z","end":"2026-10-26T20:11:12.28105Z","steps":["trace[995271169] 'process raft request' (duration: 104.750639ms)","trace[995271169] 'compare' (duration: 114.075386ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:11:12.512212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.62861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-26T20:11:12.512335Z","caller":"traceutil/trace.go:171","msg":"trace[985935058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22037; }","duration":"102.865327ms","start":"2026-10-26T20:11:12.409439Z","end":"2026-10-26T20:11:12.512305Z","steps":["trace[985935058] 'range keys from in-memory index tree' (duration: 102.332182ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:15:01.407671Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21630} {"level":"info","ts":"2026-10-26T20:15:01.532767Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21630,"took":"120.342499ms","hash":1009511946,"current-db-size-bytes":54308864,"current-db-size":"54 MB","current-db-size-in-use-bytes":31068160,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T20:15:01.532854Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1009511946,"revision":21630,"compact-revision":19179} {"level":"info","ts":"2026-10-26T20:17:21.975428Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000682e.wal"} {"level":"info","ts":"2026-10-26T20:18:46.644536Z","caller":"traceutil/trace.go:171","msg":"trace[115479095] transaction","detail":"{read_only:false; response_revision:26560; number_of_response:1; }","duration":"163.278054ms","start":"2026-10-26T20:18:46.481226Z","end":"2026-10-26T20:18:46.644504Z","steps":["trace[115479095] 'process raft request' (duration: 163.041958ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:18:52.031648Z","caller":"traceutil/trace.go:171","msg":"trace[978213087] linearizableReadLoop","detail":"{readStateIndex:27506; appliedIndex:27505; }","duration":"138.859ms","start":"2026-10-26T20:18:51.892752Z","end":"2026-10-26T20:18:52.031611Z","steps":["trace[978213087] 'read index received' (duration: 134.323965ms)","trace[978213087] 'applied index is now lower than readState.Index' (duration: 4.533385ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:18:52.032011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.22534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:18:52.032174Z","caller":"traceutil/trace.go:171","msg":"trace[1144878228] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26609; }","duration":"139.443487ms","start":"2026-10-26T20:18:51.892697Z","end":"2026-10-26T20:18:52.03214Z","steps":["trace[1144878228] 'agreement among raft nodes before linearized reading' (duration: 139.135158ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:18:52.03251Z","caller":"traceutil/trace.go:171","msg":"trace[1601347127] transaction","detail":"{read_only:false; response_revision:26609; number_of_response:1; }","duration":"142.753167ms","start":"2026-10-26T20:18:51.889736Z","end":"2026-10-26T20:18:52.032489Z","steps":["trace[1601347127] 'process raft request' (duration: 137.586475ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:18:52.630478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.075135ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T20:18:52.630812Z","caller":"traceutil/trace.go:171","msg":"trace[1832796417] linearizableReadLoop","detail":"{readStateIndex:27509; appliedIndex:27507; }","duration":"353.943213ms","start":"2026-10-26T20:18:52.276822Z","end":"2026-10-26T20:18:52.630766Z","steps":["trace[1832796417] 'read index received' (duration: 52.447966ms)","trace[1832796417] 'applied index is now lower than readState.Index' (duration: 301.493997ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:18:52.630973Z","caller":"traceutil/trace.go:171","msg":"trace[626998892] transaction","detail":"{read_only:false; response_revision:26611; number_of_response:1; }","duration":"486.541912ms","start":"2026-10-26T20:18:52.144415Z","end":"2026-10-26T20:18:52.630957Z","steps":["trace[626998892] 'process raft request' (duration: 184.87628ms)","trace[626998892] 'compare' (duration: 300.901091ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:18:52.631132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:18:52.144389Z","time spent":"486.702126ms","remote":"127.0.0.1:34842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:18:52.631404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.365465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:18:52.63139Z","caller":"traceutil/trace.go:171","msg":"trace[1605824604] transaction","detail":"{read_only:false; response_revision:26612; number_of_response:1; }","duration":"382.136941ms","start":"2026-10-26T20:18:52.249017Z","end":"2026-10-26T20:18:52.631154Z","steps":["trace[1605824604] 'process raft request' (duration: 381.683129ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:18:52.631498Z","caller":"traceutil/trace.go:171","msg":"trace[273553149] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:26612; }","duration":"354.716775ms","start":"2026-10-26T20:18:52.27676Z","end":"2026-10-26T20:18:52.631477Z","steps":["trace[273553149] 'agreement among raft nodes before linearized reading' (duration: 354.323604ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:18:52.631538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:18:52.276737Z","time spent":"354.793417ms","remote":"127.0.0.1:45692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-10-26T20:18:52.631592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:18:52.248979Z","time spent":"382.521262ms","remote":"127.0.0.1:34842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:18:52.817794Z","caller":"traceutil/trace.go:171","msg":"trace[1854223767] linearizableReadLoop","detail":"{readStateIndex:27510; appliedIndex:27509; }","duration":"103.820654ms","start":"2026-10-26T20:18:52.71391Z","end":"2026-10-26T20:18:52.81773Z","steps":["trace[1854223767] 'read index received' (duration: 68.375616ms)","trace[1854223767] 'applied index is now lower than readState.Index' (duration: 35.443158ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:18:52.817831Z","caller":"traceutil/trace.go:171","msg":"trace[1844480827] transaction","detail":"{read_only:false; response_revision:26613; number_of_response:1; }","duration":"152.225599ms","start":"2026-10-26T20:18:52.665516Z","end":"2026-10-26T20:18:52.817742Z","steps":["trace[1844480827] 'process raft request' (duration: 116.860824ms)","trace[1844480827] 'compare' (duration: 33.920125ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:18:52.818322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.377109ms","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:2083"} {"level":"info","ts":"2026-10-26T20:18:52.818576Z","caller":"traceutil/trace.go:171","msg":"trace[794322134] 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:26613; }","duration":"104.650647ms","start":"2026-10-26T20:18:52.71389Z","end":"2026-10-26T20:18:52.81854Z","steps":["trace[794322134] 'agreement among raft nodes before linearized reading' (duration: 104.010249ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:19:55.19003Z","caller":"traceutil/trace.go:171","msg":"trace[211127214] linearizableReadLoop","detail":"{readStateIndex:28082; appliedIndex:28081; }","duration":"240.643319ms","start":"2026-10-26T20:19:54.949353Z","end":"2026-10-26T20:19:55.189997Z","steps":["trace[211127214] 'read index received' (duration: 240.441873ms)","trace[211127214] 'applied index is now lower than readState.Index' (duration: 199.946µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:19:55.190142Z","caller":"traceutil/trace.go:171","msg":"trace[1451120580] transaction","detail":"{read_only:false; response_revision:27172; number_of_response:1; }","duration":"292.466868ms","start":"2026-10-26T20:19:54.897636Z","end":"2026-10-26T20:19:55.190103Z","steps":["trace[1451120580] 'process raft request' (duration: 292.132749ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:19:55.190539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.153513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.4\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-26T20:19:55.190646Z","caller":"traceutil/trace.go:171","msg":"trace[41534647] range","detail":"{range_begin:/registry/masterleases/162.253.55.4; range_end:; response_count:1; response_revision:27172; }","duration":"241.322147ms","start":"2026-10-26T20:19:54.949294Z","end":"2026-10-26T20:19:55.190616Z","steps":["trace[41534647] 'agreement among raft nodes before linearized reading' (duration: 240.853924ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:19:55.441453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.238386ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T20:19:55.441743Z","caller":"traceutil/trace.go:171","msg":"trace[1670494053] transaction","detail":"{read_only:false; response_revision:27173; number_of_response:1; }","duration":"370.87428ms","start":"2026-10-26T20:19:55.070819Z","end":"2026-10-26T20:19:55.441693Z","steps":["trace[1670494053] 'process raft request' (duration: 225.300575ms)","trace[1670494053] 'compare' (duration: 145.106282ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:19:55.44193Z","caller":"traceutil/trace.go:171","msg":"trace[1936550736] linearizableReadLoop","detail":"{readStateIndex:28083; appliedIndex:28082; }","duration":"251.771844ms","start":"2026-10-26T20:19:55.190131Z","end":"2026-10-26T20:19:55.441903Z","steps":["trace[1936550736] 'read index received' (duration: 106.015464ms)","trace[1936550736] 'applied index is now lower than readState.Index' (duration: 145.7535ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:19:55.442072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:19:55.070769Z","time spent":"371.181868ms","remote":"127.0.0.1:34842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:19:55.44248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.211175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/groups/\" range_end:\"/registry/openstack.k-orc.cloud/groups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:19:55.442553Z","caller":"traceutil/trace.go:171","msg":"trace[827768650] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/groups/; range_end:/registry/openstack.k-orc.cloud/groups0; response_count:0; response_revision:27173; }","duration":"290.659948ms","start":"2026-10-26T20:19:55.151879Z","end":"2026-10-26T20:19:55.442539Z","steps":["trace[827768650] 'agreement among raft nodes before linearized reading' (duration: 290.223685ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:19:55.443145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.711061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11795"} {"level":"info","ts":"2026-10-26T20:19:55.443296Z","caller":"traceutil/trace.go:171","msg":"trace[1089607342] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:27173; }","duration":"434.897836ms","start":"2026-10-26T20:19:55.008349Z","end":"2026-10-26T20:19:55.443247Z","steps":["trace[1089607342] 'agreement among raft nodes before linearized reading' (duration: 433.94585ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:19:55.44336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:19:55.008318Z","time spent":"435.02922ms","remote":"127.0.0.1:34994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11818,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"warn","ts":"2026-10-26T20:19:55.760843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.335516ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T20:19:55.761114Z","caller":"traceutil/trace.go:171","msg":"trace[1039453172] transaction","detail":"{read_only:false; response_revision:27177; number_of_response:1; }","duration":"143.461198ms","start":"2026-10-26T20:19:55.61763Z","end":"2026-10-26T20:19:55.761091Z","steps":["trace[1039453172] 'process raft request' (duration: 143.404286ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:19:55.761195Z","caller":"traceutil/trace.go:171","msg":"trace[722935690] linearizableReadLoop","detail":"{readStateIndex:28087; appliedIndex:28084; }","duration":"309.467577ms","start":"2026-10-26T20:19:55.451703Z","end":"2026-10-26T20:19:55.76117Z","steps":["trace[722935690] 'read index received' (duration: 5.861492ms)","trace[722935690] 'applied index is now lower than readState.Index' (duration: 303.603715ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:19:55.76123Z","caller":"traceutil/trace.go:171","msg":"trace[1148682415] transaction","detail":"{read_only:false; response_revision:27176; number_of_response:1; }","duration":"311.303808ms","start":"2026-10-26T20:19:55.449824Z","end":"2026-10-26T20:19:55.761128Z","steps":["trace[1148682415] 'process raft request' (duration: 311.132523ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:19:55.76142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.725855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11795"} {"level":"info","ts":"2026-10-26T20:19:55.761461Z","caller":"traceutil/trace.go:171","msg":"trace[1733285142] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:27177; }","duration":"309.813007ms","start":"2026-10-26T20:19:55.451636Z","end":"2026-10-26T20:19:55.761449Z","steps":["trace[1733285142] 'agreement among raft nodes before linearized reading' (duration: 309.649332ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:19:55.761498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:19:55.451614Z","time spent":"309.876418ms","remote":"127.0.0.1:34994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11818,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"warn","ts":"2026-10-26T20:19:55.761618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:19:55.449768Z","time spent":"311.633026ms","remote":"127.0.0.1:34842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:19:55.761266Z","caller":"traceutil/trace.go:171","msg":"trace[1552771799] transaction","detail":"{read_only:false; response_revision:27175; number_of_response:1; }","duration":"316.991744ms","start":"2026-10-26T20:19:55.444191Z","end":"2026-10-26T20:19:55.761183Z","steps":["trace[1552771799] 'process raft request' (duration: 153.175405ms)","trace[1552771799] 'compare' (duration: 163.029068ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:19:55.763049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:19:55.444154Z","time spent":"318.660151ms","remote":"127.0.0.1:34842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:19:55.822436Z","caller":"traceutil/trace.go:171","msg":"trace[1738404064] transaction","detail":"{read_only:false; response_revision:27178; number_of_response:1; }","duration":"198.062604ms","start":"2026-10-26T20:19:55.624333Z","end":"2026-10-26T20:19:55.822395Z","steps":["trace[1738404064] 'process raft request' (duration: 197.836618ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:19:55.823154Z","caller":"traceutil/trace.go:171","msg":"trace[475272187] transaction","detail":"{read_only:false; response_revision:27179; number_of_response:1; }","duration":"129.401689ms","start":"2026-10-26T20:19:55.693732Z","end":"2026-10-26T20:19:55.823134Z","steps":["trace[475272187] 'process raft request' (duration: 128.611248ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:19:55.823248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.988445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-26T20:19:55.823376Z","caller":"traceutil/trace.go:171","msg":"trace[1254567940] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:27179; }","duration":"155.16433ms","start":"2026-10-26T20:19:55.668187Z","end":"2026-10-26T20:19:55.823352Z","steps":["trace[1254567940] 'agreement among raft nodes before linearized reading' (duration: 154.848912ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:19:55.823426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.822222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:19:55.823522Z","caller":"traceutil/trace.go:171","msg":"trace[465932804] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:27179; }","duration":"174.992167ms","start":"2026-10-26T20:19:55.648503Z","end":"2026-10-26T20:19:55.823495Z","steps":["trace[465932804] 'agreement among raft nodes before linearized reading' (duration: 174.490774ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:19:55.823684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.745774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-26T20:19:55.823728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.132237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-10-26T20:19:55.823972Z","caller":"traceutil/trace.go:171","msg":"trace[65892282] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:27179; }","duration":"363.417686ms","start":"2026-10-26T20:19:55.460483Z","end":"2026-10-26T20:19:55.823901Z","steps":["trace[65892282] 'agreement among raft nodes before linearized reading' (duration: 362.897921ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:19:55.823844Z","caller":"traceutil/trace.go:171","msg":"trace[1355269449] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:27179; }","duration":"102.96985ms","start":"2026-10-26T20:19:55.720846Z","end":"2026-10-26T20:19:55.823816Z","steps":["trace[1355269449] 'agreement among raft nodes before linearized reading' (duration: 102.618771ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:19:55.824033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:19:55.460467Z","time spent":"363.552289ms","remote":"127.0.0.1:34746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-10-26T20:19:55.824198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.485491ms","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-10-26T20:19:55.824377Z","caller":"traceutil/trace.go:171","msg":"trace[1732199825] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27179; }","duration":"345.703237ms","start":"2026-10-26T20:19:55.478639Z","end":"2026-10-26T20:19:55.824343Z","steps":["trace[1732199825] 'agreement among raft nodes before linearized reading' (duration: 345.019388ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:19:55.824512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:19:55.478627Z","time spent":"345.866991ms","remote":"127.0.0.1:34842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-26T20:20:01.414632Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23824} {"level":"info","ts":"2026-10-26T20:20:01.565881Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23824,"took":"145.488584ms","hash":1726790474,"current-db-size-bytes":56418304,"current-db-size":"56 MB","current-db-size-in-use-bytes":45244416,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-26T20:20:01.565957Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1726790474,"revision":23824,"compact-revision":21630} {"level":"info","ts":"2026-10-26T20:23:09.641067Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"caafea9f270da559","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T20:23:09.647532Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-26T20:23:09.647622Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-26T20:25:01.424744Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27231} {"level":"info","ts":"2026-10-26T20:25:01.6125Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27231,"took":"181.251959ms","hash":3207209214,"current-db-size-bytes":58056704,"current-db-size":"58 MB","current-db-size-in-use-bytes":36511744,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-26T20:25:01.612586Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3207209214,"revision":27231,"compact-revision":23824} {"level":"info","ts":"2026-10-26T20:30:01.438874Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30108} {"level":"info","ts":"2026-10-26T20:30:01.604854Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30108,"took":"160.398265ms","hash":2845502772,"current-db-size-bytes":58056704,"current-db-size":"58 MB","current-db-size-in-use-bytes":31207424,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T20:30:01.604945Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2845502772,"revision":30108,"compact-revision":27231} {"level":"info","ts":"2026-10-26T20:35:01.445328Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32360} {"level":"info","ts":"2026-10-26T20:35:01.609298Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32360,"took":"158.971664ms","hash":1002355369,"current-db-size-bytes":58056704,"current-db-size":"58 MB","current-db-size-in-use-bytes":30289920,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T20:35:01.609442Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1002355369,"revision":32360,"compact-revision":30108} {"level":"info","ts":"2026-10-26T20:40:01.454724Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34586} {"level":"info","ts":"2026-10-26T20:40:01.603702Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34586,"took":"140.761363ms","hash":4247510478,"current-db-size-bytes":58056704,"current-db-size":"58 MB","current-db-size-in-use-bytes":30437376,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T20:40:01.603772Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4247510478,"revision":34586,"compact-revision":32360} {"level":"info","ts":"2026-10-26T20:44:10.451271Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"caafea9f270da559","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T20:44:10.459276Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-26T20:44:10.459865Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-26T20:45:01.464423Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36821} {"level":"info","ts":"2026-10-26T20:45:01.598618Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36821,"took":"128.183073ms","hash":2518596799,"current-db-size-bytes":58056704,"current-db-size":"58 MB","current-db-size-in-use-bytes":32591872,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-26T20:45:01.598724Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2518596799,"revision":36821,"compact-revision":34586} {"level":"info","ts":"2026-10-26T20:50:01.472358Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39159} {"level":"info","ts":"2026-10-26T20:50:01.611516Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39159,"took":"134.045983ms","hash":2626432950,"current-db-size-bytes":59330560,"current-db-size":"59 MB","current-db-size-in-use-bytes":31137792,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T20:50:01.611603Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2626432950,"revision":39159,"compact-revision":36821} {"level":"info","ts":"2026-10-26T20:55:01.483026Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41388} {"level":"info","ts":"2026-10-26T20:55:01.611545Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41388,"took":"123.915984ms","hash":817338873,"current-db-size-bytes":59330560,"current-db-size":"59 MB","current-db-size-in-use-bytes":31182848,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T20:55:01.611638Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":817338873,"revision":41388,"compact-revision":39159}