{"level":"warn","ts":"2026-08-06T07:06:20.445885Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-06T07:06:20.446199Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.123:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.123:2380","--initial-cluster=instance=https://199.19.213.123:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.123:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.123:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-06T07:06:20.446279Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-06T07:06:20.446296Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.123:2380"]} {"level":"info","ts":"2026-08-06T07:06:20.446319Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-06T07:06:20.44725Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.123:2379"]} {"level":"info","ts":"2026-08-06T07:06:20.447342Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.123:2380"],"listen-peer-urls":["https://199.19.213.123:2380"],"advertise-client-urls":["https://199.19.213.123:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.123:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.123:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-06T07:06:20.449705Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.094802ms"} {"level":"info","ts":"2026-08-06T07:06:20.454369Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3e4829a3cabe4fce","cluster-id":"b469cfed817d8605"} {"level":"info","ts":"2026-08-06T07:06:20.45442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce switched to configuration voters=()"} {"level":"info","ts":"2026-08-06T07:06:20.45444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce became follower at term 0"} {"level":"info","ts":"2026-08-06T07:06:20.454496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3e4829a3cabe4fce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-06T07:06:20.454515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce became follower at term 1"} {"level":"info","ts":"2026-08-06T07:06:20.454555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce switched to configuration voters=(4487882812132577230)"} {"level":"warn","ts":"2026-08-06T07:06:20.463676Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-06T07:06:20.467162Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-06T07:06:20.468781Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-06T07:06:20.471789Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3e4829a3cabe4fce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-06T07:06:20.472416Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3e4829a3cabe4fce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-06T07:06:20.472496Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-06T07:06:20.472523Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-06T07:06:20.472542Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-06T07:06:20.473199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce switched to configuration voters=(4487882812132577230)"} {"level":"info","ts":"2026-08-06T07:06:20.47337Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b469cfed817d8605","local-member-id":"3e4829a3cabe4fce","added-peer-id":"3e4829a3cabe4fce","added-peer-peer-urls":["https://199.19.213.123:2380"]} {"level":"info","ts":"2026-08-06T07:06:20.484987Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-06T07:06:20.485219Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.123:2380"} {"level":"info","ts":"2026-08-06T07:06:20.48531Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.123:2380"} {"level":"info","ts":"2026-08-06T07:06:20.485341Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3e4829a3cabe4fce","initial-advertise-peer-urls":["https://199.19.213.123:2380"],"listen-peer-urls":["https://199.19.213.123:2380"],"advertise-client-urls":["https://199.19.213.123:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.123:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-06T07:06:20.485499Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-06T07:06:21.154728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce is starting a new election at term 1"} {"level":"info","ts":"2026-08-06T07:06:21.154789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce became pre-candidate at term 1"} {"level":"info","ts":"2026-08-06T07:06:21.154829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce received MsgPreVoteResp from 3e4829a3cabe4fce at term 1"} {"level":"info","ts":"2026-08-06T07:06:21.154876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce became candidate at term 2"} {"level":"info","ts":"2026-08-06T07:06:21.154887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce received MsgVoteResp from 3e4829a3cabe4fce at term 2"} {"level":"info","ts":"2026-08-06T07:06:21.154902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce became leader at term 2"} {"level":"info","ts":"2026-08-06T07:06:21.154936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3e4829a3cabe4fce elected leader 3e4829a3cabe4fce at term 2"} {"level":"info","ts":"2026-08-06T07:06:21.155841Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T07:06:21.156622Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-06T07:06:21.156638Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3e4829a3cabe4fce","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.123:2379]}","request-path":"/0/members/3e4829a3cabe4fce/attributes","cluster-id":"b469cfed817d8605","publish-timeout":"7s"} {"level":"info","ts":"2026-08-06T07:06:21.156649Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-06T07:06:21.157014Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b469cfed817d8605","local-member-id":"3e4829a3cabe4fce","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T07:06:21.156983Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-06T07:06:21.157094Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-06T07:06:21.157206Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T07:06:21.157275Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T07:06:21.15852Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-06T07:06:21.168228Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.123:2379"} {"level":"info","ts":"2026-08-06T07:07:08.188347Z","caller":"traceutil/trace.go:171","msg":"trace[697862147] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"169.483198ms","start":"2026-08-06T07:07:08.018842Z","end":"2026-08-06T07:07:08.188326Z","steps":["trace[697862147] 'process raft request' (duration: 169.091389ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:07:08.469164Z","caller":"traceutil/trace.go:171","msg":"trace[373328605] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"243.984367ms","start":"2026-08-06T07:07:08.22516Z","end":"2026-08-06T07:07:08.469144Z","steps":["trace[373328605] 'process raft request' (duration: 243.843833ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:07:08.683924Z","caller":"traceutil/trace.go:171","msg":"trace[1658493495] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"114.063556ms","start":"2026-08-06T07:07:08.569837Z","end":"2026-08-06T07:07:08.683901Z","steps":["trace[1658493495] 'read index received' (duration: 111.788349ms)","trace[1658493495] 'applied index is now lower than readState.Index' (duration: 2.274397ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:07:08.683998Z","caller":"traceutil/trace.go:171","msg":"trace[557852987] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"430.24578ms","start":"2026-08-06T07:07:08.253732Z","end":"2026-08-06T07:07:08.683978Z","steps":["trace[557852987] 'process raft request' (duration: 427.86662ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:07:08.684092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.248951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-06T07:07:08.684146Z","caller":"traceutil/trace.go:171","msg":"trace[1090472040] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:646; }","duration":"114.378234ms","start":"2026-08-06T07:07:08.56976Z","end":"2026-08-06T07:07:08.684138Z","steps":["trace[1090472040] 'agreement among raft nodes before linearized reading' (duration: 114.259481ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:07:08.684166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.271592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-06T07:07:08.684241Z","caller":"traceutil/trace.go:171","msg":"trace[19084916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:646; }","duration":"114.400875ms","start":"2026-08-06T07:07:08.569825Z","end":"2026-08-06T07:07:08.684226Z","steps":["trace[19084916] 'agreement among raft nodes before linearized reading' (duration: 114.232741ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:07:08.684546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:07:08.253705Z","time spent":"430.343372ms","remote":"127.0.0.1:45356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T07:07:23.778236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.707187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-06T07:07:23.778377Z","caller":"traceutil/trace.go:171","msg":"trace[1712277410] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:805; }","duration":"218.901592ms","start":"2026-08-06T07:07:23.559451Z","end":"2026-08-06T07:07:23.778352Z","steps":["trace[1712277410] 'range keys from in-memory index tree' (duration: 218.46046ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:08:36.110814Z","caller":"traceutil/trace.go:171","msg":"trace[465254119] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"153.7233ms","start":"2026-08-06T07:08:35.957068Z","end":"2026-08-06T07:08:36.110791Z","steps":["trace[465254119] 'process raft request' (duration: 153.494095ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:08:36.635495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.027984ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T07:08:36.635904Z","caller":"traceutil/trace.go:171","msg":"trace[2133211844] linearizableReadLoop","detail":"{readStateIndex:1931; appliedIndex:1930; }","duration":"310.019254ms","start":"2026-08-06T07:08:36.325836Z","end":"2026-08-06T07:08:36.635856Z","steps":["trace[2133211844] 'read index received' (duration: 167.324452ms)","trace[2133211844] 'applied index is now lower than readState.Index' (duration: 142.693682ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:08:36.635923Z","caller":"traceutil/trace.go:171","msg":"trace[2141016936] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"365.759581ms","start":"2026-08-06T07:08:36.270126Z","end":"2026-08-06T07:08:36.635886Z","steps":["trace[2141016936] 'process raft request' (duration: 223.08536ms)","trace[2141016936] 'compare' (duration: 141.816599ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:08:36.636141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:08:36.27011Z","time spent":"365.999637ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T07:08:36.636042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.212208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-06T07:08:36.636219Z","caller":"traceutil/trace.go:171","msg":"trace[780880428] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1863; }","duration":"310.411424ms","start":"2026-08-06T07:08:36.325792Z","end":"2026-08-06T07:08:36.636203Z","steps":["trace[780880428] 'agreement among raft nodes before linearized reading' (duration: 310.168527ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:08:36.636296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:08:36.325773Z","time spent":"310.489266ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-06T07:08:49.326029Z","caller":"traceutil/trace.go:171","msg":"trace[1874021358] linearizableReadLoop","detail":"{readStateIndex:2044; appliedIndex:2043; }","duration":"157.058414ms","start":"2026-08-06T07:08:49.168932Z","end":"2026-08-06T07:08:49.32599Z","steps":["trace[1874021358] 'read index received' (duration: 134.420633ms)","trace[1874021358] 'applied index is now lower than readState.Index' (duration: 22.633011ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:08:49.326151Z","caller":"traceutil/trace.go:171","msg":"trace[1561122447] transaction","detail":"{read_only:false; response_revision:1973; number_of_response:1; }","duration":"111.002061ms","start":"2026-08-06T07:08:49.215107Z","end":"2026-08-06T07:08:49.326109Z","steps":["trace[1561122447] 'process raft request' (duration: 110.803056ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:08:49.326392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.443654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-06T07:08:49.326467Z","caller":"traceutil/trace.go:171","msg":"trace[2051141624] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1973; }","duration":"157.531876ms","start":"2026-08-06T07:08:49.168914Z","end":"2026-08-06T07:08:49.326446Z","steps":["trace[2051141624] 'agreement among raft nodes before linearized reading' (duration: 157.25959ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:08:49.326397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.349904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-06T07:08:49.326568Z","caller":"traceutil/trace.go:171","msg":"trace[517091548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1973; }","duration":"148.57397ms","start":"2026-08-06T07:08:49.177974Z","end":"2026-08-06T07:08:49.326548Z","steps":["trace[517091548] 'agreement among raft nodes before linearized reading' (duration: 148.276473ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:09:20.554149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.549011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T07:09:20.554308Z","caller":"traceutil/trace.go:171","msg":"trace[12393081] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"156.822459ms","start":"2026-08-06T07:09:20.397449Z","end":"2026-08-06T07:09:20.554271Z","steps":["trace[12393081] 'process raft request' (duration: 21.074252ms)","trace[12393081] 'compare' (duration: 135.417828ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:09:26.523051Z","caller":"traceutil/trace.go:171","msg":"trace[2128725036] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"136.184567ms","start":"2026-08-06T07:09:26.386848Z","end":"2026-08-06T07:09:26.523032Z","steps":["trace[2128725036] 'process raft request' (duration: 136.064794ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:09:26.783996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.73364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T07:09:26.784225Z","caller":"traceutil/trace.go:171","msg":"trace[1821888790] linearizableReadLoop","detail":"{readStateIndex:2268; appliedIndex:2267; }","duration":"189.977785ms","start":"2026-08-06T07:09:26.594224Z","end":"2026-08-06T07:09:26.784202Z","steps":["trace[1821888790] 'read index received' (duration: 75.913466ms)","trace[1821888790] 'applied index is now lower than readState.Index' (duration: 114.063489ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:09:26.784277Z","caller":"traceutil/trace.go:171","msg":"trace[798078617] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"212.012182ms","start":"2026-08-06T07:09:26.572259Z","end":"2026-08-06T07:09:26.784271Z","steps":["trace[798078617] 'process raft request' (duration: 97.949283ms)","trace[798078617] 'compare' (duration: 113.557375ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:09:26.784423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.20316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-06T07:09:26.784447Z","caller":"traceutil/trace.go:171","msg":"trace[475146939] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2188; }","duration":"190.250382ms","start":"2026-08-06T07:09:26.594189Z","end":"2026-08-06T07:09:26.78444Z","steps":["trace[475146939] 'agreement among raft nodes before linearized reading' (duration: 190.1611ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:09:26.784464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.752985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-06T07:09:26.784506Z","caller":"traceutil/trace.go:171","msg":"trace[422928416] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2188; }","duration":"171.821657ms","start":"2026-08-06T07:09:26.61267Z","end":"2026-08-06T07:09:26.784492Z","steps":["trace[422928416] 'agreement among raft nodes before linearized reading' (duration: 171.714314ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:10:23.162525Z","caller":"traceutil/trace.go:171","msg":"trace[300585380] transaction","detail":"{read_only:false; response_revision:2666; number_of_response:1; }","duration":"185.711754ms","start":"2026-08-06T07:10:22.976797Z","end":"2026-08-06T07:10:23.162508Z","steps":["trace[300585380] 'process raft request' (duration: 185.592851ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:10:23.177639Z","caller":"traceutil/trace.go:171","msg":"trace[810793158] transaction","detail":"{read_only:false; response_revision:2667; number_of_response:1; }","duration":"121.288803ms","start":"2026-08-06T07:10:23.056316Z","end":"2026-08-06T07:10:23.177604Z","steps":["trace[810793158] 'process raft request' (duration: 120.861672ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:10:57.000177Z","caller":"traceutil/trace.go:171","msg":"trace[1133725443] linearizableReadLoop","detail":"{readStateIndex:2976; appliedIndex:2975; }","duration":"152.617609ms","start":"2026-08-06T07:10:56.84754Z","end":"2026-08-06T07:10:57.000157Z","steps":["trace[1133725443] 'read index received' (duration: 152.383243ms)","trace[1133725443] 'applied index is now lower than readState.Index' (duration: 233.585µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:10:57.000231Z","caller":"traceutil/trace.go:171","msg":"trace[1806104637] transaction","detail":"{read_only:false; response_revision:2870; number_of_response:1; }","duration":"169.720745ms","start":"2026-08-06T07:10:56.830481Z","end":"2026-08-06T07:10:57.000202Z","steps":["trace[1806104637] 'process raft request' (duration: 169.53112ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:10:57.000314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.762783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T07:10:57.000387Z","caller":"traceutil/trace.go:171","msg":"trace[974755472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2870; }","duration":"152.880285ms","start":"2026-08-06T07:10:56.847499Z","end":"2026-08-06T07:10:57.000379Z","steps":["trace[974755472] 'agreement among raft nodes before linearized reading' (duration: 152.772912ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:13:47.701769Z","caller":"traceutil/trace.go:171","msg":"trace[518994231] transaction","detail":"{read_only:false; response_revision:3979; number_of_response:1; }","duration":"102.369434ms","start":"2026-08-06T07:13:47.59937Z","end":"2026-08-06T07:13:47.70174Z","steps":["trace[518994231] 'process raft request' (duration: 102.044455ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:13:49.829808Z","caller":"traceutil/trace.go:171","msg":"trace[1918579021] transaction","detail":"{read_only:false; response_revision:3988; number_of_response:1; }","duration":"120.016167ms","start":"2026-08-06T07:13:49.709735Z","end":"2026-08-06T07:13:49.829751Z","steps":["trace[1918579021] 'process raft request' (duration: 119.821523ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:14:58.788174Z","caller":"traceutil/trace.go:171","msg":"trace[1129243744] transaction","detail":"{read_only:false; response_revision:4949; number_of_response:1; }","duration":"113.720968ms","start":"2026-08-06T07:14:58.674433Z","end":"2026-08-06T07:14:58.788154Z","steps":["trace[1129243744] 'process raft request' (duration: 113.587935ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:15:02.712931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.490027ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T07:15:02.713047Z","caller":"traceutil/trace.go:171","msg":"trace[964648902] linearizableReadLoop","detail":"{readStateIndex:5276; appliedIndex:5275; }","duration":"143.084581ms","start":"2026-08-06T07:15:02.569946Z","end":"2026-08-06T07:15:02.713031Z","steps":["trace[964648902] 'read index received' (duration: 6.348558ms)","trace[964648902] 'applied index is now lower than readState.Index' (duration: 136.734723ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:15:02.713058Z","caller":"traceutil/trace.go:171","msg":"trace[1694788032] transaction","detail":"{read_only:false; response_revision:5092; number_of_response:1; }","duration":"146.047095ms","start":"2026-08-06T07:15:02.566983Z","end":"2026-08-06T07:15:02.71303Z","steps":["trace[1694788032] 'compare' (duration: 136.389984ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:15:02.713183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.221084ms","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-08-06T07:15:02.713192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.486841ms","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-08-06T07:15:02.71321Z","caller":"traceutil/trace.go:171","msg":"trace[1048313212] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5092; }","duration":"143.262215ms","start":"2026-08-06T07:15:02.569941Z","end":"2026-08-06T07:15:02.713203Z","steps":["trace[1048313212] 'agreement among raft nodes before linearized reading' (duration: 143.156773ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:15:02.713225Z","caller":"traceutil/trace.go:171","msg":"trace[1741382984] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5092; }","duration":"141.520142ms","start":"2026-08-06T07:15:02.571694Z","end":"2026-08-06T07:15:02.713214Z","steps":["trace[1741382984] 'agreement among raft nodes before linearized reading' (duration: 141.413429ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:15:02.84559Z","caller":"traceutil/trace.go:171","msg":"trace[715579290] transaction","detail":"{read_only:false; response_revision:5093; number_of_response:1; }","duration":"128.249871ms","start":"2026-08-06T07:15:02.71732Z","end":"2026-08-06T07:15:02.84557Z","steps":["trace[715579290] 'process raft request' (duration: 128.112778ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:15:02.902448Z","caller":"traceutil/trace.go:171","msg":"trace[355530604] linearizableReadLoop","detail":"{readStateIndex:5278; appliedIndex:5276; }","duration":"181.996739ms","start":"2026-08-06T07:15:02.720431Z","end":"2026-08-06T07:15:02.902427Z","steps":["trace[355530604] 'read index received' (duration: 125.072632ms)","trace[355530604] 'applied index is now lower than readState.Index' (duration: 56.923447ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:15:02.90268Z","caller":"traceutil/trace.go:171","msg":"trace[1236182432] transaction","detail":"{read_only:false; response_revision:5094; number_of_response:1; }","duration":"185.26213ms","start":"2026-08-06T07:15:02.717409Z","end":"2026-08-06T07:15:02.902671Z","steps":["trace[1236182432] 'process raft request' (duration: 184.830949ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:15:02.902869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.77764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8bca7c1f-64a0-4b5a-a664-49a17bca25e0\" ","response":"range_response_count:1 size:3721"} {"level":"info","ts":"2026-08-06T07:15:02.902944Z","caller":"traceutil/trace.go:171","msg":"trace[403822463] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8bca7c1f-64a0-4b5a-a664-49a17bca25e0; range_end:; response_count:1; response_revision:5095; }","duration":"174.892393ms","start":"2026-08-06T07:15:02.728035Z","end":"2026-08-06T07:15:02.902927Z","steps":["trace[403822463] 'agreement among raft nodes before linearized reading' (duration: 174.669388ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:15:02.902867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.406589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T07:15:02.903081Z","caller":"traceutil/trace.go:171","msg":"trace[166111674] range","detail":"{range_begin:/registry/configmaps/monitoring/vector; range_end:; response_count:0; response_revision:5095; }","duration":"182.668336ms","start":"2026-08-06T07:15:02.720403Z","end":"2026-08-06T07:15:02.903071Z","steps":["trace[166111674] 'agreement among raft nodes before linearized reading' (duration: 182.360238ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:15:03.213605Z","caller":"traceutil/trace.go:171","msg":"trace[1197314709] transaction","detail":"{read_only:false; response_revision:5105; number_of_response:1; }","duration":"118.016296ms","start":"2026-08-06T07:15:03.095566Z","end":"2026-08-06T07:15:03.213583Z","steps":["trace[1197314709] 'process raft request' (duration: 117.582596ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:15:03.222992Z","caller":"traceutil/trace.go:171","msg":"trace[738331688] transaction","detail":"{read_only:false; response_revision:5106; number_of_response:1; }","duration":"111.227359ms","start":"2026-08-06T07:15:03.111736Z","end":"2026-08-06T07:15:03.222963Z","steps":["trace[738331688] 'process raft request' (duration: 110.998993ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:15:16.330734Z","caller":"traceutil/trace.go:171","msg":"trace[22823425] transaction","detail":"{read_only:false; response_revision:5363; number_of_response:1; }","duration":"114.372556ms","start":"2026-08-06T07:15:16.216343Z","end":"2026-08-06T07:15:16.330716Z","steps":["trace[22823425] 'process raft request' (duration: 114.318875ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:15:16.330762Z","caller":"traceutil/trace.go:171","msg":"trace[1871132753] transaction","detail":"{read_only:false; response_revision:5362; number_of_response:1; }","duration":"148.615287ms","start":"2026-08-06T07:15:16.182106Z","end":"2026-08-06T07:15:16.330721Z","steps":["trace[1871132753] 'process raft request' (duration: 82.270538ms)","trace[1871132753] 'compare' (duration: 66.141044ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:15:16.920768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.124094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-vffgj\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-08-06T07:15:16.920852Z","caller":"traceutil/trace.go:171","msg":"trace[901102318] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-vffgj; range_end:; response_count:1; response_revision:5365; }","duration":"256.237478ms","start":"2026-08-06T07:15:16.664592Z","end":"2026-08-06T07:15:16.920829Z","steps":["trace[901102318] 'range keys from in-memory index tree' (duration: 255.93823ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:15:16.920895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.850982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-06T07:15:16.920931Z","caller":"traceutil/trace.go:171","msg":"trace[1513509794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5365; }","duration":"125.912444ms","start":"2026-08-06T07:15:16.795007Z","end":"2026-08-06T07:15:16.920919Z","steps":["trace[1513509794] 'range keys from in-memory index tree' (duration: 125.76026ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:15:16.920855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.495362ms","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-08-06T07:15:16.921128Z","caller":"traceutil/trace.go:171","msg":"trace[1690402135] 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:5365; }","duration":"174.841761ms","start":"2026-08-06T07:15:16.746277Z","end":"2026-08-06T07:15:16.921118Z","steps":["trace[1690402135] 'count revisions from in-memory index tree' (duration: 174.361199ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:16:21.182091Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3005} {"level":"info","ts":"2026-08-06T07:16:21.258597Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3005,"took":"75.009365ms","hash":1306207690,"current-db-size-bytes":56266752,"current-db-size":"56 MB","current-db-size-in-use-bytes":31592448,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-06T07:16:21.258659Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1306207690,"revision":3005,"compact-revision":-1} {"level":"info","ts":"2026-08-06T07:17:46.257389Z","caller":"traceutil/trace.go:171","msg":"trace[1208910721] transaction","detail":"{read_only:false; response_revision:6626; number_of_response:1; }","duration":"393.472765ms","start":"2026-08-06T07:17:45.863896Z","end":"2026-08-06T07:17:46.257368Z","steps":["trace[1208910721] 'process raft request' (duration: 356.278906ms)","trace[1208910721] 'compare' (duration: 37.070266ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:17:46.257496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:17:45.863869Z","time spent":"393.572438ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T07:17:46.257512Z","caller":"traceutil/trace.go:171","msg":"trace[52146328] linearizableReadLoop","detail":"{readStateIndex:6858; appliedIndex:6856; }","duration":"391.971849ms","start":"2026-08-06T07:17:45.865513Z","end":"2026-08-06T07:17:46.257484Z","steps":["trace[52146328] 'read index received' (duration: 354.856962ms)","trace[52146328] 'applied index is now lower than readState.Index' (duration: 37.113097ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:17:46.257551Z","caller":"traceutil/trace.go:171","msg":"trace[1237863308] transaction","detail":"{read_only:false; response_revision:6627; number_of_response:1; }","duration":"393.344172ms","start":"2026-08-06T07:17:45.864185Z","end":"2026-08-06T07:17:46.257529Z","steps":["trace[1237863308] 'process raft request' (duration: 393.150127ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:46.257684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:17:45.864173Z","time spent":"393.419533ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T07:17:46.257754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.232685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vczgz\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-06T07:17:46.257789Z","caller":"traceutil/trace.go:171","msg":"trace[1800631433] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vczgz; range_end:; response_count:1; response_revision:6627; }","duration":"392.299046ms","start":"2026-08-06T07:17:45.865479Z","end":"2026-08-06T07:17:46.257778Z","steps":["trace[1800631433] 'agreement among raft nodes before linearized reading' (duration: 392.150142ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:46.257804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.69572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-06T07:17:46.257834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:17:45.865464Z","time spent":"392.348638ms","remote":"127.0.0.1:45310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-vczgz\" "} {"level":"info","ts":"2026-08-06T07:17:46.25787Z","caller":"traceutil/trace.go:171","msg":"trace[1211479928] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:6627; }","duration":"291.792382ms","start":"2026-08-06T07:17:45.966058Z","end":"2026-08-06T07:17:46.25785Z","steps":["trace[1211479928] 'agreement among raft nodes before linearized reading' (duration: 291.679049ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:21:21.187835Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6058} {"level":"info","ts":"2026-08-06T07:21:21.280348Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6058,"took":"91.09175ms","hash":3385253183,"current-db-size-bytes":56266752,"current-db-size":"56 MB","current-db-size-in-use-bytes":21942272,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-08-06T07:21:21.280982Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3385253183,"revision":6058,"compact-revision":3005} {"level":"info","ts":"2026-08-06T07:21:35.069706Z","caller":"traceutil/trace.go:171","msg":"trace[1558311205] transaction","detail":"{read_only:false; response_revision:8377; number_of_response:1; }","duration":"118.329092ms","start":"2026-08-06T07:21:34.95135Z","end":"2026-08-06T07:21:35.069679Z","steps":["trace[1558311205] 'process raft request' (duration: 118.173528ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:21:35.09161Z","caller":"traceutil/trace.go:171","msg":"trace[1719876810] transaction","detail":"{read_only:false; response_revision:8378; number_of_response:1; }","duration":"129.6646ms","start":"2026-08-06T07:21:34.961923Z","end":"2026-08-06T07:21:35.091588Z","steps":["trace[1719876810] 'process raft request' (duration: 129.520817ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:21:47.929243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.426947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vczgz\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-06T07:21:47.929333Z","caller":"traceutil/trace.go:171","msg":"trace[739635153] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vczgz; range_end:; response_count:1; response_revision:8444; }","duration":"298.55467ms","start":"2026-08-06T07:21:47.630758Z","end":"2026-08-06T07:21:47.929313Z","steps":["trace[739635153] 'range keys from in-memory index tree' (duration: 298.269453ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:21:47.92952Z","caller":"traceutil/trace.go:171","msg":"trace[764423682] transaction","detail":"{read_only:false; response_revision:8445; number_of_response:1; }","duration":"250.172425ms","start":"2026-08-06T07:21:47.679311Z","end":"2026-08-06T07:21:47.929484Z","steps":["trace[764423682] 'process raft request' (duration: 237.722648ms)","trace[764423682] 'compare' (duration: 12.061908ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:21:47.969839Z","caller":"traceutil/trace.go:171","msg":"trace[688814022] transaction","detail":"{read_only:false; response_revision:8446; number_of_response:1; }","duration":"270.802484ms","start":"2026-08-06T07:21:47.699013Z","end":"2026-08-06T07:21:47.969816Z","steps":["trace[688814022] 'process raft request' (duration: 270.6091ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:21:47.969967Z","caller":"traceutil/trace.go:171","msg":"trace[1299835320] linearizableReadLoop","detail":"{readStateIndex:8736; appliedIndex:8733; }","duration":"124.617337ms","start":"2026-08-06T07:21:47.845312Z","end":"2026-08-06T07:21:47.969929Z","steps":["trace[1299835320] 'read index received' (duration: 71.899976ms)","trace[1299835320] 'applied index is now lower than readState.Index' (duration: 52.714511ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:21:47.970067Z","caller":"traceutil/trace.go:171","msg":"trace[668179735] transaction","detail":"{read_only:false; response_revision:8447; number_of_response:1; }","duration":"244.093116ms","start":"2026-08-06T07:21:47.725923Z","end":"2026-08-06T07:21:47.970016Z","steps":["trace[668179735] 'process raft request' (duration: 243.820478ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:21:47.970175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.824892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T07:21:47.97021Z","caller":"traceutil/trace.go:171","msg":"trace[553486643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8447; }","duration":"124.943595ms","start":"2026-08-06T07:21:47.845255Z","end":"2026-08-06T07:21:47.970199Z","steps":["trace[553486643] 'agreement among raft nodes before linearized reading' (duration: 124.804541ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:22:16.417724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.514563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T07:22:16.417823Z","caller":"traceutil/trace.go:171","msg":"trace[1550437999] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:8657; }","duration":"207.661087ms","start":"2026-08-06T07:22:16.210143Z","end":"2026-08-06T07:22:16.417804Z","steps":["trace[1550437999] 'count revisions from in-memory index tree' (duration: 207.431431ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:22:16.417844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.263614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228905"} {"level":"info","ts":"2026-08-06T07:22:16.417908Z","caller":"traceutil/trace.go:171","msg":"trace[1812624191] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8657; }","duration":"288.334736ms","start":"2026-08-06T07:22:16.129533Z","end":"2026-08-06T07:22:16.417868Z","steps":["trace[1812624191] 'range keys from in-memory index tree' (duration: 287.975998ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:22:16.418099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.823184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T07:22:16.418156Z","caller":"traceutil/trace.go:171","msg":"trace[271125671] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:8657; }","duration":"199.914775ms","start":"2026-08-06T07:22:16.218231Z","end":"2026-08-06T07:22:16.418146Z","steps":["trace[271125671] 'count revisions from in-memory index tree' (duration: 199.756591ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:22:19.883402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.913501ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-06T07:22:19.883545Z","caller":"traceutil/trace.go:171","msg":"trace[230208946] linearizableReadLoop","detail":"{readStateIndex:8967; appliedIndex:8966; }","duration":"238.233001ms","start":"2026-08-06T07:22:19.64529Z","end":"2026-08-06T07:22:19.883523Z","steps":["trace[230208946] 'read index received' (duration: 122.159695ms)","trace[230208946] 'applied index is now lower than readState.Index' (duration: 116.071256ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:22:19.883744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.443506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-06T07:22:19.883835Z","caller":"traceutil/trace.go:171","msg":"trace[214708332] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8669; }","duration":"238.587669ms","start":"2026-08-06T07:22:19.645228Z","end":"2026-08-06T07:22:19.883816Z","steps":["trace[214708332] 'agreement among raft nodes before linearized reading' (duration: 238.330013ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:22:19.883842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.324158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-06T07:22:19.883932Z","caller":"traceutil/trace.go:171","msg":"trace[1653634151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8669; }","duration":"237.443412ms","start":"2026-08-06T07:22:19.64646Z","end":"2026-08-06T07:22:19.883903Z","steps":["trace[1653634151] 'agreement among raft nodes before linearized reading' (duration: 237.251247ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:22:19.884003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.075378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T07:22:19.884042Z","caller":"traceutil/trace.go:171","msg":"trace[259049801] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:8669; }","duration":"202.15085ms","start":"2026-08-06T07:22:19.681881Z","end":"2026-08-06T07:22:19.884032Z","steps":["trace[259049801] 'agreement among raft nodes before linearized reading' (duration: 202.074038ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:22:19.884161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.718574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-06T07:22:19.884227Z","caller":"traceutil/trace.go:171","msg":"trace[1549764904] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8669; }","duration":"236.812116ms","start":"2026-08-06T07:22:19.647397Z","end":"2026-08-06T07:22:19.884209Z","steps":["trace[1549764904] 'agreement among raft nodes before linearized reading' (duration: 236.56748ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:22:30.132845Z","caller":"traceutil/trace.go:171","msg":"trace[1859796467] transaction","detail":"{read_only:false; response_revision:8766; number_of_response:1; }","duration":"142.437536ms","start":"2026-08-06T07:22:29.990384Z","end":"2026-08-06T07:22:30.132821Z","steps":["trace[1859796467] 'process raft request' (duration: 127.362445ms)","trace[1859796467] 'compare' (duration: 14.970569ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:22:30.279589Z","caller":"traceutil/trace.go:171","msg":"trace[166442597] transaction","detail":"{read_only:false; response_revision:8767; number_of_response:1; }","duration":"226.800108ms","start":"2026-08-06T07:22:30.052764Z","end":"2026-08-06T07:22:30.279564Z","steps":["trace[166442597] 'process raft request' (duration: 221.60736ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:22:30.279791Z","caller":"traceutil/trace.go:171","msg":"trace[245935807] linearizableReadLoop","detail":"{readStateIndex:9069; appliedIndex:9067; }","duration":"197.553717ms","start":"2026-08-06T07:22:30.082213Z","end":"2026-08-06T07:22:30.279767Z","steps":["trace[245935807] 'read index received' (duration: 35.509537ms)","trace[245935807] 'applied index is now lower than readState.Index' (duration: 162.0433ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:22:30.279917Z","caller":"traceutil/trace.go:171","msg":"trace[863342016] transaction","detail":"{read_only:false; response_revision:8768; number_of_response:1; }","duration":"196.742507ms","start":"2026-08-06T07:22:30.083154Z","end":"2026-08-06T07:22:30.279897Z","steps":["trace[863342016] 'process raft request' (duration: 196.377998ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:22:30.279992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.768362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-kmhrd\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-08-06T07:22:30.28003Z","caller":"traceutil/trace.go:171","msg":"trace[296742087] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-kmhrd; range_end:; response_count:1; response_revision:8769; }","duration":"197.853435ms","start":"2026-08-06T07:22:30.082167Z","end":"2026-08-06T07:22:30.28002Z","steps":["trace[296742087] 'agreement among raft nodes before linearized reading' (duration: 197.692001ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:22:30.280164Z","caller":"traceutil/trace.go:171","msg":"trace[450841271] transaction","detail":"{read_only:false; response_revision:8769; number_of_response:1; }","duration":"164.158503ms","start":"2026-08-06T07:22:30.115993Z","end":"2026-08-06T07:22:30.280151Z","steps":["trace[450841271] 'process raft request' (duration: 163.707062ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:22:30.286296Z","caller":"traceutil/trace.go:171","msg":"trace[1497542900] transaction","detail":"{read_only:false; response_revision:8770; number_of_response:1; }","duration":"163.322351ms","start":"2026-08-06T07:22:30.122956Z","end":"2026-08-06T07:22:30.286278Z","steps":["trace[1497542900] 'process raft request' (duration: 163.233939ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:22:43.134012Z","caller":"traceutil/trace.go:171","msg":"trace[1668606089] transaction","detail":"{read_only:false; response_revision:8873; number_of_response:1; }","duration":"163.793153ms","start":"2026-08-06T07:22:42.969938Z","end":"2026-08-06T07:22:43.133731Z","steps":["trace[1668606089] 'process raft request' (duration: 162.706147ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:22:45.000686Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023ff.wal"} {"level":"info","ts":"2026-08-06T07:23:47.044632Z","caller":"traceutil/trace.go:171","msg":"trace[2114177567] transaction","detail":"{read_only:false; response_revision:9523; number_of_response:1; }","duration":"134.435961ms","start":"2026-08-06T07:23:46.910171Z","end":"2026-08-06T07:23:47.044607Z","steps":["trace[2114177567] 'process raft request' (duration: 133.97424ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:24:05.096527Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3e4829a3cabe4fce","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-06T07:24:05.102269Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-06T07:24:05.102362Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-06T07:26:21.193327Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8165} {"level":"info","ts":"2026-08-06T07:26:21.255854Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8165,"took":"60.613557ms","hash":3543651318,"current-db-size-bytes":56266752,"current-db-size":"56 MB","current-db-size-in-use-bytes":29736960,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-06T07:26:21.25592Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3543651318,"revision":8165,"compact-revision":6058} {"level":"info","ts":"2026-08-06T07:27:31.967976Z","caller":"traceutil/trace.go:171","msg":"trace[1497446527] linearizableReadLoop","detail":"{readStateIndex:11760; appliedIndex:11759; }","duration":"128.914931ms","start":"2026-08-06T07:27:31.839039Z","end":"2026-08-06T07:27:31.967954Z","steps":["trace[1497446527] 'read index received' (duration: 128.694146ms)","trace[1497446527] 'applied index is now lower than readState.Index' (duration: 220.085µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:27:31.968031Z","caller":"traceutil/trace.go:171","msg":"trace[836054780] transaction","detail":"{read_only:false; response_revision:11381; number_of_response:1; }","duration":"161.351854ms","start":"2026-08-06T07:27:31.806649Z","end":"2026-08-06T07:27:31.968001Z","steps":["trace[836054780] 'process raft request' (duration: 161.150118ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:27:31.969175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.256193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-08-06T07:27:31.969653Z","caller":"traceutil/trace.go:171","msg":"trace[1805687862] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11381; }","duration":"123.733934ms","start":"2026-08-06T07:27:31.845878Z","end":"2026-08-06T07:27:31.969612Z","steps":["trace[1805687862] 'agreement among raft nodes before linearized reading' (duration: 122.290819ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:27:31.969672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.517522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T07:27:31.969767Z","caller":"traceutil/trace.go:171","msg":"trace[867769022] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11381; }","duration":"130.642535ms","start":"2026-08-06T07:27:31.839085Z","end":"2026-08-06T07:27:31.969727Z","steps":["trace[867769022] 'agreement among raft nodes before linearized reading' (duration: 129.008925ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:27:31.969987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.868473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T07:27:31.970088Z","caller":"traceutil/trace.go:171","msg":"trace[1120685134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11381; }","duration":"122.983966ms","start":"2026-08-06T07:27:31.847071Z","end":"2026-08-06T07:27:31.970055Z","steps":["trace[1120685134] 'agreement among raft nodes before linearized reading' (duration: 121.09999ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:27:31.970261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.532071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T07:27:31.970338Z","caller":"traceutil/trace.go:171","msg":"trace[568370493] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11381; }","duration":"130.647364ms","start":"2026-08-06T07:27:31.839656Z","end":"2026-08-06T07:27:31.970304Z","steps":["trace[568370493] 'agreement among raft nodes before linearized reading' (duration: 128.528892ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:27:31.970406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.990152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T07:27:31.970472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.400974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T07:27:31.970502Z","caller":"traceutil/trace.go:171","msg":"trace[865844496] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11381; }","duration":"131.097285ms","start":"2026-08-06T07:27:31.839377Z","end":"2026-08-06T07:27:31.970474Z","steps":["trace[865844496] 'agreement among raft nodes before linearized reading' (duration: 128.803559ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:27:31.970579Z","caller":"traceutil/trace.go:171","msg":"trace[1623812158] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11381; }","duration":"131.547806ms","start":"2026-08-06T07:27:31.838996Z","end":"2026-08-06T07:27:31.970544Z","steps":["trace[1623812158] 'agreement among raft nodes before linearized reading' (duration: 129.051626ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:27:33.263525Z","caller":"traceutil/trace.go:171","msg":"trace[397567081] transaction","detail":"{read_only:false; response_revision:11383; number_of_response:1; }","duration":"133.992848ms","start":"2026-08-06T07:27:33.129506Z","end":"2026-08-06T07:27:33.263499Z","steps":["trace[397567081] 'process raft request' (duration: 133.830934ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:31:21.199149Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10755} {"level":"info","ts":"2026-08-06T07:31:21.275362Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10755,"took":"73.725206ms","hash":4272359804,"current-db-size-bytes":56266752,"current-db-size":"56 MB","current-db-size-in-use-bytes":27164672,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-06T07:31:21.275419Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4272359804,"revision":10755,"compact-revision":8165} {"level":"info","ts":"2026-08-06T07:33:17.190364Z","caller":"traceutil/trace.go:171","msg":"trace[1243286005] transaction","detail":"{read_only:false; response_revision:13708; number_of_response:1; }","duration":"149.613305ms","start":"2026-08-06T07:33:17.040712Z","end":"2026-08-06T07:33:17.190325Z","steps":["trace[1243286005] 'process raft request' (duration: 149.44829ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:33:17.3474Z","caller":"traceutil/trace.go:171","msg":"trace[418410108] transaction","detail":"{read_only:false; response_revision:13711; number_of_response:1; }","duration":"106.717438ms","start":"2026-08-06T07:33:17.240639Z","end":"2026-08-06T07:33:17.347357Z","steps":["trace[418410108] 'process raft request' (duration: 106.391779ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:33:47.212312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.963981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T07:33:47.212417Z","caller":"traceutil/trace.go:171","msg":"trace[1138620375] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:13904; }","duration":"152.114345ms","start":"2026-08-06T07:33:47.060276Z","end":"2026-08-06T07:33:47.212391Z","steps":["trace[1138620375] 'count revisions from in-memory index tree' (duration: 151.88541ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:33:47.212331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.143743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-06T07:33:47.212512Z","caller":"traceutil/trace.go:171","msg":"trace[1104289575] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13904; }","duration":"119.363969ms","start":"2026-08-06T07:33:47.093121Z","end":"2026-08-06T07:33:47.212485Z","steps":["trace[1104289575] 'range keys from in-memory index tree' (duration: 118.908527ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:34:03.172691Z","caller":"traceutil/trace.go:171","msg":"trace[10318371] transaction","detail":"{read_only:false; response_revision:14001; number_of_response:1; }","duration":"141.452464ms","start":"2026-08-06T07:34:03.031216Z","end":"2026-08-06T07:34:03.172669Z","steps":["trace[10318371] 'process raft request' (duration: 141.406543ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:34:03.172716Z","caller":"traceutil/trace.go:171","msg":"trace[268639674] transaction","detail":"{read_only:false; response_revision:14000; number_of_response:1; }","duration":"289.148619ms","start":"2026-08-06T07:34:02.883538Z","end":"2026-08-06T07:34:03.172687Z","steps":["trace[268639674] 'process raft request' (duration: 272.349176ms)","trace[268639674] 'compare' (duration: 16.390773ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:34:03.172818Z","caller":"traceutil/trace.go:171","msg":"trace[245905558] linearizableReadLoop","detail":"{readStateIndex:14471; appliedIndex:14470; }","duration":"142.742375ms","start":"2026-08-06T07:34:03.030049Z","end":"2026-08-06T07:34:03.172792Z","steps":["trace[245905558] 'read index received' (duration: 125.850189ms)","trace[245905558] 'applied index is now lower than readState.Index' (duration: 16.889426ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:34:03.172954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.906078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-06T07:34:03.172991Z","caller":"traceutil/trace.go:171","msg":"trace[1702072395] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14001; }","duration":"142.988921ms","start":"2026-08-06T07:34:03.029992Z","end":"2026-08-06T07:34:03.172981Z","steps":["trace[1702072395] 'agreement among raft nodes before linearized reading' (duration: 142.864077ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:34:24.979659Z","caller":"traceutil/trace.go:171","msg":"trace[1219224972] linearizableReadLoop","detail":"{readStateIndex:14793; appliedIndex:14792; }","duration":"241.469874ms","start":"2026-08-06T07:34:24.738174Z","end":"2026-08-06T07:34:24.979644Z","steps":["trace[1219224972] 'read index received' (duration: 241.415543ms)","trace[1219224972] 'applied index is now lower than readState.Index' (duration: 53.441µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:34:24.979744Z","caller":"traceutil/trace.go:171","msg":"trace[1633446786] transaction","detail":"{read_only:false; response_revision:14308; number_of_response:1; }","duration":"255.881879ms","start":"2026-08-06T07:34:24.723855Z","end":"2026-08-06T07:34:24.979737Z","steps":["trace[1633446786] 'process raft request' (duration: 255.694274ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:34:24.979954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.763481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d1b29b0d-6be7-4791-a0ca-8437f84ae777\" ","response":"range_response_count:1 size:3757"} {"level":"info","ts":"2026-08-06T07:34:24.980029Z","caller":"traceutil/trace.go:171","msg":"trace[1245014984] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d1b29b0d-6be7-4791-a0ca-8437f84ae777; range_end:; response_count:1; response_revision:14308; }","duration":"241.882854ms","start":"2026-08-06T07:34:24.738129Z","end":"2026-08-06T07:34:24.980011Z","steps":["trace[1245014984] 'agreement among raft nodes before linearized reading' (duration: 241.71217ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:34:25.140653Z","caller":"traceutil/trace.go:171","msg":"trace[302202761] linearizableReadLoop","detail":"{readStateIndex:14794; appliedIndex:14793; }","duration":"160.898221ms","start":"2026-08-06T07:34:24.979716Z","end":"2026-08-06T07:34:25.140615Z","steps":["trace[302202761] 'read index received' (duration: 152.345271ms)","trace[302202761] 'applied index is now lower than readState.Index' (duration: 8.55124ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:34:25.140709Z","caller":"traceutil/trace.go:171","msg":"trace[1213753947] transaction","detail":"{read_only:false; response_revision:14309; number_of_response:1; }","duration":"211.827035ms","start":"2026-08-06T07:34:24.928839Z","end":"2026-08-06T07:34:25.140666Z","steps":["trace[1213753947] 'process raft request' (duration: 203.327855ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:34:25.140819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.367472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T07:34:25.140926Z","caller":"traceutil/trace.go:171","msg":"trace[1917994568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14309; }","duration":"295.512545ms","start":"2026-08-06T07:34:24.845383Z","end":"2026-08-06T07:34:25.140895Z","steps":["trace[1917994568] 'agreement among raft nodes before linearized reading' (duration: 295.351741ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:35:40.742537Z","caller":"traceutil/trace.go:171","msg":"trace[407855860] transaction","detail":"{read_only:false; response_revision:15065; number_of_response:1; }","duration":"120.879125ms","start":"2026-08-06T07:35:40.621614Z","end":"2026-08-06T07:35:40.742493Z","steps":["trace[407855860] 'process raft request' (duration: 120.551797ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:35:41.938751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.548279ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T07:35:41.938885Z","caller":"traceutil/trace.go:171","msg":"trace[2115595656] linearizableReadLoop","detail":"{readStateIndex:15573; appliedIndex:15572; }","duration":"287.339083ms","start":"2026-08-06T07:35:41.651533Z","end":"2026-08-06T07:35:41.938873Z","steps":["trace[2115595656] 'read index received' (duration: 33.711µs)","trace[2115595656] 'applied index is now lower than readState.Index' (duration: 287.304472ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:35:41.938937Z","caller":"traceutil/trace.go:171","msg":"trace[521576876] transaction","detail":"{read_only:false; response_revision:15068; number_of_response:1; }","duration":"412.440232ms","start":"2026-08-06T07:35:41.52647Z","end":"2026-08-06T07:35:41.93891Z","steps":["trace[521576876] 'process raft request' (duration: 113.648617ms)","trace[521576876] 'compare' (duration: 298.432856ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:35:41.939172Z","caller":"traceutil/trace.go:171","msg":"trace[596168453] transaction","detail":"{read_only:false; response_revision:15069; number_of_response:1; }","duration":"167.757209ms","start":"2026-08-06T07:35:41.771371Z","end":"2026-08-06T07:35:41.939129Z","steps":["trace[596168453] 'process raft request' (duration: 167.467512ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:35:41.939116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.827574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T07:35:41.939201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.656141ms","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-08-06T07:35:41.939219Z","caller":"traceutil/trace.go:171","msg":"trace[1704990027] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15069; }","duration":"252.967186ms","start":"2026-08-06T07:35:41.686237Z","end":"2026-08-06T07:35:41.939204Z","steps":["trace[1704990027] 'agreement among raft nodes before linearized reading' (duration: 252.830943ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:35:41.939244Z","caller":"traceutil/trace.go:171","msg":"trace[85344041] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15069; }","duration":"287.731183ms","start":"2026-08-06T07:35:41.6515Z","end":"2026-08-06T07:35:41.939231Z","steps":["trace[85344041] 'agreement among raft nodes before linearized reading' (duration: 287.438276ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:35:41.939275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.32146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-08-06T07:35:41.939313Z","caller":"traceutil/trace.go:171","msg":"trace[1286058151] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15069; }","duration":"241.373372ms","start":"2026-08-06T07:35:41.697926Z","end":"2026-08-06T07:35:41.939299Z","steps":["trace[1286058151] 'agreement among raft nodes before linearized reading' (duration: 241.182697ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:35:41.939338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.787504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-08-06T07:35:41.939411Z","caller":"traceutil/trace.go:171","msg":"trace[298624654] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15069; }","duration":"248.892137ms","start":"2026-08-06T07:35:41.690499Z","end":"2026-08-06T07:35:41.939391Z","steps":["trace[298624654] 'agreement among raft nodes before linearized reading' (duration: 248.677782ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:35:41.93907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:35:41.526459Z","time spent":"412.538674ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T07:35:41.939551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.728452ms","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-08-06T07:35:41.939649Z","caller":"traceutil/trace.go:171","msg":"trace[1495548284] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15069; }","duration":"183.833665ms","start":"2026-08-06T07:35:41.755791Z","end":"2026-08-06T07:35:41.939624Z","steps":["trace[1495548284] 'agreement among raft nodes before linearized reading' (duration: 183.320862ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:36:21.207787Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12865} {"level":"info","ts":"2026-08-06T07:36:21.300085Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12865,"took":"90.255243ms","hash":529261940,"current-db-size-bytes":56266752,"current-db-size":"56 MB","current-db-size-in-use-bytes":29949952,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-06T07:36:21.300138Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":529261940,"revision":12865,"compact-revision":10755} {"level":"info","ts":"2026-08-06T07:41:21.214481Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15272} {"level":"info","ts":"2026-08-06T07:41:21.316899Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15272,"took":"99.042147ms","hash":668688648,"current-db-size-bytes":56266752,"current-db-size":"56 MB","current-db-size-in-use-bytes":31625216,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-06T07:41:21.316976Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":668688648,"revision":15272,"compact-revision":12865} {"level":"info","ts":"2026-08-06T07:41:53.003035Z","caller":"traceutil/trace.go:171","msg":"trace[1165295107] transaction","detail":"{read_only:false; response_revision:17765; number_of_response:1; }","duration":"143.921701ms","start":"2026-08-06T07:41:52.859029Z","end":"2026-08-06T07:41:53.00295Z","steps":["trace[1165295107] 'process raft request' (duration: 143.725986ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:41:53.412359Z","caller":"traceutil/trace.go:171","msg":"trace[773420570] transaction","detail":"{read_only:false; response_revision:17767; number_of_response:1; }","duration":"115.347527ms","start":"2026-08-06T07:41:53.296986Z","end":"2026-08-06T07:41:53.412333Z","steps":["trace[773420570] 'process raft request' (duration: 115.184634ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:41:53.470684Z","caller":"traceutil/trace.go:171","msg":"trace[1662109437] transaction","detail":"{read_only:false; response_revision:17768; number_of_response:1; }","duration":"172.823021ms","start":"2026-08-06T07:41:53.29781Z","end":"2026-08-06T07:41:53.470633Z","steps":["trace[1662109437] 'process raft request' (duration: 170.797871ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:41:54.012799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.900596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:41:54.012889Z","caller":"traceutil/trace.go:171","msg":"trace[2014098121] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17768; }","duration":"165.03597ms","start":"2026-08-06T07:41:53.847835Z","end":"2026-08-06T07:41:54.012871Z","steps":["trace[2014098121] 'range keys from in-memory index tree' (duration: 164.812594ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:41:54.013359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.8039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-06T07:41:54.013426Z","caller":"traceutil/trace.go:171","msg":"trace[1889645944] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17768; }","duration":"183.905123ms","start":"2026-08-06T07:41:53.829504Z","end":"2026-08-06T07:41:54.013409Z","steps":["trace[1889645944] 'range keys from in-memory index tree' (duration: 183.655187ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:41:54.013387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.665607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-08-06T07:41:54.013477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.518714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-06T07:41:54.013524Z","caller":"traceutil/trace.go:171","msg":"trace[1525537169] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17768; }","duration":"244.858152ms","start":"2026-08-06T07:41:53.768645Z","end":"2026-08-06T07:41:54.013503Z","steps":["trace[1525537169] 'range keys from in-memory index tree' (duration: 244.568395ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:41:54.013351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.547527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18623"} {"level":"info","ts":"2026-08-06T07:41:54.013577Z","caller":"traceutil/trace.go:171","msg":"trace[2034289847] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17768; }","duration":"244.663807ms","start":"2026-08-06T07:41:53.768895Z","end":"2026-08-06T07:41:54.013559Z","steps":["trace[2034289847] 'range keys from in-memory index tree' (duration: 244.343879ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:41:54.013651Z","caller":"traceutil/trace.go:171","msg":"trace[1111326312] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:17768; }","duration":"225.858965ms","start":"2026-08-06T07:41:53.787752Z","end":"2026-08-06T07:41:54.013611Z","steps":["trace[1111326312] 'range keys from in-memory index tree' (duration: 225.369852ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:42:02.328177Z","caller":"traceutil/trace.go:171","msg":"trace[65160154] transaction","detail":"{read_only:false; response_revision:17890; number_of_response:1; }","duration":"146.823471ms","start":"2026-08-06T07:42:02.181327Z","end":"2026-08-06T07:42:02.32815Z","steps":["trace[65160154] 'process raft request' (duration: 126.117972ms)","trace[65160154] 'compare' (duration: 20.538595ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:45:02.050291Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3e4829a3cabe4fce","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-06T07:45:02.059715Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-06T07:45:02.059818Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-06T07:46:21.219757Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17548} {"level":"info","ts":"2026-08-06T07:46:21.308075Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17548,"took":"85.135844ms","hash":695594346,"current-db-size-bytes":56266752,"current-db-size":"56 MB","current-db-size-in-use-bytes":34140160,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-06T07:46:21.308139Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":695594346,"revision":17548,"compact-revision":15272} {"level":"warn","ts":"2026-08-06T07:48:02.099755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.294346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-06T07:48:02.099985Z","caller":"traceutil/trace.go:171","msg":"trace[654777393] linearizableReadLoop","detail":"{readStateIndex:21259; appliedIndex:21258; }","duration":"207.888882ms","start":"2026-08-06T07:48:01.892016Z","end":"2026-08-06T07:48:02.099905Z","steps":["trace[654777393] 'read index received' (duration: 83.151375ms)","trace[654777393] 'applied index is now lower than readState.Index' (duration: 124.735157ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:48:02.10013Z","caller":"traceutil/trace.go:171","msg":"trace[42519944] transaction","detail":"{read_only:false; response_revision:20568; number_of_response:1; }","duration":"208.629731ms","start":"2026-08-06T07:48:01.891488Z","end":"2026-08-06T07:48:02.100117Z","steps":["trace[42519944] 'process raft request' (duration: 83.74154ms)","trace[42519944] 'compare' (duration: 123.474506ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:48:02.100309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.575605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-06T07:48:02.100372Z","caller":"traceutil/trace.go:171","msg":"trace[1655811739] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20569; }","duration":"207.652826ms","start":"2026-08-06T07:48:01.892694Z","end":"2026-08-06T07:48:02.100346Z","steps":["trace[1655811739] 'agreement among raft nodes before linearized reading' (duration: 207.521313ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:48:02.10058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.461732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-06T07:48:02.100632Z","caller":"traceutil/trace.go:171","msg":"trace[1882196152] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20569; }","duration":"205.558995ms","start":"2026-08-06T07:48:01.895061Z","end":"2026-08-06T07:48:02.10062Z","steps":["trace[1882196152] 'agreement among raft nodes before linearized reading' (duration: 205.442952ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:48:02.100912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.899448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-06T07:48:02.10096Z","caller":"traceutil/trace.go:171","msg":"trace[2035632720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20569; }","duration":"208.951389ms","start":"2026-08-06T07:48:01.891999Z","end":"2026-08-06T07:48:02.10095Z","steps":["trace[2035632720] 'agreement among raft nodes before linearized reading' (duration: 208.823356ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:48:02.266419Z","caller":"traceutil/trace.go:171","msg":"trace[353524196] transaction","detail":"{read_only:false; response_revision:20570; number_of_response:1; }","duration":"158.242972ms","start":"2026-08-06T07:48:02.108155Z","end":"2026-08-06T07:48:02.266398Z","steps":["trace[353524196] 'process raft request' (duration: 148.512532ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:48:02.266667Z","caller":"traceutil/trace.go:171","msg":"trace[537330334] transaction","detail":"{read_only:false; response_revision:20571; number_of_response:1; }","duration":"158.325124ms","start":"2026-08-06T07:48:02.10826Z","end":"2026-08-06T07:48:02.266585Z","steps":["trace[537330334] 'process raft request' (duration: 158.094709ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:48:02.267106Z","caller":"traceutil/trace.go:171","msg":"trace[492148302] linearizableReadLoop","detail":"{readStateIndex:21263; appliedIndex:21260; }","duration":"134.870647ms","start":"2026-08-06T07:48:02.132198Z","end":"2026-08-06T07:48:02.267068Z","steps":["trace[492148302] 'read index received' (duration: 124.489541ms)","trace[492148302] 'applied index is now lower than readState.Index' (duration: 10.378526ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:48:02.267433Z","caller":"traceutil/trace.go:171","msg":"trace[1578394839] transaction","detail":"{read_only:false; response_revision:20572; number_of_response:1; }","duration":"158.761524ms","start":"2026-08-06T07:48:02.108408Z","end":"2026-08-06T07:48:02.267169Z","steps":["trace[1578394839] 'process raft request' (duration: 158.118829ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:48:02.267476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.259996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"warn","ts":"2026-08-06T07:48:02.267501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.113991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:48:02.267522Z","caller":"traceutil/trace.go:171","msg":"trace[740502007] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:20572; }","duration":"135.362658ms","start":"2026-08-06T07:48:02.132143Z","end":"2026-08-06T07:48:02.267505Z","steps":["trace[740502007] 'agreement among raft nodes before linearized reading' (duration: 135.101122ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:48:02.267542Z","caller":"traceutil/trace.go:171","msg":"trace[372076637] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:20572; }","duration":"104.199712ms","start":"2026-08-06T07:48:02.16333Z","end":"2026-08-06T07:48:02.26753Z","steps":["trace[372076637] 'agreement among raft nodes before linearized reading' (duration: 104.11333ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:48:02.544106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.345199ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-06T07:48:02.54426Z","caller":"traceutil/trace.go:171","msg":"trace[1752853211] transaction","detail":"{read_only:false; response_revision:20573; number_of_response:1; }","duration":"208.023385ms","start":"2026-08-06T07:48:02.336213Z","end":"2026-08-06T07:48:02.544236Z","steps":["trace[1752853211] 'process raft request' (duration: 85.42349ms)","trace[1752853211] 'compare' (duration: 122.160005ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:48:02.768196Z","caller":"traceutil/trace.go:171","msg":"trace[2649041] transaction","detail":"{read_only:false; response_revision:20574; number_of_response:1; }","duration":"117.51054ms","start":"2026-08-06T07:48:02.650663Z","end":"2026-08-06T07:48:02.768174Z","steps":["trace[2649041] 'process raft request' (duration: 52.197123ms)","trace[2649041] 'compare' (duration: 65.211874ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:51:21.226829Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19929} {"level":"info","ts":"2026-08-06T07:51:21.33686Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19929,"took":"106.426427ms","hash":417961637,"current-db-size-bytes":56266752,"current-db-size":"56 MB","current-db-size-in-use-bytes":30609408,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-06T07:51:21.336929Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":417961637,"revision":19929,"compact-revision":17548} {"level":"info","ts":"2026-08-06T07:51:52.666155Z","caller":"traceutil/trace.go:171","msg":"trace[1185631763] linearizableReadLoop","detail":"{readStateIndex:23765; appliedIndex:23764; }","duration":"292.397578ms","start":"2026-08-06T07:51:52.373732Z","end":"2026-08-06T07:51:52.666129Z","steps":["trace[1185631763] 'read index received' (duration: 292.310406ms)","trace[1185631763] 'applied index is now lower than readState.Index' (duration: 86.142µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:51:52.666446Z","caller":"traceutil/trace.go:171","msg":"trace[1582545836] transaction","detail":"{read_only:false; response_revision:23004; number_of_response:1; }","duration":"294.178831ms","start":"2026-08-06T07:51:52.372252Z","end":"2026-08-06T07:51:52.666431Z","steps":["trace[1582545836] 'process raft request' (duration: 293.72817ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:51:52.666735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.988463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager\" ","response":"range_response_count:1 size:5261"} {"level":"info","ts":"2026-08-06T07:51:52.666795Z","caller":"traceutil/trace.go:171","msg":"trace[785084119] range","detail":"{range_begin:/registry/deployments/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager; range_end:; response_count:1; response_revision:23004; }","duration":"293.094955ms","start":"2026-08-06T07:51:52.373686Z","end":"2026-08-06T07:51:52.66678Z","steps":["trace[785084119] 'agreement among raft nodes before linearized reading' (duration: 292.89636ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:08.701567Z","caller":"traceutil/trace.go:171","msg":"trace[1558917738] linearizableReadLoop","detail":"{readStateIndex:25846; appliedIndex:25845; }","duration":"418.634271ms","start":"2026-08-06T07:55:08.282911Z","end":"2026-08-06T07:55:08.701545Z","steps":["trace[1558917738] 'read index received' (duration: 418.475387ms)","trace[1558917738] 'applied index is now lower than readState.Index' (duration: 158.034µs)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:55:08.701715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.779864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:55:08.701794Z","caller":"traceutil/trace.go:171","msg":"trace[1490137435] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25031; }","duration":"418.912958ms","start":"2026-08-06T07:55:08.282867Z","end":"2026-08-06T07:55:08.70178Z","steps":["trace[1490137435] 'agreement among raft nodes before linearized reading' (duration: 418.765044ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:08.701833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:08.282847Z","time spent":"418.980059ms","remote":"127.0.0.1:45312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"info","ts":"2026-08-06T07:55:08.701679Z","caller":"traceutil/trace.go:171","msg":"trace[1882373543] transaction","detail":"{read_only:false; response_revision:25031; number_of_response:1; }","duration":"468.341703ms","start":"2026-08-06T07:55:08.233255Z","end":"2026-08-06T07:55:08.701597Z","steps":["trace[1882373543] 'process raft request' (duration: 468.146458ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:08.701981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:08.233239Z","time spent":"468.64924ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T07:55:09.202767Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5750709515217056989,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-06T07:55:09.203779Z","caller":"traceutil/trace.go:171","msg":"trace[365132199] transaction","detail":"{read_only:false; response_revision:25033; number_of_response:1; }","duration":"896.803235ms","start":"2026-08-06T07:55:08.306958Z","end":"2026-08-06T07:55:09.203762Z","steps":["trace[365132199] 'process raft request' (duration: 896.733163ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:09.203933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:08.306941Z","time spent":"896.908518ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T07:55:09.203993Z","caller":"traceutil/trace.go:171","msg":"trace[181379706] transaction","detail":"{read_only:false; response_revision:25032; number_of_response:1; }","duration":"914.793847ms","start":"2026-08-06T07:55:08.289137Z","end":"2026-08-06T07:55:09.203931Z","steps":["trace[181379706] 'process raft request' (duration: 914.419148ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:09.204158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:08.289116Z","time spent":"914.96487ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T07:55:09.204391Z","caller":"traceutil/trace.go:171","msg":"trace[675561726] transaction","detail":"{read_only:false; response_revision:25034; number_of_response:1; }","duration":"892.248943ms","start":"2026-08-06T07:55:08.312132Z","end":"2026-08-06T07:55:09.204381Z","steps":["trace[675561726] 'process raft request' (duration: 891.598677ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:09.204455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:08.31211Z","time spent":"892.315064ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T07:55:09.220642Z","caller":"traceutil/trace.go:171","msg":"trace[1667757362] linearizableReadLoop","detail":"{readStateIndex:25850; appliedIndex:25849; }","duration":"518.972787ms","start":"2026-08-06T07:55:08.70163Z","end":"2026-08-06T07:55:09.220603Z","steps":["trace[1667757362] 'read index received' (duration: 502.991825ms)","trace[1667757362] 'applied index is now lower than readState.Index' (duration: 15.979652ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:55:09.220953Z","caller":"traceutil/trace.go:171","msg":"trace[95550237] transaction","detail":"{read_only:false; response_revision:25035; number_of_response:1; }","duration":"366.010307ms","start":"2026-08-06T07:55:08.854926Z","end":"2026-08-06T07:55:09.220936Z","steps":["trace[95550237] 'process raft request' (duration: 365.508565ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:09.221076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:08.854908Z","time spent":"366.085888ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T07:55:09.221478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.225162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:55:09.221547Z","caller":"traceutil/trace.go:171","msg":"trace[886328382] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25035; }","duration":"512.339254ms","start":"2026-08-06T07:55:08.70919Z","end":"2026-08-06T07:55:09.221529Z","steps":["trace[886328382] 'agreement among raft nodes before linearized reading' (duration: 512.205611ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:09.221591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:08.709174Z","time spent":"512.408466ms","remote":"127.0.0.1:41192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true "} {"level":"warn","ts":"2026-08-06T07:55:09.221869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.788503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:55:09.221935Z","caller":"traceutil/trace.go:171","msg":"trace[1571955444] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25035; }","duration":"374.877736ms","start":"2026-08-06T07:55:08.847039Z","end":"2026-08-06T07:55:09.221917Z","steps":["trace[1571955444] 'agreement among raft nodes before linearized reading' (duration: 374.791223ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:09.221989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:08.84702Z","time spent":"374.954407ms","remote":"127.0.0.1:45078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T07:55:09.222013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.074618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:55:09.222068Z","caller":"traceutil/trace.go:171","msg":"trace[667301242] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25035; }","duration":"512.16304ms","start":"2026-08-06T07:55:08.709891Z","end":"2026-08-06T07:55:09.222054Z","steps":["trace[667301242] 'agreement among raft nodes before linearized reading' (duration: 512.031467ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:09.222082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.939503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-08-06T07:55:09.222107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:08.709875Z","time spent":"512.224202ms","remote":"127.0.0.1:45380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"info","ts":"2026-08-06T07:55:09.222133Z","caller":"traceutil/trace.go:171","msg":"trace[1762086360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25035; }","duration":"791.047206ms","start":"2026-08-06T07:55:08.43107Z","end":"2026-08-06T07:55:09.222117Z","steps":["trace[1762086360] 'agreement among raft nodes before linearized reading' (duration: 790.895003ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:09.22217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:08.431054Z","time spent":"791.107357ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-06T07:55:09.222561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.359107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-06T07:55:09.222606Z","caller":"traceutil/trace.go:171","msg":"trace[1857863391] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25035; }","duration":"794.47578ms","start":"2026-08-06T07:55:08.428116Z","end":"2026-08-06T07:55:09.222592Z","steps":["trace[1857863391] 'agreement among raft nodes before linearized reading' (duration: 794.399498ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:09.222644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:08.42808Z","time spent":"794.554762ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-06T07:55:09.222814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.742121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-06T07:55:09.222851Z","caller":"traceutil/trace.go:171","msg":"trace[1256467712] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25035; }","duration":"806.777992ms","start":"2026-08-06T07:55:08.416061Z","end":"2026-08-06T07:55:09.222839Z","steps":["trace[1256467712] 'agreement among raft nodes before linearized reading' (duration: 806.71597ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:09.222896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:08.416019Z","time spent":"806.866984ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-06T07:55:09.415374Z","caller":"traceutil/trace.go:171","msg":"trace[916420480] transaction","detail":"{read_only:false; response_revision:25036; number_of_response:1; }","duration":"187.785216ms","start":"2026-08-06T07:55:09.227569Z","end":"2026-08-06T07:55:09.415354Z","steps":["trace[916420480] 'process raft request' (duration: 157.755968ms)","trace[916420480] 'compare' (duration: 29.938206ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:55:09.781327Z","caller":"traceutil/trace.go:171","msg":"trace[1403176557] linearizableReadLoop","detail":"{readStateIndex:25853; appliedIndex:25850; }","duration":"542.528686ms","start":"2026-08-06T07:55:09.23878Z","end":"2026-08-06T07:55:09.781309Z","steps":["trace[1403176557] 'read index received' (duration: 146.636314ms)","trace[1403176557] 'applied index is now lower than readState.Index' (duration: 395.891722ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:55:09.781408Z","caller":"traceutil/trace.go:171","msg":"trace[1854136455] transaction","detail":"{read_only:false; response_revision:25037; number_of_response:1; }","duration":"550.248356ms","start":"2026-08-06T07:55:09.231152Z","end":"2026-08-06T07:55:09.7814Z","steps":["trace[1854136455] 'process raft request' (duration: 481.869105ms)","trace[1854136455] 'compare' (duration: 67.841278ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:55:09.781472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:09.231126Z","time spent":"550.297597ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T07:55:09.781472Z","caller":"traceutil/trace.go:171","msg":"trace[1757396540] transaction","detail":"{read_only:false; response_revision:25038; number_of_response:1; }","duration":"545.294284ms","start":"2026-08-06T07:55:09.236162Z","end":"2026-08-06T07:55:09.781456Z","steps":["trace[1757396540] 'process raft request' (duration: 545.103839ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:09.781544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:09.236146Z","time spent":"545.360295ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T07:55:09.781604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.824964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:55:09.781625Z","caller":"traceutil/trace.go:171","msg":"trace[264692901] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25038; }","duration":"542.872335ms","start":"2026-08-06T07:55:09.238745Z","end":"2026-08-06T07:55:09.781618Z","steps":["trace[264692901] 'agreement among raft nodes before linearized reading' (duration: 542.833074ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:09.781642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:09.238734Z","time spent":"542.903406ms","remote":"127.0.0.1:45380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-08-06T07:55:10.009056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.571278ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-06T07:55:10.010643Z","caller":"traceutil/trace.go:171","msg":"trace[1404838637] linearizableReadLoop","detail":"{readStateIndex:25855; appliedIndex:25853; }","duration":"229.242115ms","start":"2026-08-06T07:55:09.781375Z","end":"2026-08-06T07:55:10.010617Z","steps":["trace[1404838637] 'read index received' (duration: 8.028097ms)","trace[1404838637] 'applied index is now lower than readState.Index' (duration: 221.212658ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:55:10.010813Z","caller":"traceutil/trace.go:171","msg":"trace[495136041] transaction","detail":"{read_only:false; response_revision:25039; number_of_response:1; }","duration":"355.064197ms","start":"2026-08-06T07:55:09.655725Z","end":"2026-08-06T07:55:10.010789Z","steps":["trace[495136041] 'process raft request' (duration: 353.439728ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:10.010966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:09.655693Z","time spent":"355.183951ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T07:55:10.342872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010459319s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-06T07:55:10.34295Z","caller":"traceutil/trace.go:171","msg":"trace[1771179258] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:25039; }","duration":"1.010583991s","start":"2026-08-06T07:55:09.332345Z","end":"2026-08-06T07:55:10.342929Z","steps":["trace[1771179258] 'agreement among raft nodes before linearized reading' (duration: 679.054772ms)","trace[1771179258] 'count revisions from in-memory index tree' (duration: 331.397286ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:55:10.34298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:09.332326Z","time spent":"1.010647044s","remote":"127.0.0.1:32906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":37,"response size":31,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-08-06T07:55:10.342954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.222637ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-06T07:55:10.343174Z","caller":"traceutil/trace.go:171","msg":"trace[977617655] transaction","detail":"{read_only:false; response_revision:25041; number_of_response:1; }","duration":"250.905768ms","start":"2026-08-06T07:55:10.092251Z","end":"2026-08-06T07:55:10.343157Z","steps":["trace[977617655] 'process raft request' (duration: 250.797855ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:10.343456Z","caller":"traceutil/trace.go:171","msg":"trace[1006918575] linearizableReadLoop","detail":"{readStateIndex:25856; appliedIndex:25855; }","duration":"332.697739ms","start":"2026-08-06T07:55:10.010736Z","end":"2026-08-06T07:55:10.343433Z","steps":["trace[1006918575] 'read index received' (duration: 682.558µs)","trace[1006918575] 'applied index is now lower than readState.Index' (duration: 332.011941ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:55:10.343569Z","caller":"traceutil/trace.go:171","msg":"trace[913617731] transaction","detail":"{read_only:false; response_revision:25040; number_of_response:1; }","duration":"391.635347ms","start":"2026-08-06T07:55:09.951924Z","end":"2026-08-06T07:55:10.343559Z","steps":["trace[913617731] 'process raft request' (duration: 58.685382ms)","trace[913617731] 'compare' (duration: 331.907869ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:55:10.343662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:09.951906Z","time spent":"391.689059ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T07:55:10.343701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.114378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:55:10.343955Z","caller":"traceutil/trace.go:171","msg":"trace[869419214] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25041; }","duration":"490.410765ms","start":"2026-08-06T07:55:09.853527Z","end":"2026-08-06T07:55:10.343938Z","steps":["trace[869419214] 'agreement among raft nodes before linearized reading' (duration: 490.090607ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:10.343982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:09.853499Z","time spent":"490.477067ms","remote":"127.0.0.1:45078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-06T07:55:10.393992Z","caller":"traceutil/trace.go:171","msg":"trace[45726044] transaction","detail":"{read_only:false; response_revision:25042; number_of_response:1; }","duration":"292.896389ms","start":"2026-08-06T07:55:10.101077Z","end":"2026-08-06T07:55:10.393973Z","steps":["trace[45726044] 'process raft request' (duration: 292.783886ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:10.39458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.445239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7914"} {"level":"info","ts":"2026-08-06T07:55:10.394644Z","caller":"traceutil/trace.go:171","msg":"trace[153334501] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:25042; }","duration":"349.525431ms","start":"2026-08-06T07:55:10.045103Z","end":"2026-08-06T07:55:10.394629Z","steps":["trace[153334501] 'agreement among raft nodes before linearized reading' (duration: 349.354887ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:10.394678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:10.045098Z","time spent":"349.572892ms","remote":"127.0.0.1:46882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7937,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"warn","ts":"2026-08-06T07:55:10.394966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.701891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-06T07:55:10.394998Z","caller":"traceutil/trace.go:171","msg":"trace[2137297989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25042; }","duration":"350.749882ms","start":"2026-08-06T07:55:10.04424Z","end":"2026-08-06T07:55:10.39499Z","steps":["trace[2137297989] 'agreement among raft nodes before linearized reading' (duration: 350.599958ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:10.395028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:10.044234Z","time spent":"350.785392ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-06T07:55:10.395172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:55:10.395205Z","caller":"traceutil/trace.go:171","msg":"trace[2108827712] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:25042; }","duration":"353.525071ms","start":"2026-08-06T07:55:10.04167Z","end":"2026-08-06T07:55:10.395195Z","steps":["trace[2108827712] 'agreement among raft nodes before linearized reading' (duration: 353.473269ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:10.395232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:10.041653Z","time spent":"353.572622ms","remote":"127.0.0.1:43986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"info","ts":"2026-08-06T07:55:10.740167Z","caller":"traceutil/trace.go:171","msg":"trace[1517278048] linearizableReadLoop","detail":"{readStateIndex:25859; appliedIndex:25858; }","duration":"345.670148ms","start":"2026-08-06T07:55:10.394442Z","end":"2026-08-06T07:55:10.740112Z","steps":["trace[1517278048] 'read index received' (duration: 345.507884ms)","trace[1517278048] 'applied index is now lower than readState.Index' (duration: 160.964µs)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:55:10.740569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.882001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:55:10.740647Z","caller":"traceutil/trace.go:171","msg":"trace[1294309428] transaction","detail":"{read_only:false; response_revision:25043; number_of_response:1; }","duration":"346.387714ms","start":"2026-08-06T07:55:10.394158Z","end":"2026-08-06T07:55:10.740545Z","steps":["trace[1294309428] 'process raft request' (duration: 345.7886ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:10.740679Z","caller":"traceutil/trace.go:171","msg":"trace[2032418607] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25043; }","duration":"396.018134ms","start":"2026-08-06T07:55:10.344639Z","end":"2026-08-06T07:55:10.740657Z","steps":["trace[2032418607] 'agreement among raft nodes before linearized reading' (duration: 395.85955ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:10.740724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:10.344626Z","time spent":"396.088306ms","remote":"127.0.0.1:45074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T07:55:10.740806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:10.394137Z","time spent":"346.575249ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T07:55:10.994598Z","caller":"traceutil/trace.go:171","msg":"trace[1030812759] transaction","detail":"{read_only:false; response_revision:25045; number_of_response:1; }","duration":"227.022501ms","start":"2026-08-06T07:55:10.767558Z","end":"2026-08-06T07:55:10.99458Z","steps":["trace[1030812759] 'process raft request' (duration: 226.974449ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:10.994686Z","caller":"traceutil/trace.go:171","msg":"trace[1840380544] transaction","detail":"{read_only:false; response_revision:25044; number_of_response:1; }","duration":"589.077851ms","start":"2026-08-06T07:55:10.405578Z","end":"2026-08-06T07:55:10.994655Z","steps":["trace[1840380544] 'process raft request' (duration: 586.145058ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:10.99484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:10.405561Z","time spent":"589.199004ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T07:55:10.99471Z","caller":"traceutil/trace.go:171","msg":"trace[551786804] linearizableReadLoop","detail":"{readStateIndex:25860; appliedIndex:25859; }","duration":"254.442455ms","start":"2026-08-06T07:55:10.740245Z","end":"2026-08-06T07:55:10.994687Z","steps":["trace[551786804] 'read index received' (duration: 251.614436ms)","trace[551786804] 'applied index is now lower than readState.Index' (duration: 2.823779ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:55:10.995032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.285789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-06T07:55:10.995059Z","caller":"traceutil/trace.go:171","msg":"trace[522182963] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25045; }","duration":"271.356961ms","start":"2026-08-06T07:55:10.723695Z","end":"2026-08-06T07:55:10.995052Z","steps":["trace[522182963] 'agreement among raft nodes before linearized reading' (duration: 271.264689ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:10.995268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.433765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:55:10.995317Z","caller":"traceutil/trace.go:171","msg":"trace[534295601] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25045; }","duration":"147.514147ms","start":"2026-08-06T07:55:10.847788Z","end":"2026-08-06T07:55:10.995302Z","steps":["trace[534295601] 'agreement among raft nodes before linearized reading' (duration: 147.434535ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:10.995429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.808131ms","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-08-06T07:55:10.995456Z","caller":"traceutil/trace.go:171","msg":"trace[199816506] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25045; }","duration":"273.870432ms","start":"2026-08-06T07:55:10.721579Z","end":"2026-08-06T07:55:10.99545Z","steps":["trace[199816506] 'agreement among raft nodes before linearized reading' (duration: 273.79019ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:10.997461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.086086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:55:10.997549Z","caller":"traceutil/trace.go:171","msg":"trace[516017533] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:25045; }","duration":"422.220349ms","start":"2026-08-06T07:55:10.57531Z","end":"2026-08-06T07:55:10.99753Z","steps":["trace[516017533] 'agreement among raft nodes before linearized reading' (duration: 419.441431ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:10.997602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:10.575288Z","time spent":"422.296761ms","remote":"127.0.0.1:41498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":29,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true "} {"level":"info","ts":"2026-08-06T07:55:13.141245Z","caller":"traceutil/trace.go:171","msg":"trace[664269836] linearizableReadLoop","detail":"{readStateIndex:25883; appliedIndex:25882; }","duration":"135.06698ms","start":"2026-08-06T07:55:13.00616Z","end":"2026-08-06T07:55:13.141227Z","steps":["trace[664269836] 'read index received' (duration: 134.816154ms)","trace[664269836] 'applied index is now lower than readState.Index' (duration: 250.226µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:55:13.141288Z","caller":"traceutil/trace.go:171","msg":"trace[1814064481] transaction","detail":"{read_only:false; response_revision:25067; number_of_response:1; }","duration":"136.063965ms","start":"2026-08-06T07:55:13.005198Z","end":"2026-08-06T07:55:13.141262Z","steps":["trace[1814064481] 'process raft request' (duration: 135.87563ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:13.141472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.302926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-06T07:55:13.141513Z","caller":"traceutil/trace.go:171","msg":"trace[1230608368] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25067; }","duration":"135.364117ms","start":"2026-08-06T07:55:13.006129Z","end":"2026-08-06T07:55:13.141493Z","steps":["trace[1230608368] 'agreement among raft nodes before linearized reading' (duration: 135.230043ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:13.443176Z","caller":"traceutil/trace.go:171","msg":"trace[1647479665] transaction","detail":"{read_only:false; response_revision:25068; number_of_response:1; }","duration":"297.441842ms","start":"2026-08-06T07:55:13.145713Z","end":"2026-08-06T07:55:13.443155Z","steps":["trace[1647479665] 'process raft request' (duration: 297.283529ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:13.611514Z","caller":"traceutil/trace.go:171","msg":"trace[1231719819] transaction","detail":"{read_only:false; response_revision:25069; number_of_response:1; }","duration":"463.064514ms","start":"2026-08-06T07:55:13.148417Z","end":"2026-08-06T07:55:13.611482Z","steps":["trace[1231719819] 'process raft request' (duration: 462.800558ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:13.611545Z","caller":"traceutil/trace.go:171","msg":"trace[973308201] transaction","detail":"{read_only:false; response_revision:25070; number_of_response:1; }","duration":"385.323983ms","start":"2026-08-06T07:55:13.226189Z","end":"2026-08-06T07:55:13.611513Z","steps":["trace[973308201] 'process raft request' (duration: 385.22642ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:13.611642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:13.148398Z","time spent":"463.178036ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T07:55:13.61174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:13.226163Z","time spent":"385.471796ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T07:55:13.611859Z","caller":"traceutil/trace.go:171","msg":"trace[151078190] transaction","detail":"{read_only:false; response_revision:25071; number_of_response:1; }","duration":"380.021752ms","start":"2026-08-06T07:55:13.231785Z","end":"2026-08-06T07:55:13.611807Z","steps":["trace[151078190] 'process raft request' (duration: 379.672933ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:13.612056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:13.231719Z","time spent":"380.232377ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T07:55:13.612115Z","caller":"traceutil/trace.go:171","msg":"trace[1244395448] transaction","detail":"{read_only:false; response_revision:25073; number_of_response:1; }","duration":"378.75291ms","start":"2026-08-06T07:55:13.233344Z","end":"2026-08-06T07:55:13.612097Z","steps":["trace[1244395448] 'process raft request' (duration: 378.603156ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:13.612082Z","caller":"traceutil/trace.go:171","msg":"trace[1895142729] linearizableReadLoop","detail":"{readStateIndex:25889; appliedIndex:25884; }","duration":"185.798587ms","start":"2026-08-06T07:55:13.426246Z","end":"2026-08-06T07:55:13.612044Z","steps":["trace[1895142729] 'read index received' (duration: 17.269084ms)","trace[1895142729] 'applied index is now lower than readState.Index' (duration: 168.527623ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:55:13.612196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:13.23333Z","time spent":"378.830192ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T07:55:13.612199Z","caller":"traceutil/trace.go:171","msg":"trace[1808744653] transaction","detail":"{read_only:false; response_revision:25072; number_of_response:1; }","duration":"380.228807ms","start":"2026-08-06T07:55:13.231806Z","end":"2026-08-06T07:55:13.612035Z","steps":["trace[1808744653] 'process raft request' (duration: 379.91031ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:13.612266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.018022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-08-06T07:55:13.612277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:13.231773Z","time spent":"380.462832ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T07:55:13.6123Z","caller":"traceutil/trace.go:171","msg":"trace[1525277197] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25073; }","duration":"186.081474ms","start":"2026-08-06T07:55:13.426207Z","end":"2026-08-06T07:55:13.612289Z","steps":["trace[1525277197] 'agreement among raft nodes before linearized reading' (duration: 185.9439ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:13.81361Z","caller":"traceutil/trace.go:171","msg":"trace[827068589] linearizableReadLoop","detail":"{readStateIndex:25890; appliedIndex:25889; }","duration":"147.068736ms","start":"2026-08-06T07:55:13.666522Z","end":"2026-08-06T07:55:13.813591Z","steps":["trace[827068589] 'read index received' (duration: 144.358529ms)","trace[827068589] 'applied index is now lower than readState.Index' (duration: 2.709537ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:55:13.813879Z","caller":"traceutil/trace.go:171","msg":"trace[650206941] transaction","detail":"{read_only:false; response_revision:25074; number_of_response:1; }","duration":"197.249359ms","start":"2026-08-06T07:55:13.616612Z","end":"2026-08-06T07:55:13.813861Z","steps":["trace[650206941] 'process raft request' (duration: 194.317147ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:13.813997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.463295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-06T07:55:13.814045Z","caller":"traceutil/trace.go:171","msg":"trace[1415950698] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:25074; }","duration":"147.558988ms","start":"2026-08-06T07:55:13.666471Z","end":"2026-08-06T07:55:13.81403Z","steps":["trace[1415950698] 'agreement among raft nodes before linearized reading' (duration: 147.440095ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:24.158948Z","caller":"traceutil/trace.go:171","msg":"trace[1831978492] transaction","detail":"{read_only:false; response_revision:25163; number_of_response:1; }","duration":"101.568918ms","start":"2026-08-06T07:55:24.057357Z","end":"2026-08-06T07:55:24.158926Z","steps":["trace[1831978492] 'process raft request' (duration: 101.369663ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:24.611281Z","caller":"traceutil/trace.go:171","msg":"trace[2091593759] linearizableReadLoop","detail":"{readStateIndex:25985; appliedIndex:25984; }","duration":"173.578386ms","start":"2026-08-06T07:55:24.437686Z","end":"2026-08-06T07:55:24.611264Z","steps":["trace[2091593759] 'read index received' (duration: 104.461358ms)","trace[2091593759] 'applied index is now lower than readState.Index' (duration: 69.116138ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:55:24.611365Z","caller":"traceutil/trace.go:171","msg":"trace[1239238081] transaction","detail":"{read_only:false; response_revision:25166; number_of_response:1; }","duration":"205.831629ms","start":"2026-08-06T07:55:24.405504Z","end":"2026-08-06T07:55:24.611336Z","steps":["trace[1239238081] 'process raft request' (duration: 136.740171ms)","trace[1239238081] 'compare' (duration: 68.945445ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:55:24.611453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.739501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-06T07:55:24.611486Z","caller":"traceutil/trace.go:171","msg":"trace[1339332986] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:25166; }","duration":"173.833983ms","start":"2026-08-06T07:55:24.437643Z","end":"2026-08-06T07:55:24.611477Z","steps":["trace[1339332986] 'agreement among raft nodes before linearized reading' (duration: 173.694009ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:24.615112Z","caller":"traceutil/trace.go:171","msg":"trace[1858861154] transaction","detail":"{read_only:false; response_revision:25167; number_of_response:1; }","duration":"155.352219ms","start":"2026-08-06T07:55:24.459737Z","end":"2026-08-06T07:55:24.615089Z","steps":["trace[1858861154] 'process raft request' (duration: 155.063151ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:27.011681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.698899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:55:27.011757Z","caller":"traceutil/trace.go:171","msg":"trace[723841493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25243; }","duration":"164.822111ms","start":"2026-08-06T07:55:26.846919Z","end":"2026-08-06T07:55:27.011741Z","steps":["trace[723841493] 'range keys from in-memory index tree' (duration: 164.609097ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:27.012268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.185507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:55:27.012303Z","caller":"traceutil/trace.go:171","msg":"trace[836444247] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:25243; }","duration":"197.266259ms","start":"2026-08-06T07:55:26.815026Z","end":"2026-08-06T07:55:27.012292Z","steps":["trace[836444247] 'count revisions from in-memory index tree' (duration: 197.035814ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:27.012528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.698324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-efdeed6f-ab78-4f9a-976f-f262b8d719b0\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-08-06T07:55:27.012611Z","caller":"traceutil/trace.go:171","msg":"trace[1969851880] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-efdeed6f-ab78-4f9a-976f-f262b8d719b0; range_end:; response_count:1; response_revision:25243; }","duration":"176.782346ms","start":"2026-08-06T07:55:26.83582Z","end":"2026-08-06T07:55:27.012602Z","steps":["trace[1969851880] 'range keys from in-memory index tree' (duration: 176.549531ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:27.17757Z","caller":"traceutil/trace.go:171","msg":"trace[1737024828] transaction","detail":"{read_only:false; response_revision:25244; number_of_response:1; }","duration":"105.441531ms","start":"2026-08-06T07:55:27.072093Z","end":"2026-08-06T07:55:27.177535Z","steps":["trace[1737024828] 'process raft request' (duration: 104.512669ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:27.454954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.455611ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-06T07:55:27.455078Z","caller":"traceutil/trace.go:171","msg":"trace[1688195321] linearizableReadLoop","detail":"{readStateIndex:26064; appliedIndex:26063; }","duration":"167.697962ms","start":"2026-08-06T07:55:27.287364Z","end":"2026-08-06T07:55:27.455061Z","steps":["trace[1688195321] 'read index received' (duration: 47.001256ms)","trace[1688195321] 'applied index is now lower than readState.Index' (duration: 120.691976ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:55:27.455156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.804055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-06T07:55:27.455192Z","caller":"traceutil/trace.go:171","msg":"trace[1850589105] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:25245; }","duration":"167.891047ms","start":"2026-08-06T07:55:27.28729Z","end":"2026-08-06T07:55:27.455181Z","steps":["trace[1850589105] 'agreement among raft nodes before linearized reading' (duration: 167.819336ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:27.455344Z","caller":"traceutil/trace.go:171","msg":"trace[1965565394] transaction","detail":"{read_only:false; response_revision:25245; number_of_response:1; }","duration":"252.133278ms","start":"2026-08-06T07:55:27.203138Z","end":"2026-08-06T07:55:27.455271Z","steps":["trace[1965565394] 'process raft request' (duration: 131.278527ms)","trace[1965565394] 'compare' (duration: 119.939108ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:55:27.743284Z","caller":"traceutil/trace.go:171","msg":"trace[129803787] linearizableReadLoop","detail":"{readStateIndex:26065; appliedIndex:26064; }","duration":"207.771487ms","start":"2026-08-06T07:55:27.535478Z","end":"2026-08-06T07:55:27.743249Z","steps":["trace[129803787] 'read index received' (duration: 117.342034ms)","trace[129803787] 'applied index is now lower than readState.Index' (duration: 90.428263ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:55:27.743333Z","caller":"traceutil/trace.go:171","msg":"trace[1570073922] transaction","detail":"{read_only:false; response_revision:25246; number_of_response:1; }","duration":"236.664887ms","start":"2026-08-06T07:55:27.506638Z","end":"2026-08-06T07:55:27.743303Z","steps":["trace[1570073922] 'process raft request' (duration: 146.153462ms)","trace[1570073922] 'compare' (duration: 90.3267ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:55:27.743422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.931681ms","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-08-06T07:55:27.743474Z","caller":"traceutil/trace.go:171","msg":"trace[789486746] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:25246; }","duration":"208.019533ms","start":"2026-08-06T07:55:27.535425Z","end":"2026-08-06T07:55:27.743445Z","steps":["trace[789486746] 'agreement among raft nodes before linearized reading' (duration: 207.940481ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:27.792494Z","caller":"traceutil/trace.go:171","msg":"trace[1979639476] transaction","detail":"{read_only:false; response_revision:25247; number_of_response:1; }","duration":"130.064648ms","start":"2026-08-06T07:55:27.662403Z","end":"2026-08-06T07:55:27.792468Z","steps":["trace[1979639476] 'process raft request' (duration: 129.856633ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:27.80044Z","caller":"traceutil/trace.go:171","msg":"trace[1689177520] transaction","detail":"{read_only:false; response_revision:25250; number_of_response:1; }","duration":"128.54769ms","start":"2026-08-06T07:55:27.67187Z","end":"2026-08-06T07:55:27.800417Z","steps":["trace[1689177520] 'process raft request' (duration: 128.497619ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:27.800645Z","caller":"traceutil/trace.go:171","msg":"trace[817864513] transaction","detail":"{read_only:false; response_revision:25248; number_of_response:1; }","duration":"135.211094ms","start":"2026-08-06T07:55:27.665404Z","end":"2026-08-06T07:55:27.800615Z","steps":["trace[817864513] 'process raft request' (duration: 134.725932ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:27.800698Z","caller":"traceutil/trace.go:171","msg":"trace[635788618] transaction","detail":"{read_only:false; response_revision:25249; number_of_response:1; }","duration":"129.554535ms","start":"2026-08-06T07:55:27.671094Z","end":"2026-08-06T07:55:27.800648Z","steps":["trace[635788618] 'process raft request' (duration: 129.195466ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:28.095969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.015891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-06T07:55:28.096021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.893094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-06T07:55:28.096104Z","caller":"traceutil/trace.go:171","msg":"trace[426625559] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25250; }","duration":"213.023506ms","start":"2026-08-06T07:55:27.88306Z","end":"2026-08-06T07:55:28.096084Z","steps":["trace[426625559] 'range keys from in-memory index tree' (duration: 212.743499ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:28.096116Z","caller":"traceutil/trace.go:171","msg":"trace[146422779] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routerinterfaces/; range_end:/registry/openstack.k-orc.cloud/routerinterfaces0; response_count:0; response_revision:25250; }","duration":"227.223575ms","start":"2026-08-06T07:55:27.868856Z","end":"2026-08-06T07:55:28.09608Z","steps":["trace[146422779] 'count revisions from in-memory index tree' (duration: 226.926878ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:28.096225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.519076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-06T07:55:28.096288Z","caller":"traceutil/trace.go:171","msg":"trace[219090022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25250; }","duration":"217.619159ms","start":"2026-08-06T07:55:27.878652Z","end":"2026-08-06T07:55:28.096271Z","steps":["trace[219090022] 'range keys from in-memory index tree' (duration: 217.220259ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:28.096288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.744804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-06T07:55:28.096334Z","caller":"traceutil/trace.go:171","msg":"trace[452203754] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25250; }","duration":"213.828876ms","start":"2026-08-06T07:55:27.882492Z","end":"2026-08-06T07:55:28.096321Z","steps":["trace[452203754] 'range keys from in-memory index tree' (duration: 213.59614ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:28.45992Z","caller":"traceutil/trace.go:171","msg":"trace[1713574712] linearizableReadLoop","detail":"{readStateIndex:26072; appliedIndex:26069; }","duration":"349.944622ms","start":"2026-08-06T07:55:28.109946Z","end":"2026-08-06T07:55:28.45989Z","steps":["trace[1713574712] 'read index received' (duration: 47.832596ms)","trace[1713574712] 'applied index is now lower than readState.Index' (duration: 302.110536ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:55:28.460061Z","caller":"traceutil/trace.go:171","msg":"trace[1188734297] transaction","detail":"{read_only:false; response_revision:25253; number_of_response:1; }","duration":"356.057993ms","start":"2026-08-06T07:55:28.103979Z","end":"2026-08-06T07:55:28.460037Z","steps":["trace[1188734297] 'process raft request' (duration: 355.864448ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:28.460066Z","caller":"traceutil/trace.go:171","msg":"trace[1013164105] transaction","detail":"{read_only:false; response_revision:25252; number_of_response:1; }","duration":"356.716828ms","start":"2026-08-06T07:55:28.103302Z","end":"2026-08-06T07:55:28.460019Z","steps":["trace[1013164105] 'process raft request' (duration: 356.275958ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:28.460194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:28.103957Z","time spent":"356.132544ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T07:55:28.460282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:28.103285Z","time spent":"356.889613ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T07:55:28.460624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.6586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-08-06T07:55:28.460728Z","caller":"traceutil/trace.go:171","msg":"trace[803093701] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:25253; }","duration":"350.813593ms","start":"2026-08-06T07:55:28.109888Z","end":"2026-08-06T07:55:28.460702Z","steps":["trace[803093701] 'agreement among raft nodes before linearized reading' (duration: 350.127736ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:28.460776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:28.109867Z","time spent":"350.897846ms","remote":"127.0.0.1:45276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1883,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" "} {"level":"info","ts":"2026-08-06T07:55:28.476398Z","caller":"traceutil/trace.go:171","msg":"trace[162698920] transaction","detail":"{read_only:false; response_revision:25254; number_of_response:1; }","duration":"304.692581ms","start":"2026-08-06T07:55:28.171673Z","end":"2026-08-06T07:55:28.476366Z","steps":["trace[162698920] 'process raft request' (duration: 303.415958ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:28.476537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:28.171618Z","time spent":"304.844504ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T07:55:28.832777Z","caller":"traceutil/trace.go:171","msg":"trace[621934716] transaction","detail":"{read_only:false; response_revision:25257; number_of_response:1; }","duration":"207.027949ms","start":"2026-08-06T07:55:28.625698Z","end":"2026-08-06T07:55:28.832726Z","steps":["trace[621934716] 'process raft request' (duration: 139.065829ms)","trace[621934716] 'compare' (duration: 67.644142ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:55:29.748785Z","caller":"traceutil/trace.go:171","msg":"trace[2022777344] linearizableReadLoop","detail":"{readStateIndex:26077; appliedIndex:26076; }","duration":"177.088393ms","start":"2026-08-06T07:55:29.571678Z","end":"2026-08-06T07:55:29.748767Z","steps":["trace[2022777344] 'read index received' (duration: 177.022801ms)","trace[2022777344] 'applied index is now lower than readState.Index' (duration: 64.442µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:55:29.748902Z","caller":"traceutil/trace.go:171","msg":"trace[1379215427] transaction","detail":"{read_only:false; response_revision:25258; number_of_response:1; }","duration":"286.158334ms","start":"2026-08-06T07:55:29.462712Z","end":"2026-08-06T07:55:29.74887Z","steps":["trace[1379215427] 'process raft request' (duration: 285.936759ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:29.748949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.257407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-08-06T07:55:29.748979Z","caller":"traceutil/trace.go:171","msg":"trace[1619191262] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25258; }","duration":"177.328959ms","start":"2026-08-06T07:55:29.571639Z","end":"2026-08-06T07:55:29.748968Z","steps":["trace[1619191262] 'agreement among raft nodes before linearized reading' (duration: 177.227276ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:30.041356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.222661ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-06T07:55:30.041488Z","caller":"traceutil/trace.go:171","msg":"trace[692529654] linearizableReadLoop","detail":"{readStateIndex:26078; appliedIndex:26077; }","duration":"292.616663ms","start":"2026-08-06T07:55:29.748847Z","end":"2026-08-06T07:55:30.041464Z","steps":["trace[692529654] 'read index received' (duration: 136.377582ms)","trace[692529654] 'applied index is now lower than readState.Index' (duration: 156.237141ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:55:30.041731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.29465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-manila-server-0.18c92832c8a569a2\" ","response":"range_response_count:1 size:1010"} {"level":"warn","ts":"2026-08-06T07:55:30.041763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.482009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-06T07:55:30.04183Z","caller":"traceutil/trace.go:171","msg":"trace[488054342] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25258; }","duration":"294.583242ms","start":"2026-08-06T07:55:29.747224Z","end":"2026-08-06T07:55:30.041807Z","steps":["trace[488054342] 'agreement among raft nodes before linearized reading' (duration: 294.337376ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:30.041833Z","caller":"traceutil/trace.go:171","msg":"trace[1815013855] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-manila-server-0.18c92832c8a569a2; range_end:; response_count:1; response_revision:25258; }","duration":"354.428783ms","start":"2026-08-06T07:55:29.687369Z","end":"2026-08-06T07:55:30.041797Z","steps":["trace[1815013855] 'agreement among raft nodes before linearized reading' (duration: 354.231629ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:30.042148Z","caller":"traceutil/trace.go:171","msg":"trace[2035087908] transaction","detail":"{read_only:false; response_revision:25260; number_of_response:1; }","duration":"244.12183ms","start":"2026-08-06T07:55:29.798001Z","end":"2026-08-06T07:55:30.042123Z","steps":["trace[2035087908] 'process raft request' (duration: 243.871604ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:30.042195Z","caller":"traceutil/trace.go:171","msg":"trace[1959391774] transaction","detail":"{read_only:false; response_revision:25263; number_of_response:1; }","duration":"235.866987ms","start":"2026-08-06T07:55:29.806308Z","end":"2026-08-06T07:55:30.042175Z","steps":["trace[1959391774] 'process raft request' (duration: 235.723474ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:30.042278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.111797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:55:30.042293Z","caller":"traceutil/trace.go:171","msg":"trace[1729986590] transaction","detail":"{read_only:false; response_revision:25261; number_of_response:1; }","duration":"236.984306ms","start":"2026-08-06T07:55:29.805292Z","end":"2026-08-06T07:55:30.042276Z","steps":["trace[1729986590] 'process raft request' (duration: 236.657588ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:30.042293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.182904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:55:30.042335Z","caller":"traceutil/trace.go:171","msg":"trace[2135809113] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25263; }","duration":"217.21491ms","start":"2026-08-06T07:55:29.825104Z","end":"2026-08-06T07:55:30.042319Z","steps":["trace[2135809113] 'agreement among raft nodes before linearized reading' (duration: 217.076467ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:30.042366Z","caller":"traceutil/trace.go:171","msg":"trace[473945149] transaction","detail":"{read_only:false; response_revision:25262; number_of_response:1; }","duration":"236.35662ms","start":"2026-08-06T07:55:29.806Z","end":"2026-08-06T07:55:30.042356Z","steps":["trace[473945149] 'process raft request' (duration: 235.991341ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:30.042387Z","caller":"traceutil/trace.go:171","msg":"trace[74710556] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:25263; }","duration":"155.308957ms","start":"2026-08-06T07:55:29.887057Z","end":"2026-08-06T07:55:30.042366Z","steps":["trace[74710556] 'agreement among raft nodes before linearized reading' (duration: 155.149433ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:30.0423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:29.687348Z","time spent":"354.842033ms","remote":"127.0.0.1:45168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1033,"request content":"key:\"/registry/events/openstack/persistence-rabbitmq-manila-server-0.18c92832c8a569a2\" "} {"level":"info","ts":"2026-08-06T07:55:30.042606Z","caller":"traceutil/trace.go:171","msg":"trace[1370142814] transaction","detail":"{read_only:false; response_revision:25259; number_of_response:1; }","duration":"288.545513ms","start":"2026-08-06T07:55:29.75345Z","end":"2026-08-06T07:55:30.041996Z","steps":["trace[1370142814] 'process raft request' (duration: 288.231086ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:30.370411Z","caller":"traceutil/trace.go:171","msg":"trace[1435956307] transaction","detail":"{read_only:false; response_revision:25265; number_of_response:1; }","duration":"324.041956ms","start":"2026-08-06T07:55:30.046333Z","end":"2026-08-06T07:55:30.370375Z","steps":["trace[1435956307] 'process raft request' (duration: 322.109978ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:30.370541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:30.046317Z","time spent":"324.157289ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T07:55:30.370398Z","caller":"traceutil/trace.go:171","msg":"trace[1222784230] linearizableReadLoop","detail":"{readStateIndex:26085; appliedIndex:26083; }","duration":"322.642041ms","start":"2026-08-06T07:55:30.047688Z","end":"2026-08-06T07:55:30.37033Z","steps":["trace[1222784230] 'read index received' (duration: 50.595994ms)","trace[1222784230] 'applied index is now lower than readState.Index' (duration: 272.044177ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:55:30.370534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.826835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:55:30.370766Z","caller":"traceutil/trace.go:171","msg":"trace[921272467] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25265; }","duration":"323.03457ms","start":"2026-08-06T07:55:30.047673Z","end":"2026-08-06T07:55:30.370708Z","steps":["trace[921272467] 'agreement among raft nodes before linearized reading' (duration: 322.779594ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:30.370861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.872874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-06T07:55:30.370894Z","caller":"traceutil/trace.go:171","msg":"trace[458664069] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25265; }","duration":"208.927765ms","start":"2026-08-06T07:55:30.161954Z","end":"2026-08-06T07:55:30.370882Z","steps":["trace[458664069] 'agreement among raft nodes before linearized reading' (duration: 208.809923ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:30.370912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:30.047631Z","time spent":"323.234475ms","remote":"127.0.0.1:45312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-08-06T07:55:30.370963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.299769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-efdeed6f-ab78-4f9a-976f-f262b8d719b0\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-08-06T07:55:30.371251Z","caller":"traceutil/trace.go:171","msg":"trace[1839200154] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-efdeed6f-ab78-4f9a-976f-f262b8d719b0; range_end:; response_count:1; response_revision:25265; }","duration":"260.612296ms","start":"2026-08-06T07:55:30.110614Z","end":"2026-08-06T07:55:30.371226Z","steps":["trace[1839200154] 'agreement among raft nodes before linearized reading' (duration: 260.107594ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:30.656471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.64589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-06T07:55:30.656599Z","caller":"traceutil/trace.go:171","msg":"trace[1109663342] transaction","detail":"{read_only:false; response_revision:25266; number_of_response:1; }","duration":"281.772665ms","start":"2026-08-06T07:55:30.374812Z","end":"2026-08-06T07:55:30.656585Z","steps":["trace[1109663342] 'process raft request' (duration: 133.945181ms)","trace[1109663342] 'compare' (duration: 147.535067ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:55:30.70605Z","caller":"traceutil/trace.go:171","msg":"trace[126941726] transaction","detail":"{read_only:false; response_revision:25268; number_of_response:1; }","duration":"224.200871ms","start":"2026-08-06T07:55:30.481826Z","end":"2026-08-06T07:55:30.706026Z","steps":["trace[126941726] 'process raft request' (duration: 224.102139ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:30.706071Z","caller":"traceutil/trace.go:171","msg":"trace[1206836658] transaction","detail":"{read_only:false; response_revision:25267; number_of_response:1; }","duration":"330.74278ms","start":"2026-08-06T07:55:30.375293Z","end":"2026-08-06T07:55:30.706036Z","steps":["trace[1206836658] 'process raft request' (duration: 330.431093ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:30.706232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:30.37527Z","time spent":"330.876893ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T07:55:30.706306Z","caller":"traceutil/trace.go:171","msg":"trace[1054441617] transaction","detail":"{read_only:false; response_revision:25269; number_of_response:1; }","duration":"224.395416ms","start":"2026-08-06T07:55:30.481896Z","end":"2026-08-06T07:55:30.706291Z","steps":["trace[1054441617] 'process raft request' (duration: 224.091828ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:30.706516Z","caller":"traceutil/trace.go:171","msg":"trace[1917181368] linearizableReadLoop","detail":"{readStateIndex:26087; appliedIndex:26085; }","duration":"329.194431ms","start":"2026-08-06T07:55:30.377295Z","end":"2026-08-06T07:55:30.70649Z","steps":["trace[1917181368] 'read index received' (duration: 131.483931ms)","trace[1917181368] 'applied index is now lower than readState.Index' (duration: 197.70855ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:55:30.706545Z","caller":"traceutil/trace.go:171","msg":"trace[43538726] transaction","detail":"{read_only:false; response_revision:25270; number_of_response:1; }","duration":"223.965775ms","start":"2026-08-06T07:55:30.482567Z","end":"2026-08-06T07:55:30.706533Z","steps":["trace[43538726] 'process raft request' (duration: 223.681909ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:30.706725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.480916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-06T07:55:30.706771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.431709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:55:30.706781Z","caller":"traceutil/trace.go:171","msg":"trace[1441408453] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:25270; }","duration":"228.582878ms","start":"2026-08-06T07:55:30.478182Z","end":"2026-08-06T07:55:30.706765Z","steps":["trace[1441408453] 'agreement among raft nodes before linearized reading' (duration: 228.493906ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:30.706832Z","caller":"traceutil/trace.go:171","msg":"trace[481496564] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25270; }","duration":"329.550982ms","start":"2026-08-06T07:55:30.377246Z","end":"2026-08-06T07:55:30.706797Z","steps":["trace[481496564] 'agreement among raft nodes before linearized reading' (duration: 329.373997ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:30.706854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.610439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-08-06T07:55:30.706838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.48529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-06T07:55:30.706898Z","caller":"traceutil/trace.go:171","msg":"trace[1503660958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25270; }","duration":"241.686781ms","start":"2026-08-06T07:55:30.465197Z","end":"2026-08-06T07:55:30.706884Z","steps":["trace[1503660958] 'agreement among raft nodes before linearized reading' (duration: 241.541027ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:30.706869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:30.37723Z","time spent":"329.630493ms","remote":"127.0.0.1:45380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"info","ts":"2026-08-06T07:55:30.706966Z","caller":"traceutil/trace.go:171","msg":"trace[1603552962] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25270; }","duration":"242.635473ms","start":"2026-08-06T07:55:30.46427Z","end":"2026-08-06T07:55:30.706904Z","steps":["trace[1603552962] 'agreement among raft nodes before linearized reading' (duration: 242.399598ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:30.877756Z","caller":"traceutil/trace.go:171","msg":"trace[578547857] linearizableReadLoop","detail":"{readStateIndex:26091; appliedIndex:26090; }","duration":"165.482407ms","start":"2026-08-06T07:55:30.712241Z","end":"2026-08-06T07:55:30.877724Z","steps":["trace[578547857] 'read index received' (duration: 143.537728ms)","trace[578547857] 'applied index is now lower than readState.Index' (duration: 21.943749ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:55:30.877931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.658442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:55:30.877971Z","caller":"traceutil/trace.go:171","msg":"trace[2114670068] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25271; }","duration":"165.742134ms","start":"2026-08-06T07:55:30.712218Z","end":"2026-08-06T07:55:30.877961Z","steps":["trace[2114670068] 'agreement among raft nodes before linearized reading' (duration: 165.640352ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:30.877892Z","caller":"traceutil/trace.go:171","msg":"trace[925902786] transaction","detail":"{read_only:false; response_revision:25271; number_of_response:1; }","duration":"166.253986ms","start":"2026-08-06T07:55:30.7116Z","end":"2026-08-06T07:55:30.877854Z","steps":["trace[925902786] 'process raft request' (duration: 144.150183ms)","trace[925902786] 'compare' (duration: 21.839667ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:55:31.359143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.631537ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-06T07:55:31.359316Z","caller":"traceutil/trace.go:171","msg":"trace[1576669843] linearizableReadLoop","detail":"{readStateIndex:26094; appliedIndex:26091; }","duration":"481.475835ms","start":"2026-08-06T07:55:30.87783Z","end":"2026-08-06T07:55:31.359305Z","steps":["trace[1576669843] 'read index received' (duration: 268.556002ms)","trace[1576669843] 'applied index is now lower than readState.Index' (duration: 212.918863ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:55:31.359348Z","caller":"traceutil/trace.go:171","msg":"trace[1025509953] transaction","detail":"{read_only:false; response_revision:25273; number_of_response:1; }","duration":"591.438868ms","start":"2026-08-06T07:55:30.767888Z","end":"2026-08-06T07:55:31.359327Z","steps":["trace[1025509953] 'process raft request' (duration: 591.332415ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:31.359381Z","caller":"traceutil/trace.go:171","msg":"trace[1227501327] transaction","detail":"{read_only:false; response_revision:25274; number_of_response:1; }","duration":"521.472158ms","start":"2026-08-06T07:55:30.837903Z","end":"2026-08-06T07:55:31.359375Z","steps":["trace[1227501327] 'process raft request' (duration: 521.372306ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:31.359416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:30.837854Z","time spent":"521.54096ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T07:55:31.359452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:30.767862Z","time spent":"591.532531ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T07:55:31.359562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.883948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:55:31.359579Z","caller":"traceutil/trace.go:171","msg":"trace[1158711024] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25274; }","duration":"512.933789ms","start":"2026-08-06T07:55:30.84664Z","end":"2026-08-06T07:55:31.359573Z","steps":["trace[1158711024] 'agreement among raft nodes before linearized reading' (duration: 512.876928ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:31.359611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:30.846619Z","time spent":"512.98835ms","remote":"127.0.0.1:45078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-06T07:55:31.359676Z","caller":"traceutil/trace.go:171","msg":"trace[984153312] transaction","detail":"{read_only:false; response_revision:25272; number_of_response:1; }","duration":"646.250376ms","start":"2026-08-06T07:55:30.713408Z","end":"2026-08-06T07:55:31.359659Z","steps":["trace[984153312] 'process raft request' (duration: 433.029775ms)","trace[984153312] 'compare' (duration: 212.001611ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:55:31.359741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.887462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:55:31.359819Z","caller":"traceutil/trace.go:171","msg":"trace[693435441] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:25274; }","duration":"180.017935ms","start":"2026-08-06T07:55:31.179779Z","end":"2026-08-06T07:55:31.359797Z","steps":["trace[693435441] 'agreement among raft nodes before linearized reading' (duration: 179.860942ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:31.359944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.459151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:55:31.360013Z","caller":"traceutil/trace.go:171","msg":"trace[1777952921] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:25274; }","duration":"559.577245ms","start":"2026-08-06T07:55:30.80041Z","end":"2026-08-06T07:55:31.359987Z","steps":["trace[1777952921] 'agreement among raft nodes before linearized reading' (duration: 559.474902ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:31.36004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:30.80038Z","time spent":"559.653576ms","remote":"127.0.0.1:44054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-08-06T07:55:31.360012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:30.713402Z","time spent":"646.312077ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T07:55:32.355297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.218856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-efdeed6f-ab78-4f9a-976f-f262b8d719b0\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-08-06T07:55:32.35566Z","caller":"traceutil/trace.go:171","msg":"trace[1095754963] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-efdeed6f-ab78-4f9a-976f-f262b8d719b0; range_end:; response_count:1; response_revision:25279; }","duration":"203.629435ms","start":"2026-08-06T07:55:32.152014Z","end":"2026-08-06T07:55:32.355643Z","steps":["trace[1095754963] 'range keys from in-memory index tree' (duration: 203.044232ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:32.355275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.624737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:55:32.355814Z","caller":"traceutil/trace.go:171","msg":"trace[918803004] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:25279; }","duration":"104.210942ms","start":"2026-08-06T07:55:32.251569Z","end":"2026-08-06T07:55:32.35578Z","steps":["trace[918803004] 'count revisions from in-memory index tree' (duration: 103.528825ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:32.355429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.95614ms","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-08-06T07:55:32.356065Z","caller":"traceutil/trace.go:171","msg":"trace[1873543311] 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:25279; }","duration":"188.603616ms","start":"2026-08-06T07:55:32.167429Z","end":"2026-08-06T07:55:32.356032Z","steps":["trace[1873543311] 'range keys from in-memory index tree' (duration: 187.803586ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:37.734335Z","caller":"traceutil/trace.go:171","msg":"trace[1557248050] transaction","detail":"{read_only:false; response_revision:25341; number_of_response:1; }","duration":"223.196827ms","start":"2026-08-06T07:55:37.511099Z","end":"2026-08-06T07:55:37.734296Z","steps":["trace[1557248050] 'process raft request' (duration: 135.191263ms)","trace[1557248050] 'compare' (duration: 87.671876ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:55:41.936049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.413828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-06T07:55:41.936131Z","caller":"traceutil/trace.go:171","msg":"trace[891097572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25377; }","duration":"100.528941ms","start":"2026-08-06T07:55:41.835584Z","end":"2026-08-06T07:55:41.936113Z","steps":["trace[891097572] 'range keys from in-memory index tree' (duration: 100.219144ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:42.192464Z","caller":"traceutil/trace.go:171","msg":"trace[2029706712] transaction","detail":"{read_only:false; response_revision:25378; number_of_response:1; }","duration":"244.669394ms","start":"2026-08-06T07:55:41.947742Z","end":"2026-08-06T07:55:42.192411Z","steps":["trace[2029706712] 'process raft request' (duration: 244.49091ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:42.195857Z","caller":"traceutil/trace.go:171","msg":"trace[1384456980] transaction","detail":"{read_only:false; response_revision:25382; number_of_response:1; }","duration":"110.502026ms","start":"2026-08-06T07:55:42.085331Z","end":"2026-08-06T07:55:42.195833Z","steps":["trace[1384456980] 'process raft request' (duration: 110.458965ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:42.19598Z","caller":"traceutil/trace.go:171","msg":"trace[1136633924] transaction","detail":"{read_only:false; response_revision:25379; number_of_response:1; }","duration":"116.731379ms","start":"2026-08-06T07:55:42.079225Z","end":"2026-08-06T07:55:42.195957Z","steps":["trace[1136633924] 'process raft request' (duration: 116.36049ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:42.196007Z","caller":"traceutil/trace.go:171","msg":"trace[1591262199] transaction","detail":"{read_only:false; response_revision:25380; number_of_response:1; }","duration":"112.391683ms","start":"2026-08-06T07:55:42.083575Z","end":"2026-08-06T07:55:42.195967Z","steps":["trace[1591262199] 'process raft request' (duration: 112.129097ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:42.196055Z","caller":"traceutil/trace.go:171","msg":"trace[1958619496] transaction","detail":"{read_only:false; response_revision:25381; number_of_response:1; }","duration":"110.765552ms","start":"2026-08-06T07:55:42.085253Z","end":"2026-08-06T07:55:42.196019Z","steps":["trace[1958619496] 'process raft request' (duration: 110.493996ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:58.714283Z","caller":"traceutil/trace.go:171","msg":"trace[1521831628] transaction","detail":"{read_only:false; response_revision:25511; number_of_response:1; }","duration":"167.537599ms","start":"2026-08-06T07:55:58.546675Z","end":"2026-08-06T07:55:58.714212Z","steps":["trace[1521831628] 'process raft request' (duration: 167.373175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:58.939978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.844359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-06T07:55:58.940057Z","caller":"traceutil/trace.go:171","msg":"trace[1385984259] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25511; }","duration":"100.972633ms","start":"2026-08-06T07:55:58.839061Z","end":"2026-08-06T07:55:58.940034Z","steps":["trace[1385984259] 'range keys from in-memory index tree' (duration: 100.663735ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:59.296377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.796256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-06T07:55:59.296526Z","caller":"traceutil/trace.go:171","msg":"trace[238248687] linearizableReadLoop","detail":"{readStateIndex:26340; appliedIndex:26338; }","duration":"355.507819ms","start":"2026-08-06T07:55:58.941006Z","end":"2026-08-06T07:55:59.296514Z","steps":["trace[238248687] 'read index received' (duration: 117.497178ms)","trace[238248687] 'applied index is now lower than readState.Index' (duration: 238.009541ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:55:59.296535Z","caller":"traceutil/trace.go:171","msg":"trace[43188561] transaction","detail":"{read_only:false; response_revision:25513; number_of_response:1; }","duration":"431.076917ms","start":"2026-08-06T07:55:58.865432Z","end":"2026-08-06T07:55:59.296509Z","steps":["trace[43188561] 'process raft request' (duration: 193.060076ms)","trace[43188561] 'compare' (duration: 237.697613ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:55:59.29661Z","caller":"traceutil/trace.go:171","msg":"trace[448115107] transaction","detail":"{read_only:false; response_revision:25514; number_of_response:1; }","duration":"430.598875ms","start":"2026-08-06T07:55:58.866003Z","end":"2026-08-06T07:55:59.296602Z","steps":["trace[448115107] 'process raft request' (duration: 430.456192ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:59.296665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:58.865976Z","time spent":"430.660496ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T07:55:59.296669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:58.865414Z","time spent":"431.195549ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T07:55:59.296814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.793096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:55:59.296879Z","caller":"traceutil/trace.go:171","msg":"trace[2114516911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25514; }","duration":"355.879748ms","start":"2026-08-06T07:55:58.940983Z","end":"2026-08-06T07:55:59.296863Z","steps":["trace[2114516911] 'agreement among raft nodes before linearized reading' (duration: 355.766305ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:59.296911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:58.940969Z","time spent":"355.93697ms","remote":"127.0.0.1:45074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T07:55:59.30371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.096377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-06T07:55:59.303784Z","caller":"traceutil/trace.go:171","msg":"trace[177352726] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:25515; }","duration":"361.201469ms","start":"2026-08-06T07:55:58.942567Z","end":"2026-08-06T07:55:59.303769Z","steps":["trace[177352726] 'agreement among raft nodes before linearized reading' (duration: 361.061355ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:59.303811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:58.942556Z","time spent":"361.2489ms","remote":"127.0.0.1:50336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":31,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true "} {"level":"warn","ts":"2026-08-06T07:55:59.303897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.874305ms","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-08-06T07:55:59.303973Z","caller":"traceutil/trace.go:171","msg":"trace[946630468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25515; }","duration":"289.963247ms","start":"2026-08-06T07:55:59.013991Z","end":"2026-08-06T07:55:59.303954Z","steps":["trace[946630468] 'agreement among raft nodes before linearized reading' (duration: 289.737872ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:55:59.30399Z","caller":"traceutil/trace.go:171","msg":"trace[1748855283] transaction","detail":"{read_only:false; response_revision:25515; number_of_response:1; }","duration":"359.63641ms","start":"2026-08-06T07:55:58.944028Z","end":"2026-08-06T07:55:59.303665Z","steps":["trace[1748855283] 'process raft request' (duration: 359.498827ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:55:59.304085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:55:58.944015Z","time spent":"360.017349ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T07:55:59.509857Z","caller":"traceutil/trace.go:171","msg":"trace[707384289] transaction","detail":"{read_only:false; response_revision:25516; number_of_response:1; }","duration":"202.369044ms","start":"2026-08-06T07:55:59.307464Z","end":"2026-08-06T07:55:59.509833Z","steps":["trace[707384289] 'process raft request' (duration: 138.572266ms)","trace[707384289] 'compare' (duration: 63.009009ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:55:59.860816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.284778ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-06T07:55:59.860903Z","caller":"traceutil/trace.go:171","msg":"trace[801291708] linearizableReadLoop","detail":"{readStateIndex:26343; appliedIndex:26342; }","duration":"185.152962ms","start":"2026-08-06T07:55:59.675734Z","end":"2026-08-06T07:55:59.860887Z","steps":["trace[801291708] 'read index received' (duration: 55.742851ms)","trace[801291708] 'applied index is now lower than readState.Index' (duration: 129.409181ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:55:59.860934Z","caller":"traceutil/trace.go:171","msg":"trace[113293670] transaction","detail":"{read_only:false; response_revision:25517; number_of_response:1; }","duration":"267.111876ms","start":"2026-08-06T07:55:59.593788Z","end":"2026-08-06T07:55:59.8609Z","steps":["trace[113293670] 'process raft request' (duration: 137.675354ms)","trace[113293670] 'compare' (duration: 129.193436ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:55:59.861011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.241023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-06T07:55:59.861056Z","caller":"traceutil/trace.go:171","msg":"trace[36727214] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:25517; }","duration":"185.349566ms","start":"2026-08-06T07:55:59.675693Z","end":"2026-08-06T07:55:59.861043Z","steps":["trace[36727214] 'agreement among raft nodes before linearized reading' (duration: 185.226763ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:56:00.045917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.554859ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-06T07:56:00.046014Z","caller":"traceutil/trace.go:171","msg":"trace[879190369] linearizableReadLoop","detail":"{readStateIndex:26344; appliedIndex:26343; }","duration":"185.07848ms","start":"2026-08-06T07:55:59.860921Z","end":"2026-08-06T07:56:00.046Z","steps":["trace[879190369] 'read index received' (duration: 6.415528ms)","trace[879190369] 'applied index is now lower than readState.Index' (duration: 178.661452ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:56:00.046151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.629184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-08-06T07:56:00.04615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.061923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:56:00.04618Z","caller":"traceutil/trace.go:171","msg":"trace[1702648906] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25517; }","duration":"218.702476ms","start":"2026-08-06T07:55:59.827468Z","end":"2026-08-06T07:56:00.04617Z","steps":["trace[1702648906] 'agreement among raft nodes before linearized reading' (duration: 218.566023ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:56:00.046223Z","caller":"traceutil/trace.go:171","msg":"trace[1899802879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25517; }","duration":"199.173826ms","start":"2026-08-06T07:55:59.84703Z","end":"2026-08-06T07:56:00.046203Z","steps":["trace[1899802879] 'agreement among raft nodes before linearized reading' (duration: 199.057703ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:56:00.085469Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000066ea.wal"} {"level":"info","ts":"2026-08-06T07:56:00.086273Z","caller":"traceutil/trace.go:171","msg":"trace[1313514482] transaction","detail":"{read_only:false; response_revision:25518; number_of_response:1; }","duration":"169.264291ms","start":"2026-08-06T07:55:59.916984Z","end":"2026-08-06T07:56:00.086248Z","steps":["trace[1313514482] 'process raft request' (duration: 168.891371ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:56:00.876944Z","caller":"traceutil/trace.go:171","msg":"trace[1723560149] transaction","detail":"{read_only:false; response_revision:25528; number_of_response:1; }","duration":"150.211863ms","start":"2026-08-06T07:56:00.726704Z","end":"2026-08-06T07:56:00.876916Z","steps":["trace[1723560149] 'process raft request' (duration: 91.348436ms)","trace[1723560149] 'compare' (duration: 58.377915ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:56:01.071277Z","caller":"traceutil/trace.go:171","msg":"trace[1089106182] linearizableReadLoop","detail":"{readStateIndex:26356; appliedIndex:26355; }","duration":"124.551841ms","start":"2026-08-06T07:56:00.946691Z","end":"2026-08-06T07:56:01.071242Z","steps":["trace[1089106182] 'read index received' (duration: 100.099981ms)","trace[1089106182] 'applied index is now lower than readState.Index' (duration: 24.45076ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:56:01.071316Z","caller":"traceutil/trace.go:171","msg":"trace[1210019775] transaction","detail":"{read_only:false; response_revision:25529; number_of_response:1; }","duration":"127.305289ms","start":"2026-08-06T07:56:00.943966Z","end":"2026-08-06T07:56:01.071272Z","steps":["trace[1210019775] 'process raft request' (duration: 102.848498ms)","trace[1210019775] 'compare' (duration: 24.293667ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:56:01.071475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.754326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:56:01.071518Z","caller":"traceutil/trace.go:171","msg":"trace[944295233] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25529; }","duration":"124.849179ms","start":"2026-08-06T07:56:00.946656Z","end":"2026-08-06T07:56:01.071505Z","steps":["trace[944295233] 'agreement among raft nodes before linearized reading' (duration: 124.751266ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:56:12.477054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.748861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:56:12.477122Z","caller":"traceutil/trace.go:171","msg":"trace[811135105] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:25609; }","duration":"258.860344ms","start":"2026-08-06T07:56:12.218247Z","end":"2026-08-06T07:56:12.477107Z","steps":["trace[811135105] 'count revisions from in-memory index tree' (duration: 258.673159ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:56:12.477291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.430024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-06T07:56:12.477326Z","caller":"traceutil/trace.go:171","msg":"trace[906241611] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25609; }","duration":"280.489945ms","start":"2026-08-06T07:56:12.196827Z","end":"2026-08-06T07:56:12.477317Z","steps":["trace[906241611] 'range keys from in-memory index tree' (duration: 280.2969ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:56:12.477405Z","caller":"traceutil/trace.go:171","msg":"trace[1365688631] transaction","detail":"{read_only:false; response_revision:25610; number_of_response:1; }","duration":"188.462022ms","start":"2026-08-06T07:56:12.288913Z","end":"2026-08-06T07:56:12.477375Z","steps":["trace[1365688631] 'process raft request' (duration: 136.363361ms)","trace[1365688631] 'compare' (duration: 51.977138ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:56:12.477275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.287876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-06T07:56:12.47773Z","caller":"traceutil/trace.go:171","msg":"trace[369834698] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25609; }","duration":"277.746548ms","start":"2026-08-06T07:56:12.199944Z","end":"2026-08-06T07:56:12.477691Z","steps":["trace[369834698] 'range keys from in-memory index tree' (duration: 277.177244ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:56:12.495754Z","caller":"traceutil/trace.go:171","msg":"trace[588394166] transaction","detail":"{read_only:false; response_revision:25611; number_of_response:1; }","duration":"203.729667ms","start":"2026-08-06T07:56:12.291995Z","end":"2026-08-06T07:56:12.495725Z","steps":["trace[588394166] 'process raft request' (duration: 203.538623ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:56:12.495983Z","caller":"traceutil/trace.go:171","msg":"trace[1760248642] transaction","detail":"{read_only:false; response_revision:25612; number_of_response:1; }","duration":"203.947942ms","start":"2026-08-06T07:56:12.29202Z","end":"2026-08-06T07:56:12.495967Z","steps":["trace[1760248642] 'process raft request' (duration: 203.649545ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:56:12.496068Z","caller":"traceutil/trace.go:171","msg":"trace[295411048] linearizableReadLoop","detail":"{readStateIndex:26443; appliedIndex:26439; }","duration":"152.477898ms","start":"2026-08-06T07:56:12.343569Z","end":"2026-08-06T07:56:12.496047Z","steps":["trace[295411048] 'read index received' (duration: 81.79335ms)","trace[295411048] 'applied index is now lower than readState.Index' (duration: 70.683728ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:56:12.496093Z","caller":"traceutil/trace.go:171","msg":"trace[90253723] transaction","detail":"{read_only:false; response_revision:25613; number_of_response:1; }","duration":"202.426077ms","start":"2026-08-06T07:56:12.293649Z","end":"2026-08-06T07:56:12.496075Z","steps":["trace[90253723] 'process raft request' (duration: 202.268462ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:56:12.496279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.689533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-06T07:56:12.496307Z","caller":"traceutil/trace.go:171","msg":"trace[1472205080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25613; }","duration":"152.760625ms","start":"2026-08-06T07:56:12.343539Z","end":"2026-08-06T07:56:12.4963Z","steps":["trace[1472205080] 'agreement among raft nodes before linearized reading' (duration: 152.613342ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:56:12.676264Z","caller":"traceutil/trace.go:171","msg":"trace[721837936] transaction","detail":"{read_only:false; response_revision:25615; number_of_response:1; }","duration":"175.470863ms","start":"2026-08-06T07:56:12.500754Z","end":"2026-08-06T07:56:12.676224Z","steps":["trace[721837936] 'process raft request' (duration: 104.326044ms)","trace[721837936] 'compare' (duration: 70.252048ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:56:12.676838Z","caller":"traceutil/trace.go:171","msg":"trace[1063416190] linearizableReadLoop","detail":"{readStateIndex:26445; appliedIndex:26444; }","duration":"161.952891ms","start":"2026-08-06T07:56:12.51352Z","end":"2026-08-06T07:56:12.675473Z","steps":["trace[1063416190] 'read index received' (duration: 91.500278ms)","trace[1063416190] 'applied index is now lower than readState.Index' (duration: 70.451723ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:56:12.676839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.288783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18231"} {"level":"info","ts":"2026-08-06T07:56:12.677011Z","caller":"traceutil/trace.go:171","msg":"trace[1194414795] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25615; }","duration":"163.476718ms","start":"2026-08-06T07:56:12.513503Z","end":"2026-08-06T07:56:12.67698Z","steps":["trace[1194414795] 'agreement among raft nodes before linearized reading' (duration: 163.13562ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:56:12.965845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.993852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-06T07:56:12.966017Z","caller":"traceutil/trace.go:171","msg":"trace[632430101] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25615; }","duration":"186.178367ms","start":"2026-08-06T07:56:12.779803Z","end":"2026-08-06T07:56:12.965981Z","steps":["trace[632430101] 'range keys from in-memory index tree' (duration: 185.842858ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:56:12.965896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.095363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:56:12.96618Z","caller":"traceutil/trace.go:171","msg":"trace[1087174950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25615; }","duration":"120.404ms","start":"2026-08-06T07:56:12.845744Z","end":"2026-08-06T07:56:12.966148Z","steps":["trace[1087174950] 'range keys from in-memory index tree' (duration: 120.020981ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:56:13.216088Z","caller":"traceutil/trace.go:171","msg":"trace[1236680630] transaction","detail":"{read_only:false; response_revision:25617; number_of_response:1; }","duration":"110.745663ms","start":"2026-08-06T07:56:13.105297Z","end":"2026-08-06T07:56:13.216043Z","steps":["trace[1236680630] 'process raft request' (duration: 40.202898ms)","trace[1236680630] 'compare' (duration: 70.333079ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:56:13.513018Z","caller":"traceutil/trace.go:171","msg":"trace[625786697] transaction","detail":"{read_only:false; response_revision:25620; number_of_response:1; }","duration":"127.058884ms","start":"2026-08-06T07:56:13.385928Z","end":"2026-08-06T07:56:13.512987Z","steps":["trace[625786697] 'process raft request' (duration: 94.571045ms)","trace[625786697] 'compare' (duration: 32.272303ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:56:17.187928Z","caller":"traceutil/trace.go:171","msg":"trace[980930034] transaction","detail":"{read_only:false; response_revision:25647; number_of_response:1; }","duration":"125.442143ms","start":"2026-08-06T07:56:17.062455Z","end":"2026-08-06T07:56:17.187898Z","steps":["trace[980930034] 'process raft request' (duration: 125.283129ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:56:17.342852Z","caller":"traceutil/trace.go:171","msg":"trace[1998018984] transaction","detail":"{read_only:false; response_revision:25648; number_of_response:1; }","duration":"116.74016ms","start":"2026-08-06T07:56:17.226089Z","end":"2026-08-06T07:56:17.34283Z","steps":["trace[1998018984] 'process raft request' (duration: 45.184591ms)","trace[1998018984] 'compare' (duration: 71.374965ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:56:17.584445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.919883ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-06T07:56:17.584707Z","caller":"traceutil/trace.go:171","msg":"trace[1982676039] transaction","detail":"{read_only:false; response_revision:25650; number_of_response:1; }","duration":"193.686802ms","start":"2026-08-06T07:56:17.390985Z","end":"2026-08-06T07:56:17.584672Z","steps":["trace[1982676039] 'process raft request' (duration: 61.454411ms)","trace[1982676039] 'compare' (duration: 131.727518ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:56:17.807188Z","caller":"traceutil/trace.go:171","msg":"trace[744071554] transaction","detail":"{read_only:false; response_revision:25652; number_of_response:1; }","duration":"155.492472ms","start":"2026-08-06T07:56:17.651674Z","end":"2026-08-06T07:56:17.807167Z","steps":["trace[744071554] 'process raft request' (duration: 155.298128ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:56:17.808292Z","caller":"traceutil/trace.go:171","msg":"trace[873627304] transaction","detail":"{read_only:false; response_revision:25653; number_of_response:1; }","duration":"155.849541ms","start":"2026-08-06T07:56:17.65243Z","end":"2026-08-06T07:56:17.80828Z","steps":["trace[873627304] 'process raft request' (duration: 155.351009ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:56:21.242333Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22210} {"level":"info","ts":"2026-08-06T07:56:21.353365Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22210,"took":"108.23975ms","hash":1097814281,"current-db-size-bytes":56688640,"current-db-size":"57 MB","current-db-size-in-use-bytes":45068288,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-08-06T07:56:21.35343Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1097814281,"revision":22210,"compact-revision":19929} {"level":"info","ts":"2026-08-06T07:56:23.453508Z","caller":"traceutil/trace.go:171","msg":"trace[964813257] linearizableReadLoop","detail":"{readStateIndex:26523; appliedIndex:26522; }","duration":"210.076594ms","start":"2026-08-06T07:56:23.243404Z","end":"2026-08-06T07:56:23.453481Z","steps":["trace[964813257] 'read index received' (duration: 209.751027ms)","trace[964813257] 'applied index is now lower than readState.Index' (duration: 323.967µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:56:23.453608Z","caller":"traceutil/trace.go:171","msg":"trace[951395339] transaction","detail":"{read_only:false; response_revision:25690; number_of_response:1; }","duration":"243.039614ms","start":"2026-08-06T07:56:23.210545Z","end":"2026-08-06T07:56:23.453585Z","steps":["trace[951395339] 'process raft request' (duration: 242.610744ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:56:23.453759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.335051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-08-06T07:56:23.453814Z","caller":"traceutil/trace.go:171","msg":"trace[1803382542] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:25690; }","duration":"210.428093ms","start":"2026-08-06T07:56:23.243362Z","end":"2026-08-06T07:56:23.45379Z","steps":["trace[1803382542] 'agreement among raft nodes before linearized reading' (duration: 210.239248ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:56:23.614441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.530614ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-06T07:56:23.614619Z","caller":"traceutil/trace.go:171","msg":"trace[953746255] transaction","detail":"{read_only:false; response_revision:25692; number_of_response:1; }","duration":"143.750283ms","start":"2026-08-06T07:56:23.470851Z","end":"2026-08-06T07:56:23.614601Z","steps":["trace[953746255] 'process raft request' (duration: 143.694452ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:56:23.614689Z","caller":"traceutil/trace.go:171","msg":"trace[1775946193] transaction","detail":"{read_only:false; response_revision:25691; number_of_response:1; }","duration":"255.192743ms","start":"2026-08-06T07:56:23.359456Z","end":"2026-08-06T07:56:23.614649Z","steps":["trace[1775946193] 'process raft request' (duration: 151.374731ms)","trace[1775946193] 'compare' (duration: 103.420032ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:56:23.614722Z","caller":"traceutil/trace.go:171","msg":"trace[1773954615] linearizableReadLoop","detail":"{readStateIndex:26524; appliedIndex:26523; }","duration":"159.602723ms","start":"2026-08-06T07:56:23.455094Z","end":"2026-08-06T07:56:23.614697Z","steps":["trace[1773954615] 'read index received' (duration: 55.753069ms)","trace[1773954615] 'applied index is now lower than readState.Index' (duration: 103.847434ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:56:23.614872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.780718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/local-path-provisioner-679c578f5-dqsbt\" ","response":"range_response_count:1 size:3722"} {"level":"info","ts":"2026-08-06T07:56:23.614915Z","caller":"traceutil/trace.go:171","msg":"trace[1240751747] range","detail":"{range_begin:/registry/pods/local-path-storage/local-path-provisioner-679c578f5-dqsbt; range_end:; response_count:1; response_revision:25692; }","duration":"159.86383ms","start":"2026-08-06T07:56:23.455041Z","end":"2026-08-06T07:56:23.614905Z","steps":["trace[1240751747] 'agreement among raft nodes before linearized reading' (duration: 159.734217ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:56:23.886588Z","caller":"traceutil/trace.go:171","msg":"trace[319671051] linearizableReadLoop","detail":"{readStateIndex:26527; appliedIndex:26526; }","duration":"109.439251ms","start":"2026-08-06T07:56:23.77711Z","end":"2026-08-06T07:56:23.88655Z","steps":["trace[319671051] 'read index received' (duration: 90.124176ms)","trace[319671051] 'applied index is now lower than readState.Index' (duration: 19.313785ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:56:23.886595Z","caller":"traceutil/trace.go:171","msg":"trace[2706876] transaction","detail":"{read_only:false; response_revision:25694; number_of_response:1; }","duration":"203.667016ms","start":"2026-08-06T07:56:23.682893Z","end":"2026-08-06T07:56:23.88656Z","steps":["trace[2706876] 'process raft request' (duration: 184.418103ms)","trace[2706876] 'compare' (duration: 18.980776ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:56:23.886743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.612525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:56:23.886856Z","caller":"traceutil/trace.go:171","msg":"trace[974587777] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25694; }","duration":"109.704467ms","start":"2026-08-06T07:56:23.777095Z","end":"2026-08-06T07:56:23.8868Z","steps":["trace[974587777] 'agreement among raft nodes before linearized reading' (duration: 109.569304ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:56:24.172555Z","caller":"traceutil/trace.go:171","msg":"trace[692354560] linearizableReadLoop","detail":"{readStateIndex:26529; appliedIndex:26528; }","duration":"188.222068ms","start":"2026-08-06T07:56:23.984313Z","end":"2026-08-06T07:56:24.172535Z","steps":["trace[692354560] 'read index received' (duration: 188.105606ms)","trace[692354560] 'applied index is now lower than readState.Index' (duration: 115.842µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:56:24.172643Z","caller":"traceutil/trace.go:171","msg":"trace[1663040683] transaction","detail":"{read_only:false; response_revision:25696; number_of_response:1; }","duration":"225.140695ms","start":"2026-08-06T07:56:23.947475Z","end":"2026-08-06T07:56:24.172615Z","steps":["trace[1663040683] 'process raft request' (duration: 224.948661ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:56:24.172673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.351371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:56:24.172703Z","caller":"traceutil/trace.go:171","msg":"trace[1729425422] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:25696; }","duration":"188.421653ms","start":"2026-08-06T07:56:23.984269Z","end":"2026-08-06T07:56:24.172691Z","steps":["trace[1729425422] 'agreement among raft nodes before linearized reading' (duration: 188.360042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:56:24.650824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.104974ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-06T07:56:24.651094Z","caller":"traceutil/trace.go:171","msg":"trace[1026280773] transaction","detail":"{read_only:false; response_revision:25698; number_of_response:1; }","duration":"128.61593ms","start":"2026-08-06T07:56:24.522455Z","end":"2026-08-06T07:56:24.651071Z","steps":["trace[1026280773] 'process raft request' (duration: 128.529998ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:56:24.65129Z","caller":"traceutil/trace.go:171","msg":"trace[63249689] transaction","detail":"{read_only:false; response_revision:25697; number_of_response:1; }","duration":"284.182026ms","start":"2026-08-06T07:56:24.367097Z","end":"2026-08-06T07:56:24.651279Z","steps":["trace[63249689] 'process raft request' (duration: 73.536218ms)","trace[63249689] 'compare' (duration: 209.978521ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:56:24.881361Z","caller":"traceutil/trace.go:171","msg":"trace[1086126641] transaction","detail":"{read_only:false; response_revision:25700; number_of_response:1; }","duration":"325.268636ms","start":"2026-08-06T07:56:24.55608Z","end":"2026-08-06T07:56:24.881349Z","steps":["trace[1086126641] 'process raft request' (duration: 325.192414ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:56:24.881443Z","caller":"traceutil/trace.go:171","msg":"trace[1181656713] linearizableReadLoop","detail":"{readStateIndex:26535; appliedIndex:26530; }","duration":"305.021368ms","start":"2026-08-06T07:56:24.57641Z","end":"2026-08-06T07:56:24.881431Z","steps":["trace[1181656713] 'read index received' (duration: 17.10959ms)","trace[1181656713] 'applied index is now lower than readState.Index' (duration: 287.910468ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:56:24.881509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:56:24.556048Z","time spent":"325.379818ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T07:56:24.881357Z","caller":"traceutil/trace.go:171","msg":"trace[241398638] transaction","detail":"{read_only:false; response_revision:25699; number_of_response:1; }","duration":"347.004771ms","start":"2026-08-06T07:56:24.534322Z","end":"2026-08-06T07:56:24.881327Z","steps":["trace[241398638] 'process raft request' (duration: 346.802426ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:56:24.881603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.197763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-06T07:56:24.881576Z","caller":"traceutil/trace.go:171","msg":"trace[1857866546] transaction","detail":"{read_only:false; response_revision:25701; number_of_response:1; }","duration":"324.642581ms","start":"2026-08-06T07:56:24.556869Z","end":"2026-08-06T07:56:24.881512Z","steps":["trace[1857866546] 'process raft request' (duration: 324.440616ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:56:24.881651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:56:24.534306Z","time spent":"347.315288ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T07:56:24.881639Z","caller":"traceutil/trace.go:171","msg":"trace[227088426] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25701; }","duration":"305.256604ms","start":"2026-08-06T07:56:24.576368Z","end":"2026-08-06T07:56:24.881625Z","steps":["trace[227088426] 'agreement among raft nodes before linearized reading' (duration: 305.130631ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:56:24.881691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:56:24.576356Z","time spent":"305.326646ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-06T07:56:24.88178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:56:24.556862Z","time spent":"324.826705ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T07:56:24.894318Z","caller":"traceutil/trace.go:171","msg":"trace[241157843] transaction","detail":"{read_only:false; response_revision:25702; number_of_response:1; }","duration":"123.523777ms","start":"2026-08-06T07:56:24.770772Z","end":"2026-08-06T07:56:24.894296Z","steps":["trace[241157843] 'process raft request' (duration: 123.296601ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:56:24.894337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.12148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-06T07:56:24.894369Z","caller":"traceutil/trace.go:171","msg":"trace[1481677782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25702; }","duration":"172.191082ms","start":"2026-08-06T07:56:24.722168Z","end":"2026-08-06T07:56:24.894359Z","steps":["trace[1481677782] 'agreement among raft nodes before linearized reading' (duration: 172.09317ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:56:24.894397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.05526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-08-06T07:56:24.894469Z","caller":"traceutil/trace.go:171","msg":"trace[1709555662] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25702; }","duration":"240.164243ms","start":"2026-08-06T07:56:24.654285Z","end":"2026-08-06T07:56:24.894449Z","steps":["trace[1709555662] 'agreement among raft nodes before linearized reading' (duration: 239.934378ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:56:42.452739Z","caller":"traceutil/trace.go:171","msg":"trace[1829286017] transaction","detail":"{read_only:false; response_revision:25982; number_of_response:1; }","duration":"213.089548ms","start":"2026-08-06T07:56:42.239616Z","end":"2026-08-06T07:56:42.452705Z","steps":["trace[1829286017] 'process raft request' (duration: 212.714499ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:56:42.617597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.713783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:56:42.617702Z","caller":"traceutil/trace.go:171","msg":"trace[1451296203] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:25982; }","duration":"108.834806ms","start":"2026-08-06T07:56:42.508813Z","end":"2026-08-06T07:56:42.617648Z","steps":["trace[1451296203] 'count revisions from in-memory index tree' (duration: 108.6124ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:56:43.209713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.918317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-06T07:56:43.209828Z","caller":"traceutil/trace.go:171","msg":"trace[1691044761] linearizableReadLoop","detail":"{readStateIndex:26826; appliedIndex:26825; }","duration":"244.244954ms","start":"2026-08-06T07:56:42.965563Z","end":"2026-08-06T07:56:43.209808Z","steps":["trace[1691044761] 'read index received' (duration: 12.105587ms)","trace[1691044761] 'applied index is now lower than readState.Index' (duration: 232.137537ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:56:43.21021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.642033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-06T07:56:43.210189Z","caller":"traceutil/trace.go:171","msg":"trace[1651811887] transaction","detail":"{read_only:false; response_revision:25986; number_of_response:1; }","duration":"265.26685ms","start":"2026-08-06T07:56:42.944863Z","end":"2026-08-06T07:56:43.21013Z","steps":["trace[1651811887] 'process raft request' (duration: 48.847141ms)","trace[1651811887] 'compare' (duration: 215.294902ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:56:43.210262Z","caller":"traceutil/trace.go:171","msg":"trace[839447920] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25986; }","duration":"244.740875ms","start":"2026-08-06T07:56:42.96551Z","end":"2026-08-06T07:56:43.21025Z","steps":["trace[839447920] 'agreement among raft nodes before linearized reading' (duration: 244.450608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:56:43.210316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.014756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-06T07:56:43.210404Z","caller":"traceutil/trace.go:171","msg":"trace[682502141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25986; }","duration":"189.14926ms","start":"2026-08-06T07:56:43.021227Z","end":"2026-08-06T07:56:43.210376Z","steps":["trace[682502141] 'agreement among raft nodes before linearized reading' (duration: 188.844092ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:56:43.400948Z","caller":"traceutil/trace.go:171","msg":"trace[1233814820] linearizableReadLoop","detail":"{readStateIndex:26828; appliedIndex:26826; }","duration":"134.562137ms","start":"2026-08-06T07:56:43.266369Z","end":"2026-08-06T07:56:43.400932Z","steps":["trace[1233814820] 'read index received' (duration: 98.826949ms)","trace[1233814820] 'applied index is now lower than readState.Index' (duration: 35.734108ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:56:43.401049Z","caller":"traceutil/trace.go:171","msg":"trace[819574828] transaction","detail":"{read_only:false; response_revision:25988; number_of_response:1; }","duration":"187.53831ms","start":"2026-08-06T07:56:43.2135Z","end":"2026-08-06T07:56:43.401039Z","steps":["trace[819574828] 'process raft request' (duration: 187.377096ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:56:43.401058Z","caller":"traceutil/trace.go:171","msg":"trace[1589122426] transaction","detail":"{read_only:false; response_revision:25987; number_of_response:1; }","duration":"187.53739ms","start":"2026-08-06T07:56:43.213484Z","end":"2026-08-06T07:56:43.401022Z","steps":["trace[1589122426] 'process raft request' (duration: 151.722769ms)","trace[1589122426] 'compare' (duration: 35.088902ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:56:43.401232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.854034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-06T07:56:43.401275Z","caller":"traceutil/trace.go:171","msg":"trace[477559663] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25988; }","duration":"134.941368ms","start":"2026-08-06T07:56:43.266326Z","end":"2026-08-06T07:56:43.401268Z","steps":["trace[477559663] 'agreement among raft nodes before linearized reading' (duration: 134.798464ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T08:01:21.247234Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25676} {"level":"info","ts":"2026-08-06T08:01:21.416778Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25676,"took":"165.012809ms","hash":4216290649,"current-db-size-bytes":59195392,"current-db-size":"59 MB","current-db-size-in-use-bytes":36777984,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-08-06T08:01:21.416851Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4216290649,"revision":25676,"compact-revision":22210} {"level":"info","ts":"2026-08-06T08:02:22.999173Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3e4829a3cabe4fce","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-06T08:02:23.004121Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-06T08:02:23.004297Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-06T08:06:21.252774Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28631} {"level":"info","ts":"2026-08-06T08:06:21.39211Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28631,"took":"134.406356ms","hash":950748407,"current-db-size-bytes":59195392,"current-db-size":"59 MB","current-db-size-in-use-bytes":31096832,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-06T08:06:21.392161Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":950748407,"revision":28631,"compact-revision":25676} {"level":"info","ts":"2026-08-06T08:11:21.259826Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30887} {"level":"info","ts":"2026-08-06T08:11:21.398089Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30887,"took":"128.200975ms","hash":339185127,"current-db-size-bytes":59195392,"current-db-size":"59 MB","current-db-size-in-use-bytes":30543872,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-06T08:11:21.398167Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":339185127,"revision":30887,"compact-revision":28631} {"level":"warn","ts":"2026-08-06T08:13:08.241032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.060842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-06T08:13:08.241152Z","caller":"traceutil/trace.go:171","msg":"trace[2142974193] linearizableReadLoop","detail":"{readStateIndex:34993; appliedIndex:34992; }","duration":"396.168328ms","start":"2026-08-06T08:13:07.844968Z","end":"2026-08-06T08:13:08.241136Z","steps":["trace[2142974193] 'read index received' (duration: 282.952393ms)","trace[2142974193] 'applied index is now lower than readState.Index' (duration: 113.214705ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T08:13:08.241332Z","caller":"traceutil/trace.go:171","msg":"trace[1100359728] transaction","detail":"{read_only:false; response_revision:33932; number_of_response:1; }","duration":"497.866932ms","start":"2026-08-06T08:13:07.743453Z","end":"2026-08-06T08:13:08.24132Z","steps":["trace[1100359728] 'process raft request' (duration: 384.434481ms)","trace[1100359728] 'compare' (duration: 112.950509ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T08:13:08.241398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T08:13:07.74343Z","time spent":"497.937614ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T08:13:08.241571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.577649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T08:13:08.241686Z","caller":"traceutil/trace.go:171","msg":"trace[394921006] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:33932; }","duration":"396.697032ms","start":"2026-08-06T08:13:07.84496Z","end":"2026-08-06T08:13:08.241657Z","steps":["trace[394921006] 'agreement among raft nodes before linearized reading' (duration: 396.523748ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T08:13:08.241745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T08:13:07.84491Z","time spent":"396.821465ms","remote":"127.0.0.1:45078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-06T08:13:08.326113Z","caller":"traceutil/trace.go:171","msg":"trace[32924134] transaction","detail":"{read_only:false; response_revision:33933; number_of_response:1; }","duration":"326.029633ms","start":"2026-08-06T08:13:08.000049Z","end":"2026-08-06T08:13:08.326079Z","steps":["trace[32924134] 'process raft request' (duration: 325.816717ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T08:13:08.326215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.221677ms","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":"warn","ts":"2026-08-06T08:13:08.32625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.563831ms","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:6"} {"level":"info","ts":"2026-08-06T08:13:08.326368Z","caller":"traceutil/trace.go:171","msg":"trace[594362099] 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:33933; }","duration":"355.737285ms","start":"2026-08-06T08:13:07.970605Z","end":"2026-08-06T08:13:08.326342Z","steps":["trace[594362099] 'agreement among raft nodes before linearized reading' (duration: 355.519819ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T08:13:08.326431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T08:13:07.970585Z","time spent":"355.830297ms","remote":"127.0.0.1:44178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-08-06T08:13:08.326289Z","caller":"traceutil/trace.go:171","msg":"trace[2053081685] 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:33933; }","duration":"150.33843ms","start":"2026-08-06T08:13:08.175928Z","end":"2026-08-06T08:13:08.326267Z","steps":["trace[2053081685] 'agreement among raft nodes before linearized reading' (duration: 150.119864ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T08:13:08.326291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T08:13:08.000024Z","time spent":"326.172107ms","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T08:13:08.362236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.459009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T08:13:08.36227Z","caller":"traceutil/trace.go:171","msg":"trace[1478084020] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:33934; }","duration":"119.518821ms","start":"2026-08-06T08:13:08.242743Z","end":"2026-08-06T08:13:08.362262Z","steps":["trace[1478084020] 'agreement among raft nodes before linearized reading' (duration: 119.45677ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T08:13:08.818375Z","caller":"traceutil/trace.go:171","msg":"trace[1135562763] transaction","detail":"{read_only:false; response_revision:33936; number_of_response:1; }","duration":"136.9372ms","start":"2026-08-06T08:13:08.681417Z","end":"2026-08-06T08:13:08.818355Z","steps":["trace[1135562763] 'process raft request' (duration: 136.806167ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T08:13:08.861837Z","caller":"traceutil/trace.go:171","msg":"trace[228269474] linearizableReadLoop","detail":"{readStateIndex:34998; appliedIndex:34997; }","duration":"164.97226ms","start":"2026-08-06T08:13:08.696842Z","end":"2026-08-06T08:13:08.861815Z","steps":["trace[228269474] 'read index received' (duration: 121.50337ms)","trace[228269474] 'applied index is now lower than readState.Index' (duration: 43.46777ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T08:13:08.861933Z","caller":"traceutil/trace.go:171","msg":"trace[804235217] transaction","detail":"{read_only:false; response_revision:33937; number_of_response:1; }","duration":"177.54398ms","start":"2026-08-06T08:13:08.684378Z","end":"2026-08-06T08:13:08.861922Z","steps":["trace[804235217] 'process raft request' (duration: 177.305014ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T08:13:08.862187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.333429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-06T08:13:08.862294Z","caller":"traceutil/trace.go:171","msg":"trace[1114654066] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:33937; }","duration":"165.485792ms","start":"2026-08-06T08:13:08.696786Z","end":"2026-08-06T08:13:08.862272Z","steps":["trace[1114654066] 'agreement among raft nodes before linearized reading' (duration: 165.252617ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T08:13:08.951255Z","caller":"traceutil/trace.go:171","msg":"trace[152230300] transaction","detail":"{read_only:false; response_revision:33938; number_of_response:1; }","duration":"130.350048ms","start":"2026-08-06T08:13:08.820854Z","end":"2026-08-06T08:13:08.951204Z","steps":["trace[152230300] 'process raft request' (duration: 55.290451ms)","trace[152230300] 'compare' (duration: 74.829592ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T08:13:08.951358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.42878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T08:13:08.951277Z","caller":"traceutil/trace.go:171","msg":"trace[622242149] transaction","detail":"{read_only:false; response_revision:33939; number_of_response:1; }","duration":"113.034342ms","start":"2026-08-06T08:13:08.838212Z","end":"2026-08-06T08:13:08.951246Z","steps":["trace[622242149] 'process raft request' (duration: 112.913459ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T08:13:08.951453Z","caller":"traceutil/trace.go:171","msg":"trace[1151855762] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:33939; }","duration":"104.529243ms","start":"2026-08-06T08:13:08.846902Z","end":"2026-08-06T08:13:08.951431Z","steps":["trace[1151855762] 'agreement among raft nodes before linearized reading' (duration: 104.312668ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T08:13:09.1582Z","caller":"traceutil/trace.go:171","msg":"trace[2110286936] transaction","detail":"{read_only:false; response_revision:33941; number_of_response:1; }","duration":"130.278427ms","start":"2026-08-06T08:13:09.027892Z","end":"2026-08-06T08:13:09.158171Z","steps":["trace[2110286936] 'process raft request' (duration: 130.05686ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T08:13:10.366466Z","caller":"traceutil/trace.go:171","msg":"trace[1643441141] transaction","detail":"{read_only:false; response_revision:33947; number_of_response:1; }","duration":"119.913731ms","start":"2026-08-06T08:13:10.246524Z","end":"2026-08-06T08:13:10.366438Z","steps":["trace[1643441141] 'process raft request' (duration: 119.734166ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T08:13:11.10683Z","caller":"traceutil/trace.go:171","msg":"trace[127495998] transaction","detail":"{read_only:false; response_revision:33953; number_of_response:1; }","duration":"146.68096ms","start":"2026-08-06T08:13:10.960093Z","end":"2026-08-06T08:13:11.106774Z","steps":["trace[127495998] 'process raft request' (duration: 146.380902ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T08:13:11.443572Z","caller":"traceutil/trace.go:171","msg":"trace[1183773543] transaction","detail":"{read_only:false; response_revision:33957; number_of_response:1; }","duration":"130.093781ms","start":"2026-08-06T08:13:11.313444Z","end":"2026-08-06T08:13:11.443538Z","steps":["trace[1183773543] 'process raft request' (duration: 129.830175ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T08:16:21.265162Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33149} {"level":"info","ts":"2026-08-06T08:16:21.419955Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33149,"took":"151.462327ms","hash":2936124066,"current-db-size-bytes":59195392,"current-db-size":"59 MB","current-db-size-in-use-bytes":31207424,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-06T08:16:21.420024Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2936124066,"revision":33149,"compact-revision":30887} {"level":"info","ts":"2026-08-06T08:21:21.271731Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35446} {"level":"info","ts":"2026-08-06T08:21:21.41446Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35446,"took":"137.108994ms","hash":2669984818,"current-db-size-bytes":59670528,"current-db-size":"60 MB","current-db-size-in-use-bytes":32215040,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-06T08:21:21.414543Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2669984818,"revision":35446,"compact-revision":33149} {"level":"info","ts":"2026-08-06T08:23:49.020098Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3e4829a3cabe4fce","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-06T08:23:49.026942Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-08-06T08:23:49.027952Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-08-06T08:26:21.284949Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37676} {"level":"info","ts":"2026-08-06T08:26:21.454187Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37676,"took":"163.598095ms","hash":3052798549,"current-db-size-bytes":61784064,"current-db-size":"62 MB","current-db-size-in-use-bytes":32595968,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-06T08:26:21.454266Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3052798549,"revision":37676,"compact-revision":35446}