{"level":"warn","ts":"2026-05-12T05:42:39.738655Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-12T05:42:39.738797Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.144:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.144:2380","--initial-cluster=instance=https://199.204.45.144:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.144:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.144: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-05-12T05:42:39.739072Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-12T05:42:39.739138Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-05-12T05:42:39.739226Z","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-05-12T05:42:39.742198Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"]} {"level":"info","ts":"2026-05-12T05:42:39.742413Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.144: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-05-12T05:42:39.74656Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.318622ms"} {"level":"info","ts":"2026-05-12T05:42:39.751652Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4594e5f716bad646","cluster-id":"ff2e51b364668606"} {"level":"info","ts":"2026-05-12T05:42:39.751713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=()"} {"level":"info","ts":"2026-05-12T05:42:39.751731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 0"} {"level":"info","ts":"2026-05-12T05:42:39.751745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4594e5f716bad646 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-12T05:42:39.751758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 1"} {"level":"info","ts":"2026-05-12T05:42:39.751798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"warn","ts":"2026-05-12T05:42:39.758073Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-12T05:42:39.761262Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-12T05:42:39.762531Z","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-05-12T05:42:39.765231Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4594e5f716bad646","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-12T05:42:39.769115Z","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-05-12T05:42:39.769352Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4594e5f716bad646","initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-12T05:42:39.769392Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-12T05:42:39.769583Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4594e5f716bad646","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-12T05:42:39.769816Z","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-05-12T05:42:39.769944Z","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-05-12T05:42:39.769961Z","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-05-12T05:42:39.770259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"info","ts":"2026-05-12T05:42:39.770196Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-05-12T05:42:39.770297Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-05-12T05:42:39.770357Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","added-peer-id":"4594e5f716bad646","added-peer-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-05-12T05:42:40.552488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 is starting a new election at term 1"} {"level":"info","ts":"2026-05-12T05:42:40.552559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-12T05:42:40.552583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgPreVoteResp from 4594e5f716bad646 at term 1"} {"level":"info","ts":"2026-05-12T05:42:40.552604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became candidate at term 2"} {"level":"info","ts":"2026-05-12T05:42:40.552616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgVoteResp from 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-05-12T05:42:40.552647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became leader at term 2"} {"level":"info","ts":"2026-05-12T05:42:40.552687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4594e5f716bad646 elected leader 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-05-12T05:42:40.553842Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T05:42:40.555063Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T05:42:40.555087Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T05:42:40.555463Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4594e5f716bad646","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.144:2379]}","request-path":"/0/members/4594e5f716bad646/attributes","cluster-id":"ff2e51b364668606","publish-timeout":"7s"} {"level":"info","ts":"2026-05-12T05:42:40.555647Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T05:42:40.555739Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T05:42:40.555772Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T05:42:40.556454Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-12T05:42:40.55661Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-12T05:42:40.558886Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-12T05:42:40.559117Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.144:2379"} {"level":"info","ts":"2026-05-12T05:43:16.396323Z","caller":"traceutil/trace.go:171","msg":"trace[1708022386] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"223.37258ms","start":"2026-05-12T05:43:16.17292Z","end":"2026-05-12T05:43:16.396293Z","steps":["trace[1708022386] 'process raft request' (duration: 222.818104ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:43:16.580343Z","caller":"traceutil/trace.go:171","msg":"trace[391573402] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"112.072356ms","start":"2026-05-12T05:43:16.468246Z","end":"2026-05-12T05:43:16.580319Z","steps":["trace[391573402] 'read index received' (duration: 111.88629ms)","trace[391573402] 'applied index is now lower than readState.Index' (duration: 182.325µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:43:16.580398Z","caller":"traceutil/trace.go:171","msg":"trace[381747520] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"122.450874ms","start":"2026-05-12T05:43:16.457919Z","end":"2026-05-12T05:43:16.58037Z","steps":["trace[381747520] 'process raft request' (duration: 122.255029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:16.580594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.321172ms","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-05-12T05:43:16.580668Z","caller":"traceutil/trace.go:171","msg":"trace[649853658] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:617; }","duration":"112.444506ms","start":"2026-05-12T05:43:16.468213Z","end":"2026-05-12T05:43:16.580657Z","steps":["trace[649853658] 'agreement among raft nodes before linearized reading' (duration: 112.22973ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:43:16.633675Z","caller":"traceutil/trace.go:171","msg":"trace[1067301028] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"124.495871ms","start":"2026-05-12T05:43:16.509151Z","end":"2026-05-12T05:43:16.633647Z","steps":["trace[1067301028] 'process raft request' (duration: 116.285263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:17.080135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.225097ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-12T05:43:17.080245Z","caller":"traceutil/trace.go:171","msg":"trace[876868154] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"153.746774ms","start":"2026-05-12T05:43:16.926481Z","end":"2026-05-12T05:43:17.080228Z","steps":["trace[876868154] 'read index received' (duration: 21.792366ms)","trace[876868154] 'applied index is now lower than readState.Index' (duration: 131.952858ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:43:17.08038Z","caller":"traceutil/trace.go:171","msg":"trace[284598987] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"431.518875ms","start":"2026-05-12T05:43:16.648824Z","end":"2026-05-12T05:43:17.080343Z","steps":["trace[284598987] 'process raft request' (duration: 299.370182ms)","trace[284598987] 'compare' (duration: 131.082384ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:43:17.080417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.932768ms","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-05-12T05:43:17.080456Z","caller":"traceutil/trace.go:171","msg":"trace[1692136748] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:620; }","duration":"154.004381ms","start":"2026-05-12T05:43:16.926442Z","end":"2026-05-12T05:43:17.080447Z","steps":["trace[1692136748] 'agreement among raft nodes before linearized reading' (duration: 153.832256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:17.080504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:43:16.648809Z","time spent":"431.647239ms","remote":"127.0.0.1:36280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T05:43:32.877945Z","caller":"traceutil/trace.go:171","msg":"trace[301016791] linearizableReadLoop","detail":"{readStateIndex:800; appliedIndex:799; }","duration":"317.951592ms","start":"2026-05-12T05:43:32.559969Z","end":"2026-05-12T05:43:32.87792Z","steps":["trace[301016791] 'read index received' (duration: 317.749416ms)","trace[301016791] 'applied index is now lower than readState.Index' (duration: 200.936µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:43:32.878045Z","caller":"traceutil/trace.go:171","msg":"trace[756732193] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"326.800478ms","start":"2026-05-12T05:43:32.551204Z","end":"2026-05-12T05:43:32.878004Z","steps":["trace[756732193] 'process raft request' (duration: 326.524441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:32.878154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.162568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:43:32.878213Z","caller":"traceutil/trace.go:171","msg":"trace[1526273835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:778; }","duration":"318.274922ms","start":"2026-05-12T05:43:32.559922Z","end":"2026-05-12T05:43:32.878197Z","steps":["trace[1526273835] 'agreement among raft nodes before linearized reading' (duration: 318.175978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:32.878263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:43:32.559901Z","time spent":"318.350954ms","remote":"127.0.0.1:36080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T05:43:32.878359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.478285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-12T05:43:32.87843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:43:32.551179Z","time spent":"326.962444ms","remote":"127.0.0.1:36280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:43:32.878449Z","caller":"traceutil/trace.go:171","msg":"trace[331857395] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:778; }","duration":"157.629129ms","start":"2026-05-12T05:43:32.720799Z","end":"2026-05-12T05:43:32.878428Z","steps":["trace[331857395] 'agreement among raft nodes before linearized reading' (duration: 157.374612ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:44:44.881658Z","caller":"traceutil/trace.go:171","msg":"trace[235530214] linearizableReadLoop","detail":"{readStateIndex:1903; appliedIndex:1902; }","duration":"202.806706ms","start":"2026-05-12T05:44:44.678826Z","end":"2026-05-12T05:44:44.881632Z","steps":["trace[235530214] 'read index received' (duration: 202.648672ms)","trace[235530214] 'applied index is now lower than readState.Index' (duration: 156.704µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:44:44.881725Z","caller":"traceutil/trace.go:171","msg":"trace[1003694147] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"230.980018ms","start":"2026-05-12T05:44:44.650703Z","end":"2026-05-12T05:44:44.881683Z","steps":["trace[1003694147] 'process raft request' (duration: 230.765442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:44:44.881898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.068684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-12T05:44:44.88194Z","caller":"traceutil/trace.go:171","msg":"trace[986403751] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1837; }","duration":"203.193418ms","start":"2026-05-12T05:44:44.678736Z","end":"2026-05-12T05:44:44.881929Z","steps":["trace[986403751] 'agreement among raft nodes before linearized reading' (duration: 203.025403ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:44:44.903051Z","caller":"traceutil/trace.go:171","msg":"trace[1148737134] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"173.126944ms","start":"2026-05-12T05:44:44.72989Z","end":"2026-05-12T05:44:44.903017Z","steps":["trace[1148737134] 'process raft request' (duration: 172.932869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:44:44.903197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.545713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-df28bbd9-36b3-4996-8d71-dc3a2e9c1393\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-12T05:44:44.903264Z","caller":"traceutil/trace.go:171","msg":"trace[1001275536] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-df28bbd9-36b3-4996-8d71-dc3a2e9c1393; range_end:; response_count:1; response_revision:1838; }","duration":"156.696617ms","start":"2026-05-12T05:44:44.746549Z","end":"2026-05-12T05:44:44.903246Z","steps":["trace[1001275536] 'agreement among raft nodes before linearized reading' (duration: 156.43002ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:44:57.861724Z","caller":"traceutil/trace.go:171","msg":"trace[48108285] transaction","detail":"{read_only:false; response_revision:1949; number_of_response:1; }","duration":"134.480951ms","start":"2026-05-12T05:44:57.727219Z","end":"2026-05-12T05:44:57.8617Z","steps":["trace[48108285] 'process raft request' (duration: 134.340857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:35.808911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.467936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:45:35.80907Z","caller":"traceutil/trace.go:171","msg":"trace[1655641776] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2167; }","duration":"247.669612ms","start":"2026-05-12T05:45:35.56134Z","end":"2026-05-12T05:45:35.80901Z","steps":["trace[1655641776] 'range keys from in-memory index tree' (duration: 247.357073ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:35.944552Z","caller":"traceutil/trace.go:171","msg":"trace[30108441] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"129.195264ms","start":"2026-05-12T05:45:35.815329Z","end":"2026-05-12T05:45:35.944525Z","steps":["trace[30108441] 'process raft request' (duration: 129.02422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:36.322514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.908398ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T05:45:36.322668Z","caller":"traceutil/trace.go:171","msg":"trace[1007372283] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"330.480229ms","start":"2026-05-12T05:45:35.992146Z","end":"2026-05-12T05:45:36.322627Z","steps":["trace[1007372283] 'process raft request' (duration: 197.367465ms)","trace[1007372283] 'compare' (duration: 132.633761ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:45:36.322744Z","caller":"traceutil/trace.go:171","msg":"trace[31186744] linearizableReadLoop","detail":"{readStateIndex:2246; appliedIndex:2245; }","duration":"320.473791ms","start":"2026-05-12T05:45:36.002251Z","end":"2026-05-12T05:45:36.322725Z","steps":["trace[31186744] 'read index received' (duration: 187.177793ms)","trace[31186744] 'applied index is now lower than readState.Index' (duration: 133.294238ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:45:36.322868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:35.992116Z","time spent":"330.637963ms","remote":"127.0.0.1:36360","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-05-12T05:45:36.322979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.731288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-12T05:45:36.323036Z","caller":"traceutil/trace.go:171","msg":"trace[823844946] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2170; }","duration":"320.834241ms","start":"2026-05-12T05:45:36.002189Z","end":"2026-05-12T05:45:36.323023Z","steps":["trace[823844946] 'agreement among raft nodes before linearized reading' (duration: 320.651136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:36.323075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:36.002004Z","time spent":"321.062987ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-05-12T05:45:36.325618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.396027ms","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-05-12T05:45:36.325711Z","caller":"traceutil/trace.go:171","msg":"trace[391414292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2171; }","duration":"188.546391ms","start":"2026-05-12T05:45:36.137146Z","end":"2026-05-12T05:45:36.325693Z","steps":["trace[391414292] 'agreement among raft nodes before linearized reading' (duration: 188.297684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:36.325693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.177738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-12T05:45:36.325823Z","caller":"traceutil/trace.go:171","msg":"trace[1806332715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2171; }","duration":"189.363053ms","start":"2026-05-12T05:45:36.136424Z","end":"2026-05-12T05:45:36.325787Z","steps":["trace[1806332715] 'agreement among raft nodes before linearized reading' (duration: 189.003923ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:46:27.567493Z","caller":"traceutil/trace.go:171","msg":"trace[282505495] transaction","detail":"{read_only:false; response_revision:2621; number_of_response:1; }","duration":"147.83064ms","start":"2026-05-12T05:46:27.419637Z","end":"2026-05-12T05:46:27.567468Z","steps":["trace[282505495] 'process raft request' (duration: 147.661555ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:47:01.414917Z","caller":"traceutil/trace.go:171","msg":"trace[716724761] transaction","detail":"{read_only:false; response_revision:2825; number_of_response:1; }","duration":"130.204858ms","start":"2026-05-12T05:47:01.284674Z","end":"2026-05-12T05:47:01.414879Z","steps":["trace[716724761] 'process raft request' (duration: 129.947871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:50:50.04058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.56976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T05:50:50.040678Z","caller":"traceutil/trace.go:171","msg":"trace[61742255] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:4971; }","duration":"192.731154ms","start":"2026-05-12T05:50:49.847923Z","end":"2026-05-12T05:50:50.040654Z","steps":["trace[61742255] 'count revisions from in-memory index tree' (duration: 192.467028ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:51:05.656253Z","caller":"traceutil/trace.go:171","msg":"trace[1735707861] transaction","detail":"{read_only:false; response_revision:5240; number_of_response:1; }","duration":"139.634113ms","start":"2026-05-12T05:51:05.516595Z","end":"2026-05-12T05:51:05.656229Z","steps":["trace[1735707861] 'process raft request' (duration: 139.326345ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:51:07.706616Z","caller":"traceutil/trace.go:171","msg":"trace[1730591453] transaction","detail":"{read_only:false; response_revision:5306; number_of_response:1; }","duration":"132.344922ms","start":"2026-05-12T05:51:07.574251Z","end":"2026-05-12T05:51:07.706596Z","steps":["trace[1730591453] 'process raft request' (duration: 132.212389ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:51:07.835373Z","caller":"traceutil/trace.go:171","msg":"trace[692137682] linearizableReadLoop","detail":"{readStateIndex:5496; appliedIndex:5495; }","duration":"171.316298ms","start":"2026-05-12T05:51:07.664025Z","end":"2026-05-12T05:51:07.835341Z","steps":["trace[692137682] 'read index received' (duration: 42.708464ms)","trace[692137682] 'applied index is now lower than readState.Index' (duration: 128.605933ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:51:07.83549Z","caller":"traceutil/trace.go:171","msg":"trace[329699051] transaction","detail":"{read_only:false; response_revision:5307; number_of_response:1; }","duration":"175.013915ms","start":"2026-05-12T05:51:07.660432Z","end":"2026-05-12T05:51:07.835446Z","steps":["trace[329699051] 'process raft request' (duration: 141.881536ms)","trace[329699051] 'compare' (duration: 32.770211ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:51:07.835658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.618754ms","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-05-12T05:51:07.835719Z","caller":"traceutil/trace.go:171","msg":"trace[875823677] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5307; }","duration":"171.723437ms","start":"2026-05-12T05:51:07.663981Z","end":"2026-05-12T05:51:07.835705Z","steps":["trace[875823677] 'agreement among raft nodes before linearized reading' (duration: 171.487841ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:52:40.742231Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3040} {"level":"info","ts":"2026-05-12T05:52:40.831221Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3040,"took":"86.346909ms","hash":1185469187,"current-db-size-bytes":58732544,"current-db-size":"59 MB","current-db-size-in-use-bytes":31969280,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-12T05:52:40.831299Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1185469187,"revision":3040,"compact-revision":-1} {"level":"info","ts":"2026-05-12T05:57:23.729331Z","caller":"traceutil/trace.go:171","msg":"trace[897246800] transaction","detail":"{read_only:false; response_revision:8228; number_of_response:1; }","duration":"112.648484ms","start":"2026-05-12T05:57:23.616651Z","end":"2026-05-12T05:57:23.7293Z","steps":["trace[897246800] 'process raft request' (duration: 112.347016ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:57:25.325833Z","caller":"traceutil/trace.go:171","msg":"trace[1296385883] transaction","detail":"{read_only:false; response_revision:8240; number_of_response:1; }","duration":"205.243585ms","start":"2026-05-12T05:57:25.120561Z","end":"2026-05-12T05:57:25.325804Z","steps":["trace[1296385883] 'process raft request' (duration: 162.609834ms)","trace[1296385883] 'compare' (duration: 42.392665ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:57:25.453039Z","caller":"traceutil/trace.go:171","msg":"trace[20066138] transaction","detail":"{read_only:false; response_revision:8241; number_of_response:1; }","duration":"127.465334ms","start":"2026-05-12T05:57:25.325521Z","end":"2026-05-12T05:57:25.452986Z","steps":["trace[20066138] 'process raft request' (duration: 102.88862ms)","trace[20066138] 'compare' (duration: 24.42112ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:57:26.020465Z","caller":"traceutil/trace.go:171","msg":"trace[408323169] transaction","detail":"{read_only:false; response_revision:8244; number_of_response:1; }","duration":"103.663311ms","start":"2026-05-12T05:57:25.916765Z","end":"2026-05-12T05:57:26.020428Z","steps":["trace[408323169] 'process raft request' (duration: 51.676087ms)","trace[408323169] 'compare' (duration: 51.782638ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:57:26.311863Z","caller":"traceutil/trace.go:171","msg":"trace[2124921575] transaction","detail":"{read_only:false; response_revision:8245; number_of_response:1; }","duration":"106.23506ms","start":"2026-05-12T05:57:26.205564Z","end":"2026-05-12T05:57:26.311799Z","steps":["trace[2124921575] 'process raft request' (duration: 106.052495ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:57:26.335728Z","caller":"traceutil/trace.go:171","msg":"trace[483681294] transaction","detail":"{read_only:false; response_revision:8246; number_of_response:1; }","duration":"129.829519ms","start":"2026-05-12T05:57:26.205872Z","end":"2026-05-12T05:57:26.335701Z","steps":["trace[483681294] 'process raft request' (duration: 129.442118ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:57:40.749155Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6181} {"level":"info","ts":"2026-05-12T05:57:40.847674Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6181,"took":"96.438019ms","hash":3828287990,"current-db-size-bytes":58732544,"current-db-size":"59 MB","current-db-size-in-use-bytes":23003136,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-12T05:57:40.847753Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3828287990,"revision":6181,"compact-revision":3040} {"level":"info","ts":"2026-05-12T05:57:53.073603Z","caller":"traceutil/trace.go:171","msg":"trace[1990003134] transaction","detail":"{read_only:false; response_revision:8440; number_of_response:1; }","duration":"102.082352ms","start":"2026-05-12T05:57:52.971486Z","end":"2026-05-12T05:57:53.073568Z","steps":["trace[1990003134] 'process raft request' (duration: 101.843436ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:58:05.276591Z","caller":"traceutil/trace.go:171","msg":"trace[779331566] linearizableReadLoop","detail":"{readStateIndex:8842; appliedIndex:8841; }","duration":"124.502381ms","start":"2026-05-12T05:58:05.152059Z","end":"2026-05-12T05:58:05.276561Z","steps":["trace[779331566] 'read index received' (duration: 124.387967ms)","trace[779331566] 'applied index is now lower than readState.Index' (duration: 109.613µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:58:05.276926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.835801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6wlxr\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-12T05:58:05.277101Z","caller":"traceutil/trace.go:171","msg":"trace[1587347716] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6wlxr; range_end:; response_count:1; response_revision:8546; }","duration":"124.996035ms","start":"2026-05-12T05:58:05.152035Z","end":"2026-05-12T05:58:05.277031Z","steps":["trace[1587347716] 'agreement among raft nodes before linearized reading' (duration: 124.628614ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:58:05.277199Z","caller":"traceutil/trace.go:171","msg":"trace[1452653634] transaction","detail":"{read_only:false; response_revision:8546; number_of_response:1; }","duration":"136.826046ms","start":"2026-05-12T05:58:05.139784Z","end":"2026-05-12T05:58:05.27661Z","steps":["trace[1452653634] 'process raft request' (duration: 136.60235ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:58:06.250117Z","caller":"traceutil/trace.go:171","msg":"trace[1261481435] transaction","detail":"{read_only:false; response_revision:8557; number_of_response:1; }","duration":"163.429338ms","start":"2026-05-12T05:58:06.086654Z","end":"2026-05-12T05:58:06.250083Z","steps":["trace[1261481435] 'process raft request' (duration: 163.210012ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:58:57.370442Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024a4.wal"} {"level":"info","ts":"2026-05-12T05:59:23.724029Z","caller":"traceutil/trace.go:171","msg":"trace[2056492039] linearizableReadLoop","detail":"{readStateIndex:9617; appliedIndex:9616; }","duration":"172.65271ms","start":"2026-05-12T05:59:23.551342Z","end":"2026-05-12T05:59:23.723995Z","steps":["trace[2056492039] 'read index received' (duration: 172.540336ms)","trace[2056492039] 'applied index is now lower than readState.Index' (duration: 111.084µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:59:23.724166Z","caller":"traceutil/trace.go:171","msg":"trace[1315225210] transaction","detail":"{read_only:false; response_revision:9300; number_of_response:1; }","duration":"177.901432ms","start":"2026-05-12T05:59:23.546218Z","end":"2026-05-12T05:59:23.724119Z","steps":["trace[1315225210] 'process raft request' (duration: 177.577552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:59:23.724253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.898536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-12T05:59:23.724308Z","caller":"traceutil/trace.go:171","msg":"trace[1774169016] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:9300; }","duration":"172.989219ms","start":"2026-05-12T05:59:23.551302Z","end":"2026-05-12T05:59:23.724291Z","steps":["trace[1774169016] 'agreement among raft nodes before linearized reading' (duration: 172.835665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:59:23.732527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.387835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-12T05:59:23.73266Z","caller":"traceutil/trace.go:171","msg":"trace[1351862017] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:9301; }","duration":"111.787406ms","start":"2026-05-12T05:59:23.620845Z","end":"2026-05-12T05:59:23.732633Z","steps":["trace[1351862017] 'agreement among raft nodes before linearized reading' (duration: 110.963573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:59:23.893607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.979349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6wlxr\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-12T05:59:23.893719Z","caller":"traceutil/trace.go:171","msg":"trace[1515911962] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6wlxr; range_end:; response_count:1; response_revision:9306; }","duration":"106.137092ms","start":"2026-05-12T05:59:23.787557Z","end":"2026-05-12T05:59:23.893694Z","steps":["trace[1515911962] 'agreement among raft nodes before linearized reading' (duration: 104.704884ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:00:15.570484Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4594e5f716bad646","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-12T06:00:15.577124Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-12T06:00:15.577252Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-12T06:02:40.755761Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8326} {"level":"info","ts":"2026-05-12T06:02:40.831422Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8326,"took":"73.276038ms","hash":387461542,"current-db-size-bytes":58732544,"current-db-size":"59 MB","current-db-size-in-use-bytes":26611712,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-12T06:02:40.831486Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":387461542,"revision":8326,"compact-revision":6181} {"level":"info","ts":"2026-05-12T06:02:58.241346Z","caller":"traceutil/trace.go:171","msg":"trace[941597467] transaction","detail":"{read_only:false; response_revision:11099; number_of_response:1; }","duration":"223.643111ms","start":"2026-05-12T06:02:58.01767Z","end":"2026-05-12T06:02:58.241313Z","steps":["trace[941597467] 'process raft request' (duration: 223.438805ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:02:58.31223Z","caller":"traceutil/trace.go:171","msg":"trace[642851090] transaction","detail":"{read_only:false; response_revision:11101; number_of_response:1; }","duration":"285.064198ms","start":"2026-05-12T06:02:58.027142Z","end":"2026-05-12T06:02:58.312206Z","steps":["trace[642851090] 'process raft request' (duration: 285.015627ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:02:58.312394Z","caller":"traceutil/trace.go:171","msg":"trace[646858041] transaction","detail":"{read_only:false; response_revision:11100; number_of_response:1; }","duration":"291.883433ms","start":"2026-05-12T06:02:58.020502Z","end":"2026-05-12T06:02:58.312386Z","steps":["trace[646858041] 'process raft request' (duration: 282.166779ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:02:59.646019Z","caller":"traceutil/trace.go:171","msg":"trace[1565471265] transaction","detail":"{read_only:false; response_revision:11108; number_of_response:1; }","duration":"142.182109ms","start":"2026-05-12T06:02:59.503814Z","end":"2026-05-12T06:02:59.645996Z","steps":["trace[1565471265] 'process raft request' (duration: 141.735817ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:07:40.761921Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11007} {"level":"info","ts":"2026-05-12T06:07:40.835718Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11007,"took":"71.144023ms","hash":174358402,"current-db-size-bytes":58732544,"current-db-size":"59 MB","current-db-size-in-use-bytes":28135424,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-12T06:07:40.835797Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":174358402,"revision":11007,"compact-revision":8326} {"level":"info","ts":"2026-05-12T06:09:22.0991Z","caller":"traceutil/trace.go:171","msg":"trace[1005032467] transaction","detail":"{read_only:false; response_revision:13702; number_of_response:1; }","duration":"193.853614ms","start":"2026-05-12T06:09:21.905213Z","end":"2026-05-12T06:09:22.099066Z","steps":["trace[1005032467] 'process raft request' (duration: 166.704208ms)","trace[1005032467] 'compare' (duration: 27.023393ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:09:37.082667Z","caller":"traceutil/trace.go:171","msg":"trace[1827770269] linearizableReadLoop","detail":"{readStateIndex:14263; appliedIndex:14261; }","duration":"124.685857ms","start":"2026-05-12T06:09:36.957954Z","end":"2026-05-12T06:09:37.08264Z","steps":["trace[1827770269] 'read index received' (duration: 75.294105ms)","trace[1827770269] 'applied index is now lower than readState.Index' (duration: 49.390382ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:09:37.082854Z","caller":"traceutil/trace.go:171","msg":"trace[1773307356] transaction","detail":"{read_only:false; response_revision:13798; number_of_response:1; }","duration":"129.836816ms","start":"2026-05-12T06:09:36.952926Z","end":"2026-05-12T06:09:37.082762Z","steps":["trace[1773307356] 'process raft request' (duration: 129.588759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:09:37.082959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.983554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-m29pz\" ","response":"range_response_count:1 size:12364"} {"level":"info","ts":"2026-05-12T06:09:37.083008Z","caller":"traceutil/trace.go:171","msg":"trace[1249078226] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-m29pz; range_end:; response_count:1; response_revision:13798; }","duration":"125.080207ms","start":"2026-05-12T06:09:36.957914Z","end":"2026-05-12T06:09:37.082994Z","steps":["trace[1249078226] 'agreement among raft nodes before linearized reading' (duration: 124.838411ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:09:37.839555Z","caller":"traceutil/trace.go:171","msg":"trace[1319710743] transaction","detail":"{read_only:false; response_revision:13801; number_of_response:1; }","duration":"255.619571ms","start":"2026-05-12T06:09:37.583912Z","end":"2026-05-12T06:09:37.839531Z","steps":["trace[1319710743] 'process raft request' (duration: 255.451916ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:09:46.8673Z","caller":"traceutil/trace.go:171","msg":"trace[1223355344] transaction","detail":"{read_only:false; response_revision:13968; number_of_response:1; }","duration":"169.37655ms","start":"2026-05-12T06:09:46.697892Z","end":"2026-05-12T06:09:46.867268Z","steps":["trace[1223355344] 'process raft request' (duration: 169.275107ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:09:46.867283Z","caller":"traceutil/trace.go:171","msg":"trace[613217462] transaction","detail":"{read_only:false; response_revision:13967; number_of_response:1; }","duration":"201.911703ms","start":"2026-05-12T06:09:46.665285Z","end":"2026-05-12T06:09:46.867197Z","steps":["trace[613217462] 'process raft request' (duration: 130.202195ms)","trace[613217462] 'compare' (duration: 71.324997ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:09:46.867388Z","caller":"traceutil/trace.go:171","msg":"trace[2083938201] linearizableReadLoop","detail":"{readStateIndex:14435; appliedIndex:14434; }","duration":"184.124711ms","start":"2026-05-12T06:09:46.683167Z","end":"2026-05-12T06:09:46.867292Z","steps":["trace[2083938201] 'read index received' (duration: 112.337721ms)","trace[2083938201] 'applied index is now lower than readState.Index' (duration: 71.781969ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T06:09:46.867782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.596813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6wlxr\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-12T06:09:46.867862Z","caller":"traceutil/trace.go:171","msg":"trace[796881069] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6wlxr; range_end:; response_count:1; response_revision:13968; }","duration":"184.741416ms","start":"2026-05-12T06:09:46.683108Z","end":"2026-05-12T06:09:46.867849Z","steps":["trace[796881069] 'agreement among raft nodes before linearized reading' (duration: 184.409448ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:11:30.505067Z","caller":"traceutil/trace.go:171","msg":"trace[750861489] transaction","detail":"{read_only:false; response_revision:15074; number_of_response:1; }","duration":"114.756027ms","start":"2026-05-12T06:11:30.390286Z","end":"2026-05-12T06:11:30.505042Z","steps":["trace[750861489] 'process raft request' (duration: 114.574722ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:11:30.648417Z","caller":"traceutil/trace.go:171","msg":"trace[288854387] linearizableReadLoop","detail":"{readStateIndex:15577; appliedIndex:15576; }","duration":"105.909456ms","start":"2026-05-12T06:11:30.542481Z","end":"2026-05-12T06:11:30.64839Z","steps":["trace[288854387] 'read index received' (duration: 49.109764ms)","trace[288854387] 'applied index is now lower than readState.Index' (duration: 56.798612ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:11:30.64849Z","caller":"traceutil/trace.go:171","msg":"trace[1512222746] transaction","detail":"{read_only:false; response_revision:15075; number_of_response:1; }","duration":"239.700099ms","start":"2026-05-12T06:11:30.408757Z","end":"2026-05-12T06:11:30.648457Z","steps":["trace[1512222746] 'process raft request' (duration: 182.806295ms)","trace[1512222746] 'compare' (duration: 56.689859ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T06:11:30.648674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.169153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-fccxr\" ","response":"range_response_count:1 size:22093"} {"level":"info","ts":"2026-05-12T06:11:30.648717Z","caller":"traceutil/trace.go:171","msg":"trace[239966471] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-fccxr; range_end:; response_count:1; response_revision:15075; }","duration":"106.276286ms","start":"2026-05-12T06:11:30.542429Z","end":"2026-05-12T06:11:30.648705Z","steps":["trace[239966471] 'agreement among raft nodes before linearized reading' (duration: 106.099611ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:11:31.766324Z","caller":"traceutil/trace.go:171","msg":"trace[1539782123] transaction","detail":"{read_only:false; response_revision:15081; number_of_response:1; }","duration":"121.027796ms","start":"2026-05-12T06:11:31.645273Z","end":"2026-05-12T06:11:31.766301Z","steps":["trace[1539782123] 'process raft request' (duration: 120.809411ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:11:31.767523Z","caller":"traceutil/trace.go:171","msg":"trace[842909385] transaction","detail":"{read_only:false; response_revision:15082; number_of_response:1; }","duration":"119.471993ms","start":"2026-05-12T06:11:31.648018Z","end":"2026-05-12T06:11:31.76749Z","steps":["trace[842909385] 'process raft request' (duration: 119.052922ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:12:40.769539Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13071} {"level":"info","ts":"2026-05-12T06:12:40.851673Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13071,"took":"79.252912ms","hash":2935094904,"current-db-size-bytes":58732544,"current-db-size":"59 MB","current-db-size-in-use-bytes":31166464,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-12T06:12:40.851726Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2935094904,"revision":13071,"compact-revision":11007} {"level":"info","ts":"2026-05-12T06:17:03.899073Z","caller":"traceutil/trace.go:171","msg":"trace[888889729] linearizableReadLoop","detail":"{readStateIndex:18219; appliedIndex:18217; }","duration":"121.220695ms","start":"2026-05-12T06:17:03.777825Z","end":"2026-05-12T06:17:03.899046Z","steps":["trace[888889729] 'read index received' (duration: 33.210085ms)","trace[888889729] 'applied index is now lower than readState.Index' (duration: 88.00892ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T06:17:03.899219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.369138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T06:17:03.899311Z","caller":"traceutil/trace.go:171","msg":"trace[785703911] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:17634; }","duration":"121.460181ms","start":"2026-05-12T06:17:03.777793Z","end":"2026-05-12T06:17:03.899253Z","steps":["trace[785703911] 'agreement among raft nodes before linearized reading' (duration: 121.364089ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:17:03.899551Z","caller":"traceutil/trace.go:171","msg":"trace[183821322] transaction","detail":"{read_only:false; response_revision:17634; number_of_response:1; }","duration":"127.236018ms","start":"2026-05-12T06:17:03.772276Z","end":"2026-05-12T06:17:03.899512Z","steps":["trace[183821322] 'process raft request' (duration: 126.616491ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:17:09.786958Z","caller":"traceutil/trace.go:171","msg":"trace[1000441701] transaction","detail":"{read_only:false; response_revision:17682; number_of_response:1; }","duration":"153.604428ms","start":"2026-05-12T06:17:09.633323Z","end":"2026-05-12T06:17:09.786927Z","steps":["trace[1000441701] 'process raft request' (duration: 153.440404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:17:10.240216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.045774ms","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-05-12T06:17:10.240296Z","caller":"traceutil/trace.go:171","msg":"trace[2059812030] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17685; }","duration":"116.187189ms","start":"2026-05-12T06:17:10.12409Z","end":"2026-05-12T06:17:10.240277Z","steps":["trace[2059812030] 'range keys from in-memory index tree' (duration: 115.830859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:17:10.709113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.541769ms","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-05-12T06:17:10.709255Z","caller":"traceutil/trace.go:171","msg":"trace[628935783] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:17686; }","duration":"287.747675ms","start":"2026-05-12T06:17:10.421483Z","end":"2026-05-12T06:17:10.709231Z","steps":["trace[628935783] 'count revisions from in-memory index tree' (duration: 287.445067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:17:10.709573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.151275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T06:17:10.709632Z","caller":"traceutil/trace.go:171","msg":"trace[1854658982] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:17686; }","duration":"274.215317ms","start":"2026-05-12T06:17:10.435401Z","end":"2026-05-12T06:17:10.709617Z","steps":["trace[1854658982] 'count revisions from in-memory index tree' (duration: 274.088593ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:17:10.709842Z","caller":"traceutil/trace.go:171","msg":"trace[1553057614] linearizableReadLoop","detail":"{readStateIndex:18273; appliedIndex:18272; }","duration":"146.436552ms","start":"2026-05-12T06:17:10.56336Z","end":"2026-05-12T06:17:10.709796Z","steps":["trace[1553057614] 'read index received' (duration: 105.906577ms)","trace[1553057614] 'applied index is now lower than readState.Index' (duration: 40.527515ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:17:10.710025Z","caller":"traceutil/trace.go:171","msg":"trace[665839318] transaction","detail":"{read_only:false; response_revision:17687; number_of_response:1; }","duration":"267.814682ms","start":"2026-05-12T06:17:10.442191Z","end":"2026-05-12T06:17:10.710006Z","steps":["trace[665839318] 'process raft request' (duration: 227.02738ms)","trace[665839318] 'compare' (duration: 40.218506ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T06:17:10.710204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.858125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T06:17:10.710249Z","caller":"traceutil/trace.go:171","msg":"trace[1168884608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17687; }","duration":"146.934177ms","start":"2026-05-12T06:17:10.563304Z","end":"2026-05-12T06:17:10.710238Z","steps":["trace[1168884608] 'agreement among raft nodes before linearized reading' (duration: 146.874325ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:17:10.875818Z","caller":"traceutil/trace.go:171","msg":"trace[2097544835] transaction","detail":"{read_only:false; response_revision:17689; number_of_response:1; }","duration":"160.356423ms","start":"2026-05-12T06:17:10.715437Z","end":"2026-05-12T06:17:10.875793Z","steps":["trace[2097544835] 'process raft request' (duration: 160.270511ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:17:10.875907Z","caller":"traceutil/trace.go:171","msg":"trace[2029558970] transaction","detail":"{read_only:false; response_revision:17688; number_of_response:1; }","duration":"160.59537ms","start":"2026-05-12T06:17:10.715181Z","end":"2026-05-12T06:17:10.875776Z","steps":["trace[2029558970] 'process raft request' (duration: 121.722239ms)","trace[2029558970] 'compare' (duration: 38.663965ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:17:10.876073Z","caller":"traceutil/trace.go:171","msg":"trace[1745418514] transaction","detail":"{read_only:false; response_revision:17690; number_of_response:1; }","duration":"121.090202ms","start":"2026-05-12T06:17:10.754948Z","end":"2026-05-12T06:17:10.876038Z","steps":["trace[1745418514] 'process raft request' (duration: 120.805214ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:17:40.775052Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15533} {"level":"info","ts":"2026-05-12T06:17:40.869209Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15533,"took":"90.97762ms","hash":1952507562,"current-db-size-bytes":58732544,"current-db-size":"59 MB","current-db-size-in-use-bytes":33398784,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-12T06:17:40.869298Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1952507562,"revision":15533,"compact-revision":13071} {"level":"info","ts":"2026-05-12T06:20:37.292025Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4594e5f716bad646","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-12T06:20:37.297082Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-12T06:20:37.29722Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-12T06:22:40.780876Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18116} {"level":"info","ts":"2026-05-12T06:22:40.893982Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18116,"took":"109.563871ms","hash":142290938,"current-db-size-bytes":58732544,"current-db-size":"59 MB","current-db-size-in-use-bytes":30404608,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-12T06:22:40.894059Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":142290938,"revision":18116,"compact-revision":15533} {"level":"info","ts":"2026-05-12T06:22:57.43852Z","caller":"traceutil/trace.go:171","msg":"trace[639704022] transaction","detail":"{read_only:false; response_revision:20281; number_of_response:1; }","duration":"244.75972ms","start":"2026-05-12T06:22:57.193725Z","end":"2026-05-12T06:22:57.438485Z","steps":["trace[639704022] 'process raft request' (duration: 213.994913ms)","trace[639704022] 'compare' (duration: 30.646094ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:22:57.491866Z","caller":"traceutil/trace.go:171","msg":"trace[2103180084] transaction","detail":"{read_only:false; response_revision:20282; number_of_response:1; }","duration":"297.952227ms","start":"2026-05-12T06:22:57.193774Z","end":"2026-05-12T06:22:57.491726Z","steps":["trace[2103180084] 'process raft request' (duration: 297.526785ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:22:57.49193Z","caller":"traceutil/trace.go:171","msg":"trace[157301511] linearizableReadLoop","detail":"{readStateIndex:20958; appliedIndex:20955; }","duration":"285.2288ms","start":"2026-05-12T06:22:57.206663Z","end":"2026-05-12T06:22:57.491892Z","steps":["trace[157301511] 'read index received' (duration: 201.073851ms)","trace[157301511] 'applied index is now lower than readState.Index' (duration: 84.152489ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:22:57.491982Z","caller":"traceutil/trace.go:171","msg":"trace[1379380603] transaction","detail":"{read_only:false; response_revision:20283; number_of_response:1; }","duration":"297.33595ms","start":"2026-05-12T06:22:57.194612Z","end":"2026-05-12T06:22:57.491948Z","steps":["trace[1379380603] 'process raft request' (duration: 297.019591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:22:57.492181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.493528ms","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-05-12T06:22:57.49224Z","caller":"traceutil/trace.go:171","msg":"trace[1549045671] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20283; }","duration":"285.600491ms","start":"2026-05-12T06:22:57.206618Z","end":"2026-05-12T06:22:57.492219Z","steps":["trace[1549045671] 'agreement among raft nodes before linearized reading' (duration: 285.388155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:23:08.185031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.92945ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T06:23:08.185216Z","caller":"traceutil/trace.go:171","msg":"trace[2041439956] linearizableReadLoop","detail":"{readStateIndex:21012; appliedIndex:21011; }","duration":"151.44822ms","start":"2026-05-12T06:23:08.033744Z","end":"2026-05-12T06:23:08.185192Z","steps":["trace[2041439956] 'read index received' (duration: 37.233833ms)","trace[2041439956] 'applied index is now lower than readState.Index' (duration: 114.211957ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:23:08.18527Z","caller":"traceutil/trace.go:171","msg":"trace[64128668] transaction","detail":"{read_only:false; response_revision:20335; number_of_response:1; }","duration":"164.25946ms","start":"2026-05-12T06:23:08.020938Z","end":"2026-05-12T06:23:08.185197Z","steps":["trace[64128668] 'process raft request' (duration: 50.028941ms)","trace[64128668] 'compare' (duration: 113.622842ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T06:23:08.18548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.726108ms","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-05-12T06:23:08.18554Z","caller":"traceutil/trace.go:171","msg":"trace[787633270] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20335; }","duration":"151.82698ms","start":"2026-05-12T06:23:08.033698Z","end":"2026-05-12T06:23:08.185525Z","steps":["trace[787633270] 'agreement among raft nodes before linearized reading' (duration: 151.568263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:23:08.491667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.760778ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T06:23:08.491978Z","caller":"traceutil/trace.go:171","msg":"trace[1752419601] transaction","detail":"{read_only:false; response_revision:20336; number_of_response:1; }","duration":"301.943595ms","start":"2026-05-12T06:23:08.189956Z","end":"2026-05-12T06:23:08.4919Z","steps":["trace[1752419601] 'process raft request' (duration: 174.853537ms)","trace[1752419601] 'compare' (duration: 126.382218ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T06:23:08.492786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:23:08.189933Z","time spent":"302.52816ms","remote":"127.0.0.1:36360","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-05-12T06:23:08.991048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.994055ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-12T06:23:08.992166Z","caller":"traceutil/trace.go:171","msg":"trace[497541995] transaction","detail":"{read_only:false; response_revision:20337; number_of_response:1; }","duration":"265.985337ms","start":"2026-05-12T06:23:08.725959Z","end":"2026-05-12T06:23:08.991945Z","steps":["trace[497541995] 'process raft request' (duration: 265.211136ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:23:09.069677Z","caller":"traceutil/trace.go:171","msg":"trace[3964414] transaction","detail":"{read_only:false; response_revision:20338; number_of_response:1; }","duration":"343.62606ms","start":"2026-05-12T06:23:08.726004Z","end":"2026-05-12T06:23:09.06963Z","steps":["trace[3964414] 'process raft request' (duration: 265.888705ms)","trace[3964414] 'compare' (duration: 77.56396ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T06:23:09.069878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:23:08.725965Z","time spent":"343.794734ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T06:26:57.32299Z","caller":"traceutil/trace.go:171","msg":"trace[1791774321] linearizableReadLoop","detail":"{readStateIndex:23485; appliedIndex:23484; }","duration":"341.247415ms","start":"2026-05-12T06:26:56.981718Z","end":"2026-05-12T06:26:57.322966Z","steps":["trace[1791774321] 'read index received' (duration: 341.078099ms)","trace[1791774321] 'applied index is now lower than readState.Index' (duration: 168.206µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:26:57.323085Z","caller":"traceutil/trace.go:171","msg":"trace[473153260] transaction","detail":"{read_only:false; response_revision:22734; number_of_response:1; }","duration":"344.01509ms","start":"2026-05-12T06:26:56.979001Z","end":"2026-05-12T06:26:57.323016Z","steps":["trace[473153260] 'process raft request' (duration: 343.797563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:26:57.323261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:26:56.978963Z","time spent":"344.203955ms","remote":"127.0.0.1:36586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5164,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T06:26:57.323308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.561713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:417501"} {"level":"info","ts":"2026-05-12T06:26:57.323372Z","caller":"traceutil/trace.go:171","msg":"trace[1845404002] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:22734; }","duration":"341.674906ms","start":"2026-05-12T06:26:56.981685Z","end":"2026-05-12T06:26:57.32336Z","steps":["trace[1845404002] 'agreement among raft nodes before linearized reading' (duration: 341.389569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:26:57.323407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:26:56.981667Z","time spent":"341.731027ms","remote":"127.0.0.1:48250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":417524,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"info","ts":"2026-05-12T06:26:57.33981Z","caller":"traceutil/trace.go:171","msg":"trace[1666900642] transaction","detail":"{read_only:false; response_revision:22735; number_of_response:1; }","duration":"325.347393ms","start":"2026-05-12T06:26:57.014451Z","end":"2026-05-12T06:26:57.339799Z","steps":["trace[1666900642] 'process raft request' (duration: 325.26903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:26:57.33989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:26:57.014429Z","time spent":"325.433345ms","remote":"127.0.0.1:36360","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-05-12T06:27:40.787405Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20196} {"level":"info","ts":"2026-05-12T06:27:40.892104Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20196,"took":"101.820495ms","hash":3290868019,"current-db-size-bytes":58732544,"current-db-size":"59 MB","current-db-size-in-use-bytes":40673280,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-05-12T06:27:40.892173Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3290868019,"revision":20196,"compact-revision":18116} {"level":"info","ts":"2026-05-12T06:30:33.831591Z","caller":"traceutil/trace.go:171","msg":"trace[150266188] linearizableReadLoop","detail":"{readStateIndex:25724; appliedIndex:25723; }","duration":"106.728999ms","start":"2026-05-12T06:30:33.724834Z","end":"2026-05-12T06:30:33.831563Z","steps":["trace[150266188] 'read index received' (duration: 106.488272ms)","trace[150266188] 'applied index is now lower than readState.Index' (duration: 239.357µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:30:33.83166Z","caller":"traceutil/trace.go:171","msg":"trace[1659010296] transaction","detail":"{read_only:false; response_revision:24917; number_of_response:1; }","duration":"128.535304ms","start":"2026-05-12T06:30:33.703078Z","end":"2026-05-12T06:30:33.831613Z","steps":["trace[1659010296] 'process raft request' (duration: 128.260457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:33.831907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.062528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-12T06:30:33.831969Z","caller":"traceutil/trace.go:171","msg":"trace[1133836451] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:24917; }","duration":"107.171831ms","start":"2026-05-12T06:30:33.724782Z","end":"2026-05-12T06:30:33.831953Z","steps":["trace[1133836451] 'agreement among raft nodes before linearized reading' (duration: 106.969376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:34.085955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.464325ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-05-12T06:30:34.086262Z","caller":"traceutil/trace.go:171","msg":"trace[859598582] transaction","detail":"{read_only:false; response_revision:24918; number_of_response:1; }","duration":"249.913553ms","start":"2026-05-12T06:30:33.836326Z","end":"2026-05-12T06:30:34.086239Z","steps":["trace[859598582] 'process raft request' (duration: 249.759458ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:30:34.476286Z","caller":"traceutil/trace.go:171","msg":"trace[2135155483] transaction","detail":"{read_only:false; response_revision:24921; number_of_response:1; }","duration":"285.417379ms","start":"2026-05-12T06:30:34.190838Z","end":"2026-05-12T06:30:34.476255Z","steps":["trace[2135155483] 'process raft request' (duration: 285.033109ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:30:34.49434Z","caller":"traceutil/trace.go:171","msg":"trace[1429315052] transaction","detail":"{read_only:false; response_revision:24922; number_of_response:1; }","duration":"248.264207ms","start":"2026-05-12T06:30:34.246034Z","end":"2026-05-12T06:30:34.494298Z","steps":["trace[1429315052] 'process raft request' (duration: 248.01851ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:30:34.494587Z","caller":"traceutil/trace.go:171","msg":"trace[672293892] transaction","detail":"{read_only:false; response_revision:24923; number_of_response:1; }","duration":"208.906484ms","start":"2026-05-12T06:30:34.285648Z","end":"2026-05-12T06:30:34.494555Z","steps":["trace[672293892] 'process raft request' (duration: 208.584565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:38.692137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.50027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T06:30:38.692223Z","caller":"traceutil/trace.go:171","msg":"trace[563976182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24956; }","duration":"129.635644ms","start":"2026-05-12T06:30:38.562569Z","end":"2026-05-12T06:30:38.692205Z","steps":["trace[563976182] 'range keys from in-memory index tree' (duration: 129.411787ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:30:38.851674Z","caller":"traceutil/trace.go:171","msg":"trace[1407200373] transaction","detail":"{read_only:false; response_revision:24957; number_of_response:1; }","duration":"155.438368ms","start":"2026-05-12T06:30:38.696203Z","end":"2026-05-12T06:30:38.851641Z","steps":["trace[1407200373] 'process raft request' (duration: 155.262773ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:30:38.860279Z","caller":"traceutil/trace.go:171","msg":"trace[416733018] transaction","detail":"{read_only:false; response_revision:24958; number_of_response:1; }","duration":"162.498459ms","start":"2026-05-12T06:30:38.69775Z","end":"2026-05-12T06:30:38.860248Z","steps":["trace[416733018] 'process raft request' (duration: 162.284013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:39.017973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.741019ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-12T06:30:39.105398Z","caller":"traceutil/trace.go:171","msg":"trace[1498952514] transaction","detail":"{read_only:false; response_revision:24959; number_of_response:1; }","duration":"109.32884ms","start":"2026-05-12T06:30:38.996052Z","end":"2026-05-12T06:30:39.105381Z","steps":["trace[1498952514] 'process raft request' (duration: 109.187857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:39.2601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.23187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-12T06:30:39.260177Z","caller":"traceutil/trace.go:171","msg":"trace[1855672574] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:24959; }","duration":"101.371314ms","start":"2026-05-12T06:30:39.158789Z","end":"2026-05-12T06:30:39.26016Z","steps":["trace[1855672574] 'count revisions from in-memory index tree' (duration: 101.154518ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:30:39.372234Z","caller":"traceutil/trace.go:171","msg":"trace[172131892] transaction","detail":"{read_only:false; response_revision:24960; number_of_response:1; }","duration":"165.09986ms","start":"2026-05-12T06:30:39.207109Z","end":"2026-05-12T06:30:39.372209Z","steps":["trace[172131892] 'process raft request' (duration: 164.925435ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:30:39.448756Z","caller":"traceutil/trace.go:171","msg":"trace[1103937879] linearizableReadLoop","detail":"{readStateIndex:25770; appliedIndex:25768; }","duration":"194.116311ms","start":"2026-05-12T06:30:39.254614Z","end":"2026-05-12T06:30:39.44873Z","steps":["trace[1103937879] 'read index received' (duration: 117.563254ms)","trace[1103937879] 'applied index is now lower than readState.Index' (duration: 76.551907ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:30:39.44891Z","caller":"traceutil/trace.go:171","msg":"trace[808036280] transaction","detail":"{read_only:false; response_revision:24961; number_of_response:1; }","duration":"240.418503ms","start":"2026-05-12T06:30:39.208474Z","end":"2026-05-12T06:30:39.448893Z","steps":["trace[808036280] 'process raft request' (duration: 240.105015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:39.449162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.723163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-12T06:30:39.449193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.550003ms","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-05-12T06:30:39.449248Z","caller":"traceutil/trace.go:171","msg":"trace[1067568578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24961; }","duration":"192.865317ms","start":"2026-05-12T06:30:39.256364Z","end":"2026-05-12T06:30:39.44923Z","steps":["trace[1067568578] 'agreement among raft nodes before linearized reading' (duration: 192.60169ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:30:39.449286Z","caller":"traceutil/trace.go:171","msg":"trace[1222127876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24961; }","duration":"194.701477ms","start":"2026-05-12T06:30:39.254564Z","end":"2026-05-12T06:30:39.449266Z","steps":["trace[1222127876] 'agreement among raft nodes before linearized reading' (duration: 194.43818ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:30:39.555432Z","caller":"traceutil/trace.go:171","msg":"trace[2141087627] transaction","detail":"{read_only:false; response_revision:24963; number_of_response:1; }","duration":"100.815598ms","start":"2026-05-12T06:30:39.454589Z","end":"2026-05-12T06:30:39.555404Z","steps":["trace[2141087627] 'process raft request' (duration: 100.582252ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:30:39.556112Z","caller":"traceutil/trace.go:171","msg":"trace[1400228802] transaction","detail":"{read_only:false; response_revision:24964; number_of_response:1; }","duration":"101.078596ms","start":"2026-05-12T06:30:39.455003Z","end":"2026-05-12T06:30:39.556082Z","steps":["trace[1400228802] 'process raft request' (duration: 100.354646ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:30:39.969052Z","caller":"traceutil/trace.go:171","msg":"trace[2010754534] transaction","detail":"{read_only:false; response_revision:24967; number_of_response:1; }","duration":"120.544856ms","start":"2026-05-12T06:30:39.848486Z","end":"2026-05-12T06:30:39.969031Z","steps":["trace[2010754534] 'process raft request' (duration: 120.406802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:40.25006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.807801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-05-12T06:30:40.25013Z","caller":"traceutil/trace.go:171","msg":"trace[1530003195] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24967; }","duration":"168.918824ms","start":"2026-05-12T06:30:40.081191Z","end":"2026-05-12T06:30:40.25011Z","steps":["trace[1530003195] 'range keys from in-memory index tree' (duration: 168.638327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:40.250101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.576236ms","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-05-12T06:30:40.25021Z","caller":"traceutil/trace.go:171","msg":"trace[867779398] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24967; }","duration":"124.71077ms","start":"2026-05-12T06:30:40.125455Z","end":"2026-05-12T06:30:40.250166Z","steps":["trace[867779398] 'range keys from in-memory index tree' (duration: 124.387ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:30:40.485766Z","caller":"traceutil/trace.go:171","msg":"trace[674420929] linearizableReadLoop","detail":"{readStateIndex:25778; appliedIndex:25776; }","duration":"231.281894ms","start":"2026-05-12T06:30:40.254462Z","end":"2026-05-12T06:30:40.485744Z","steps":["trace[674420929] 'read index received' (duration: 47.865265ms)","trace[674420929] 'applied index is now lower than readState.Index' (duration: 183.415509ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T06:30:40.486086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.965175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T06:30:40.486131Z","caller":"traceutil/trace.go:171","msg":"trace[1401511748] transaction","detail":"{read_only:false; response_revision:24969; number_of_response:1; }","duration":"232.032754ms","start":"2026-05-12T06:30:40.254059Z","end":"2026-05-12T06:30:40.486092Z","steps":["trace[1401511748] 'process raft request' (duration: 145.239758ms)","trace[1401511748] 'compare' (duration: 86.114768ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:30:40.486209Z","caller":"traceutil/trace.go:171","msg":"trace[1026298081] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:24969; }","duration":"173.102259ms","start":"2026-05-12T06:30:40.313084Z","end":"2026-05-12T06:30:40.486187Z","steps":["trace[1026298081] 'agreement among raft nodes before linearized reading' (duration: 172.919094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:40.486202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.719336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18621"} {"level":"info","ts":"2026-05-12T06:30:40.486894Z","caller":"traceutil/trace.go:171","msg":"trace[1647802639] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24969; }","duration":"232.436204ms","start":"2026-05-12T06:30:40.254436Z","end":"2026-05-12T06:30:40.486872Z","steps":["trace[1647802639] 'agreement among raft nodes before linearized reading' (duration: 231.608682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:40.802733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.453357ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T06:30:40.80305Z","caller":"traceutil/trace.go:171","msg":"trace[545481326] linearizableReadLoop","detail":"{readStateIndex:25780; appliedIndex:25778; }","duration":"241.309218ms","start":"2026-05-12T06:30:40.561728Z","end":"2026-05-12T06:30:40.803037Z","steps":["trace[545481326] 'read index received' (duration: 103.590284ms)","trace[545481326] 'applied index is now lower than readState.Index' (duration: 137.718024ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:30:40.803059Z","caller":"traceutil/trace.go:171","msg":"trace[1879335743] transaction","detail":"{read_only:false; response_revision:24970; number_of_response:1; }","duration":"311.068609ms","start":"2026-05-12T06:30:40.491953Z","end":"2026-05-12T06:30:40.803021Z","steps":["trace[1879335743] 'process raft request' (duration: 173.216522ms)","trace[1879335743] 'compare' (duration: 137.121988ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T06:30:40.803132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.40256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T06:30:40.803152Z","caller":"traceutil/trace.go:171","msg":"trace[1481367043] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24971; }","duration":"241.464332ms","start":"2026-05-12T06:30:40.561681Z","end":"2026-05-12T06:30:40.803146Z","steps":["trace[1481367043] 'agreement among raft nodes before linearized reading' (duration: 241.4227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:40.803191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:40.491933Z","time spent":"311.180822ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T06:30:40.803236Z","caller":"traceutil/trace.go:171","msg":"trace[195777480] transaction","detail":"{read_only:false; response_revision:24971; number_of_response:1; }","duration":"292.196655ms","start":"2026-05-12T06:30:40.510916Z","end":"2026-05-12T06:30:40.803112Z","steps":["trace[195777480] 'process raft request' (duration: 292.0399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:40.804952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.041935ms","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-05-12T06:30:40.804996Z","caller":"traceutil/trace.go:171","msg":"trace[1094078446] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24972; }","duration":"243.113647ms","start":"2026-05-12T06:30:40.561872Z","end":"2026-05-12T06:30:40.804986Z","steps":["trace[1094078446] 'agreement among raft nodes before linearized reading' (duration: 243.016424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:41.141322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.60546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T06:30:41.141511Z","caller":"traceutil/trace.go:171","msg":"trace[1097011589] linearizableReadLoop","detail":"{readStateIndex:25783; appliedIndex:25781; }","duration":"162.082898ms","start":"2026-05-12T06:30:40.979415Z","end":"2026-05-12T06:30:41.141497Z","steps":["trace[1097011589] 'read index received' (duration: 35.369715ms)","trace[1097011589] 'applied index is now lower than readState.Index' (duration: 126.712113ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T06:30:41.141576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.16592ms","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-05-12T06:30:41.141604Z","caller":"traceutil/trace.go:171","msg":"trace[938700498] 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:24974; }","duration":"162.225182ms","start":"2026-05-12T06:30:40.979366Z","end":"2026-05-12T06:30:41.141592Z","steps":["trace[938700498] 'agreement among raft nodes before linearized reading' (duration: 162.175281ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:30:41.141582Z","caller":"traceutil/trace.go:171","msg":"trace[1094761577] transaction","detail":"{read_only:false; response_revision:24973; number_of_response:1; }","duration":"281.878733ms","start":"2026-05-12T06:30:40.85968Z","end":"2026-05-12T06:30:41.141559Z","steps":["trace[1094761577] 'process raft request' (duration: 154.958934ms)","trace[1094761577] 'compare' (duration: 126.387675ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:30:41.141647Z","caller":"traceutil/trace.go:171","msg":"trace[966058734] transaction","detail":"{read_only:false; response_revision:24974; number_of_response:1; }","duration":"277.556595ms","start":"2026-05-12T06:30:40.864038Z","end":"2026-05-12T06:30:41.141595Z","steps":["trace[966058734] 'process raft request' (duration: 277.395091ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:30:41.360301Z","caller":"traceutil/trace.go:171","msg":"trace[943612555] linearizableReadLoop","detail":"{readStateIndex:25784; appliedIndex:25783; }","duration":"214.24915ms","start":"2026-05-12T06:30:41.146029Z","end":"2026-05-12T06:30:41.360279Z","steps":["trace[943612555] 'read index received' (duration: 214.084475ms)","trace[943612555] 'applied index is now lower than readState.Index' (duration: 163.805µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:30:41.360319Z","caller":"traceutil/trace.go:171","msg":"trace[630243483] transaction","detail":"{read_only:false; response_revision:24975; number_of_response:1; }","duration":"250.152988ms","start":"2026-05-12T06:30:41.110142Z","end":"2026-05-12T06:30:41.360295Z","steps":["trace[630243483] 'process raft request' (duration: 249.998034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:41.360479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.420534ms","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-05-12T06:30:41.360561Z","caller":"traceutil/trace.go:171","msg":"trace[189824781] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24975; }","duration":"214.541708ms","start":"2026-05-12T06:30:41.146001Z","end":"2026-05-12T06:30:41.360542Z","steps":["trace[189824781] 'agreement among raft nodes before linearized reading' (duration: 214.365273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:41.576671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.49052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T06:30:41.576874Z","caller":"traceutil/trace.go:171","msg":"trace[1188858628] transaction","detail":"{read_only:false; response_revision:24976; number_of_response:1; }","duration":"200.854445ms","start":"2026-05-12T06:30:41.375997Z","end":"2026-05-12T06:30:41.576851Z","steps":["trace[1188858628] 'process raft request' (duration: 75.074776ms)","trace[1188858628] 'compare' (duration: 125.327545ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:30:41.631142Z","caller":"traceutil/trace.go:171","msg":"trace[1141706862] linearizableReadLoop","detail":"{readStateIndex:25786; appliedIndex:25785; }","duration":"161.263236ms","start":"2026-05-12T06:30:41.469826Z","end":"2026-05-12T06:30:41.631089Z","steps":["trace[1141706862] 'read index received' (duration: 161.206014ms)","trace[1141706862] 'applied index is now lower than readState.Index' (duration: 56.242µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:30:41.631265Z","caller":"traceutil/trace.go:171","msg":"trace[2110666394] transaction","detail":"{read_only:false; response_revision:24977; number_of_response:1; }","duration":"176.235924ms","start":"2026-05-12T06:30:41.454986Z","end":"2026-05-12T06:30:41.631222Z","steps":["trace[2110666394] 'process raft request' (duration: 175.984528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:41.631299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.464262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-12T06:30:41.631344Z","caller":"traceutil/trace.go:171","msg":"trace[1346368492] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:24977; }","duration":"161.561904ms","start":"2026-05-12T06:30:41.469771Z","end":"2026-05-12T06:30:41.631333Z","steps":["trace[1346368492] 'agreement among raft nodes before linearized reading' (duration: 161.462002ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:41.645025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.338663ms","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-05-12T06:30:41.645084Z","caller":"traceutil/trace.go:171","msg":"trace[1547336102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24979; }","duration":"119.437096ms","start":"2026-05-12T06:30:41.525633Z","end":"2026-05-12T06:30:41.64507Z","steps":["trace[1547336102] 'agreement among raft nodes before linearized reading' (duration: 119.21423ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:30:41.817832Z","caller":"traceutil/trace.go:171","msg":"trace[7102610] transaction","detail":"{read_only:false; response_revision:24981; number_of_response:1; }","duration":"168.126043ms","start":"2026-05-12T06:30:41.649682Z","end":"2026-05-12T06:30:41.817809Z","steps":["trace[7102610] 'process raft request' (duration: 167.926727ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:30:41.818031Z","caller":"traceutil/trace.go:171","msg":"trace[1897974273] linearizableReadLoop","detail":"{readStateIndex:25791; appliedIndex:25789; }","duration":"102.753591ms","start":"2026-05-12T06:30:41.715249Z","end":"2026-05-12T06:30:41.818003Z","steps":["trace[1897974273] 'read index received' (duration: 102.293137ms)","trace[1897974273] 'applied index is now lower than readState.Index' (duration: 458.584µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:30:41.81809Z","caller":"traceutil/trace.go:171","msg":"trace[1251311991] transaction","detail":"{read_only:false; response_revision:24982; number_of_response:1; }","duration":"168.182214ms","start":"2026-05-12T06:30:41.649892Z","end":"2026-05-12T06:30:41.818074Z","steps":["trace[1251311991] 'process raft request' (duration: 167.865135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:41.818203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.924145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T06:30:41.818249Z","caller":"traceutil/trace.go:171","msg":"trace[1338175351] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:24982; }","duration":"103.014928ms","start":"2026-05-12T06:30:41.715222Z","end":"2026-05-12T06:30:41.818236Z","steps":["trace[1338175351] 'agreement among raft nodes before linearized reading' (duration: 102.911775ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:30:42.121023Z","caller":"traceutil/trace.go:171","msg":"trace[58232417] linearizableReadLoop","detail":"{readStateIndex:25792; appliedIndex:25791; }","duration":"110.938245ms","start":"2026-05-12T06:30:42.01006Z","end":"2026-05-12T06:30:42.120998Z","steps":["trace[58232417] 'read index received' (duration: 103.279855ms)","trace[58232417] 'applied index is now lower than readState.Index' (duration: 7.65723ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:30:42.121046Z","caller":"traceutil/trace.go:171","msg":"trace[924009798] transaction","detail":"{read_only:false; response_revision:24983; number_of_response:1; }","duration":"146.874824ms","start":"2026-05-12T06:30:41.974137Z","end":"2026-05-12T06:30:42.121012Z","steps":["trace[924009798] 'process raft request' (duration: 139.309167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:42.121339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.207272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"info","ts":"2026-05-12T06:30:42.121417Z","caller":"traceutil/trace.go:171","msg":"trace[1811129242] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:24983; }","duration":"111.379157ms","start":"2026-05-12T06:30:42.010026Z","end":"2026-05-12T06:30:42.121405Z","steps":["trace[1811129242] 'agreement among raft nodes before linearized reading' (duration: 111.12596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:42.432725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.834906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-12T06:30:42.432804Z","caller":"traceutil/trace.go:171","msg":"trace[354180146] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24983; }","duration":"130.96823ms","start":"2026-05-12T06:30:42.301816Z","end":"2026-05-12T06:30:42.432785Z","steps":["trace[354180146] 'range keys from in-memory index tree' (duration: 130.569289ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:30:42.677484Z","caller":"traceutil/trace.go:171","msg":"trace[2056045627] linearizableReadLoop","detail":"{readStateIndex:25793; appliedIndex:25792; }","duration":"187.523491ms","start":"2026-05-12T06:30:42.489938Z","end":"2026-05-12T06:30:42.677461Z","steps":["trace[2056045627] 'read index received' (duration: 187.283184ms)","trace[2056045627] 'applied index is now lower than readState.Index' (duration: 239.397µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T06:30:42.677641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.654035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T06:30:42.677676Z","caller":"traceutil/trace.go:171","msg":"trace[1319888489] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24984; }","duration":"114.743737ms","start":"2026-05-12T06:30:42.562919Z","end":"2026-05-12T06:30:42.677663Z","steps":["trace[1319888489] 'agreement among raft nodes before linearized reading' (duration: 114.666714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:42.677729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.772028ms","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-05-12T06:30:42.677814Z","caller":"traceutil/trace.go:171","msg":"trace[486663024] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24984; }","duration":"187.905973ms","start":"2026-05-12T06:30:42.489886Z","end":"2026-05-12T06:30:42.677792Z","steps":["trace[486663024] 'agreement among raft nodes before linearized reading' (duration: 187.646185ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:30:42.678127Z","caller":"traceutil/trace.go:171","msg":"trace[630641922] transaction","detail":"{read_only:false; response_revision:24984; number_of_response:1; }","duration":"370.415167ms","start":"2026-05-12T06:30:42.307658Z","end":"2026-05-12T06:30:42.678073Z","steps":["trace[630641922] 'process raft request' (duration: 369.628515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:42.678233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:42.307626Z","time spent":"370.546771ms","remote":"127.0.0.1:59810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7516,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T06:30:42.922231Z","caller":"traceutil/trace.go:171","msg":"trace[1136312147] transaction","detail":"{read_only:false; response_revision:24986; number_of_response:1; }","duration":"114.251164ms","start":"2026-05-12T06:30:42.807937Z","end":"2026-05-12T06:30:42.922189Z","steps":["trace[1136312147] 'process raft request' (duration: 101.369803ms)","trace[1136312147] 'compare' (duration: 12.613124ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:30:42.922304Z","caller":"traceutil/trace.go:171","msg":"trace[1174799632] transaction","detail":"{read_only:false; response_revision:24987; number_of_response:1; }","duration":"113.32581ms","start":"2026-05-12T06:30:42.80893Z","end":"2026-05-12T06:30:42.922256Z","steps":["trace[1174799632] 'process raft request' (duration: 113.185476ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:30:43.419107Z","caller":"traceutil/trace.go:171","msg":"trace[1994085641] transaction","detail":"{read_only:false; response_revision:24988; number_of_response:1; }","duration":"272.559039ms","start":"2026-05-12T06:30:43.146522Z","end":"2026-05-12T06:30:43.419081Z","steps":["trace[1994085641] 'process raft request' (duration: 272.384384ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:30:43.420118Z","caller":"traceutil/trace.go:171","msg":"trace[1260957846] transaction","detail":"{read_only:false; response_revision:24989; number_of_response:1; }","duration":"270.642957ms","start":"2026-05-12T06:30:43.149456Z","end":"2026-05-12T06:30:43.420099Z","steps":["trace[1260957846] 'process raft request' (duration: 270.503843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:43.593795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.883248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-12T06:30:43.59432Z","caller":"traceutil/trace.go:171","msg":"trace[223641894] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:24991; }","duration":"102.417163ms","start":"2026-05-12T06:30:43.491882Z","end":"2026-05-12T06:30:43.594299Z","steps":["trace[223641894] 'count revisions from in-memory index tree' (duration: 101.749674ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:30:43.978465Z","caller":"traceutil/trace.go:171","msg":"trace[1907570625] transaction","detail":"{read_only:false; response_revision:24993; number_of_response:1; }","duration":"341.88321ms","start":"2026-05-12T06:30:43.636561Z","end":"2026-05-12T06:30:43.978444Z","steps":["trace[1907570625] 'process raft request' (duration: 341.736726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:43.978599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:43.636543Z","time spent":"341.985752ms","remote":"127.0.0.1:36360","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-05-12T06:30:44.06328Z","caller":"traceutil/trace.go:171","msg":"trace[258389470] linearizableReadLoop","detail":"{readStateIndex:25803; appliedIndex:25802; }","duration":"389.279731ms","start":"2026-05-12T06:30:43.673975Z","end":"2026-05-12T06:30:44.063254Z","steps":["trace[258389470] 'read index received' (duration: 304.48435ms)","trace[258389470] 'applied index is now lower than readState.Index' (duration: 84.794191ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:30:44.063411Z","caller":"traceutil/trace.go:171","msg":"trace[802006578] transaction","detail":"{read_only:false; response_revision:24994; number_of_response:1; }","duration":"412.404771ms","start":"2026-05-12T06:30:43.65099Z","end":"2026-05-12T06:30:44.063395Z","steps":["trace[802006578] 'process raft request' (duration: 398.158273ms)","trace[802006578] 'compare' (duration: 13.914469ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T06:30:44.063516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:43.650966Z","time spent":"412.468893ms","remote":"127.0.0.1:36360","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-05-12T06:30:44.063533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.59296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-12T06:30:44.0636Z","caller":"traceutil/trace.go:171","msg":"trace[2132564852] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:24994; }","duration":"338.708613ms","start":"2026-05-12T06:30:43.724874Z","end":"2026-05-12T06:30:44.063582Z","steps":["trace[2132564852] 'agreement among raft nodes before linearized reading' (duration: 338.532428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:44.063568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.773526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-12T06:30:44.063638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:43.724854Z","time spent":"338.776324ms","remote":"127.0.0.1:36100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/199.204.45.144\" "} {"level":"info","ts":"2026-05-12T06:30:44.063668Z","caller":"traceutil/trace.go:171","msg":"trace[635050210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24994; }","duration":"148.92068ms","start":"2026-05-12T06:30:43.914723Z","end":"2026-05-12T06:30:44.063644Z","steps":["trace[635050210] 'agreement among raft nodes before linearized reading' (duration: 148.730764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:44.063953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.560477ms","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-05-12T06:30:44.064002Z","caller":"traceutil/trace.go:171","msg":"trace[2028552155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24994; }","duration":"240.638269ms","start":"2026-05-12T06:30:43.823353Z","end":"2026-05-12T06:30:44.063991Z","steps":["trace[2028552155] 'agreement among raft nodes before linearized reading' (duration: 240.508385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:44.064116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.146685ms","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-05-12T06:30:44.06415Z","caller":"traceutil/trace.go:171","msg":"trace[258123232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24994; }","duration":"390.212106ms","start":"2026-05-12T06:30:43.673928Z","end":"2026-05-12T06:30:44.06414Z","steps":["trace[258123232] 'agreement among raft nodes before linearized reading' (duration: 390.069452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:44.06418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:43.673916Z","time spent":"390.255988ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T06:30:44.064193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.932351ms","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-05-12T06:30:44.064251Z","caller":"traceutil/trace.go:171","msg":"trace[603593810] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24994; }","duration":"243.035704ms","start":"2026-05-12T06:30:43.821198Z","end":"2026-05-12T06:30:44.064234Z","steps":["trace[603593810] 'agreement among raft nodes before linearized reading' (duration: 242.836429ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:30:44.170338Z","caller":"traceutil/trace.go:171","msg":"trace[1799841585] transaction","detail":"{read_only:false; response_revision:24997; number_of_response:1; }","duration":"100.828458ms","start":"2026-05-12T06:30:44.06949Z","end":"2026-05-12T06:30:44.170319Z","steps":["trace[1799841585] 'process raft request' (duration: 100.759436ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:30:44.17035Z","caller":"traceutil/trace.go:171","msg":"trace[930827971] transaction","detail":"{read_only:false; response_revision:24996; number_of_response:1; }","duration":"101.785044ms","start":"2026-05-12T06:30:44.068545Z","end":"2026-05-12T06:30:44.17033Z","steps":["trace[930827971] 'process raft request' (duration: 101.672741ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:30:44.17035Z","caller":"traceutil/trace.go:171","msg":"trace[671575698] transaction","detail":"{read_only:false; response_revision:24995; number_of_response:1; }","duration":"102.023781ms","start":"2026-05-12T06:30:44.068296Z","end":"2026-05-12T06:30:44.17032Z","steps":["trace[671575698] 'process raft request' (duration: 101.781804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:53.992803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.012516ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T06:30:53.992901Z","caller":"traceutil/trace.go:171","msg":"trace[929128539] linearizableReadLoop","detail":"{readStateIndex:25873; appliedIndex:25872; }","duration":"513.084996ms","start":"2026-05-12T06:30:53.4798Z","end":"2026-05-12T06:30:53.992885Z","steps":["trace[929128539] 'read index received' (duration: 244.924526ms)","trace[929128539] 'applied index is now lower than readState.Index' (duration: 268.15958ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:30:53.992929Z","caller":"traceutil/trace.go:171","msg":"trace[2133135938] transaction","detail":"{read_only:false; response_revision:25062; number_of_response:1; }","duration":"536.073203ms","start":"2026-05-12T06:30:53.456824Z","end":"2026-05-12T06:30:53.992897Z","steps":["trace[2133135938] 'process raft request' (duration: 267.885602ms)","trace[2133135938] 'compare' (duration: 267.675746ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T06:30:53.993085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:53.456802Z","time spent":"536.197887ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T06:30:53.993066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.24443ms","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-05-12T06:30:53.993172Z","caller":"traceutil/trace.go:171","msg":"trace[1800793753] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25062; }","duration":"513.390744ms","start":"2026-05-12T06:30:53.479759Z","end":"2026-05-12T06:30:53.99315Z","steps":["trace[1800793753] 'agreement among raft nodes before linearized reading' (duration: 513.163918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:53.99322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:53.479742Z","time spent":"513.465657ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-12T06:30:54.548695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.662486ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T06:30:54.548954Z","caller":"traceutil/trace.go:171","msg":"trace[576727193] linearizableReadLoop","detail":"{readStateIndex:25874; appliedIndex:25873; }","duration":"556.013845ms","start":"2026-05-12T06:30:53.99292Z","end":"2026-05-12T06:30:54.548934Z","steps":["trace[576727193] 'read index received' (duration: 236.931087ms)","trace[576727193] 'applied index is now lower than readState.Index' (duration: 319.081028ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:30:54.548946Z","caller":"traceutil/trace.go:171","msg":"trace[850818007] transaction","detail":"{read_only:false; response_revision:25063; number_of_response:1; }","duration":"922.891546ms","start":"2026-05-12T06:30:53.625972Z","end":"2026-05-12T06:30:54.548864Z","steps":["trace[850818007] 'process raft request' (duration: 603.946662ms)","trace[850818007] 'compare' (duration: 318.544113ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:30:54.54914Z","caller":"traceutil/trace.go:171","msg":"trace[707666439] transaction","detail":"{read_only:false; response_revision:25066; number_of_response:1; }","duration":"455.624189ms","start":"2026-05-12T06:30:54.093491Z","end":"2026-05-12T06:30:54.549115Z","steps":["trace[707666439] 'process raft request' (duration: 455.504955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:54.549219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.268907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:140"} {"level":"warn","ts":"2026-05-12T06:30:54.549236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:53.625955Z","time spent":"923.097941ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T06:30:54.549297Z","caller":"traceutil/trace.go:171","msg":"trace[765013699] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:25066; }","duration":"824.360689ms","start":"2026-05-12T06:30:53.724899Z","end":"2026-05-12T06:30:54.54926Z","steps":["trace[765013699] 'agreement among raft nodes before linearized reading' (duration: 824.164964ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:30:54.549328Z","caller":"traceutil/trace.go:171","msg":"trace[1349156308] transaction","detail":"{read_only:false; response_revision:25065; number_of_response:1; }","duration":"528.217728ms","start":"2026-05-12T06:30:54.021095Z","end":"2026-05-12T06:30:54.549313Z","steps":["trace[1349156308] 'process raft request' (duration: 527.834138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:54.549362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:53.724881Z","time spent":"824.467743ms","remote":"127.0.0.1:36100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/199.204.45.144\" "} {"level":"warn","ts":"2026-05-12T06:30:54.549414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:54.02107Z","time spent":"528.298469ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T06:30:54.549233Z","caller":"traceutil/trace.go:171","msg":"trace[2043547845] transaction","detail":"{read_only:false; response_revision:25064; number_of_response:1; }","duration":"552.287314ms","start":"2026-05-12T06:30:53.996906Z","end":"2026-05-12T06:30:54.549193Z","steps":["trace[2043547845] 'process raft request' (duration: 551.938624ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:30:54.549572Z","caller":"traceutil/trace.go:171","msg":"trace[2131649882] transaction","detail":"{read_only:false; response_revision:25067; number_of_response:1; }","duration":"334.273391ms","start":"2026-05-12T06:30:54.215278Z","end":"2026-05-12T06:30:54.549552Z","steps":["trace[2131649882] 'process raft request' (duration: 333.783018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:54.549595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.905855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-12T06:30:54.549636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:53.996885Z","time spent":"552.705295ms","remote":"127.0.0.1:36360","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-05-12T06:30:54.549652Z","caller":"traceutil/trace.go:171","msg":"trace[461674710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25067; }","duration":"989.005898ms","start":"2026-05-12T06:30:53.560631Z","end":"2026-05-12T06:30:54.549637Z","steps":["trace[461674710] 'agreement among raft nodes before linearized reading' (duration: 988.886014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:54.549668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:54.215245Z","time spent":"334.376454ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T06:30:54.549685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:53.560608Z","time spent":"989.07153ms","remote":"127.0.0.1:36080","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-05-12T06:30:54.54971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.250112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-12T06:30:54.54927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:54.093459Z","time spent":"455.734212ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T06:30:54.54971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.444167ms","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-05-12T06:30:54.549744Z","caller":"traceutil/trace.go:171","msg":"trace[313002657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25067; }","duration":"326.302894ms","start":"2026-05-12T06:30:54.223432Z","end":"2026-05-12T06:30:54.549735Z","steps":["trace[313002657] 'agreement among raft nodes before linearized reading' (duration: 326.210482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:54.549769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:54.223425Z","time spent":"326.339315ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-12T06:30:54.549773Z","caller":"traceutil/trace.go:171","msg":"trace[1148475906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25067; }","duration":"326.560831ms","start":"2026-05-12T06:30:54.223199Z","end":"2026-05-12T06:30:54.549759Z","steps":["trace[1148475906] 'agreement among raft nodes before linearized reading' (duration: 326.410167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:54.549786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.819283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-12T06:30:54.549817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:54.22317Z","time spent":"326.635633ms","remote":"127.0.0.1:36360","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":"info","ts":"2026-05-12T06:30:54.54984Z","caller":"traceutil/trace.go:171","msg":"trace[518505605] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:25067; }","duration":"466.894886ms","start":"2026-05-12T06:30:54.082924Z","end":"2026-05-12T06:30:54.549819Z","steps":["trace[518505605] 'agreement among raft nodes before linearized reading' (duration: 466.820464ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:54.549686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.76882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-12T06:30:54.549858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.810175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-05-12T06:30:54.549886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:54.082904Z","time spent":"466.973089ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":7,"response size":31,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-05-12T06:30:54.549897Z","caller":"traceutil/trace.go:171","msg":"trace[2028990957] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25067; }","duration":"327.887338ms","start":"2026-05-12T06:30:54.221997Z","end":"2026-05-12T06:30:54.549885Z","steps":["trace[2028990957] 'agreement among raft nodes before linearized reading' (duration: 327.743253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:54.549936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:54.221971Z","time spent":"327.959081ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-12T06:30:54.549932Z","caller":"traceutil/trace.go:171","msg":"trace[1137070991] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:25067; }","duration":"508.060319ms","start":"2026-05-12T06:30:54.041829Z","end":"2026-05-12T06:30:54.549889Z","steps":["trace[1137070991] 'agreement among raft nodes before linearized reading' (duration: 507.73943ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:54.550047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:54.041811Z","time spent":"508.189092ms","remote":"127.0.0.1:56590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-12T06:30:54.850136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.809044ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-05-12T06:30:55.314823Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440202210036978553,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-12T06:30:55.318922Z","caller":"traceutil/trace.go:171","msg":"trace[1682715410] transaction","detail":"{read_only:false; response_revision:25068; number_of_response:1; }","duration":"764.842017ms","start":"2026-05-12T06:30:54.554049Z","end":"2026-05-12T06:30:55.318891Z","steps":["trace[1682715410] 'process raft request' (duration: 764.632132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:55.319043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:54.554033Z","time spent":"764.941141ms","remote":"127.0.0.1:36360","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-05-12T06:30:55.319259Z","caller":"traceutil/trace.go:171","msg":"trace[1742569091] transaction","detail":"{read_only:false; response_revision:25069; number_of_response:1; }","duration":"763.986055ms","start":"2026-05-12T06:30:54.555262Z","end":"2026-05-12T06:30:55.319248Z","steps":["trace[1742569091] 'process raft request' (duration: 763.584394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:55.319336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:54.555247Z","time spent":"764.047416ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T06:30:55.320391Z","caller":"traceutil/trace.go:171","msg":"trace[16322268] transaction","detail":"{read_only:false; response_revision:25071; number_of_response:1; }","duration":"746.476808ms","start":"2026-05-12T06:30:54.573891Z","end":"2026-05-12T06:30:55.320368Z","steps":["trace[16322268] 'process raft request' (duration: 745.782348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:55.320574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:54.573872Z","time spent":"746.57503ms","remote":"127.0.0.1:36360","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-05-12T06:30:55.320504Z","caller":"traceutil/trace.go:171","msg":"trace[530085376] transaction","detail":"{read_only:false; response_revision:25070; number_of_response:1; }","duration":"765.158187ms","start":"2026-05-12T06:30:54.555315Z","end":"2026-05-12T06:30:55.320473Z","steps":["trace[530085376] 'process raft request' (duration: 764.1799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:55.320895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:54.555294Z","time spent":"765.515057ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T06:30:55.618977Z","caller":"traceutil/trace.go:171","msg":"trace[2115388098] linearizableReadLoop","detail":"{readStateIndex:25886; appliedIndex:25879; }","duration":"804.513979ms","start":"2026-05-12T06:30:54.814436Z","end":"2026-05-12T06:30:55.61895Z","steps":["trace[2115388098] 'read index received' (duration: 504.277485ms)","trace[2115388098] 'applied index is now lower than readState.Index' (duration: 300.235544ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:30:55.619086Z","caller":"traceutil/trace.go:171","msg":"trace[1342984200] transaction","detail":"{read_only:false; response_revision:25073; number_of_response:1; }","duration":"656.316329ms","start":"2026-05-12T06:30:54.962759Z","end":"2026-05-12T06:30:55.619076Z","steps":["trace[1342984200] 'process raft request' (duration: 656.029942ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:55.619184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:54.962736Z","time spent":"656.375551ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T06:30:55.619375Z","caller":"traceutil/trace.go:171","msg":"trace[738168720] transaction","detail":"{read_only:false; response_revision:25074; number_of_response:1; }","duration":"655.198209ms","start":"2026-05-12T06:30:54.964167Z","end":"2026-05-12T06:30:55.619365Z","steps":["trace[738168720] 'process raft request' (duration: 654.706366ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:55.619441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:54.96414Z","time spent":"655.264711ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T06:30:55.619912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.299429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T06:30:55.619973Z","caller":"traceutil/trace.go:171","msg":"trace[1126550289] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:25075; }","duration":"452.406601ms","start":"2026-05-12T06:30:55.167556Z","end":"2026-05-12T06:30:55.619962Z","steps":["trace[1126550289] 'agreement among raft nodes before linearized reading' (duration: 452.291458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:55.620004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:55.167531Z","time spent":"452.465553ms","remote":"127.0.0.1:36240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-05-12T06:30:55.620011Z","caller":"traceutil/trace.go:171","msg":"trace[1445957012] transaction","detail":"{read_only:false; response_revision:25072; number_of_response:1; }","duration":"767.49643ms","start":"2026-05-12T06:30:54.851641Z","end":"2026-05-12T06:30:55.619137Z","steps":["trace[1445957012] 'process raft request' (duration: 766.00747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:55.62023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.804754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-12T06:30:55.620266Z","caller":"traceutil/trace.go:171","msg":"trace[273405179] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25075; }","duration":"805.875937ms","start":"2026-05-12T06:30:54.81438Z","end":"2026-05-12T06:30:55.620256Z","steps":["trace[273405179] 'agreement among raft nodes before linearized reading' (duration: 805.175247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:55.620296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:54.814359Z","time spent":"805.930678ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-12T06:30:55.620301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:54.851618Z","time spent":"768.583381ms","remote":"127.0.0.1:36100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T06:30:55.620424Z","caller":"traceutil/trace.go:171","msg":"trace[429164204] transaction","detail":"{read_only:false; response_revision:25075; number_of_response:1; }","duration":"162.093088ms","start":"2026-05-12T06:30:55.458322Z","end":"2026-05-12T06:30:55.620415Z","steps":["trace[429164204] 'process raft request' (duration: 160.591507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:55.620683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.091541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-05-12T06:30:55.620723Z","caller":"traceutil/trace.go:171","msg":"trace[1594621960] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25075; }","duration":"314.166143ms","start":"2026-05-12T06:30:55.306546Z","end":"2026-05-12T06:30:55.620712Z","steps":["trace[1594621960] 'agreement among raft nodes before linearized reading' (duration: 314.079301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:55.620749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:55.306525Z","time spent":"314.217434ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":584,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-05-12T06:30:56.076173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.150197ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T06:30:56.076566Z","caller":"traceutil/trace.go:171","msg":"trace[643396931] transaction","detail":"{read_only:false; response_revision:25077; number_of_response:1; }","duration":"441.35233ms","start":"2026-05-12T06:30:55.635186Z","end":"2026-05-12T06:30:56.076538Z","steps":["trace[643396931] 'process raft request' (duration: 160.732781ms)","trace[643396931] 'compare' (duration: 279.857468ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T06:30:56.076675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:55.635167Z","time spent":"441.457683ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T06:30:56.076662Z","caller":"traceutil/trace.go:171","msg":"trace[541405547] linearizableReadLoop","detail":"{readStateIndex:25889; appliedIndex:25887; }","duration":"436.97326ms","start":"2026-05-12T06:30:55.639615Z","end":"2026-05-12T06:30:56.076588Z","steps":["trace[541405547] 'read index received' (duration: 49.152619ms)","trace[541405547] 'applied index is now lower than readState.Index' (duration: 387.817131ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T06:30:56.076763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.109785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T06:30:56.07683Z","caller":"traceutil/trace.go:171","msg":"trace[1163180686] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25078; }","duration":"437.215379ms","start":"2026-05-12T06:30:55.639598Z","end":"2026-05-12T06:30:56.076813Z","steps":["trace[1163180686] 'agreement among raft nodes before linearized reading' (duration: 437.068375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:56.07688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:55.639591Z","time spent":"437.281101ms","remote":"127.0.0.1:58156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-05-12T06:30:56.588253Z","caller":"traceutil/trace.go:171","msg":"trace[35640623] linearizableReadLoop","detail":"{readStateIndex:25891; appliedIndex:25890; }","duration":"444.074294ms","start":"2026-05-12T06:30:56.144092Z","end":"2026-05-12T06:30:56.588166Z","steps":["trace[35640623] 'read index received' (duration: 443.846288ms)","trace[35640623] 'applied index is now lower than readState.Index' (duration: 226.566µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:30:56.588593Z","caller":"traceutil/trace.go:171","msg":"trace[177027655] transaction","detail":"{read_only:false; response_revision:25079; number_of_response:1; }","duration":"502.579249ms","start":"2026-05-12T06:30:56.085999Z","end":"2026-05-12T06:30:56.588579Z","steps":["trace[177027655] 'process raft request' (duration: 501.970542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:56.588682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:56.085983Z","time spent":"502.64847ms","remote":"127.0.0.1:58156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1633,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T06:30:56.588929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.829864ms","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-05-12T06:30:56.588967Z","caller":"traceutil/trace.go:171","msg":"trace[301448904] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25079; }","duration":"444.870735ms","start":"2026-05-12T06:30:56.144087Z","end":"2026-05-12T06:30:56.588958Z","steps":["trace[301448904] 'agreement among raft nodes before linearized reading' (duration: 444.753822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:56.588999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:30:56.144046Z","time spent":"444.945987ms","remote":"127.0.0.1:36586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-05-12T06:30:56.599142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.642791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-05-12T06:30:56.599216Z","caller":"traceutil/trace.go:171","msg":"trace[1921204244] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:25083; }","duration":"170.765504ms","start":"2026-05-12T06:30:56.428433Z","end":"2026-05-12T06:30:56.599199Z","steps":["trace[1921204244] 'agreement among raft nodes before linearized reading' (duration: 170.629501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:30:59.932338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.014137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e44ab608-f7bb-4ced-88ed-22525b936f8e\" ","response":"range_response_count:1 size:3937"} {"level":"info","ts":"2026-05-12T06:30:59.932461Z","caller":"traceutil/trace.go:171","msg":"trace[1920607884] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e44ab608-f7bb-4ced-88ed-22525b936f8e; range_end:; response_count:1; response_revision:25167; }","duration":"111.136439ms","start":"2026-05-12T06:30:59.821268Z","end":"2026-05-12T06:30:59.932405Z","steps":["trace[1920607884] 'range keys from in-memory index tree' (duration: 110.852582ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:03.997848Z","caller":"traceutil/trace.go:171","msg":"trace[1010626649] transaction","detail":"{read_only:false; response_revision:25214; number_of_response:1; }","duration":"230.913014ms","start":"2026-05-12T06:31:03.766896Z","end":"2026-05-12T06:31:03.997809Z","steps":["trace[1010626649] 'process raft request' (duration: 154.850391ms)","trace[1010626649] 'compare' (duration: 75.769825ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:31:03.997907Z","caller":"traceutil/trace.go:171","msg":"trace[1372831318] linearizableReadLoop","detail":"{readStateIndex:26029; appliedIndex:26028; }","duration":"199.326432ms","start":"2026-05-12T06:31:03.798549Z","end":"2026-05-12T06:31:03.997875Z","steps":["trace[1372831318] 'read index received' (duration: 123.196528ms)","trace[1372831318] 'applied index is now lower than readState.Index' (duration: 76.127784ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:31:03.997959Z","caller":"traceutil/trace.go:171","msg":"trace[999608800] transaction","detail":"{read_only:false; response_revision:25215; number_of_response:1; }","duration":"195.235942ms","start":"2026-05-12T06:31:03.802696Z","end":"2026-05-12T06:31:03.997932Z","steps":["trace[999608800] 'process raft request' (duration: 195.087568ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:03.998168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.602221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-12T06:31:03.998218Z","caller":"traceutil/trace.go:171","msg":"trace[2084496913] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25215; }","duration":"199.667222ms","start":"2026-05-12T06:31:03.798537Z","end":"2026-05-12T06:31:03.998204Z","steps":["trace[2084496913] 'agreement among raft nodes before linearized reading' (duration: 199.466087ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:04.143975Z","caller":"traceutil/trace.go:171","msg":"trace[926929054] linearizableReadLoop","detail":"{readStateIndex:26031; appliedIndex:26030; }","duration":"139.601935ms","start":"2026-05-12T06:31:04.004353Z","end":"2026-05-12T06:31:04.143955Z","steps":["trace[926929054] 'read index received' (duration: 119.433µs)","trace[926929054] 'applied index is now lower than readState.Index' (duration: 139.481642ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:31:04.144035Z","caller":"traceutil/trace.go:171","msg":"trace[1903393040] transaction","detail":"{read_only:false; response_revision:25216; number_of_response:1; }","duration":"139.661307ms","start":"2026-05-12T06:31:04.004333Z","end":"2026-05-12T06:31:04.143994Z","steps":["trace[1903393040] 'process raft request' (duration: 139.444271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:04.144151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.78236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-e44ab608-f7bb-4ced-88ed-22525b936f8e\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-05-12T06:31:04.144193Z","caller":"traceutil/trace.go:171","msg":"trace[636238119] range","detail":"{range_begin:/registry/persistentvolumes/pvc-e44ab608-f7bb-4ced-88ed-22525b936f8e; range_end:; response_count:1; response_revision:25216; }","duration":"139.874122ms","start":"2026-05-12T06:31:04.004311Z","end":"2026-05-12T06:31:04.144185Z","steps":["trace[636238119] 'agreement among raft nodes before linearized reading' (duration: 139.728548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:04.426683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.845873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T06:31:04.427141Z","caller":"traceutil/trace.go:171","msg":"trace[693186273] transaction","detail":"{read_only:false; response_revision:25217; number_of_response:1; }","duration":"422.773753ms","start":"2026-05-12T06:31:04.00434Z","end":"2026-05-12T06:31:04.427114Z","steps":["trace[693186273] 'process raft request' (duration: 250.334604ms)","trace[693186273] 'compare' (duration: 171.677599ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T06:31:04.427317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:04.004327Z","time spent":"422.868626ms","remote":"127.0.0.1:36270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T06:31:04.427603Z","caller":"traceutil/trace.go:171","msg":"trace[1255202776] transaction","detail":"{read_only:false; response_revision:25219; number_of_response:1; }","duration":"422.457975ms","start":"2026-05-12T06:31:04.005132Z","end":"2026-05-12T06:31:04.42759Z","steps":["trace[1255202776] 'process raft request' (duration: 422.225778ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:04.427593Z","caller":"traceutil/trace.go:171","msg":"trace[976931154] transaction","detail":"{read_only:false; response_revision:25220; number_of_response:1; }","duration":"308.985673ms","start":"2026-05-12T06:31:04.118585Z","end":"2026-05-12T06:31:04.427571Z","steps":["trace[976931154] 'process raft request' (duration: 308.884349ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:04.427578Z","caller":"traceutil/trace.go:171","msg":"trace[882496418] transaction","detail":"{read_only:false; response_revision:25218; number_of_response:1; }","duration":"422.462624ms","start":"2026-05-12T06:31:04.005067Z","end":"2026-05-12T06:31:04.427529Z","steps":["trace[882496418] 'process raft request' (duration: 422.122605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:04.427742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:04.005054Z","time spent":"422.605399ms","remote":"127.0.0.1:36374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1415,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T06:31:04.427738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:04.118557Z","time spent":"309.090506ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T06:31:04.427793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:04.005123Z","time spent":"422.510386ms","remote":"127.0.0.1:36270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":885,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T06:31:04.42795Z","caller":"traceutil/trace.go:171","msg":"trace[1869800370] transaction","detail":"{read_only:false; response_revision:25221; number_of_response:1; }","duration":"264.654384ms","start":"2026-05-12T06:31:04.16327Z","end":"2026-05-12T06:31:04.427925Z","steps":["trace[1869800370] 'process raft request' (duration: 264.259362ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:24.927534Z","caller":"traceutil/trace.go:171","msg":"trace[462636744] transaction","detail":"{read_only:false; response_revision:25388; number_of_response:1; }","duration":"237.979057ms","start":"2026-05-12T06:31:24.689535Z","end":"2026-05-12T06:31:24.927514Z","steps":["trace[462636744] 'process raft request' (duration: 237.838583ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:25.107886Z","caller":"traceutil/trace.go:171","msg":"trace[1132602609] linearizableReadLoop","detail":"{readStateIndex:26210; appliedIndex:26207; }","duration":"347.087391ms","start":"2026-05-12T06:31:24.760776Z","end":"2026-05-12T06:31:25.107864Z","steps":["trace[1132602609] 'read index received' (duration: 166.729144ms)","trace[1132602609] 'applied index is now lower than readState.Index' (duration: 180.357277ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:31:25.10791Z","caller":"traceutil/trace.go:171","msg":"trace[850233690] transaction","detail":"{read_only:false; response_revision:25390; number_of_response:1; }","duration":"415.870866ms","start":"2026-05-12T06:31:24.692013Z","end":"2026-05-12T06:31:25.107884Z","steps":["trace[850233690] 'process raft request' (duration: 415.687971ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:25.108168Z","caller":"traceutil/trace.go:171","msg":"trace[611509798] transaction","detail":"{read_only:false; response_revision:25391; number_of_response:1; }","duration":"404.546547ms","start":"2026-05-12T06:31:24.703596Z","end":"2026-05-12T06:31:25.108142Z","steps":["trace[611509798] 'process raft request' (duration: 404.202348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:25.108256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:24.703581Z","time spent":"404.6206ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T06:31:25.108159Z","caller":"traceutil/trace.go:171","msg":"trace[369327127] transaction","detail":"{read_only:false; response_revision:25389; number_of_response:1; }","duration":"416.118133ms","start":"2026-05-12T06:31:24.692012Z","end":"2026-05-12T06:31:25.10813Z","steps":["trace[369327127] 'process raft request' (duration: 402.869461ms)","trace[369327127] 'compare' (duration: 12.395348ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T06:31:25.108453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.662637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-12T06:31:25.108483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:24.691991Z","time spent":"415.982619ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T06:31:25.108512Z","caller":"traceutil/trace.go:171","msg":"trace[623115276] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25391; }","duration":"347.77635ms","start":"2026-05-12T06:31:24.760718Z","end":"2026-05-12T06:31:25.108494Z","steps":["trace[623115276] 'agreement among raft nodes before linearized reading' (duration: 347.235875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:25.108554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:24.691991Z","time spent":"416.476002ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T06:31:25.108608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:24.760673Z","time spent":"347.918813ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-12T06:31:25.384125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.918078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T06:31:25.38428Z","caller":"traceutil/trace.go:171","msg":"trace[1715966473] transaction","detail":"{read_only:false; response_revision:25392; number_of_response:1; }","duration":"271.221012ms","start":"2026-05-12T06:31:25.113017Z","end":"2026-05-12T06:31:25.384238Z","steps":["trace[1715966473] 'process raft request' (duration: 136.10782ms)","trace[1715966473] 'compare' (duration: 134.574908ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T06:31:25.657977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.306871ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T06:31:25.658589Z","caller":"traceutil/trace.go:171","msg":"trace[2054356649] linearizableReadLoop","detail":"{readStateIndex:26212; appliedIndex:26211; }","duration":"260.350687ms","start":"2026-05-12T06:31:25.398212Z","end":"2026-05-12T06:31:25.658563Z","steps":["trace[2054356649] 'read index received' (duration: 114.333717ms)","trace[2054356649] 'applied index is now lower than readState.Index' (duration: 146.01426ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:31:25.658694Z","caller":"traceutil/trace.go:171","msg":"trace[861924820] transaction","detail":"{read_only:false; response_revision:25393; number_of_response:1; }","duration":"292.946314ms","start":"2026-05-12T06:31:25.365669Z","end":"2026-05-12T06:31:25.658616Z","steps":["trace[861924820] 'process raft request' (duration: 146.863292ms)","trace[861924820] 'compare' (duration: 145.035324ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T06:31:25.658914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.924722ms","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-05-12T06:31:25.658963Z","caller":"traceutil/trace.go:171","msg":"trace[1396585501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25393; }","duration":"199.019574ms","start":"2026-05-12T06:31:25.459933Z","end":"2026-05-12T06:31:25.658952Z","steps":["trace[1396585501] 'agreement among raft nodes before linearized reading' (duration: 198.761277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:25.658821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.580163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-05-12T06:31:25.659235Z","caller":"traceutil/trace.go:171","msg":"trace[677499017] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:25393; }","duration":"261.041175ms","start":"2026-05-12T06:31:25.398171Z","end":"2026-05-12T06:31:25.659212Z","steps":["trace[677499017] 'agreement among raft nodes before linearized reading' (duration: 260.47053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:25.659349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.889448ms","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-05-12T06:31:25.659464Z","caller":"traceutil/trace.go:171","msg":"trace[11457685] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25393; }","duration":"193.067762ms","start":"2026-05-12T06:31:25.466368Z","end":"2026-05-12T06:31:25.659436Z","steps":["trace[11457685] 'agreement among raft nodes before linearized reading' (duration: 192.345022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:25.659321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.84752ms","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-05-12T06:31:25.659972Z","caller":"traceutil/trace.go:171","msg":"trace[1257806364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25393; }","duration":"199.544339ms","start":"2026-05-12T06:31:25.460401Z","end":"2026-05-12T06:31:25.659945Z","steps":["trace[1257806364] 'agreement among raft nodes before linearized reading' (duration: 198.674405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:25.932844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.292168ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T06:31:25.933316Z","caller":"traceutil/trace.go:171","msg":"trace[337121446] transaction","detail":"{read_only:false; response_revision:25394; number_of_response:1; }","duration":"269.948907ms","start":"2026-05-12T06:31:25.663347Z","end":"2026-05-12T06:31:25.933296Z","steps":["trace[337121446] 'process raft request' (duration: 156.113915ms)","trace[337121446] 'compare' (duration: 113.031841ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:31:25.933518Z","caller":"traceutil/trace.go:171","msg":"trace[725639680] transaction","detail":"{read_only:false; response_revision:25397; number_of_response:1; }","duration":"214.824225ms","start":"2026-05-12T06:31:25.718666Z","end":"2026-05-12T06:31:25.93349Z","steps":["trace[725639680] 'process raft request' (duration: 214.435624ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:25.933615Z","caller":"traceutil/trace.go:171","msg":"trace[1144263590] linearizableReadLoop","detail":"{readStateIndex:26218; appliedIndex:26212; }","duration":"116.097504ms","start":"2026-05-12T06:31:25.817498Z","end":"2026-05-12T06:31:25.933596Z","steps":["trace[1144263590] 'read index received' (duration: 1.945764ms)","trace[1144263590] 'applied index is now lower than readState.Index' (duration: 114.14968ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:31:25.93365Z","caller":"traceutil/trace.go:171","msg":"trace[866822139] transaction","detail":"{read_only:false; response_revision:25395; number_of_response:1; }","duration":"269.456965ms","start":"2026-05-12T06:31:25.66415Z","end":"2026-05-12T06:31:25.933607Z","steps":["trace[866822139] 'process raft request' (duration: 268.827398ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:25.933643Z","caller":"traceutil/trace.go:171","msg":"trace[2141022163] transaction","detail":"{read_only:false; response_revision:25396; number_of_response:1; }","duration":"269.346291ms","start":"2026-05-12T06:31:25.664268Z","end":"2026-05-12T06:31:25.933614Z","steps":["trace[2141022163] 'process raft request' (duration: 268.802036ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:25.933677Z","caller":"traceutil/trace.go:171","msg":"trace[1328683491] transaction","detail":"{read_only:false; response_revision:25399; number_of_response:1; }","duration":"202.234232ms","start":"2026-05-12T06:31:25.731421Z","end":"2026-05-12T06:31:25.933655Z","steps":["trace[1328683491] 'process raft request' (duration: 201.761609ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:25.933691Z","caller":"traceutil/trace.go:171","msg":"trace[1795029476] transaction","detail":"{read_only:false; response_revision:25398; number_of_response:1; }","duration":"210.853407ms","start":"2026-05-12T06:31:25.722809Z","end":"2026-05-12T06:31:25.933662Z","steps":["trace[1795029476] 'process raft request' (duration: 210.334673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:25.933856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.347232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-12T06:31:25.933894Z","caller":"traceutil/trace.go:171","msg":"trace[557926841] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25399; }","duration":"116.443984ms","start":"2026-05-12T06:31:25.817438Z","end":"2026-05-12T06:31:25.933882Z","steps":["trace[557926841] 'agreement among raft nodes before linearized reading' (duration: 116.261488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:26.404342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.760204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-05-12T06:31:26.404411Z","caller":"traceutil/trace.go:171","msg":"trace[303670060] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25400; }","duration":"312.865197ms","start":"2026-05-12T06:31:26.091528Z","end":"2026-05-12T06:31:26.404393Z","steps":["trace[303670060] 'range keys from in-memory index tree' (duration: 312.58076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:26.404383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.446716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-12T06:31:26.404436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:26.091511Z","time spent":"312.918988ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":584,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-05-12T06:31:26.40446Z","caller":"traceutil/trace.go:171","msg":"trace[148450834] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:25400; }","duration":"323.54141ms","start":"2026-05-12T06:31:26.080898Z","end":"2026-05-12T06:31:26.404439Z","steps":["trace[148450834] 'count revisions from in-memory index tree' (duration: 323.367724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:26.404538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:26.080847Z","time spent":"323.679393ms","remote":"127.0.0.1:56752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true "} {"level":"info","ts":"2026-05-12T06:31:26.70325Z","caller":"traceutil/trace.go:171","msg":"trace[1015453500] linearizableReadLoop","detail":"{readStateIndex:26221; appliedIndex:26220; }","duration":"118.042147ms","start":"2026-05-12T06:31:26.585186Z","end":"2026-05-12T06:31:26.703228Z","steps":["trace[1015453500] 'read index received' (duration: 117.825511ms)","trace[1015453500] 'applied index is now lower than readState.Index' (duration: 215.546µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:31:26.703329Z","caller":"traceutil/trace.go:171","msg":"trace[1352288286] transaction","detail":"{read_only:false; response_revision:25402; number_of_response:1; }","duration":"139.557284ms","start":"2026-05-12T06:31:26.563735Z","end":"2026-05-12T06:31:26.703292Z","steps":["trace[1352288286] 'process raft request' (duration: 139.357199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:26.703396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.192641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/groups/\" range_end:\"/registry/openstack.k-orc.cloud/groups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T06:31:26.703435Z","caller":"traceutil/trace.go:171","msg":"trace[1888383055] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/groups/; range_end:/registry/openstack.k-orc.cloud/groups0; response_count:0; response_revision:25402; }","duration":"118.270363ms","start":"2026-05-12T06:31:26.585151Z","end":"2026-05-12T06:31:26.703421Z","steps":["trace[1888383055] 'agreement among raft nodes before linearized reading' (duration: 118.193361ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:26.926842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.595669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"info","ts":"2026-05-12T06:31:26.92692Z","caller":"traceutil/trace.go:171","msg":"trace[230242414] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25402; }","duration":"116.722982ms","start":"2026-05-12T06:31:26.810179Z","end":"2026-05-12T06:31:26.926902Z","steps":["trace[230242414] 'range keys from in-memory index tree' (duration: 116.431184ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:27.074492Z","caller":"traceutil/trace.go:171","msg":"trace[617669868] linearizableReadLoop","detail":"{readStateIndex:26222; appliedIndex:26221; }","duration":"141.761944ms","start":"2026-05-12T06:31:26.93271Z","end":"2026-05-12T06:31:27.074472Z","steps":["trace[617669868] 'read index received' (duration: 141.714103ms)","trace[617669868] 'applied index is now lower than readState.Index' (duration: 47.011µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:31:27.07462Z","caller":"traceutil/trace.go:171","msg":"trace[1320543379] transaction","detail":"{read_only:false; response_revision:25403; number_of_response:1; }","duration":"142.159025ms","start":"2026-05-12T06:31:26.932426Z","end":"2026-05-12T06:31:27.074585Z","steps":["trace[1320543379] 'process raft request' (duration: 141.903378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:27.074645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.914169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T06:31:27.074794Z","caller":"traceutil/trace.go:171","msg":"trace[1638949816] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:25403; }","duration":"142.143954ms","start":"2026-05-12T06:31:26.93264Z","end":"2026-05-12T06:31:27.074784Z","steps":["trace[1638949816] 'agreement among raft nodes before linearized reading' (duration: 141.932629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:27.090252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.519072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T06:31:27.09031Z","caller":"traceutil/trace.go:171","msg":"trace[956009867] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:25404; }","duration":"154.597934ms","start":"2026-05-12T06:31:26.935697Z","end":"2026-05-12T06:31:27.090295Z","steps":["trace[956009867] 'agreement among raft nodes before linearized reading' (duration: 154.489201ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:27.09029Z","caller":"traceutil/trace.go:171","msg":"trace[1251723572] transaction","detail":"{read_only:false; response_revision:25404; number_of_response:1; }","duration":"147.12205ms","start":"2026-05-12T06:31:26.94314Z","end":"2026-05-12T06:31:27.090262Z","steps":["trace[1251723572] 'process raft request' (duration: 146.934345ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:27.529874Z","caller":"traceutil/trace.go:171","msg":"trace[849453773] linearizableReadLoop","detail":"{readStateIndex:26227; appliedIndex:26226; }","duration":"123.191288ms","start":"2026-05-12T06:31:27.40666Z","end":"2026-05-12T06:31:27.529852Z","steps":["trace[849453773] 'read index received' (duration: 123.022394ms)","trace[849453773] 'applied index is now lower than readState.Index' (duration: 168.044µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T06:31:27.530118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.409234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-05-12T06:31:27.530156Z","caller":"traceutil/trace.go:171","msg":"trace[15151261] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:25408; }","duration":"123.532117ms","start":"2026-05-12T06:31:27.406612Z","end":"2026-05-12T06:31:27.530144Z","steps":["trace[15151261] 'agreement among raft nodes before linearized reading' (duration: 123.328312ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:27.530485Z","caller":"traceutil/trace.go:171","msg":"trace[578468459] transaction","detail":"{read_only:false; response_revision:25408; number_of_response:1; }","duration":"139.718168ms","start":"2026-05-12T06:31:27.390753Z","end":"2026-05-12T06:31:27.530472Z","steps":["trace[578468459] 'process raft request' (duration: 138.961587ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:28.198192Z","caller":"traceutil/trace.go:171","msg":"trace[801098606] transaction","detail":"{read_only:false; response_revision:25412; number_of_response:1; }","duration":"256.229264ms","start":"2026-05-12T06:31:27.941939Z","end":"2026-05-12T06:31:28.198169Z","steps":["trace[801098606] 'process raft request' (duration: 256.157972ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:28.198219Z","caller":"traceutil/trace.go:171","msg":"trace[1441172306] transaction","detail":"{read_only:false; response_revision:25410; number_of_response:1; }","duration":"257.152479ms","start":"2026-05-12T06:31:27.941017Z","end":"2026-05-12T06:31:28.198169Z","steps":["trace[1441172306] 'process raft request' (duration: 256.884482ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:28.198424Z","caller":"traceutil/trace.go:171","msg":"trace[1758038446] linearizableReadLoop","detail":"{readStateIndex:26233; appliedIndex:26228; }","duration":"194.211644ms","start":"2026-05-12T06:31:28.004201Z","end":"2026-05-12T06:31:28.198413Z","steps":["trace[1758038446] 'read index received' (duration: 193.70855ms)","trace[1758038446] 'applied index is now lower than readState.Index' (duration: 501.954µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:31:28.198443Z","caller":"traceutil/trace.go:171","msg":"trace[1597415386] transaction","detail":"{read_only:false; response_revision:25413; number_of_response:1; }","duration":"255.983997ms","start":"2026-05-12T06:31:27.942431Z","end":"2026-05-12T06:31:28.198415Z","steps":["trace[1597415386] 'process raft request' (duration: 255.705999ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:28.198479Z","caller":"traceutil/trace.go:171","msg":"trace[2122985555] transaction","detail":"{read_only:false; response_revision:25414; number_of_response:1; }","duration":"255.385511ms","start":"2026-05-12T06:31:27.943077Z","end":"2026-05-12T06:31:28.198462Z","steps":["trace[2122985555] 'process raft request' (duration: 255.243577ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:28.198237Z","caller":"traceutil/trace.go:171","msg":"trace[375924626] transaction","detail":"{read_only:false; response_revision:25411; number_of_response:1; }","duration":"257.105359ms","start":"2026-05-12T06:31:27.94108Z","end":"2026-05-12T06:31:28.198185Z","steps":["trace[375924626] 'process raft request' (duration: 256.980115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:28.198632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.412059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-05-12T06:31:28.198646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.376907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-12T06:31:28.198744Z","caller":"traceutil/trace.go:171","msg":"trace[2125605411] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:25414; }","duration":"180.52568ms","start":"2026-05-12T06:31:28.018198Z","end":"2026-05-12T06:31:28.198724Z","steps":["trace[2125605411] 'agreement among raft nodes before linearized reading' (duration: 180.349876ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:28.198688Z","caller":"traceutil/trace.go:171","msg":"trace[625200232] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25414; }","duration":"194.477121ms","start":"2026-05-12T06:31:28.004192Z","end":"2026-05-12T06:31:28.198669Z","steps":["trace[625200232] 'agreement among raft nodes before linearized reading' (duration: 194.315306ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:28.389039Z","caller":"traceutil/trace.go:171","msg":"trace[1584850496] transaction","detail":"{read_only:false; response_revision:25415; number_of_response:1; }","duration":"185.464215ms","start":"2026-05-12T06:31:28.203543Z","end":"2026-05-12T06:31:28.389007Z","steps":["trace[1584850496] 'process raft request' (duration: 141.893807ms)","trace[1584850496] 'compare' (duration: 43.315731ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:31:28.81413Z","caller":"traceutil/trace.go:171","msg":"trace[867592547] transaction","detail":"{read_only:false; response_revision:25416; number_of_response:1; }","duration":"104.565581ms","start":"2026-05-12T06:31:28.709536Z","end":"2026-05-12T06:31:28.814102Z","steps":["trace[867592547] 'process raft request' (duration: 104.382696ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:29.301972Z","caller":"traceutil/trace.go:171","msg":"trace[227854950] transaction","detail":"{read_only:false; response_revision:25420; number_of_response:1; }","duration":"138.828704ms","start":"2026-05-12T06:31:29.163122Z","end":"2026-05-12T06:31:29.30195Z","steps":["trace[227854950] 'process raft request' (duration: 138.719751ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:29.301975Z","caller":"traceutil/trace.go:171","msg":"trace[1531198796] transaction","detail":"{read_only:false; response_revision:25419; number_of_response:1; }","duration":"138.908126ms","start":"2026-05-12T06:31:29.163023Z","end":"2026-05-12T06:31:29.301931Z","steps":["trace[1531198796] 'process raft request' (duration: 63.523742ms)","trace[1531198796] 'compare' (duration: 75.037665ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:31:33.256778Z","caller":"traceutil/trace.go:171","msg":"trace[839715133] transaction","detail":"{read_only:false; response_revision:25445; number_of_response:1; }","duration":"102.075833ms","start":"2026-05-12T06:31:33.154678Z","end":"2026-05-12T06:31:33.256754Z","steps":["trace[839715133] 'process raft request' (duration: 101.938209ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:33.362485Z","caller":"traceutil/trace.go:171","msg":"trace[998301713] linearizableReadLoop","detail":"{readStateIndex:26266; appliedIndex:26264; }","duration":"133.834798ms","start":"2026-05-12T06:31:33.228624Z","end":"2026-05-12T06:31:33.362459Z","steps":["trace[998301713] 'read index received' (duration: 27.996354ms)","trace[998301713] 'applied index is now lower than readState.Index' (duration: 105.837374ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:31:33.3628Z","caller":"traceutil/trace.go:171","msg":"trace[210591513] transaction","detail":"{read_only:false; response_revision:25446; number_of_response:1; }","duration":"134.334242ms","start":"2026-05-12T06:31:33.228453Z","end":"2026-05-12T06:31:33.362787Z","steps":["trace[210591513] 'process raft request' (duration: 86.554729ms)","trace[210591513] 'compare' (duration: 47.347761ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T06:31:33.363033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.173297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T06:31:33.363185Z","caller":"traceutil/trace.go:171","msg":"trace[1631788759] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:25446; }","duration":"134.563729ms","start":"2026-05-12T06:31:33.228597Z","end":"2026-05-12T06:31:33.363161Z","steps":["trace[1631788759] 'agreement among raft nodes before linearized reading' (duration: 134.137946ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:49.561414Z","caller":"traceutil/trace.go:171","msg":"trace[1619969904] linearizableReadLoop","detail":"{readStateIndex:26397; appliedIndex:26396; }","duration":"128.546874ms","start":"2026-05-12T06:31:49.432835Z","end":"2026-05-12T06:31:49.561382Z","steps":["trace[1619969904] 'read index received' (duration: 128.39461ms)","trace[1619969904] 'applied index is now lower than readState.Index' (duration: 147.334µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:31:49.561452Z","caller":"traceutil/trace.go:171","msg":"trace[272642995] transaction","detail":"{read_only:false; response_revision:25573; number_of_response:1; }","duration":"222.117645ms","start":"2026-05-12T06:31:49.339302Z","end":"2026-05-12T06:31:49.561419Z","steps":["trace[272642995] 'process raft request' (duration: 221.883359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:49.561699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.808401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T06:31:49.561751Z","caller":"traceutil/trace.go:171","msg":"trace[1089555848] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:25573; }","duration":"128.959906ms","start":"2026-05-12T06:31:49.432776Z","end":"2026-05-12T06:31:49.561735Z","steps":["trace[1089555848] 'agreement among raft nodes before linearized reading' (duration: 128.76093ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:49.619316Z","caller":"traceutil/trace.go:171","msg":"trace[1086704588] transaction","detail":"{read_only:false; response_revision:25574; number_of_response:1; }","duration":"178.108934ms","start":"2026-05-12T06:31:49.441107Z","end":"2026-05-12T06:31:49.619216Z","steps":["trace[1086704588] 'process raft request' (duration: 177.866678ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:49.61956Z","caller":"traceutil/trace.go:171","msg":"trace[2114082074] transaction","detail":"{read_only:false; response_revision:25575; number_of_response:1; }","duration":"159.962502ms","start":"2026-05-12T06:31:49.459569Z","end":"2026-05-12T06:31:49.619531Z","steps":["trace[2114082074] 'process raft request' (duration: 159.596761ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:49.619592Z","caller":"traceutil/trace.go:171","msg":"trace[493056288] transaction","detail":"{read_only:false; response_revision:25576; number_of_response:1; }","duration":"157.918255ms","start":"2026-05-12T06:31:49.461654Z","end":"2026-05-12T06:31:49.619573Z","steps":["trace[493056288] 'process raft request' (duration: 157.760839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:49.619916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.601827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:186"} {"level":"info","ts":"2026-05-12T06:31:49.619992Z","caller":"traceutil/trace.go:171","msg":"trace[112420403] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:25576; }","duration":"186.7009ms","start":"2026-05-12T06:31:49.433279Z","end":"2026-05-12T06:31:49.61998Z","steps":["trace[112420403] 'agreement among raft nodes before linearized reading' (duration: 186.382001ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:49.787697Z","caller":"traceutil/trace.go:171","msg":"trace[1574934708] linearizableReadLoop","detail":"{readStateIndex:26401; appliedIndex:26400; }","duration":"168.135113ms","start":"2026-05-12T06:31:49.619536Z","end":"2026-05-12T06:31:49.787671Z","steps":["trace[1574934708] 'read index received' (duration: 156.799603ms)","trace[1574934708] 'applied index is now lower than readState.Index' (duration: 11.33429ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:31:49.787719Z","caller":"traceutil/trace.go:171","msg":"trace[1950944240] transaction","detail":"{read_only:false; response_revision:25577; number_of_response:1; }","duration":"178.230219ms","start":"2026-05-12T06:31:49.60945Z","end":"2026-05-12T06:31:49.78768Z","steps":["trace[1950944240] 'process raft request' (duration: 166.904949ms)","trace[1950944240] 'compare' (duration: 11.204616ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T06:31:49.787848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.14317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T06:31:49.787912Z","caller":"traceutil/trace.go:171","msg":"trace[1661938851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25577; }","duration":"224.353706ms","start":"2026-05-12T06:31:49.563542Z","end":"2026-05-12T06:31:49.787896Z","steps":["trace[1661938851] 'agreement among raft nodes before linearized reading' (duration: 224.241933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:49.787909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.728255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-vs49q\" ","response":"range_response_count:1 size:4483"} {"level":"warn","ts":"2026-05-12T06:31:49.788112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.44666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T06:31:49.788115Z","caller":"traceutil/trace.go:171","msg":"trace[62279773] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-vs49q; range_end:; response_count:1; response_revision:25577; }","duration":"166.957932ms","start":"2026-05-12T06:31:49.621128Z","end":"2026-05-12T06:31:49.788086Z","steps":["trace[62279773] 'agreement among raft nodes before linearized reading' (duration: 166.714405ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:49.788189Z","caller":"traceutil/trace.go:171","msg":"trace[707939081] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:25577; }","duration":"165.559983ms","start":"2026-05-12T06:31:49.622608Z","end":"2026-05-12T06:31:49.788168Z","steps":["trace[707939081] 'agreement among raft nodes before linearized reading' (duration: 165.418049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:49.788204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.907217ms","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-05-12T06:31:49.788301Z","caller":"traceutil/trace.go:171","msg":"trace[1254377858] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25577; }","duration":"146.061891ms","start":"2026-05-12T06:31:49.642216Z","end":"2026-05-12T06:31:49.788278Z","steps":["trace[1254377858] 'agreement among raft nodes before linearized reading' (duration: 145.749543ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:50.257336Z","caller":"traceutil/trace.go:171","msg":"trace[1619702653] linearizableReadLoop","detail":"{readStateIndex:26403; appliedIndex:26402; }","duration":"123.519526ms","start":"2026-05-12T06:31:50.133794Z","end":"2026-05-12T06:31:50.257313Z","steps":["trace[1619702653] 'read index received' (duration: 123.29282ms)","trace[1619702653] 'applied index is now lower than readState.Index' (duration: 225.786µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:31:50.257472Z","caller":"traceutil/trace.go:171","msg":"trace[1476876940] transaction","detail":"{read_only:false; response_revision:25579; number_of_response:1; }","duration":"133.551429ms","start":"2026-05-12T06:31:50.123862Z","end":"2026-05-12T06:31:50.257413Z","steps":["trace[1476876940] 'process raft request' (duration: 133.283222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:50.257589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.764953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T06:31:50.257661Z","caller":"traceutil/trace.go:171","msg":"trace[1100395942] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:25579; }","duration":"123.885576ms","start":"2026-05-12T06:31:50.13376Z","end":"2026-05-12T06:31:50.257645Z","steps":["trace[1100395942] 'agreement among raft nodes before linearized reading' (duration: 123.763793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:50.257705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.991453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T06:31:50.257792Z","caller":"traceutil/trace.go:171","msg":"trace[2145998779] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:25579; }","duration":"108.118097ms","start":"2026-05-12T06:31:50.149655Z","end":"2026-05-12T06:31:50.257773Z","steps":["trace[2145998779] 'agreement among raft nodes before linearized reading' (duration: 107.951292ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:50.463173Z","caller":"traceutil/trace.go:171","msg":"trace[398309213] linearizableReadLoop","detail":"{readStateIndex:26405; appliedIndex:26403; }","duration":"164.896504ms","start":"2026-05-12T06:31:50.298251Z","end":"2026-05-12T06:31:50.463147Z","steps":["trace[398309213] 'read index received' (duration: 115.276052ms)","trace[398309213] 'applied index is now lower than readState.Index' (duration: 49.618742ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:31:50.463249Z","caller":"traceutil/trace.go:171","msg":"trace[1009653837] transaction","detail":"{read_only:false; response_revision:25580; number_of_response:1; }","duration":"191.839359ms","start":"2026-05-12T06:31:50.271377Z","end":"2026-05-12T06:31:50.463217Z","steps":["trace[1009653837] 'process raft request' (duration: 142.130534ms)","trace[1009653837] 'compare' (duration: 49.303024ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:31:50.4633Z","caller":"traceutil/trace.go:171","msg":"trace[1366839993] transaction","detail":"{read_only:false; response_revision:25581; number_of_response:1; }","duration":"187.400288ms","start":"2026-05-12T06:31:50.275889Z","end":"2026-05-12T06:31:50.46329Z","steps":["trace[1366839993] 'process raft request' (duration: 187.211423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:50.463544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.285125ms","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-05-12T06:31:50.463575Z","caller":"traceutil/trace.go:171","msg":"trace[1953552653] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25581; }","duration":"165.378968ms","start":"2026-05-12T06:31:50.298185Z","end":"2026-05-12T06:31:50.463564Z","steps":["trace[1953552653] 'agreement among raft nodes before linearized reading' (duration: 165.231204ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:50.46369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.959887ms","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-05-12T06:31:50.46371Z","caller":"traceutil/trace.go:171","msg":"trace[1498939771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25581; }","duration":"165.002507ms","start":"2026-05-12T06:31:50.298702Z","end":"2026-05-12T06:31:50.463704Z","steps":["trace[1498939771] 'agreement among raft nodes before linearized reading' (duration: 164.938916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:50.465195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.623511ms","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-05-12T06:31:50.465229Z","caller":"traceutil/trace.go:171","msg":"trace[1549450871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25581; }","duration":"159.705133ms","start":"2026-05-12T06:31:50.305515Z","end":"2026-05-12T06:31:50.46522Z","steps":["trace[1549450871] 'agreement among raft nodes before linearized reading' (duration: 158.252264ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:50.604162Z","caller":"traceutil/trace.go:171","msg":"trace[864890168] transaction","detail":"{read_only:false; response_revision:25582; number_of_response:1; }","duration":"135.824002ms","start":"2026-05-12T06:31:50.468316Z","end":"2026-05-12T06:31:50.60414Z","steps":["trace[864890168] 'process raft request' (duration: 135.664798ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:50.606299Z","caller":"traceutil/trace.go:171","msg":"trace[1296342398] transaction","detail":"{read_only:false; response_revision:25583; number_of_response:1; }","duration":"137.882178ms","start":"2026-05-12T06:31:50.468391Z","end":"2026-05-12T06:31:50.606274Z","steps":["trace[1296342398] 'process raft request' (duration: 137.697543ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:50.606496Z","caller":"traceutil/trace.go:171","msg":"trace[1226585612] linearizableReadLoop","detail":"{readStateIndex:26408; appliedIndex:26405; }","duration":"110.108881ms","start":"2026-05-12T06:31:50.496355Z","end":"2026-05-12T06:31:50.606464Z","steps":["trace[1226585612] 'read index received' (duration: 107.713616ms)","trace[1226585612] 'applied index is now lower than readState.Index' (duration: 2.393075ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:31:50.606526Z","caller":"traceutil/trace.go:171","msg":"trace[1827601176] transaction","detail":"{read_only:false; response_revision:25584; number_of_response:1; }","duration":"138.006742ms","start":"2026-05-12T06:31:50.468494Z","end":"2026-05-12T06:31:50.6065Z","steps":["trace[1827601176] 'process raft request' (duration: 137.718814ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:50.606754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.378639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-12T06:31:50.606814Z","caller":"traceutil/trace.go:171","msg":"trace[290237784] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25584; }","duration":"110.478502ms","start":"2026-05-12T06:31:50.496317Z","end":"2026-05-12T06:31:50.606795Z","steps":["trace[290237784] 'agreement among raft nodes before linearized reading' (duration: 110.268436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:56.182124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.20818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T06:31:56.182226Z","caller":"traceutil/trace.go:171","msg":"trace[1298264817] transaction","detail":"{read_only:false; response_revision:25619; number_of_response:1; }","duration":"544.444801ms","start":"2026-05-12T06:31:55.637762Z","end":"2026-05-12T06:31:56.182207Z","steps":["trace[1298264817] 'process raft request' (duration: 170.082047ms)","trace[1298264817] 'compare' (duration: 373.992774ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T06:31:56.182309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:55.637738Z","time spent":"544.516892ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T06:31:56.624469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.393508ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T06:31:56.624912Z","caller":"traceutil/trace.go:171","msg":"trace[1059844408] transaction","detail":"{read_only:false; response_revision:25623; number_of_response:1; }","duration":"347.878222ms","start":"2026-05-12T06:31:56.27701Z","end":"2026-05-12T06:31:56.624889Z","steps":["trace[1059844408] 'process raft request' (duration: 347.831401ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:56.624947Z","caller":"traceutil/trace.go:171","msg":"trace[888410435] transaction","detail":"{read_only:false; response_revision:25621; number_of_response:1; }","duration":"985.963935ms","start":"2026-05-12T06:31:55.638934Z","end":"2026-05-12T06:31:56.624898Z","steps":["trace[888410435] 'process raft request' (duration: 985.634826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:56.62504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:56.276987Z","time spent":"347.998726ms","remote":"127.0.0.1:36360","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-05-12T06:31:56.625152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:55.638908Z","time spent":"986.12306ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T06:31:56.625184Z","caller":"traceutil/trace.go:171","msg":"trace[790439236] transaction","detail":"{read_only:false; response_revision:25622; number_of_response:1; }","duration":"910.020185ms","start":"2026-05-12T06:31:55.715137Z","end":"2026-05-12T06:31:56.625157Z","steps":["trace[790439236] 'process raft request' (duration: 909.622755ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:56.625191Z","caller":"traceutil/trace.go:171","msg":"trace[1070176035] transaction","detail":"{read_only:false; response_revision:25620; number_of_response:1; }","duration":"986.294314ms","start":"2026-05-12T06:31:55.638855Z","end":"2026-05-12T06:31:56.625149Z","steps":["trace[1070176035] 'process raft request' (duration: 692.130586ms)","trace[1070176035] 'compare' (duration: 293.264284ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:31:56.62524Z","caller":"traceutil/trace.go:171","msg":"trace[970419678] linearizableReadLoop","detail":"{readStateIndex:26447; appliedIndex:26443; }","duration":"893.052262ms","start":"2026-05-12T06:31:55.732156Z","end":"2026-05-12T06:31:56.625208Z","steps":["trace[970419678] 'read index received' (duration: 75.719434ms)","trace[970419678] 'applied index is now lower than readState.Index' (duration: 817.329278ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T06:31:56.625327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:55.63883Z","time spent":"986.426018ms","remote":"127.0.0.1:36360","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-05-12T06:31:56.625335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:55.715108Z","time spent":"910.137679ms","remote":"127.0.0.1:36360","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-05-12T06:31:56.625406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.231567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T06:31:56.625498Z","caller":"traceutil/trace.go:171","msg":"trace[1805989774] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:25623; }","duration":"893.355011ms","start":"2026-05-12T06:31:55.732118Z","end":"2026-05-12T06:31:56.625473Z","steps":["trace[1805989774] 'agreement among raft nodes before linearized reading' (duration: 893.183806ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:56.625579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:55.732104Z","time spent":"893.457174ms","remote":"127.0.0.1:59776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true "} {"level":"warn","ts":"2026-05-12T06:31:57.015776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.099088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T06:31:57.016206Z","caller":"traceutil/trace.go:171","msg":"trace[997428372] transaction","detail":"{read_only:false; response_revision:25624; number_of_response:1; }","duration":"534.786577ms","start":"2026-05-12T06:31:56.481373Z","end":"2026-05-12T06:31:57.01616Z","steps":["trace[997428372] 'process raft request' (duration: 368.205656ms)","trace[997428372] 'compare' (duration: 165.988435ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:31:57.016239Z","caller":"traceutil/trace.go:171","msg":"trace[1048735740] transaction","detail":"{read_only:false; response_revision:25625; number_of_response:1; }","duration":"533.904963ms","start":"2026-05-12T06:31:56.482292Z","end":"2026-05-12T06:31:57.016197Z","steps":["trace[1048735740] 'process raft request' (duration: 533.600315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:57.016339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.142067221s","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-05-12T06:31:57.016386Z","caller":"traceutil/trace.go:171","msg":"trace[1333008931] 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:25626; }","duration":"1.142155733s","start":"2026-05-12T06:31:55.874217Z","end":"2026-05-12T06:31:57.016372Z","steps":["trace[1333008931] 'agreement among raft nodes before linearized reading' (duration: 1.142069901s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:57.016381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.461892ms","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-05-12T06:31:57.016244Z","caller":"traceutil/trace.go:171","msg":"trace[1275267253] linearizableReadLoop","detail":"{readStateIndex:26450; appliedIndex:26448; }","duration":"390.908745ms","start":"2026-05-12T06:31:56.625288Z","end":"2026-05-12T06:31:57.016197Z","steps":["trace[1275267253] 'read index received' (duration: 224.205071ms)","trace[1275267253] 'applied index is now lower than readState.Index' (duration: 166.700694ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T06:31:57.016408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:56.481353Z","time spent":"534.951521ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T06:31:57.016428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:55.874196Z","time spent":"1.142221254s","remote":"127.0.0.1:56938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"info","ts":"2026-05-12T06:31:57.016472Z","caller":"traceutil/trace.go:171","msg":"trace[961412998] 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:25626; }","duration":"613.611465ms","start":"2026-05-12T06:31:56.402837Z","end":"2026-05-12T06:31:57.016448Z","steps":["trace[961412998] 'agreement among raft nodes before linearized reading' (duration: 613.462261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:57.016462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.131384979s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-12T06:31:57.016463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.85939ms","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-05-12T06:31:57.016536Z","caller":"traceutil/trace.go:171","msg":"trace[1878535644] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25626; }","duration":"1.131466261s","start":"2026-05-12T06:31:55.885051Z","end":"2026-05-12T06:31:57.016518Z","steps":["trace[1878535644] 'agreement among raft nodes before linearized reading' (duration: 1.131280926s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:57.016549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:56.402806Z","time spent":"613.725528ms","remote":"127.0.0.1:56922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"warn","ts":"2026-05-12T06:31:57.016576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:55.88498Z","time spent":"1.131587334s","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-12T06:31:57.016584Z","caller":"traceutil/trace.go:171","msg":"trace[1458394808] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25626; }","duration":"553.019054ms","start":"2026-05-12T06:31:56.463538Z","end":"2026-05-12T06:31:57.016557Z","steps":["trace[1458394808] 'agreement among raft nodes before linearized reading' (duration: 552.804778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:57.016635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:56.463517Z","time spent":"553.104906ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":585,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-05-12T06:31:57.016649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.79395ms","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-05-12T06:31:57.0167Z","caller":"traceutil/trace.go:171","msg":"trace[348524331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25626; }","duration":"387.869032ms","start":"2026-05-12T06:31:56.628816Z","end":"2026-05-12T06:31:57.016685Z","steps":["trace[348524331] 'agreement among raft nodes before linearized reading' (duration: 387.743558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:57.016463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.792919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0\" ","response":"range_response_count:1 size:1566"} {"level":"warn","ts":"2026-05-12T06:31:57.016717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.506992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-12T06:31:57.016758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.483085ms","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-05-12T06:31:57.016822Z","caller":"traceutil/trace.go:171","msg":"trace[1424516834] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25626; }","duration":"382.564427ms","start":"2026-05-12T06:31:56.634244Z","end":"2026-05-12T06:31:57.016808Z","steps":["trace[1424516834] 'agreement among raft nodes before linearized reading' (duration: 382.393582ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:57.016845Z","caller":"traceutil/trace.go:171","msg":"trace[1952720823] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:25626; }","duration":"285.722178ms","start":"2026-05-12T06:31:56.731079Z","end":"2026-05-12T06:31:57.016801Z","steps":["trace[1952720823] 'agreement among raft nodes before linearized reading' (duration: 285.529732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:57.01685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.577256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-05-12T06:31:57.016872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:56.634233Z","time spent":"382.625059ms","remote":"127.0.0.1:36360","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-05-12T06:31:57.016767Z","caller":"traceutil/trace.go:171","msg":"trace[1599430633] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0; range_end:; response_count:1; response_revision:25626; }","duration":"589.142908ms","start":"2026-05-12T06:31:56.427608Z","end":"2026-05-12T06:31:57.016751Z","steps":["trace[1599430633] 'agreement among raft nodes before linearized reading' (duration: 588.672836ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:57.016898Z","caller":"traceutil/trace.go:171","msg":"trace[170667942] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25626; }","duration":"379.640568ms","start":"2026-05-12T06:31:56.637247Z","end":"2026-05-12T06:31:57.016887Z","steps":["trace[170667942] 'agreement among raft nodes before linearized reading' (duration: 379.501344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:57.016929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:56.63724Z","time spent":"379.682589ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-12T06:31:57.016759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:56.628799Z","time spent":"387.946064ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-12T06:31:57.016975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:56.427587Z","time spent":"589.335244ms","remote":"127.0.0.1:36254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":1589,"request content":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0\" "} {"level":"warn","ts":"2026-05-12T06:31:57.016406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:56.482281Z","time spent":"534.032957ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T06:31:57.017015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.271075ms","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-05-12T06:31:57.017242Z","caller":"traceutil/trace.go:171","msg":"trace[1962407304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25626; }","duration":"384.547112ms","start":"2026-05-12T06:31:56.632674Z","end":"2026-05-12T06:31:57.017221Z","steps":["trace[1962407304] 'agreement among raft nodes before linearized reading' (duration: 384.015238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:57.017394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:56.632648Z","time spent":"384.726586ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T06:31:57.017409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.92639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T06:31:57.017594Z","caller":"traceutil/trace.go:171","msg":"trace[1677827935] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25626; }","duration":"455.163027ms","start":"2026-05-12T06:31:56.562416Z","end":"2026-05-12T06:31:57.017579Z","steps":["trace[1677827935] 'agreement among raft nodes before linearized reading' (duration: 453.948584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:57.017726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:56.562393Z","time spent":"455.277889ms","remote":"127.0.0.1:36080","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-05-12T06:31:57.602781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.770104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T06:31:57.603013Z","caller":"traceutil/trace.go:171","msg":"trace[13968314] linearizableReadLoop","detail":"{readStateIndex:26457; appliedIndex:26452; }","duration":"387.403089ms","start":"2026-05-12T06:31:57.215597Z","end":"2026-05-12T06:31:57.603Z","steps":["trace[13968314] 'read index received' (duration: 285.414579ms)","trace[13968314] 'applied index is now lower than readState.Index' (duration: 101.98782ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:31:57.603072Z","caller":"traceutil/trace.go:171","msg":"trace[1308767520] transaction","detail":"{read_only:false; response_revision:25630; number_of_response:1; }","duration":"580.206135ms","start":"2026-05-12T06:31:57.022858Z","end":"2026-05-12T06:31:57.603064Z","steps":["trace[1308767520] 'process raft request' (duration: 580.04904ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:57.603057Z","caller":"traceutil/trace.go:171","msg":"trace[629537365] transaction","detail":"{read_only:false; response_revision:25629; number_of_response:1; }","duration":"580.567245ms","start":"2026-05-12T06:31:57.022461Z","end":"2026-05-12T06:31:57.603028Z","steps":["trace[629537365] 'process raft request' (duration: 580.39833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:57.603111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:57.022846Z","time spent":"580.243885ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T06:31:57.6032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:57.022451Z","time spent":"580.666397ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T06:31:57.603227Z","caller":"traceutil/trace.go:171","msg":"trace[356572664] transaction","detail":"{read_only:false; response_revision:25631; number_of_response:1; }","duration":"580.076761ms","start":"2026-05-12T06:31:57.023145Z","end":"2026-05-12T06:31:57.603222Z","steps":["trace[356572664] 'process raft request' (duration: 579.795223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:57.603261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:57.023131Z","time spent":"580.109882ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T06:31:57.603297Z","caller":"traceutil/trace.go:171","msg":"trace[854335864] transaction","detail":"{read_only:false; response_revision:25632; number_of_response:1; }","duration":"579.966768ms","start":"2026-05-12T06:31:57.023303Z","end":"2026-05-12T06:31:57.60327Z","steps":["trace[854335864] 'process raft request' (duration: 579.66705ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:57.603293Z","caller":"traceutil/trace.go:171","msg":"trace[209904529] transaction","detail":"{read_only:false; response_revision:25628; number_of_response:1; }","duration":"581.294814ms","start":"2026-05-12T06:31:57.021963Z","end":"2026-05-12T06:31:57.603258Z","steps":["trace[209904529] 'process raft request' (duration: 478.968675ms)","trace[209904529] 'compare' (duration: 101.61914ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T06:31:57.603323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.706698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-12T06:31:57.603409Z","caller":"traceutil/trace.go:171","msg":"trace[1554804924] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:25632; }","duration":"387.842311ms","start":"2026-05-12T06:31:57.215547Z","end":"2026-05-12T06:31:57.603389Z","steps":["trace[1554804924] 'agreement among raft nodes before linearized reading' (duration: 387.692677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:57.603452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:57.023293Z","time spent":"580.06599ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T06:31:57.603489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:57.215526Z","time spent":"387.939084ms","remote":"127.0.0.1:48282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":31,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"warn","ts":"2026-05-12T06:31:57.603499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:57.021954Z","time spent":"581.430658ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T06:31:57.844771Z","caller":"traceutil/trace.go:171","msg":"trace[118119152] transaction","detail":"{read_only:false; response_revision:25633; number_of_response:1; }","duration":"551.718278ms","start":"2026-05-12T06:31:57.293002Z","end":"2026-05-12T06:31:57.84472Z","steps":["trace[118119152] 'process raft request' (duration: 454.635112ms)","trace[118119152] 'compare' (duration: 96.765878ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T06:31:57.845003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:57.292952Z","time spent":"551.918564ms","remote":"127.0.0.1:36360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T06:31:57.845011Z","caller":"traceutil/trace.go:171","msg":"trace[263448951] transaction","detail":"{read_only:false; response_revision:25634; number_of_response:1; }","duration":"468.602204ms","start":"2026-05-12T06:31:57.376386Z","end":"2026-05-12T06:31:57.844989Z","steps":["trace[263448951] 'process raft request' (duration: 468.326156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:57.845175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:57.376339Z","time spent":"468.744227ms","remote":"127.0.0.1:48250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418437,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T06:31:57.845386Z","caller":"traceutil/trace.go:171","msg":"trace[887200319] transaction","detail":"{read_only:false; response_revision:25635; number_of_response:1; }","duration":"259.425902ms","start":"2026-05-12T06:31:57.585952Z","end":"2026-05-12T06:31:57.845378Z","steps":["trace[887200319] 'process raft request' (duration: 258.99305ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:57.845394Z","caller":"traceutil/trace.go:171","msg":"trace[1245547221] linearizableReadLoop","detail":"{readStateIndex:26460; appliedIndex:26457; }","duration":"242.334246ms","start":"2026-05-12T06:31:57.603032Z","end":"2026-05-12T06:31:57.845366Z","steps":["trace[1245547221] 'read index received' (duration: 144.669683ms)","trace[1245547221] 'applied index is now lower than readState.Index' (duration: 97.663373ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T06:31:57.845894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.314829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:180"} {"level":"info","ts":"2026-05-12T06:31:57.845963Z","caller":"traceutil/trace.go:171","msg":"trace[815393395] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:25635; }","duration":"317.385571ms","start":"2026-05-12T06:31:57.528552Z","end":"2026-05-12T06:31:57.845938Z","steps":["trace[815393395] 'agreement among raft nodes before linearized reading' (duration: 317.184396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:57.846005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T06:31:57.528489Z","time spent":"317.502785ms","remote":"127.0.0.1:36294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":203,"request content":"key:\"/registry/serviceaccounts/kube-system/coredns\" "} {"level":"warn","ts":"2026-05-12T06:31:57.84631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.903965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T06:31:57.846433Z","caller":"traceutil/trace.go:171","msg":"trace[1621780147] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:25635; }","duration":"271.635235ms","start":"2026-05-12T06:31:57.574773Z","end":"2026-05-12T06:31:57.846408Z","steps":["trace[1621780147] 'agreement among raft nodes before linearized reading' (duration: 270.857584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:57.846572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.575755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T06:31:57.846628Z","caller":"traceutil/trace.go:171","msg":"trace[52348833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25635; }","duration":"281.683197ms","start":"2026-05-12T06:31:57.564929Z","end":"2026-05-12T06:31:57.846612Z","steps":["trace[52348833] 'agreement among raft nodes before linearized reading' (duration: 281.574154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T06:31:58.493672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.654726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/services/\" range_end:\"/registry/openstack.k-orc.cloud/services0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T06:31:58.493796Z","caller":"traceutil/trace.go:171","msg":"trace[1002155467] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/services/; range_end:/registry/openstack.k-orc.cloud/services0; response_count:0; response_revision:25636; }","duration":"125.81763ms","start":"2026-05-12T06:31:58.367937Z","end":"2026-05-12T06:31:58.493754Z","steps":["trace[1002155467] 'count revisions from in-memory index tree' (duration: 125.522772ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:31:58.831795Z","caller":"traceutil/trace.go:171","msg":"trace[735333716] transaction","detail":"{read_only:false; response_revision:25638; number_of_response:1; }","duration":"201.035521ms","start":"2026-05-12T06:31:58.630729Z","end":"2026-05-12T06:31:58.831764Z","steps":["trace[735333716] 'process raft request' (duration: 134.124477ms)","trace[735333716] 'compare' (duration: 66.547914ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T06:31:58.832255Z","caller":"traceutil/trace.go:171","msg":"trace[2121460489] transaction","detail":"{read_only:false; response_revision:25639; number_of_response:1; }","duration":"201.057661ms","start":"2026-05-12T06:31:58.631183Z","end":"2026-05-12T06:31:58.83224Z","steps":["trace[2121460489] 'process raft request' (duration: 200.491355ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T06:32:40.793079Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23164} {"level":"info","ts":"2026-05-12T06:32:40.924823Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23164,"took":"127.379902ms","hash":2202812505,"current-db-size-bytes":58732544,"current-db-size":"59 MB","current-db-size-in-use-bytes":35885056,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-12T06:32:40.924904Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2202812505,"revision":23164,"compact-revision":20196} {"level":"info","ts":"2026-05-12T06:33:20.9685Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006acb.wal"} {"level":"info","ts":"2026-05-12T06:37:40.803453Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26121} {"level":"info","ts":"2026-05-12T06:37:40.94626Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26121,"took":"139.041409ms","hash":146066665,"current-db-size-bytes":58732544,"current-db-size":"59 MB","current-db-size-in-use-bytes":34373632,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-12T06:37:40.946346Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":146066665,"revision":26121,"compact-revision":23164} {"level":"info","ts":"2026-05-12T06:38:16.977154Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4594e5f716bad646","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-12T06:38:16.990848Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-12T06:38:16.990947Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-12T06:42:40.810135Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28819} {"level":"info","ts":"2026-05-12T06:42:40.948825Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28819,"took":"135.198045ms","hash":3834199534,"current-db-size-bytes":58732544,"current-db-size":"59 MB","current-db-size-in-use-bytes":30179328,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-12T06:42:40.948913Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3834199534,"revision":28819,"compact-revision":26121} {"level":"info","ts":"2026-05-12T06:47:40.824123Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31039} {"level":"info","ts":"2026-05-12T06:47:40.956377Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31039,"took":"128.057956ms","hash":1329386946,"current-db-size-bytes":58732544,"current-db-size":"59 MB","current-db-size-in-use-bytes":30240768,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-12T06:47:40.956465Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1329386946,"revision":31039,"compact-revision":28819} {"level":"info","ts":"2026-05-12T06:52:40.831461Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33307} {"level":"info","ts":"2026-05-12T06:52:40.978639Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33307,"took":"142.509848ms","hash":729439374,"current-db-size-bytes":59777024,"current-db-size":"60 MB","current-db-size-in-use-bytes":32018432,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-12T06:52:40.978787Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":729439374,"revision":33307,"compact-revision":31039} {"level":"info","ts":"2026-05-12T06:57:40.837821Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35599} {"level":"info","ts":"2026-05-12T06:57:40.978574Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35599,"took":"136.591623ms","hash":2622662358,"current-db-size-bytes":59777024,"current-db-size":"60 MB","current-db-size-in-use-bytes":31408128,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-12T06:57:40.978672Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2622662358,"revision":35599,"compact-revision":33307} {"level":"info","ts":"2026-05-12T06:59:47.230699Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4594e5f716bad646","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-12T06:59:47.235631Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-12T06:59:47.23577Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}