{"level":"warn","ts":"2026-04-14T06:18:14.121512Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-14T06:18:14.121633Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.157: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.157:2380","--initial-cluster=instance=https://199.204.45.157:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.157:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.157: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-04-14T06:18:14.121721Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-14T06:18:14.121736Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-04-14T06:18:14.121758Z","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-04-14T06:18:14.122441Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"]} {"level":"info","ts":"2026-04-14T06:18:14.122522Z","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.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.157: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-04-14T06:18:14.127418Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.583735ms"} {"level":"info","ts":"2026-04-14T06:18:14.133158Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f170c75a1d97cc","cluster-id":"44a3176ec85feb9"} {"level":"info","ts":"2026-04-14T06:18:14.133266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=()"} {"level":"info","ts":"2026-04-14T06:18:14.133297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 0"} {"level":"info","ts":"2026-04-14T06:18:14.13331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f170c75a1d97cc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-14T06:18:14.133325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 1"} {"level":"info","ts":"2026-04-14T06:18:14.133369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"warn","ts":"2026-04-14T06:18:14.141598Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-14T06:18:14.143927Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-14T06:18:14.145561Z","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-04-14T06:18:14.149011Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f170c75a1d97cc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-14T06:18:14.149264Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f170c75a1d97cc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-14T06:18:14.149396Z","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-04-14T06:18:14.149566Z","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-04-14T06:18:14.14959Z","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-04-14T06:18:14.150923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"info","ts":"2026-04-14T06:18:14.151553Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","added-peer-id":"5f170c75a1d97cc","added-peer-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-04-14T06:18:14.154262Z","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-04-14T06:18:14.154439Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-04-14T06:18:14.154524Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-04-14T06:18:14.154821Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f170c75a1d97cc","initial-advertise-peer-urls":["https://199.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-14T06:18:14.154883Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-14T06:18:14.433947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc is starting a new election at term 1"} {"level":"info","ts":"2026-04-14T06:18:14.434075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became pre-candidate at term 1"} {"level":"info","ts":"2026-04-14T06:18:14.434109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgPreVoteResp from 5f170c75a1d97cc at term 1"} {"level":"info","ts":"2026-04-14T06:18:14.434129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became candidate at term 2"} {"level":"info","ts":"2026-04-14T06:18:14.43414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgVoteResp from 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-04-14T06:18:14.434156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became leader at term 2"} {"level":"info","ts":"2026-04-14T06:18:14.434174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f170c75a1d97cc elected leader 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-04-14T06:18:14.435554Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f170c75a1d97cc","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.157:2379]}","request-path":"/0/members/5f170c75a1d97cc/attributes","cluster-id":"44a3176ec85feb9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-14T06:18:14.435554Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T06:18:14.435563Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T06:18:14.435591Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T06:18:14.435903Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-14T06:18:14.435934Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-14T06:18:14.436805Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T06:18:14.436946Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T06:18:14.436996Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T06:18:14.438816Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-14T06:18:14.442115Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.157:2379"} {"level":"warn","ts":"2026-04-14T06:18:33.655241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.495343ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-14T06:18:33.655348Z","caller":"traceutil/trace.go:171","msg":"trace[1096285069] linearizableReadLoop","detail":"{readStateIndex:421; appliedIndex:420; }","duration":"265.521625ms","start":"2026-04-14T06:18:33.389814Z","end":"2026-04-14T06:18:33.655336Z","steps":["trace[1096285069] 'read index received' (duration: 62.252µs)","trace[1096285069] 'applied index is now lower than readState.Index' (duration: 265.458593ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:18:33.655434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.619678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-04-14T06:18:33.655449Z","caller":"traceutil/trace.go:171","msg":"trace[1831972911] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:406; }","duration":"265.69748ms","start":"2026-04-14T06:18:33.389747Z","end":"2026-04-14T06:18:33.655445Z","steps":["trace[1831972911] 'agreement among raft nodes before linearized reading' (duration: 265.631928ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:46.764164Z","caller":"traceutil/trace.go:171","msg":"trace[659017483] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"156.439798ms","start":"2026-04-14T06:18:46.6077Z","end":"2026-04-14T06:18:46.76414Z","steps":["trace[659017483] 'process raft request' (duration: 125.910006ms)","trace[659017483] 'compare' (duration: 30.440589ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:18:53.142106Z","caller":"traceutil/trace.go:171","msg":"trace[792333924] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"172.6566ms","start":"2026-04-14T06:18:52.969404Z","end":"2026-04-14T06:18:53.142061Z","steps":["trace[792333924] 'process raft request' (duration: 171.983861ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:53.489769Z","caller":"traceutil/trace.go:171","msg":"trace[2074078827] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:602; }","duration":"234.105044ms","start":"2026-04-14T06:18:53.255643Z","end":"2026-04-14T06:18:53.489748Z","steps":["trace[2074078827] 'read index received' (duration: 210.299879ms)","trace[2074078827] 'applied index is now lower than readState.Index' (duration: 23.804465ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:18:53.489987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.333211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-77xcz\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-14T06:18:53.490021Z","caller":"traceutil/trace.go:171","msg":"trace[2105331297] range","detail":"{range_begin:/registry/pods/kube-system/cilium-77xcz; range_end:; response_count:1; response_revision:582; }","duration":"234.425104ms","start":"2026-04-14T06:18:53.255587Z","end":"2026-04-14T06:18:53.490012Z","steps":["trace[2105331297] 'agreement among raft nodes before linearized reading' (duration: 234.249279ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:53.4899Z","caller":"traceutil/trace.go:171","msg":"trace[1798719305] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"279.749727ms","start":"2026-04-14T06:18:53.210099Z","end":"2026-04-14T06:18:53.489849Z","steps":["trace[1798719305] 'process raft request' (duration: 279.52249ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:53.952339Z","caller":"traceutil/trace.go:171","msg":"trace[635817263] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"423.367812ms","start":"2026-04-14T06:18:53.528926Z","end":"2026-04-14T06:18:53.952294Z","steps":["trace[635817263] 'process raft request' (duration: 372.160296ms)","trace[635817263] 'compare' (duration: 50.782412ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:18:53.953237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:53.528898Z","time spent":"423.562738ms","remote":"127.0.0.1:49944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:19:08.934201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.446242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:19:08.934664Z","caller":"traceutil/trace.go:171","msg":"trace[350304325] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"286.536987ms","start":"2026-04-14T06:19:08.648084Z","end":"2026-04-14T06:19:08.934621Z","steps":["trace[350304325] 'process raft request' (duration: 123.577019ms)","trace[350304325] 'compare' (duration: 162.301108ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:19:37.626747Z","caller":"traceutil/trace.go:171","msg":"trace[879543792] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"129.611995ms","start":"2026-04-14T06:19:37.497093Z","end":"2026-04-14T06:19:37.626705Z","steps":["trace[879543792] 'process raft request' (duration: 129.359488ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:26.194879Z","caller":"traceutil/trace.go:171","msg":"trace[1508597900] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"354.99593ms","start":"2026-04-14T06:20:25.839855Z","end":"2026-04-14T06:20:26.194851Z","steps":["trace[1508597900] 'process raft request' (duration: 347.77283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:26.195248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:25.839825Z","time spent":"355.149994ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:20:26.241172Z","caller":"traceutil/trace.go:171","msg":"trace[1545409381] linearizableReadLoop","detail":"{readStateIndex:1902; appliedIndex:1899; }","duration":"283.856153ms","start":"2026-04-14T06:20:25.957297Z","end":"2026-04-14T06:20:26.241153Z","steps":["trace[1545409381] 'read index received' (duration: 230.34999ms)","trace[1545409381] 'applied index is now lower than readState.Index' (duration: 53.505363ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:20:26.241312Z","caller":"traceutil/trace.go:171","msg":"trace[1021380811] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"336.344349ms","start":"2026-04-14T06:20:25.90496Z","end":"2026-04-14T06:20:26.241304Z","steps":["trace[1021380811] 'process raft request' (duration: 336.152312ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:26.241256Z","caller":"traceutil/trace.go:171","msg":"trace[1921364186] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"397.870314ms","start":"2026-04-14T06:20:25.843317Z","end":"2026-04-14T06:20:26.241187Z","steps":["trace[1921364186] 'process raft request' (duration: 397.695289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:26.241423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:25.904933Z","time spent":"336.407311ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:20:26.241429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:20:25.843285Z","time spent":"398.107832ms","remote":"127.0.0.1:49944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10964,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:20:26.241559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.744902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:20:26.241665Z","caller":"traceutil/trace.go:171","msg":"trace[612765442] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1831; }","duration":"156.904836ms","start":"2026-04-14T06:20:26.08474Z","end":"2026-04-14T06:20:26.241645Z","steps":["trace[612765442] 'agreement among raft nodes before linearized reading' (duration: 156.721811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:20:26.242057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.730959ms","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-04-14T06:20:26.242169Z","caller":"traceutil/trace.go:171","msg":"trace[766775298] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1831; }","duration":"284.908815ms","start":"2026-04-14T06:20:25.957238Z","end":"2026-04-14T06:20:26.242146Z","steps":["trace[766775298] 'agreement among raft nodes before linearized reading' (duration: 284.114952ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:26.358838Z","caller":"traceutil/trace.go:171","msg":"trace[728831764] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"110.705524ms","start":"2026-04-14T06:20:26.248078Z","end":"2026-04-14T06:20:26.358783Z","steps":["trace[728831764] 'process raft request' (duration: 53.322068ms)","trace[728831764] 'compare' (duration: 57.093798ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:20:26.358927Z","caller":"traceutil/trace.go:171","msg":"trace[1190946399] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"110.2ms","start":"2026-04-14T06:20:26.248687Z","end":"2026-04-14T06:20:26.358887Z","steps":["trace[1190946399] 'process raft request' (duration: 110.074317ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:26.358941Z","caller":"traceutil/trace.go:171","msg":"trace[545998780] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"110.418706ms","start":"2026-04-14T06:20:26.24849Z","end":"2026-04-14T06:20:26.358909Z","steps":["trace[545998780] 'process raft request' (duration: 110.188929ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:20:26.359191Z","caller":"traceutil/trace.go:171","msg":"trace[152481973] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"109.307545ms","start":"2026-04-14T06:20:26.249837Z","end":"2026-04-14T06:20:26.359145Z","steps":["trace[152481973] 'process raft request' (duration: 108.998445ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:21:16.563136Z","caller":"traceutil/trace.go:171","msg":"trace[502369407] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"102.956488ms","start":"2026-04-14T06:21:16.46014Z","end":"2026-04-14T06:21:16.563096Z","steps":["trace[502369407] 'process raft request' (duration: 102.761792ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:21:16.840268Z","caller":"traceutil/trace.go:171","msg":"trace[920804849] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"116.6987ms","start":"2026-04-14T06:21:16.723529Z","end":"2026-04-14T06:21:16.840227Z","steps":["trace[920804849] 'process raft request' (duration: 116.461182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:21:17.122528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.148831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-14T06:21:17.122639Z","caller":"traceutil/trace.go:171","msg":"trace[2127638032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2157; }","duration":"152.308946ms","start":"2026-04-14T06:21:16.970314Z","end":"2026-04-14T06:21:17.122623Z","steps":["trace[2127638032] 'range keys from in-memory index tree' (duration: 151.978926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:22:09.694836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.926969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-14T06:22:09.6949Z","caller":"traceutil/trace.go:171","msg":"trace[1253490968] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2613; }","duration":"149.025722ms","start":"2026-04-14T06:22:09.545862Z","end":"2026-04-14T06:22:09.694888Z","steps":["trace[1253490968] 'range keys from in-memory index tree' (duration: 148.776314ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:22:09.695042Z","caller":"traceutil/trace.go:171","msg":"trace[1208913996] transaction","detail":"{read_only:false; response_revision:2614; number_of_response:1; }","duration":"145.961555ms","start":"2026-04-14T06:22:09.548989Z","end":"2026-04-14T06:22:09.69495Z","steps":["trace[1208913996] 'process raft request' (duration: 141.383325ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:26:46.906848Z","caller":"traceutil/trace.go:171","msg":"trace[968944070] transaction","detail":"{read_only:false; response_revision:4754; number_of_response:1; }","duration":"127.736263ms","start":"2026-04-14T06:26:46.779067Z","end":"2026-04-14T06:26:46.906803Z","steps":["trace[968944070] 'process raft request' (duration: 98.6617ms)","trace[968944070] 'compare' (duration: 28.703102ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:26:56.316107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.806104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:26:56.316192Z","caller":"traceutil/trace.go:171","msg":"trace[1240191424] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5089; }","duration":"232.958349ms","start":"2026-04-14T06:26:56.08322Z","end":"2026-04-14T06:26:56.316178Z","steps":["trace[1240191424] 'range keys from in-memory index tree' (duration: 232.67039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:26:56.316175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.79449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b32df2dd-bd8e-4e87-99f3-d44f18fec444\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-14T06:26:56.316257Z","caller":"traceutil/trace.go:171","msg":"trace[1783105893] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b32df2dd-bd8e-4e87-99f3-d44f18fec444; range_end:; response_count:1; response_revision:5089; }","duration":"250.924534ms","start":"2026-04-14T06:26:56.065312Z","end":"2026-04-14T06:26:56.316237Z","steps":["trace[1783105893] 'range keys from in-memory index tree' (duration: 250.596855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:26:58.187199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.498717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:26:58.187276Z","caller":"traceutil/trace.go:171","msg":"trace[429112044] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5124; }","duration":"103.615861ms","start":"2026-04-14T06:26:58.083642Z","end":"2026-04-14T06:26:58.187258Z","steps":["trace[429112044] 'range keys from in-memory index tree' (duration: 103.436175ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:27:06.248041Z","caller":"traceutil/trace.go:171","msg":"trace[291426266] transaction","detail":"{read_only:false; response_revision:5280; number_of_response:1; }","duration":"124.033726ms","start":"2026-04-14T06:27:06.123982Z","end":"2026-04-14T06:27:06.248016Z","steps":["trace[291426266] 'process raft request' (duration: 123.979884ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:27:06.248134Z","caller":"traceutil/trace.go:171","msg":"trace[2023477045] transaction","detail":"{read_only:false; response_revision:5279; number_of_response:1; }","duration":"141.752793ms","start":"2026-04-14T06:27:06.106332Z","end":"2026-04-14T06:27:06.248085Z","steps":["trace[2023477045] 'process raft request' (duration: 45.388231ms)","trace[2023477045] 'compare' (duration: 96.089914ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:27:06.409451Z","caller":"traceutil/trace.go:171","msg":"trace[2144819427] linearizableReadLoop","detail":"{readStateIndex:5483; appliedIndex:5481; }","duration":"155.216881ms","start":"2026-04-14T06:27:06.254214Z","end":"2026-04-14T06:27:06.409431Z","steps":["trace[2144819427] 'read index received' (duration: 82.160227ms)","trace[2144819427] 'applied index is now lower than readState.Index' (duration: 73.055894ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:27:06.409505Z","caller":"traceutil/trace.go:171","msg":"trace[173397955] transaction","detail":"{read_only:false; response_revision:5282; number_of_response:1; }","duration":"156.647131ms","start":"2026-04-14T06:27:06.252843Z","end":"2026-04-14T06:27:06.40949Z","steps":["trace[173397955] 'process raft request' (duration: 107.828081ms)","trace[173397955] 'compare' (duration: 48.577103ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:27:06.409511Z","caller":"traceutil/trace.go:171","msg":"trace[1698279746] transaction","detail":"{read_only:false; response_revision:5283; number_of_response:1; }","duration":"156.560059ms","start":"2026-04-14T06:27:06.252926Z","end":"2026-04-14T06:27:06.409486Z","steps":["trace[1698279746] 'process raft request' (duration: 156.459856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:27:06.409574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.352574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:27:06.409821Z","caller":"traceutil/trace.go:171","msg":"trace[861105181] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:5283; }","duration":"155.643463ms","start":"2026-04-14T06:27:06.254166Z","end":"2026-04-14T06:27:06.40981Z","steps":["trace[861105181] 'agreement among raft nodes before linearized reading' (duration: 155.349034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:27:15.217924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.263093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5428196a-b8b1-4853-9c27-16da8339639d\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-14T06:27:15.217996Z","caller":"traceutil/trace.go:171","msg":"trace[1919503090] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5428196a-b8b1-4853-9c27-16da8339639d; range_end:; response_count:1; response_revision:5432; }","duration":"169.374305ms","start":"2026-04-14T06:27:15.048604Z","end":"2026-04-14T06:27:15.217978Z","steps":["trace[1919503090] 'range keys from in-memory index tree' (duration: 169.1672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:27:15.217953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.647202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:27:15.218073Z","caller":"traceutil/trace.go:171","msg":"trace[436054167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5432; }","duration":"133.819547ms","start":"2026-04-14T06:27:15.084228Z","end":"2026-04-14T06:27:15.218047Z","steps":["trace[436054167] 'range keys from in-memory index tree' (duration: 133.55043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:27:31.429914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.477937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16403"} {"level":"info","ts":"2026-04-14T06:27:31.430072Z","caller":"traceutil/trace.go:171","msg":"trace[1124614889] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5660; }","duration":"184.687614ms","start":"2026-04-14T06:27:31.245346Z","end":"2026-04-14T06:27:31.430034Z","steps":["trace[1124614889] 'range keys from in-memory index tree' (duration: 184.182119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:27:31.430163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.884364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"warn","ts":"2026-04-14T06:27:31.430214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.137281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T06:27:31.430261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.818483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-14T06:27:31.430301Z","caller":"traceutil/trace.go:171","msg":"trace[1380582076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5660; }","duration":"346.281636ms","start":"2026-04-14T06:27:31.084001Z","end":"2026-04-14T06:27:31.430282Z","steps":["trace[1380582076] 'range keys from in-memory index tree' (duration: 345.997707ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:27:31.430286Z","caller":"traceutil/trace.go:171","msg":"trace[1684852235] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5660; }","duration":"346.053779ms","start":"2026-04-14T06:27:31.084205Z","end":"2026-04-14T06:27:31.430258Z","steps":["trace[1684852235] 'range keys from in-memory index tree' (duration: 345.595435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:27:31.430338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:27:31.083977Z","time spent":"346.354647ms","remote":"127.0.0.1:49746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-14T06:27:31.430337Z","caller":"traceutil/trace.go:171","msg":"trace[601514587] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5660; }","duration":"150.947337ms","start":"2026-04-14T06:27:31.279365Z","end":"2026-04-14T06:27:31.430312Z","steps":["trace[601514587] 'range keys from in-memory index tree' (duration: 150.617898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:27:31.430358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:27:31.084199Z","time spent":"346.147361ms","remote":"127.0.0.1:49944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12234,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-14T06:27:31.464545Z","caller":"traceutil/trace.go:171","msg":"trace[519408456] linearizableReadLoop","detail":"{readStateIndex:5870; appliedIndex:5869; }","duration":"129.249308ms","start":"2026-04-14T06:27:31.335263Z","end":"2026-04-14T06:27:31.464512Z","steps":["trace[519408456] 'read index received' (duration: 129.034912ms)","trace[519408456] 'applied index is now lower than readState.Index' (duration: 213.266µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:27:31.464664Z","caller":"traceutil/trace.go:171","msg":"trace[1752568812] transaction","detail":"{read_only:false; response_revision:5661; number_of_response:1; }","duration":"136.075043ms","start":"2026-04-14T06:27:31.328527Z","end":"2026-04-14T06:27:31.464602Z","steps":["trace[1752568812] 'process raft request' (duration: 135.789935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:27:31.465369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.975889ms","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-04-14T06:27:31.465531Z","caller":"traceutil/trace.go:171","msg":"trace[325953105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5661; }","duration":"130.284157ms","start":"2026-04-14T06:27:31.33521Z","end":"2026-04-14T06:27:31.465494Z","steps":["trace[325953105] 'agreement among raft nodes before linearized reading' (duration: 129.504575ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:28:14.945989Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2994} {"level":"info","ts":"2026-04-14T06:28:15.017022Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2994,"took":"69.61849ms","hash":2531027051,"current-db-size-bytes":58019840,"current-db-size":"58 MB","current-db-size-in-use-bytes":33386496,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-14T06:28:15.017122Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2531027051,"revision":2994,"compact-revision":-1} {"level":"info","ts":"2026-04-14T06:33:14.956932Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6023} {"level":"info","ts":"2026-04-14T06:33:15.049842Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6023,"took":"91.263531ms","hash":4084502010,"current-db-size-bytes":58019840,"current-db-size":"58 MB","current-db-size-in-use-bytes":21610496,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-14T06:33:15.049923Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4084502010,"revision":6023,"compact-revision":2994} {"level":"info","ts":"2026-04-14T06:33:49.036132Z","caller":"traceutil/trace.go:171","msg":"trace[1723710266] transaction","detail":"{read_only:false; response_revision:8449; number_of_response:1; }","duration":"263.656243ms","start":"2026-04-14T06:33:48.772435Z","end":"2026-04-14T06:33:49.036091Z","steps":["trace[1723710266] 'process raft request' (duration: 263.399626ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:33:49.217606Z","caller":"traceutil/trace.go:171","msg":"trace[706904785] linearizableReadLoop","detail":"{readStateIndex:8750; appliedIndex:8749; }","duration":"175.150212ms","start":"2026-04-14T06:33:49.042424Z","end":"2026-04-14T06:33:49.217574Z","steps":["trace[706904785] 'read index received' (duration: 174.028489ms)","trace[706904785] 'applied index is now lower than readState.Index' (duration: 1.120493ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:33:49.217728Z","caller":"traceutil/trace.go:171","msg":"trace[1229368661] transaction","detail":"{read_only:false; response_revision:8450; number_of_response:1; }","duration":"235.558298ms","start":"2026-04-14T06:33:48.982159Z","end":"2026-04-14T06:33:49.217717Z","steps":["trace[1229368661] 'process raft request' (duration: 234.361513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:33:49.217961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.520132ms","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-04-14T06:33:49.218002Z","caller":"traceutil/trace.go:171","msg":"trace[272567252] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8450; }","duration":"175.607435ms","start":"2026-04-14T06:33:49.042385Z","end":"2026-04-14T06:33:49.217993Z","steps":["trace[272567252] 'agreement among raft nodes before linearized reading' (duration: 175.45531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:33:49.218161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.810048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:33:49.218246Z","caller":"traceutil/trace.go:171","msg":"trace[1465976397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8450; }","duration":"130.927771ms","start":"2026-04-14T06:33:49.087294Z","end":"2026-04-14T06:33:49.218222Z","steps":["trace[1465976397] 'agreement among raft nodes before linearized reading' (duration: 130.806348ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:34:01.388518Z","caller":"traceutil/trace.go:171","msg":"trace[1656875082] transaction","detail":"{read_only:false; response_revision:8514; number_of_response:1; }","duration":"129.707315ms","start":"2026-04-14T06:34:01.258777Z","end":"2026-04-14T06:34:01.388484Z","steps":["trace[1656875082] 'process raft request' (duration: 129.494889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:34:03.448096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.110138ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-14T06:34:03.448264Z","caller":"traceutil/trace.go:171","msg":"trace[378401722] linearizableReadLoop","detail":"{readStateIndex:8825; appliedIndex:8824; }","duration":"138.025975ms","start":"2026-04-14T06:34:03.310212Z","end":"2026-04-14T06:34:03.448238Z","steps":["trace[378401722] 'read index received' (duration: 31.66413ms)","trace[378401722] 'applied index is now lower than readState.Index' (duration: 106.359725ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:34:03.448513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.285432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"info","ts":"2026-04-14T06:34:03.44856Z","caller":"traceutil/trace.go:171","msg":"trace[1049015154] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:8522; }","duration":"138.346774ms","start":"2026-04-14T06:34:03.310198Z","end":"2026-04-14T06:34:03.448545Z","steps":["trace[1049015154] 'agreement among raft nodes before linearized reading' (duration: 138.100587ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:34:03.846084Z","caller":"traceutil/trace.go:171","msg":"trace[658390925] transaction","detail":"{read_only:false; response_revision:8528; number_of_response:1; }","duration":"137.307914ms","start":"2026-04-14T06:34:03.708742Z","end":"2026-04-14T06:34:03.84605Z","steps":["trace[658390925] 'process raft request' (duration: 137.098888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:34:32.528831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.466668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T06:34:32.528906Z","caller":"traceutil/trace.go:171","msg":"trace[526085363] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:8737; }","duration":"209.55043ms","start":"2026-04-14T06:34:32.319337Z","end":"2026-04-14T06:34:32.528887Z","steps":["trace[526085363] 'count revisions from in-memory index tree' (duration: 209.309102ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:34:36.842452Z","caller":"traceutil/trace.go:171","msg":"trace[882067022] transaction","detail":"{read_only:false; response_revision:8760; number_of_response:1; }","duration":"111.72697ms","start":"2026-04-14T06:34:36.730697Z","end":"2026-04-14T06:34:36.842424Z","steps":["trace[882067022] 'process raft request' (duration: 111.670468ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:34:36.842708Z","caller":"traceutil/trace.go:171","msg":"trace[236867824] transaction","detail":"{read_only:false; response_revision:8759; number_of_response:1; }","duration":"167.347978ms","start":"2026-04-14T06:34:36.675287Z","end":"2026-04-14T06:34:36.842635Z","steps":["trace[236867824] 'process raft request' (duration: 118.411583ms)","trace[236867824] 'compare' (duration: 48.538483ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:34:46.42884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.073989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-14T06:34:46.428932Z","caller":"traceutil/trace.go:171","msg":"trace[2077524956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8851; }","duration":"199.227502ms","start":"2026-04-14T06:34:46.229685Z","end":"2026-04-14T06:34:46.428912Z","steps":["trace[2077524956] 'range keys from in-memory index tree' (duration: 198.887713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:34:46.429046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.465691ms","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-04-14T06:34:46.429124Z","caller":"traceutil/trace.go:171","msg":"trace[437390650] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8851; }","duration":"197.549734ms","start":"2026-04-14T06:34:46.231554Z","end":"2026-04-14T06:34:46.429104Z","steps":["trace[437390650] 'range keys from in-memory index tree' (duration: 197.231075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:34:46.428848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.748376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-14T06:34:46.42922Z","caller":"traceutil/trace.go:171","msg":"trace[683433577] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8851; }","duration":"107.197609ms","start":"2026-04-14T06:34:46.322011Z","end":"2026-04-14T06:34:46.429208Z","steps":["trace[683433577] 'range keys from in-memory index tree' (duration: 106.54436ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:34:56.938312Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002424.wal"} {"level":"info","ts":"2026-04-14T06:36:10.95506Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f170c75a1d97cc","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-14T06:36:10.959886Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-14T06:36:10.959999Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-14T06:38:14.965353Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8095} {"level":"info","ts":"2026-04-14T06:38:15.033212Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8095,"took":"64.46441ms","hash":3823088140,"current-db-size-bytes":58019840,"current-db-size":"58 MB","current-db-size-in-use-bytes":27955200,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-14T06:38:15.033299Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3823088140,"revision":8095,"compact-revision":6023} {"level":"info","ts":"2026-04-14T06:41:46.409731Z","caller":"traceutil/trace.go:171","msg":"trace[2116730277] linearizableReadLoop","detail":"{readStateIndex:12480; appliedIndex:12479; }","duration":"189.909694ms","start":"2026-04-14T06:41:46.219794Z","end":"2026-04-14T06:41:46.409704Z","steps":["trace[2116730277] 'read index received' (duration: 189.698538ms)","trace[2116730277] 'applied index is now lower than readState.Index' (duration: 209.336µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:41:46.409783Z","caller":"traceutil/trace.go:171","msg":"trace[524239024] transaction","detail":"{read_only:false; response_revision:12065; number_of_response:1; }","duration":"249.590966ms","start":"2026-04-14T06:41:46.160155Z","end":"2026-04-14T06:41:46.409746Z","steps":["trace[524239024] 'process raft request' (duration: 249.352399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:41:46.410086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.850909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:41:46.410158Z","caller":"traceutil/trace.go:171","msg":"trace[1863557691] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12065; }","duration":"149.946302ms","start":"2026-04-14T06:41:46.260196Z","end":"2026-04-14T06:41:46.410142Z","steps":["trace[1863557691] 'agreement among raft nodes before linearized reading' (duration: 149.820148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:41:46.410219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.197406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-04-14T06:41:46.410261Z","caller":"traceutil/trace.go:171","msg":"trace[219587180] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:12065; }","duration":"186.269688ms","start":"2026-04-14T06:41:46.22398Z","end":"2026-04-14T06:41:46.41025Z","steps":["trace[219587180] 'agreement among raft nodes before linearized reading' (duration: 186.140284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:41:46.410088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.632786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T06:41:46.410456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.25055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:41:46.410483Z","caller":"traceutil/trace.go:171","msg":"trace[1700451345] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12065; }","duration":"190.061369ms","start":"2026-04-14T06:41:46.220407Z","end":"2026-04-14T06:41:46.410469Z","steps":["trace[1700451345] 'agreement among raft nodes before linearized reading' (duration: 189.606226ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:41:46.410516Z","caller":"traceutil/trace.go:171","msg":"trace[1427309360] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12065; }","duration":"150.335613ms","start":"2026-04-14T06:41:46.260163Z","end":"2026-04-14T06:41:46.410498Z","steps":["trace[1427309360] 'agreement among raft nodes before linearized reading' (duration: 150.181039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:41:46.410527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.725148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-04-14T06:41:46.410719Z","caller":"traceutil/trace.go:171","msg":"trace[1110434089] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:12065; }","duration":"190.964235ms","start":"2026-04-14T06:41:46.219742Z","end":"2026-04-14T06:41:46.410706Z","steps":["trace[1110434089] 'agreement among raft nodes before linearized reading' (duration: 190.674977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:41:46.41072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.764349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:41:46.410755Z","caller":"traceutil/trace.go:171","msg":"trace[131497961] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12065; }","duration":"190.851201ms","start":"2026-04-14T06:41:46.219894Z","end":"2026-04-14T06:41:46.410746Z","steps":["trace[131497961] 'agreement among raft nodes before linearized reading' (duration: 190.7955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:41:46.410717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.756939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:41:46.410832Z","caller":"traceutil/trace.go:171","msg":"trace[1039341602] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12065; }","duration":"190.911433ms","start":"2026-04-14T06:41:46.21989Z","end":"2026-04-14T06:41:46.410801Z","steps":["trace[1039341602] 'agreement among raft nodes before linearized reading' (duration: 190.043768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:41:46.410781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.283885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T06:41:46.41089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.403039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5004"} {"level":"info","ts":"2026-04-14T06:41:46.410997Z","caller":"traceutil/trace.go:171","msg":"trace[1025370041] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12065; }","duration":"190.628265ms","start":"2026-04-14T06:41:46.220345Z","end":"2026-04-14T06:41:46.410973Z","steps":["trace[1025370041] 'agreement among raft nodes before linearized reading' (duration: 190.227133ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:41:46.411038Z","caller":"traceutil/trace.go:171","msg":"trace[744948090] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12065; }","duration":"190.576804ms","start":"2026-04-14T06:41:46.220426Z","end":"2026-04-14T06:41:46.411002Z","steps":["trace[744948090] 'agreement among raft nodes before linearized reading' (duration: 190.065469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:41:46.410838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.060314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:41:46.411155Z","caller":"traceutil/trace.go:171","msg":"trace[1373513324] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12065; }","duration":"151.097625ms","start":"2026-04-14T06:41:46.260039Z","end":"2026-04-14T06:41:46.411137Z","steps":["trace[1373513324] 'agreement among raft nodes before linearized reading' (duration: 149.943771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:41:46.410852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.646902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:41:46.413075Z","caller":"traceutil/trace.go:171","msg":"trace[830311167] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12065; }","duration":"152.849817ms","start":"2026-04-14T06:41:46.26017Z","end":"2026-04-14T06:41:46.41302Z","steps":["trace[830311167] 'agreement among raft nodes before linearized reading' (duration: 150.460157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:41:46.410726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.494688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5004"} {"level":"info","ts":"2026-04-14T06:41:46.413183Z","caller":"traceutil/trace.go:171","msg":"trace[1589156653] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12065; }","duration":"151.203589ms","start":"2026-04-14T06:41:46.261958Z","end":"2026-04-14T06:41:46.413161Z","steps":["trace[1589156653] 'agreement among raft nodes before linearized reading' (duration: 148.150799ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:41:46.92196Z","caller":"traceutil/trace.go:171","msg":"trace[1907144440] transaction","detail":"{read_only:false; response_revision:12072; number_of_response:1; }","duration":"156.572246ms","start":"2026-04-14T06:41:46.765348Z","end":"2026-04-14T06:41:46.921921Z","steps":["trace[1907144440] 'process raft request' (duration: 156.459122ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:41:46.922082Z","caller":"traceutil/trace.go:171","msg":"trace[1380899059] transaction","detail":"{read_only:false; response_revision:12071; number_of_response:1; }","duration":"158.704049ms","start":"2026-04-14T06:41:46.763328Z","end":"2026-04-14T06:41:46.922032Z","steps":["trace[1380899059] 'process raft request' (duration: 131.306314ms)","trace[1380899059] 'compare' (duration: 26.658942ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:43:14.971273Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10505} {"level":"info","ts":"2026-04-14T06:43:15.067905Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10505,"took":"94.297377ms","hash":2278149042,"current-db-size-bytes":58019840,"current-db-size":"58 MB","current-db-size-in-use-bytes":26038272,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-14T06:43:15.067987Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2278149042,"revision":10505,"compact-revision":8095} {"level":"warn","ts":"2026-04-14T06:48:10.58825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.171624ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:48:10.588974Z","caller":"traceutil/trace.go:171","msg":"trace[1878540704] transaction","detail":"{read_only:false; response_revision:14637; number_of_response:1; }","duration":"272.926247ms","start":"2026-04-14T06:48:10.315949Z","end":"2026-04-14T06:48:10.588875Z","steps":["trace[1878540704] 'process raft request' (duration: 150.864948ms)","trace[1878540704] 'compare' (duration: 120.730951ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:48:14.978865Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12638} {"level":"info","ts":"2026-04-14T06:48:15.058316Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12638,"took":"76.598472ms","hash":936732272,"current-db-size-bytes":58019840,"current-db-size":"58 MB","current-db-size-in-use-bytes":27340800,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-14T06:48:15.058417Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":936732272,"revision":12638,"compact-revision":10505} {"level":"warn","ts":"2026-04-14T06:48:27.361682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.71487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-14T06:48:27.361832Z","caller":"traceutil/trace.go:171","msg":"trace[1753514044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14738; }","duration":"112.943508ms","start":"2026-04-14T06:48:27.248866Z","end":"2026-04-14T06:48:27.361809Z","steps":["trace[1753514044] 'range keys from in-memory index tree' (duration: 112.406391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:48:27.361858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.191732ms","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-04-14T06:48:27.361945Z","caller":"traceutil/trace.go:171","msg":"trace[786832327] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14738; }","duration":"115.338576ms","start":"2026-04-14T06:48:27.246587Z","end":"2026-04-14T06:48:27.361926Z","steps":["trace[786832327] 'range keys from in-memory index tree' (duration: 114.991806ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:48:28.382217Z","caller":"traceutil/trace.go:171","msg":"trace[1371872624] linearizableReadLoop","detail":"{readStateIndex:15255; appliedIndex:15254; }","duration":"199.983849ms","start":"2026-04-14T06:48:28.182207Z","end":"2026-04-14T06:48:28.382191Z","steps":["trace[1371872624] 'read index received' (duration: 199.32243ms)","trace[1371872624] 'applied index is now lower than readState.Index' (duration: 660.289µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:48:28.382444Z","caller":"traceutil/trace.go:171","msg":"trace[756451534] transaction","detail":"{read_only:false; response_revision:14744; number_of_response:1; }","duration":"246.344143ms","start":"2026-04-14T06:48:28.135944Z","end":"2026-04-14T06:48:28.382288Z","steps":["trace[756451534] 'process raft request' (duration: 245.449468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:48:28.382976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.762112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:1 size:138"} {"level":"info","ts":"2026-04-14T06:48:28.383017Z","caller":"traceutil/trace.go:171","msg":"trace[2060125788] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:1; response_revision:14744; }","duration":"200.871234ms","start":"2026-04-14T06:48:28.182136Z","end":"2026-04-14T06:48:28.383007Z","steps":["trace[2060125788] 'agreement among raft nodes before linearized reading' (duration: 200.159454ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:48:37.991919Z","caller":"traceutil/trace.go:171","msg":"trace[1220124375] transaction","detail":"{read_only:false; response_revision:14914; number_of_response:1; }","duration":"134.722534ms","start":"2026-04-14T06:48:37.857173Z","end":"2026-04-14T06:48:37.991896Z","steps":["trace[1220124375] 'process raft request' (duration: 134.578289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:48:38.310269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.246269ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:48:38.310435Z","caller":"traceutil/trace.go:171","msg":"trace[1560359730] linearizableReadLoop","detail":"{readStateIndex:15430; appliedIndex:15429; }","duration":"127.420183ms","start":"2026-04-14T06:48:38.182992Z","end":"2026-04-14T06:48:38.310412Z","steps":["trace[1560359730] 'read index received' (duration: 60.391µs)","trace[1560359730] 'applied index is now lower than readState.Index' (duration: 127.357352ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:48:38.310691Z","caller":"traceutil/trace.go:171","msg":"trace[1249401674] transaction","detail":"{read_only:false; response_revision:14916; number_of_response:1; }","duration":"306.823411ms","start":"2026-04-14T06:48:38.003826Z","end":"2026-04-14T06:48:38.31065Z","steps":["trace[1249401674] 'process raft request' (duration: 139.096408ms)","trace[1249401674] 'compare' (duration: 167.054894ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:48:38.310862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:48:38.003797Z","time spent":"306.986555ms","remote":"127.0.0.1:49820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":812,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T06:48:38.310731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.723862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:1 size:138"} {"level":"info","ts":"2026-04-14T06:48:38.310971Z","caller":"traceutil/trace.go:171","msg":"trace[2135605174] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:1; response_revision:14916; }","duration":"127.980259ms","start":"2026-04-14T06:48:38.182941Z","end":"2026-04-14T06:48:38.310921Z","steps":["trace[2135605174] 'agreement among raft nodes before linearized reading' (duration: 127.531717ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:48:38.498741Z","caller":"traceutil/trace.go:171","msg":"trace[972317109] transaction","detail":"{read_only:false; response_revision:14917; number_of_response:1; }","duration":"146.732038ms","start":"2026-04-14T06:48:38.351959Z","end":"2026-04-14T06:48:38.498691Z","steps":["trace[972317109] 'process raft request' (duration: 138.740168ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:50:19.063179Z","caller":"traceutil/trace.go:171","msg":"trace[739377434] transaction","detail":"{read_only:false; response_revision:16010; number_of_response:1; }","duration":"114.822047ms","start":"2026-04-14T06:50:18.948123Z","end":"2026-04-14T06:50:19.062945Z","steps":["trace[739377434] 'process raft request' (duration: 114.681383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:50:19.36889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.196406ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:50:19.369038Z","caller":"traceutil/trace.go:171","msg":"trace[1228478193] linearizableReadLoop","detail":"{readStateIndex:16563; appliedIndex:16562; }","duration":"215.514417ms","start":"2026-04-14T06:50:19.153501Z","end":"2026-04-14T06:50:19.369016Z","steps":["trace[1228478193] 'read index received' (duration: 98.96541ms)","trace[1228478193] 'applied index is now lower than readState.Index' (duration: 116.547207ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:50:19.36905Z","caller":"traceutil/trace.go:171","msg":"trace[1912906543] transaction","detail":"{read_only:false; response_revision:16011; number_of_response:1; }","duration":"255.712045ms","start":"2026-04-14T06:50:19.113313Z","end":"2026-04-14T06:50:19.369025Z","steps":["trace[1912906543] 'process raft request' (duration: 139.270561ms)","trace[1912906543] 'compare' (duration: 116.085663ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:50:19.36956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.045111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:50:19.36963Z","caller":"traceutil/trace.go:171","msg":"trace[572442040] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:16011; }","duration":"216.234798ms","start":"2026-04-14T06:50:19.153377Z","end":"2026-04-14T06:50:19.369612Z","steps":["trace[572442040] 'agreement among raft nodes before linearized reading' (duration: 216.120104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:50:19.369873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.276152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T06:50:19.369992Z","caller":"traceutil/trace.go:171","msg":"trace[1547670837] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:16011; }","duration":"213.437577ms","start":"2026-04-14T06:50:19.15653Z","end":"2026-04-14T06:50:19.369968Z","steps":["trace[1547670837] 'agreement among raft nodes before linearized reading' (duration: 213.245851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:50:19.37016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.389599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:50:19.370218Z","caller":"traceutil/trace.go:171","msg":"trace[848172875] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16011; }","duration":"161.45747ms","start":"2026-04-14T06:50:19.208743Z","end":"2026-04-14T06:50:19.3702Z","steps":["trace[848172875] 'agreement among raft nodes before linearized reading' (duration: 161.371498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:50:19.37025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.355497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"warn","ts":"2026-04-14T06:50:19.370282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.545683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:50:19.370375Z","caller":"traceutil/trace.go:171","msg":"trace[1902117064] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16011; }","duration":"161.666936ms","start":"2026-04-14T06:50:19.208698Z","end":"2026-04-14T06:50:19.370365Z","steps":["trace[1902117064] 'agreement among raft nodes before linearized reading' (duration: 161.544642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:50:19.370389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.265364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:50:19.370436Z","caller":"traceutil/trace.go:171","msg":"trace[636657964] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16011; }","duration":"161.382519ms","start":"2026-04-14T06:50:19.209039Z","end":"2026-04-14T06:50:19.370421Z","steps":["trace[636657964] 'agreement among raft nodes before linearized reading' (duration: 161.222404ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:50:19.370304Z","caller":"traceutil/trace.go:171","msg":"trace[447415392] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16011; }","duration":"161.461651ms","start":"2026-04-14T06:50:19.208828Z","end":"2026-04-14T06:50:19.370289Z","steps":["trace[447415392] 'agreement among raft nodes before linearized reading' (duration: 161.235274ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:50:19.647885Z","caller":"traceutil/trace.go:171","msg":"trace[1597917254] transaction","detail":"{read_only:false; response_revision:16013; number_of_response:1; }","duration":"128.324076ms","start":"2026-04-14T06:50:19.519509Z","end":"2026-04-14T06:50:19.647833Z","steps":["trace[1597917254] 'process raft request' (duration: 127.892814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:50:19.899585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.799119ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:50:19.899895Z","caller":"traceutil/trace.go:171","msg":"trace[1748086197] transaction","detail":"{read_only:false; response_revision:16014; number_of_response:1; }","duration":"246.484079ms","start":"2026-04-14T06:50:19.653385Z","end":"2026-04-14T06:50:19.899869Z","steps":["trace[1748086197] 'process raft request' (duration: 75.310389ms)","trace[1748086197] 'compare' (duration: 170.679576ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:50:19.899974Z","caller":"traceutil/trace.go:171","msg":"trace[990399428] linearizableReadLoop","detail":"{readStateIndex:16566; appliedIndex:16565; }","duration":"151.069631ms","start":"2026-04-14T06:50:19.748897Z","end":"2026-04-14T06:50:19.899966Z","steps":["trace[990399428] 'read index received' (duration: 42.251µs)","trace[990399428] 'applied index is now lower than readState.Index' (duration: 151.02655ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:50:19.900094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.198015ms","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-04-14T06:50:19.90012Z","caller":"traceutil/trace.go:171","msg":"trace[716380063] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16015; }","duration":"151.272117ms","start":"2026-04-14T06:50:19.74884Z","end":"2026-04-14T06:50:19.900112Z","steps":["trace[716380063] 'agreement among raft nodes before linearized reading' (duration: 151.176224ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:53:14.98748Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14660} {"level":"info","ts":"2026-04-14T06:53:15.068902Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14660,"took":"78.781013ms","hash":1164685759,"current-db-size-bytes":58019840,"current-db-size":"58 MB","current-db-size-in-use-bytes":31916032,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-14T06:53:15.068976Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1164685759,"revision":14660,"compact-revision":12638} {"level":"info","ts":"2026-04-14T06:55:58.063782Z","caller":"traceutil/trace.go:171","msg":"trace[970814162] transaction","detail":"{read_only:false; response_revision:18502; number_of_response:1; }","duration":"101.024772ms","start":"2026-04-14T06:55:57.962727Z","end":"2026-04-14T06:55:58.063752Z","steps":["trace[970814162] 'process raft request' (duration: 100.859417ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:55:58.090846Z","caller":"traceutil/trace.go:171","msg":"trace[1520493166] transaction","detail":"{read_only:false; response_revision:18503; number_of_response:1; }","duration":"126.929682ms","start":"2026-04-14T06:55:57.96389Z","end":"2026-04-14T06:55:58.09082Z","steps":["trace[1520493166] 'process raft request' (duration: 126.781068ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:55:58.468378Z","caller":"traceutil/trace.go:171","msg":"trace[1406031663] transaction","detail":"{read_only:false; response_revision:18504; number_of_response:1; }","duration":"229.148058ms","start":"2026-04-14T06:55:58.239171Z","end":"2026-04-14T06:55:58.468319Z","steps":["trace[1406031663] 'process raft request' (duration: 202.083846ms)","trace[1406031663] 'compare' (duration: 26.63673ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:56:00.224629Z","caller":"traceutil/trace.go:171","msg":"trace[235927992] transaction","detail":"{read_only:false; response_revision:18530; number_of_response:1; }","duration":"125.574223ms","start":"2026-04-14T06:56:00.09903Z","end":"2026-04-14T06:56:00.224604Z","steps":["trace[235927992] 'process raft request' (duration: 125.417009ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:56:00.450119Z","caller":"traceutil/trace.go:171","msg":"trace[1559887155] transaction","detail":"{read_only:false; response_revision:18531; number_of_response:1; }","duration":"137.626932ms","start":"2026-04-14T06:56:00.312465Z","end":"2026-04-14T06:56:00.450091Z","steps":["trace[1559887155] 'process raft request' (duration: 100.677913ms)","trace[1559887155] 'compare' (duration: 36.579818ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:56:02.112646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.376193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-ovn-vpn-agent-default-ktwjn\" ","response":"range_response_count:1 size:15809"} {"level":"info","ts":"2026-04-14T06:56:02.112729Z","caller":"traceutil/trace.go:171","msg":"trace[309675625] range","detail":"{range_begin:/registry/pods/openstack/neutron-ovn-vpn-agent-default-ktwjn; range_end:; response_count:1; response_revision:18550; }","duration":"194.496047ms","start":"2026-04-14T06:56:01.918212Z","end":"2026-04-14T06:56:02.112708Z","steps":["trace[309675625] 'range keys from in-memory index tree' (duration: 194.103205ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:57:10.824032Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f170c75a1d97cc","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-14T06:57:10.830421Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-14T06:57:10.830577Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-14T06:58:14.996713Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17263} {"level":"info","ts":"2026-04-14T06:58:15.102742Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17263,"took":"101.477742ms","hash":2993092781,"current-db-size-bytes":58019840,"current-db-size":"58 MB","current-db-size-in-use-bytes":34488320,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-14T06:58:15.10281Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2993092781,"revision":17263,"compact-revision":14660} {"level":"info","ts":"2026-04-14T07:01:54.272118Z","caller":"traceutil/trace.go:171","msg":"trace[1363467690] transaction","detail":"{read_only:false; response_revision:21323; number_of_response:1; }","duration":"140.742086ms","start":"2026-04-14T07:01:54.131348Z","end":"2026-04-14T07:01:54.27209Z","steps":["trace[1363467690] 'process raft request' (duration: 140.581201ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:01:54.583789Z","caller":"traceutil/trace.go:171","msg":"trace[2118989511] transaction","detail":"{read_only:false; response_revision:21324; number_of_response:1; }","duration":"424.484724ms","start":"2026-04-14T07:01:54.159269Z","end":"2026-04-14T07:01:54.583753Z","steps":["trace[2118989511] 'process raft request' (duration: 377.023942ms)","trace[2118989511] 'compare' (duration: 47.255386ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:01:54.583988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:01:54.159244Z","time spent":"424.635168ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:01:54.584087Z","caller":"traceutil/trace.go:171","msg":"trace[1145994637] transaction","detail":"{read_only:false; response_revision:21325; number_of_response:1; }","duration":"407.333649ms","start":"2026-04-14T07:01:54.176708Z","end":"2026-04-14T07:01:54.584042Z","steps":["trace[1145994637] 'process raft request' (duration: 406.985879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:01:54.584279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:01:54.176681Z","time spent":"407.500953ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:01:54.795683Z","caller":"traceutil/trace.go:171","msg":"trace[1794692596] transaction","detail":"{read_only:false; response_revision:21326; number_of_response:1; }","duration":"193.871211ms","start":"2026-04-14T07:01:54.601776Z","end":"2026-04-14T07:01:54.795648Z","steps":["trace[1794692596] 'process raft request' (duration: 122.256961ms)","trace[1794692596] 'compare' (duration: 71.326321ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:01:54.795707Z","caller":"traceutil/trace.go:171","msg":"trace[1053753560] transaction","detail":"{read_only:false; response_revision:21327; number_of_response:1; }","duration":"192.646826ms","start":"2026-04-14T07:01:54.603012Z","end":"2026-04-14T07:01:54.795658Z","steps":["trace[1053753560] 'process raft request' (duration: 192.518882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:01:55.930509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.586265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-14T07:01:55.930752Z","caller":"traceutil/trace.go:171","msg":"trace[1386075303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21329; }","duration":"227.885093ms","start":"2026-04-14T07:01:55.702847Z","end":"2026-04-14T07:01:55.930732Z","steps":["trace[1386075303] 'range keys from in-memory index tree' (duration: 227.354798ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:01:56.19281Z","caller":"traceutil/trace.go:171","msg":"trace[1432052700] transaction","detail":"{read_only:false; response_revision:21330; number_of_response:1; }","duration":"379.627568ms","start":"2026-04-14T07:01:55.813144Z","end":"2026-04-14T07:01:56.192771Z","steps":["trace[1432052700] 'process raft request' (duration: 379.416902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:01:56.193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:01:55.813121Z","time spent":"379.777232ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:01:56.337657Z","caller":"traceutil/trace.go:171","msg":"trace[2014737231] linearizableReadLoop","detail":"{readStateIndex:22056; appliedIndex:22054; }","duration":"336.576104ms","start":"2026-04-14T07:01:56.001049Z","end":"2026-04-14T07:01:56.337625Z","steps":["trace[2014737231] 'read index received' (duration: 191.446922ms)","trace[2014737231] 'applied index is now lower than readState.Index' (duration: 145.127792ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:01:56.337787Z","caller":"traceutil/trace.go:171","msg":"trace[1985812610] transaction","detail":"{read_only:false; response_revision:21331; number_of_response:1; }","duration":"402.401185ms","start":"2026-04-14T07:01:55.935322Z","end":"2026-04-14T07:01:56.337723Z","steps":["trace[1985812610] 'process raft request' (duration: 364.5047ms)","trace[1985812610] 'compare' (duration: 37.38239ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:01:56.338005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:01:55.935294Z","time spent":"402.59611ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:01:56.33812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.609639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-04-14T07:01:56.338231Z","caller":"traceutil/trace.go:171","msg":"trace[765426105] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:21331; }","duration":"162.778813ms","start":"2026-04-14T07:01:56.175425Z","end":"2026-04-14T07:01:56.338204Z","steps":["trace[765426105] 'agreement among raft nodes before linearized reading' (duration: 162.459543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:01:56.338218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.084469ms","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-04-14T07:01:56.338298Z","caller":"traceutil/trace.go:171","msg":"trace[1004990506] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21331; }","duration":"337.271004ms","start":"2026-04-14T07:01:56.001005Z","end":"2026-04-14T07:01:56.338276Z","steps":["trace[1004990506] 'agreement among raft nodes before linearized reading' (duration: 336.80534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:01:56.338348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:01:56.000985Z","time spent":"337.353526ms","remote":"127.0.0.1:50034","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-04-14T07:03:15.00396Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19773} {"level":"info","ts":"2026-04-14T07:03:15.124618Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19773,"took":"118.233787ms","hash":245910755,"current-db-size-bytes":58019840,"current-db-size":"58 MB","current-db-size-in-use-bytes":30277632,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-14T07:03:15.124703Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":245910755,"revision":19773,"compact-revision":17263} {"level":"info","ts":"2026-04-14T07:04:08.848683Z","caller":"traceutil/trace.go:171","msg":"trace[455423395] linearizableReadLoop","detail":"{readStateIndex:23175; appliedIndex:23174; }","duration":"414.696364ms","start":"2026-04-14T07:04:08.433963Z","end":"2026-04-14T07:04:08.84866Z","steps":["trace[455423395] 'read index received' (duration: 7.788745ms)","trace[455423395] 'applied index is now lower than readState.Index' (duration: 406.906229ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:04:08.848797Z","caller":"traceutil/trace.go:171","msg":"trace[1163523164] transaction","detail":"{read_only:false; response_revision:22412; number_of_response:1; }","duration":"736.64275ms","start":"2026-04-14T07:04:08.112142Z","end":"2026-04-14T07:04:08.848785Z","steps":["trace[1163523164] 'process raft request' (duration: 736.334221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:04:08.849061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:04:08.112122Z","time spent":"736.738583ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:04:08.849034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.028103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-user\" ","response":"range_response_count:1 size:7301"} {"level":"info","ts":"2026-04-14T07:04:08.849157Z","caller":"traceutil/trace.go:171","msg":"trace[448940169] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-user; range_end:; response_count:1; response_revision:22412; }","duration":"415.216958ms","start":"2026-04-14T07:04:08.433909Z","end":"2026-04-14T07:04:08.849126Z","steps":["trace[448940169] 'agreement among raft nodes before linearized reading' (duration: 414.895839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:04:08.849206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:04:08.433888Z","time spent":"415.309391ms","remote":"127.0.0.1:50014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":7323,"request content":"key:\"/registry/jobs/openstack/octavia-ks-user\" "} {"level":"info","ts":"2026-04-14T07:05:54.380291Z","caller":"traceutil/trace.go:171","msg":"trace[1020317418] transaction","detail":"{read_only:false; response_revision:23808; number_of_response:1; }","duration":"183.042824ms","start":"2026-04-14T07:05:54.197219Z","end":"2026-04-14T07:05:54.380262Z","steps":["trace[1020317418] 'process raft request' (duration: 182.806448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:05:54.585625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.326393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-14T07:05:54.585814Z","caller":"traceutil/trace.go:171","msg":"trace[729824613] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:23809; }","duration":"104.592681ms","start":"2026-04-14T07:05:54.481194Z","end":"2026-04-14T07:05:54.585787Z","steps":["trace[729824613] 'count revisions from in-memory index tree' (duration: 104.215831ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:08:15.012863Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22033} {"level":"info","ts":"2026-04-14T07:08:15.12515Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22033,"took":"109.374307ms","hash":1326851774,"current-db-size-bytes":58019840,"current-db-size":"58 MB","current-db-size-in-use-bytes":42885120,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-04-14T07:08:15.125298Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1326851774,"revision":22033,"compact-revision":19773} {"level":"info","ts":"2026-04-14T07:09:33.957502Z","caller":"traceutil/trace.go:171","msg":"trace[1238070009] transaction","detail":"{read_only:false; response_revision:25955; number_of_response:1; }","duration":"430.125795ms","start":"2026-04-14T07:09:33.527301Z","end":"2026-04-14T07:09:33.957426Z","steps":["trace[1238070009] 'process raft request' (duration: 429.894758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:33.95769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:33.527269Z","time spent":"430.337011ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:09:34.559475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.326191ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-14T07:09:34.560565Z","caller":"traceutil/trace.go:171","msg":"trace[781453346] linearizableReadLoop","detail":"{readStateIndex:26810; appliedIndex:26807; }","duration":"780.891611ms","start":"2026-04-14T07:09:33.779625Z","end":"2026-04-14T07:09:34.560516Z","steps":["trace[781453346] 'read index received' (duration: 177.51783ms)","trace[781453346] 'applied index is now lower than readState.Index' (duration: 603.372541ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:09:34.56057Z","caller":"traceutil/trace.go:171","msg":"trace[1579702018] transaction","detail":"{read_only:false; response_revision:25956; number_of_response:1; }","duration":"787.824512ms","start":"2026-04-14T07:09:33.772697Z","end":"2026-04-14T07:09:34.560522Z","steps":["trace[1579702018] 'process raft request' (duration: 786.888625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:34.560752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:33.772658Z","time spent":"787.997807ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:09:34.700475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.806092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:09:34.700587Z","caller":"traceutil/trace.go:171","msg":"trace[1316894053] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25956; }","duration":"920.980738ms","start":"2026-04-14T07:09:33.779575Z","end":"2026-04-14T07:09:34.700556Z","steps":["trace[1316894053] 'agreement among raft nodes before linearized reading' (duration: 781.015105ms)","trace[1316894053] 'count revisions from in-memory index tree' (duration: 139.781067ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:09:34.700648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:33.779552Z","time spent":"921.07811ms","remote":"127.0.0.1:48670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":28,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true "} {"level":"warn","ts":"2026-04-14T07:09:34.701624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.896739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:09:34.701956Z","caller":"traceutil/trace.go:171","msg":"trace[1492111526] transaction","detail":"{read_only:false; response_revision:25957; number_of_response:1; }","duration":"666.671724ms","start":"2026-04-14T07:09:34.035213Z","end":"2026-04-14T07:09:34.701885Z","steps":["trace[1492111526] 'process raft request' (duration: 525.29408ms)","trace[1492111526] 'compare' (duration: 140.399465ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:09:34.702156Z","caller":"traceutil/trace.go:171","msg":"trace[724697944] transaction","detail":"{read_only:false; response_revision:25959; number_of_response:1; }","duration":"497.284649ms","start":"2026-04-14T07:09:34.204802Z","end":"2026-04-14T07:09:34.702087Z","steps":["trace[724697944] 'process raft request' (duration: 497.189867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:34.702216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:34.035197Z","time spent":"666.890549ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:09:34.702288Z","caller":"traceutil/trace.go:171","msg":"trace[1204975883] transaction","detail":"{read_only:false; response_revision:25960; number_of_response:1; }","duration":"343.680582ms","start":"2026-04-14T07:09:34.358577Z","end":"2026-04-14T07:09:34.702257Z","steps":["trace[1204975883] 'process raft request' (duration: 343.470326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:34.702342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:34.204762Z","time spent":"497.488425ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:09:34.702456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:34.358513Z","time spent":"343.856826ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:09:34.702721Z","caller":"traceutil/trace.go:171","msg":"trace[892904521] transaction","detail":"{read_only:false; response_revision:25958; number_of_response:1; }","duration":"572.381883ms","start":"2026-04-14T07:09:34.130323Z","end":"2026-04-14T07:09:34.702705Z","steps":["trace[892904521] 'process raft request' (duration: 571.509968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:34.702832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:34.130287Z","time spent":"572.484536ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:09:34.777937Z","caller":"traceutil/trace.go:171","msg":"trace[889506399] linearizableReadLoop","detail":"{readStateIndex:26815; appliedIndex:26810; }","duration":"217.269382ms","start":"2026-04-14T07:09:34.560628Z","end":"2026-04-14T07:09:34.777897Z","steps":["trace[889506399] 'read index received' (duration: 32.481531ms)","trace[889506399] 'applied index is now lower than readState.Index' (duration: 184.786401ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:09:34.777972Z","caller":"traceutil/trace.go:171","msg":"trace[1597170308] transaction","detail":"{read_only:false; response_revision:25961; number_of_response:1; }","duration":"225.113689ms","start":"2026-04-14T07:09:34.552811Z","end":"2026-04-14T07:09:34.777925Z","steps":["trace[1597170308] 'process raft request' (duration: 224.83115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:34.778118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.711489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-14T07:09:34.778204Z","caller":"traceutil/trace.go:171","msg":"trace[1843856253] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:25962; }","duration":"376.824272ms","start":"2026-04-14T07:09:34.401359Z","end":"2026-04-14T07:09:34.778183Z","steps":["trace[1843856253] 'agreement among raft nodes before linearized reading' (duration: 376.599205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:34.778241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.553085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:09:34.778273Z","caller":"traceutil/trace.go:171","msg":"trace[1483109350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25962; }","duration":"691.652927ms","start":"2026-04-14T07:09:34.08661Z","end":"2026-04-14T07:09:34.778263Z","steps":["trace[1483109350] 'agreement among raft nodes before linearized reading' (duration: 691.568645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:34.778271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:34.401288Z","time spent":"376.971606ms","remote":"127.0.0.1:50174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-14T07:09:34.778298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:34.086582Z","time spent":"691.708679ms","remote":"127.0.0.1:49746","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-04-14T07:09:34.778345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.511997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:09:34.778528Z","caller":"traceutil/trace.go:171","msg":"trace[290103011] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:25962; }","duration":"467.818276ms","start":"2026-04-14T07:09:34.310653Z","end":"2026-04-14T07:09:34.778471Z","steps":["trace[290103011] 'agreement among raft nodes before linearized reading' (duration: 467.380114ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:09:34.778616Z","caller":"traceutil/trace.go:171","msg":"trace[555426053] transaction","detail":"{read_only:false; response_revision:25962; number_of_response:1; }","duration":"193.855783ms","start":"2026-04-14T07:09:34.584747Z","end":"2026-04-14T07:09:34.778603Z","steps":["trace[555426053] 'process raft request' (duration: 193.096561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:34.778679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.034409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-14T07:09:34.778671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:34.310623Z","time spent":"468.001252ms","remote":"127.0.0.1:48488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true "} {"level":"info","ts":"2026-04-14T07:09:34.778714Z","caller":"traceutil/trace.go:171","msg":"trace[1792239076] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25962; }","duration":"893.118741ms","start":"2026-04-14T07:09:33.885585Z","end":"2026-04-14T07:09:34.778704Z","steps":["trace[1792239076] 'agreement among raft nodes before linearized reading' (duration: 893.053159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:34.778742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:33.885563Z","time spent":"893.172533ms","remote":"127.0.0.1:48528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-14T07:09:34.779117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.665063ms","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-04-14T07:09:34.779171Z","caller":"traceutil/trace.go:171","msg":"trace[1201059381] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25962; }","duration":"274.837788ms","start":"2026-04-14T07:09:34.504323Z","end":"2026-04-14T07:09:34.779161Z","steps":["trace[1201059381] 'agreement among raft nodes before linearized reading' (duration: 274.628832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:34.779274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.201408ms","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-04-14T07:09:34.779311Z","caller":"traceutil/trace.go:171","msg":"trace[1996576219] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25962; }","duration":"257.395333ms","start":"2026-04-14T07:09:34.521908Z","end":"2026-04-14T07:09:34.779303Z","steps":["trace[1996576219] 'agreement among raft nodes before linearized reading' (duration: 257.263119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:34.779444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.099163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:09:34.779485Z","caller":"traceutil/trace.go:171","msg":"trace[896329959] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:25962; }","duration":"403.187635ms","start":"2026-04-14T07:09:34.376286Z","end":"2026-04-14T07:09:34.779474Z","steps":["trace[896329959] 'agreement among raft nodes before linearized reading' (duration: 403.120333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:34.779507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:34.37626Z","time spent":"403.241877ms","remote":"127.0.0.1:55560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true "} {"level":"info","ts":"2026-04-14T07:09:35.014135Z","caller":"traceutil/trace.go:171","msg":"trace[2077278462] transaction","detail":"{read_only:false; response_revision:25963; number_of_response:1; }","duration":"421.063292ms","start":"2026-04-14T07:09:34.593046Z","end":"2026-04-14T07:09:35.014109Z","steps":["trace[2077278462] 'process raft request' (duration: 420.808744ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:09:35.014172Z","caller":"traceutil/trace.go:171","msg":"trace[1875284336] linearizableReadLoop","detail":"{readStateIndex:26817; appliedIndex:26816; }","duration":"234.299574ms","start":"2026-04-14T07:09:34.779826Z","end":"2026-04-14T07:09:35.014126Z","steps":["trace[1875284336] 'read index received' (duration: 234.248573ms)","trace[1875284336] 'applied index is now lower than readState.Index' (duration: 46.691µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:09:35.014314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:34.592928Z","time spent":"421.294078ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:09:35.01443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.555851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:09:35.014614Z","caller":"traceutil/trace.go:171","msg":"trace[410879002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25963; }","duration":"234.772878ms","start":"2026-04-14T07:09:34.779808Z","end":"2026-04-14T07:09:35.014581Z","steps":["trace[410879002] 'agreement among raft nodes before linearized reading' (duration: 234.48693ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:09:35.15068Z","caller":"traceutil/trace.go:171","msg":"trace[1054976320] linearizableReadLoop","detail":"{readStateIndex:26819; appliedIndex:26817; }","duration":"136.369629ms","start":"2026-04-14T07:09:35.014281Z","end":"2026-04-14T07:09:35.150651Z","steps":["trace[1054976320] 'read index received' (duration: 136.018519ms)","trace[1054976320] 'applied index is now lower than readState.Index' (duration: 346.39µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:09:35.150939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.247366ms","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-04-14T07:09:35.150973Z","caller":"traceutil/trace.go:171","msg":"trace[448933075] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25965; }","duration":"365.335839ms","start":"2026-04-14T07:09:34.785628Z","end":"2026-04-14T07:09:35.150964Z","steps":["trace[448933075] 'agreement among raft nodes before linearized reading' (duration: 365.132843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:35.151002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:34.785619Z","time spent":"365.3758ms","remote":"127.0.0.1:50034","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-04-14T07:09:35.151284Z","caller":"traceutil/trace.go:171","msg":"trace[136282899] transaction","detail":"{read_only:false; response_revision:25964; number_of_response:1; }","duration":"366.502043ms","start":"2026-04-14T07:09:34.784772Z","end":"2026-04-14T07:09:35.151274Z","steps":["trace[136282899] 'process raft request' (duration: 365.606527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:35.15138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:34.78475Z","time spent":"366.561235ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:09:35.15148Z","caller":"traceutil/trace.go:171","msg":"trace[431555685] transaction","detail":"{read_only:false; response_revision:25965; number_of_response:1; }","duration":"366.555624ms","start":"2026-04-14T07:09:34.784918Z","end":"2026-04-14T07:09:35.151473Z","steps":["trace[431555685] 'process raft request' (duration: 365.679839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:35.151522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:34.784874Z","time spent":"366.627356ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:09:35.151625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.910195ms","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-04-14T07:09:35.151654Z","caller":"traceutil/trace.go:171","msg":"trace[1499813259] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25965; }","duration":"154.992567ms","start":"2026-04-14T07:09:34.996653Z","end":"2026-04-14T07:09:35.151645Z","steps":["trace[1499813259] 'agreement among raft nodes before linearized reading' (duration: 154.943636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:35.151902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.683301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-04-14T07:09:35.151939Z","caller":"traceutil/trace.go:171","msg":"trace[170510810] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:25965; }","duration":"181.762083ms","start":"2026-04-14T07:09:34.970161Z","end":"2026-04-14T07:09:35.151923Z","steps":["trace[170510810] 'agreement among raft nodes before linearized reading' (duration: 181.590328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:35.15228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.776951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-14T07:09:35.15231Z","caller":"traceutil/trace.go:171","msg":"trace[1404318223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25965; }","duration":"365.844473ms","start":"2026-04-14T07:09:34.786457Z","end":"2026-04-14T07:09:35.152302Z","steps":["trace[1404318223] 'agreement among raft nodes before linearized reading' (duration: 365.75637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:35.152329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:34.786439Z","time spent":"365.885194ms","remote":"127.0.0.1:50034","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-04-14T07:09:35.152339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.017406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-14T07:09:35.152463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.282555ms","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-04-14T07:09:35.152471Z","caller":"traceutil/trace.go:171","msg":"trace[1288582852] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:25965; }","duration":"310.203652ms","start":"2026-04-14T07:09:34.842235Z","end":"2026-04-14T07:09:35.152439Z","steps":["trace[1288582852] 'agreement among raft nodes before linearized reading' (duration: 309.871442ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:09:35.152494Z","caller":"traceutil/trace.go:171","msg":"trace[266060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25965; }","duration":"366.368268ms","start":"2026-04-14T07:09:34.78612Z","end":"2026-04-14T07:09:35.152488Z","steps":["trace[266060] 'agreement among raft nodes before linearized reading' (duration: 366.298225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:35.15252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:34.786083Z","time spent":"366.432181ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-14T07:09:35.15253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:34.842214Z","time spent":"310.302875ms","remote":"127.0.0.1:50152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":99,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-04-14T07:09:35.30208Z","caller":"traceutil/trace.go:171","msg":"trace[1916684961] transaction","detail":"{read_only:false; response_revision:25966; number_of_response:1; }","duration":"145.695088ms","start":"2026-04-14T07:09:35.156356Z","end":"2026-04-14T07:09:35.302051Z","steps":["trace[1916684961] 'process raft request' (duration: 134.504705ms)","trace[1916684961] 'compare' (duration: 11.034119ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:09:35.380339Z","caller":"traceutil/trace.go:171","msg":"trace[1696396793] linearizableReadLoop","detail":"{readStateIndex:26822; appliedIndex:26819; }","duration":"220.465624ms","start":"2026-04-14T07:09:35.159846Z","end":"2026-04-14T07:09:35.380312Z","steps":["trace[1696396793] 'read index received' (duration: 131.040924ms)","trace[1696396793] 'applied index is now lower than readState.Index' (duration: 89.42372ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:09:35.380672Z","caller":"traceutil/trace.go:171","msg":"trace[553630945] transaction","detail":"{read_only:false; response_revision:25967; number_of_response:1; }","duration":"224.13267ms","start":"2026-04-14T07:09:35.156525Z","end":"2026-04-14T07:09:35.380658Z","steps":["trace[553630945] 'process raft request' (duration: 223.588314ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:09:35.380861Z","caller":"traceutil/trace.go:171","msg":"trace[1279983104] transaction","detail":"{read_only:false; response_revision:25968; number_of_response:1; }","duration":"222.454731ms","start":"2026-04-14T07:09:35.158397Z","end":"2026-04-14T07:09:35.380852Z","steps":["trace[1279983104] 'process raft request' (duration: 221.869744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:35.381034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.169954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:09:35.381071Z","caller":"traceutil/trace.go:171","msg":"trace[1201360821] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25968; }","duration":"221.250346ms","start":"2026-04-14T07:09:35.159809Z","end":"2026-04-14T07:09:35.381059Z","steps":["trace[1201360821] 'agreement among raft nodes before linearized reading' (duration: 221.157003ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:09:35.701753Z","caller":"traceutil/trace.go:171","msg":"trace[2082858830] transaction","detail":"{read_only:false; response_revision:25969; number_of_response:1; }","duration":"200.507106ms","start":"2026-04-14T07:09:35.501222Z","end":"2026-04-14T07:09:35.701729Z","steps":["trace[2082858830] 'process raft request' (duration: 200.318469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:36.451571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.612309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-14T07:09:36.451629Z","caller":"traceutil/trace.go:171","msg":"trace[245863830] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:25970; }","duration":"162.714902ms","start":"2026-04-14T07:09:36.288898Z","end":"2026-04-14T07:09:36.451613Z","steps":["trace[245863830] 'count revisions from in-memory index tree' (duration: 162.540277ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:09:36.793113Z","caller":"traceutil/trace.go:171","msg":"trace[1768113262] transaction","detail":"{read_only:false; response_revision:25971; number_of_response:1; }","duration":"227.025034ms","start":"2026-04-14T07:09:36.56606Z","end":"2026-04-14T07:09:36.793085Z","steps":["trace[1768113262] 'process raft request' (duration: 226.862799ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:09:43.616571Z","caller":"traceutil/trace.go:171","msg":"trace[1879257345] transaction","detail":"{read_only:false; response_revision:26029; number_of_response:1; }","duration":"126.796571ms","start":"2026-04-14T07:09:43.489746Z","end":"2026-04-14T07:09:43.616542Z","steps":["trace[1879257345] 'process raft request' (duration: 126.583865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:43.958349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.103931ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:09:43.95889Z","caller":"traceutil/trace.go:171","msg":"trace[1011959] transaction","detail":"{read_only:false; response_revision:26030; number_of_response:1; }","duration":"469.03534ms","start":"2026-04-14T07:09:43.489815Z","end":"2026-04-14T07:09:43.95885Z","steps":["trace[1011959] 'process raft request' (duration: 284.327842ms)","trace[1011959] 'compare' (duration: 183.933616ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:09:43.959058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:43.489788Z","time spent":"469.189785ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:09:44.229243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.784611ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-14T07:09:44.229742Z","caller":"traceutil/trace.go:171","msg":"trace[2063642869] transaction","detail":"{read_only:false; response_revision:26031; number_of_response:1; }","duration":"495.540408ms","start":"2026-04-14T07:09:43.734173Z","end":"2026-04-14T07:09:44.229713Z","steps":["trace[2063642869] 'process raft request' (duration: 495.168178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:44.229893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:43.734147Z","time spent":"495.665122ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:09:44.295132Z","caller":"traceutil/trace.go:171","msg":"trace[773294250] linearizableReadLoop","detail":"{readStateIndex:26888; appliedIndex:26887; }","duration":"157.83078ms","start":"2026-04-14T07:09:44.137275Z","end":"2026-04-14T07:09:44.295106Z","steps":["trace[773294250] 'read index received' (duration: 154.059081ms)","trace[773294250] 'applied index is now lower than readState.Index' (duration: 3.770549ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:09:44.295936Z","caller":"traceutil/trace.go:171","msg":"trace[1469401633] transaction","detail":"{read_only:false; response_revision:26032; number_of_response:1; }","duration":"228.592088ms","start":"2026-04-14T07:09:44.067263Z","end":"2026-04-14T07:09:44.295855Z","steps":["trace[1469401633] 'process raft request' (duration: 224.040197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:44.296306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.005294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-04-14T07:09:44.296367Z","caller":"traceutil/trace.go:171","msg":"trace[363552733] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:26032; }","duration":"159.120317ms","start":"2026-04-14T07:09:44.137231Z","end":"2026-04-14T07:09:44.296352Z","steps":["trace[363552733] 'agreement among raft nodes before linearized reading' (duration: 157.988494ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:09:44.870074Z","caller":"traceutil/trace.go:171","msg":"trace[1987921195] transaction","detail":"{read_only:false; response_revision:26033; number_of_response:1; }","duration":"100.50349ms","start":"2026-04-14T07:09:44.769545Z","end":"2026-04-14T07:09:44.870048Z","steps":["trace[1987921195] 'process raft request' (duration: 100.371306ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:09:45.107067Z","caller":"traceutil/trace.go:171","msg":"trace[243158323] transaction","detail":"{read_only:false; response_revision:26034; number_of_response:1; }","duration":"311.058807ms","start":"2026-04-14T07:09:44.795926Z","end":"2026-04-14T07:09:45.106985Z","steps":["trace[243158323] 'process raft request' (duration: 310.538782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:45.107543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:44.795913Z","time spent":"311.490809ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:09:45.108029Z","caller":"traceutil/trace.go:171","msg":"trace[878292496] transaction","detail":"{read_only:false; response_revision:26037; number_of_response:1; }","duration":"279.313887ms","start":"2026-04-14T07:09:44.828688Z","end":"2026-04-14T07:09:45.108001Z","steps":["trace[878292496] 'process raft request' (duration: 279.090451ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:09:45.107997Z","caller":"traceutil/trace.go:171","msg":"trace[135166742] linearizableReadLoop","detail":"{readStateIndex:26895; appliedIndex:26889; }","duration":"189.103325ms","start":"2026-04-14T07:09:44.918864Z","end":"2026-04-14T07:09:45.107967Z","steps":["trace[135166742] 'read index received' (duration: 187.558521ms)","trace[135166742] 'applied index is now lower than readState.Index' (duration: 1.543334ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:09:45.10804Z","caller":"traceutil/trace.go:171","msg":"trace[1454736221] transaction","detail":"{read_only:false; response_revision:26038; number_of_response:1; }","duration":"279.096191ms","start":"2026-04-14T07:09:44.828905Z","end":"2026-04-14T07:09:45.108002Z","steps":["trace[1454736221] 'process raft request' (duration: 278.932846ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:09:45.108086Z","caller":"traceutil/trace.go:171","msg":"trace[1678872862] transaction","detail":"{read_only:false; response_revision:26039; number_of_response:1; }","duration":"278.772361ms","start":"2026-04-14T07:09:44.829273Z","end":"2026-04-14T07:09:45.108045Z","steps":["trace[1678872862] 'process raft request' (duration: 278.620607ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:09:45.107974Z","caller":"traceutil/trace.go:171","msg":"trace[1662949678] transaction","detail":"{read_only:false; response_revision:26035; number_of_response:1; }","duration":"281.673675ms","start":"2026-04-14T07:09:44.826258Z","end":"2026-04-14T07:09:45.107932Z","steps":["trace[1662949678] 'process raft request' (duration: 280.629855ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:09:45.108162Z","caller":"traceutil/trace.go:171","msg":"trace[595695752] transaction","detail":"{read_only:false; response_revision:26036; number_of_response:1; }","duration":"279.800522ms","start":"2026-04-14T07:09:44.828256Z","end":"2026-04-14T07:09:45.108056Z","steps":["trace[595695752] 'process raft request' (duration: 279.419251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:45.108371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.481436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-14T07:09:45.108972Z","caller":"traceutil/trace.go:171","msg":"trace[366417475] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:26039; }","duration":"190.143726ms","start":"2026-04-14T07:09:44.918797Z","end":"2026-04-14T07:09:45.108941Z","steps":["trace[366417475] 'agreement among raft nodes before linearized reading' (duration: 189.346082ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:09:45.120254Z","caller":"traceutil/trace.go:171","msg":"trace[1952318226] transaction","detail":"{read_only:false; response_revision:26040; number_of_response:1; }","duration":"200.336922ms","start":"2026-04-14T07:09:44.919896Z","end":"2026-04-14T07:09:45.120233Z","steps":["trace[1952318226] 'process raft request' (duration: 200.175097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:45.42776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.822227ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:09:45.427837Z","caller":"traceutil/trace.go:171","msg":"trace[1363383602] linearizableReadLoop","detail":"{readStateIndex:26898; appliedIndex:26897; }","duration":"224.086248ms","start":"2026-04-14T07:09:45.203738Z","end":"2026-04-14T07:09:45.427824Z","steps":["trace[1363383602] 'read index received' (duration: 63.014725ms)","trace[1363383602] 'applied index is now lower than readState.Index' (duration: 161.070413ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:09:45.428001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.268354ms","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-04-14T07:09:45.427958Z","caller":"traceutil/trace.go:171","msg":"trace[1671574823] transaction","detail":"{read_only:false; response_revision:26042; number_of_response:1; }","duration":"224.770378ms","start":"2026-04-14T07:09:45.203145Z","end":"2026-04-14T07:09:45.427916Z","steps":["trace[1671574823] 'process raft request' (duration: 63.695534ms)","trace[1671574823] 'compare' (duration: 160.607301ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:09:45.428023Z","caller":"traceutil/trace.go:171","msg":"trace[1246934364] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26042; }","duration":"224.350317ms","start":"2026-04-14T07:09:45.20366Z","end":"2026-04-14T07:09:45.42801Z","steps":["trace[1246934364] 'agreement among raft nodes before linearized reading' (duration: 224.236273ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:09:45.895445Z","caller":"traceutil/trace.go:171","msg":"trace[282172687] transaction","detail":"{read_only:false; response_revision:26045; number_of_response:1; }","duration":"269.881295ms","start":"2026-04-14T07:09:45.625521Z","end":"2026-04-14T07:09:45.895402Z","steps":["trace[282172687] 'process raft request' (duration: 269.575896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:46.16776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.637214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:09:46.167855Z","caller":"traceutil/trace.go:171","msg":"trace[1559419401] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:26045; }","duration":"129.774968ms","start":"2026-04-14T07:09:46.03806Z","end":"2026-04-14T07:09:46.167835Z","steps":["trace[1559419401] 'count revisions from in-memory index tree' (duration: 129.567522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:46.16793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.018578ms","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-04-14T07:09:46.168008Z","caller":"traceutil/trace.go:171","msg":"trace[769615756] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26045; }","duration":"204.153092ms","start":"2026-04-14T07:09:45.963835Z","end":"2026-04-14T07:09:46.167988Z","steps":["trace[769615756] 'range keys from in-memory index tree' (duration: 203.811692ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:09:46.309691Z","caller":"traceutil/trace.go:171","msg":"trace[949165660] transaction","detail":"{read_only:false; response_revision:26046; number_of_response:1; }","duration":"137.562483ms","start":"2026-04-14T07:09:46.172097Z","end":"2026-04-14T07:09:46.309659Z","steps":["trace[949165660] 'process raft request' (duration: 137.374818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:46.901407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.865909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:09:46.901487Z","caller":"traceutil/trace.go:171","msg":"trace[1544285266] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:26048; }","duration":"388.998343ms","start":"2026-04-14T07:09:46.512471Z","end":"2026-04-14T07:09:46.901469Z","steps":["trace[1544285266] 'count revisions from in-memory index tree' (duration: 388.749686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:46.901529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:46.512451Z","time spent":"389.067085ms","remote":"127.0.0.1:50088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-14T07:09:46.901647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.788598ms","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-04-14T07:09:46.901721Z","caller":"traceutil/trace.go:171","msg":"trace[1241343534] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:26048; }","duration":"301.980424ms","start":"2026-04-14T07:09:46.599719Z","end":"2026-04-14T07:09:46.9017Z","steps":["trace[1241343534] 'range keys from in-memory index tree' (duration: 301.592182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:46.90176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:46.599699Z","time spent":"302.052156ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":583,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-04-14T07:09:47.079225Z","caller":"traceutil/trace.go:171","msg":"trace[1812203339] transaction","detail":"{read_only:false; response_revision:26050; number_of_response:1; }","duration":"155.09365ms","start":"2026-04-14T07:09:46.924104Z","end":"2026-04-14T07:09:47.079198Z","steps":["trace[1812203339] 'process raft request' (duration: 151.662261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:53.974977Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938290813757951378,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-14T07:09:54.035041Z","caller":"traceutil/trace.go:171","msg":"trace[204872680] transaction","detail":"{read_only:false; response_revision:26103; number_of_response:1; }","duration":"570.747707ms","start":"2026-04-14T07:09:53.464218Z","end":"2026-04-14T07:09:54.034966Z","steps":["trace[204872680] 'process raft request' (duration: 570.641254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:54.035147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:53.464189Z","time spent":"570.900971ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:09:54.554598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.440055ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-14T07:09:54.554781Z","caller":"traceutil/trace.go:171","msg":"trace[567203121] linearizableReadLoop","detail":"{readStateIndex:26961; appliedIndex:26959; }","duration":"1.080707241s","start":"2026-04-14T07:09:53.474054Z","end":"2026-04-14T07:09:54.554762Z","steps":["trace[567203121] 'read index received' (duration: 560.732866ms)","trace[567203121] 'applied index is now lower than readState.Index' (duration: 519.969645ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:09:54.555118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.081057333s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-14T07:09:54.555179Z","caller":"traceutil/trace.go:171","msg":"trace[825781868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26103; }","duration":"1.081163426s","start":"2026-04-14T07:09:53.474001Z","end":"2026-04-14T07:09:54.555164Z","steps":["trace[825781868] 'agreement among raft nodes before linearized reading' (duration: 1.080993441s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:54.555205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:53.473981Z","time spent":"1.081219147s","remote":"127.0.0.1:50034","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":"info","ts":"2026-04-14T07:09:54.590289Z","caller":"traceutil/trace.go:171","msg":"trace[825024857] transaction","detail":"{read_only:false; response_revision:26104; number_of_response:1; }","duration":"249.413011ms","start":"2026-04-14T07:09:54.340839Z","end":"2026-04-14T07:09:54.590252Z","steps":["trace[825024857] 'process raft request' (duration: 249.259967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:54.591445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.114643085s","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-04-14T07:09:54.591501Z","caller":"traceutil/trace.go:171","msg":"trace[169768926] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26106; }","duration":"1.114725308s","start":"2026-04-14T07:09:53.476759Z","end":"2026-04-14T07:09:54.591485Z","steps":["trace[169768926] 'agreement among raft nodes before linearized reading' (duration: 1.114548911s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:54.591535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:53.476751Z","time spent":"1.114775309s","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-14T07:09:54.591844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.997465ms","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-04-14T07:09:54.591914Z","caller":"traceutil/trace.go:171","msg":"trace[1555810721] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26106; }","duration":"655.092238ms","start":"2026-04-14T07:09:53.936805Z","end":"2026-04-14T07:09:54.591898Z","steps":["trace[1555810721] 'agreement among raft nodes before linearized reading' (duration: 654.953604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:54.591977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:53.936789Z","time spent":"655.173431ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-14T07:09:54.591854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.331033ms","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-04-14T07:09:54.592064Z","caller":"traceutil/trace.go:171","msg":"trace[306972247] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26106; }","duration":"246.58467ms","start":"2026-04-14T07:09:54.345462Z","end":"2026-04-14T07:09:54.592047Z","steps":["trace[306972247] 'agreement among raft nodes before linearized reading' (duration: 246.288191ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:09:54.591983Z","caller":"traceutil/trace.go:171","msg":"trace[1888460391] transaction","detail":"{read_only:false; response_revision:26105; number_of_response:1; }","duration":"250.641508ms","start":"2026-04-14T07:09:54.341212Z","end":"2026-04-14T07:09:54.591853Z","steps":["trace[1888460391] 'process raft request' (duration: 249.910916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:55.054851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.842198ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:09:55.054946Z","caller":"traceutil/trace.go:171","msg":"trace[1924964197] transaction","detail":"{read_only:false; response_revision:26107; number_of_response:1; }","duration":"458.468845ms","start":"2026-04-14T07:09:54.596457Z","end":"2026-04-14T07:09:55.054926Z","steps":["trace[1924964197] 'process raft request' (duration: 174.463012ms)","trace[1924964197] 'compare' (duration: 283.56301ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:09:55.055003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:54.596442Z","time spent":"458.532957ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:09:55.056178Z","caller":"traceutil/trace.go:171","msg":"trace[1354581244] linearizableReadLoop","detail":"{readStateIndex:26966; appliedIndex:26964; }","duration":"459.03264ms","start":"2026-04-14T07:09:54.597131Z","end":"2026-04-14T07:09:55.056163Z","steps":["trace[1354581244] 'read index received' (duration: 173.802963ms)","trace[1354581244] 'applied index is now lower than readState.Index' (duration: 285.228897ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:09:55.056229Z","caller":"traceutil/trace.go:171","msg":"trace[1056006720] transaction","detail":"{read_only:false; response_revision:26109; number_of_response:1; }","duration":"458.983659ms","start":"2026-04-14T07:09:54.597231Z","end":"2026-04-14T07:09:55.056214Z","steps":["trace[1056006720] 'process raft request' (duration: 458.902107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:55.05631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:54.597219Z","time spent":"459.054021ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:09:55.056318Z","caller":"traceutil/trace.go:171","msg":"trace[1467277172] transaction","detail":"{read_only:false; response_revision:26108; number_of_response:1; }","duration":"459.386372ms","start":"2026-04-14T07:09:54.596863Z","end":"2026-04-14T07:09:55.056249Z","steps":["trace[1467277172] 'process raft request' (duration: 459.172206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:55.056483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.33075ms","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-04-14T07:09:55.056575Z","caller":"traceutil/trace.go:171","msg":"trace[1211493640] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:26109; }","duration":"296.447484ms","start":"2026-04-14T07:09:54.760111Z","end":"2026-04-14T07:09:55.056558Z","steps":["trace[1211493640] 'agreement among raft nodes before linearized reading' (duration: 296.32796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:55.056446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.273368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:09:55.056727Z","caller":"traceutil/trace.go:171","msg":"trace[453237162] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:26109; }","duration":"459.563506ms","start":"2026-04-14T07:09:54.597113Z","end":"2026-04-14T07:09:55.056677Z","steps":["trace[453237162] 'agreement among raft nodes before linearized reading' (duration: 459.187374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:55.056617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:54.596835Z","time spent":"459.600728ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:09:55.056964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:54.597105Z","time spent":"459.845325ms","remote":"127.0.0.1:33230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-14T07:09:55.855682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.692381ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:09:55.855755Z","caller":"traceutil/trace.go:171","msg":"trace[945706713] linearizableReadLoop","detail":"{readStateIndex:26968; appliedIndex:26967; }","duration":"718.213637ms","start":"2026-04-14T07:09:55.137529Z","end":"2026-04-14T07:09:55.855743Z","steps":["trace[945706713] 'read index received' (duration: 201.413533ms)","trace[945706713] 'applied index is now lower than readState.Index' (duration: 516.799334ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:09:55.855783Z","caller":"traceutil/trace.go:171","msg":"trace[1630170732] transaction","detail":"{read_only:false; response_revision:26110; number_of_response:1; }","duration":"735.193809ms","start":"2026-04-14T07:09:55.120563Z","end":"2026-04-14T07:09:55.855757Z","steps":["trace[1630170732] 'process raft request' (duration: 218.353773ms)","trace[1630170732] 'compare' (duration: 515.384003ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:09:55.855896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.355421ms","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":"warn","ts":"2026-04-14T07:09:55.855953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:55.12053Z","time spent":"735.351023ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:09:55.855975Z","caller":"traceutil/trace.go:171","msg":"trace[705124102] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:26110; }","duration":"718.449763ms","start":"2026-04-14T07:09:55.137507Z","end":"2026-04-14T07:09:55.855957Z","steps":["trace[705124102] 'agreement among raft nodes before linearized reading' (duration: 718.290069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:55.856022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:55.137435Z","time spent":"718.573717ms","remote":"127.0.0.1:49886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-04-14T07:09:55.879766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.916127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-04-14T07:09:55.879836Z","caller":"traceutil/trace.go:171","msg":"trace[519231147] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:26117; }","duration":"718.033871ms","start":"2026-04-14T07:09:55.161787Z","end":"2026-04-14T07:09:55.879821Z","steps":["trace[519231147] 'agreement among raft nodes before linearized reading' (duration: 717.732732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:55.87987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:55.161777Z","time spent":"718.083872ms","remote":"127.0.0.1:49838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":232,"response size":31,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-04-14T07:09:55.880171Z","caller":"traceutil/trace.go:171","msg":"trace[394552005] transaction","detail":"{read_only:false; response_revision:26111; number_of_response:1; }","duration":"733.699915ms","start":"2026-04-14T07:09:55.146464Z","end":"2026-04-14T07:09:55.880164Z","steps":["trace[394552005] 'process raft request' (duration: 732.744006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:55.880245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:55.146438Z","time spent":"733.763997ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:09:55.880335Z","caller":"traceutil/trace.go:171","msg":"trace[1990304819] transaction","detail":"{read_only:false; response_revision:26112; number_of_response:1; }","duration":"732.654524ms","start":"2026-04-14T07:09:55.147674Z","end":"2026-04-14T07:09:55.880328Z","steps":["trace[1990304819] 'process raft request' (duration: 731.656675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:55.880373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:55.147656Z","time spent":"732.698496ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:09:55.88045Z","caller":"traceutil/trace.go:171","msg":"trace[828142558] transaction","detail":"{read_only:false; response_revision:26113; number_of_response:1; }","duration":"724.735186ms","start":"2026-04-14T07:09:55.155709Z","end":"2026-04-14T07:09:55.880444Z","steps":["trace[828142558] 'process raft request' (duration: 723.657684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:55.880486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:55.155678Z","time spent":"724.789966ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:09:55.880561Z","caller":"traceutil/trace.go:171","msg":"trace[874816763] transaction","detail":"{read_only:false; response_revision:26114; number_of_response:1; }","duration":"724.128058ms","start":"2026-04-14T07:09:55.156426Z","end":"2026-04-14T07:09:55.880554Z","steps":["trace[874816763] 'process raft request' (duration: 722.968124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:55.880602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:55.156415Z","time spent":"724.164108ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:09:55.880676Z","caller":"traceutil/trace.go:171","msg":"trace[1809659260] transaction","detail":"{read_only:false; response_revision:26115; number_of_response:1; }","duration":"719.799582ms","start":"2026-04-14T07:09:55.160871Z","end":"2026-04-14T07:09:55.88067Z","steps":["trace[1809659260] 'process raft request' (duration: 718.561656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:55.880723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:55.160857Z","time spent":"719.836543ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:09:55.880815Z","caller":"traceutil/trace.go:171","msg":"trace[1974433312] transaction","detail":"{read_only:false; response_revision:26116; number_of_response:1; }","duration":"719.152323ms","start":"2026-04-14T07:09:55.161656Z","end":"2026-04-14T07:09:55.880808Z","steps":["trace[1974433312] 'process raft request' (duration: 717.803723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:55.880856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:55.161644Z","time spent":"719.191235ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:09:55.880955Z","caller":"traceutil/trace.go:171","msg":"trace[1601822831] transaction","detail":"{read_only:false; response_revision:26117; number_of_response:1; }","duration":"715.228459ms","start":"2026-04-14T07:09:55.165717Z","end":"2026-04-14T07:09:55.880945Z","steps":["trace[1601822831] 'process raft request' (duration: 713.767236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:55.880989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:55.165707Z","time spent":"715.264601ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:09:55.881096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.373455ms","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-04-14T07:09:55.881118Z","caller":"traceutil/trace.go:171","msg":"trace[1496367639] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26117; }","duration":"130.445728ms","start":"2026-04-14T07:09:55.750667Z","end":"2026-04-14T07:09:55.881113Z","steps":["trace[1496367639] 'agreement among raft nodes before linearized reading' (duration: 130.364435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:55.881623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.170879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418229"} {"level":"info","ts":"2026-04-14T07:09:55.881719Z","caller":"traceutil/trace.go:171","msg":"trace[176981086] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:26117; }","duration":"455.328663ms","start":"2026-04-14T07:09:55.426365Z","end":"2026-04-14T07:09:55.881693Z","steps":["trace[176981086] 'agreement among raft nodes before linearized reading' (duration: 454.990774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:55.881784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:55.426335Z","time spent":"455.436537ms","remote":"127.0.0.1:55498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":418251,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"warn","ts":"2026-04-14T07:09:55.882227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.147116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:09:55.882265Z","caller":"traceutil/trace.go:171","msg":"trace[174212702] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:26117; }","duration":"678.230498ms","start":"2026-04-14T07:09:55.204025Z","end":"2026-04-14T07:09:55.882256Z","steps":["trace[174212702] 'agreement among raft nodes before linearized reading' (duration: 678.163436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:55.882285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:55.204006Z","time spent":"678.273449ms","remote":"127.0.0.1:49344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true "} {"level":"warn","ts":"2026-04-14T07:09:55.882443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.051697ms","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-04-14T07:09:55.882517Z","caller":"traceutil/trace.go:171","msg":"trace[2320255] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:26117; }","duration":"712.137899ms","start":"2026-04-14T07:09:55.170361Z","end":"2026-04-14T07:09:55.882498Z","steps":["trace[2320255] 'agreement among raft nodes before linearized reading' (duration: 711.915423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:55.882573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:55.17034Z","time spent":"712.224732ms","remote":"127.0.0.1:49914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1880,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" "} {"level":"warn","ts":"2026-04-14T07:09:55.882812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.294639ms","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-04-14T07:09:55.882849Z","caller":"traceutil/trace.go:171","msg":"trace[246742781] 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:26117; }","duration":"421.348221ms","start":"2026-04-14T07:09:55.46149Z","end":"2026-04-14T07:09:55.882838Z","steps":["trace[246742781] 'agreement among raft nodes before linearized reading' (duration: 419.715893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:09:55.88287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:09:55.461482Z","time spent":"421.382902ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-04-14T07:09:57.534531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.737023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-931ca120-3e1e-482b-a17f-8d14800a17e3\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-04-14T07:09:57.534715Z","caller":"traceutil/trace.go:171","msg":"trace[403403905] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-931ca120-3e1e-482b-a17f-8d14800a17e3; range_end:; response_count:1; response_revision:26182; }","duration":"209.984501ms","start":"2026-04-14T07:09:57.324693Z","end":"2026-04-14T07:09:57.534677Z","steps":["trace[403403905] 'range keys from in-memory index tree' (duration: 209.471276ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:00.026083Z","caller":"traceutil/trace.go:171","msg":"trace[776857874] transaction","detail":"{read_only:false; response_revision:26205; number_of_response:1; }","duration":"144.770512ms","start":"2026-04-14T07:09:59.88128Z","end":"2026-04-14T07:10:00.026051Z","steps":["trace[776857874] 'process raft request' (duration: 144.504653ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:00.092385Z","caller":"traceutil/trace.go:171","msg":"trace[1459981182] transaction","detail":"{read_only:false; response_revision:26211; number_of_response:1; }","duration":"195.404248ms","start":"2026-04-14T07:09:59.896961Z","end":"2026-04-14T07:10:00.092365Z","steps":["trace[1459981182] 'process raft request' (duration: 195.195162ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:00.092406Z","caller":"traceutil/trace.go:171","msg":"trace[511539990] transaction","detail":"{read_only:false; response_revision:26210; number_of_response:1; }","duration":"195.700017ms","start":"2026-04-14T07:09:59.896668Z","end":"2026-04-14T07:10:00.092368Z","steps":["trace[511539990] 'process raft request' (duration: 195.46378ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:00.092626Z","caller":"traceutil/trace.go:171","msg":"trace[214404270] transaction","detail":"{read_only:false; response_revision:26207; number_of_response:1; }","duration":"196.579752ms","start":"2026-04-14T07:09:59.89601Z","end":"2026-04-14T07:10:00.09259Z","steps":["trace[214404270] 'process raft request' (duration: 196.041366ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:00.092685Z","caller":"traceutil/trace.go:171","msg":"trace[644297796] transaction","detail":"{read_only:false; response_revision:26208; number_of_response:1; }","duration":"196.617163ms","start":"2026-04-14T07:09:59.89606Z","end":"2026-04-14T07:10:00.092677Z","steps":["trace[644297796] 'process raft request' (duration: 196.023096ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:00.092681Z","caller":"traceutil/trace.go:171","msg":"trace[1980758501] transaction","detail":"{read_only:false; response_revision:26206; number_of_response:1; }","duration":"198.493308ms","start":"2026-04-14T07:09:59.894169Z","end":"2026-04-14T07:10:00.092662Z","steps":["trace[1980758501] 'process raft request' (duration: 197.759497ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:00.092655Z","caller":"traceutil/trace.go:171","msg":"trace[765583744] transaction","detail":"{read_only:false; response_revision:26209; number_of_response:1; }","duration":"196.198901ms","start":"2026-04-14T07:09:59.896448Z","end":"2026-04-14T07:10:00.092647Z","steps":["trace[765583744] 'process raft request' (duration: 195.662556ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:03.517082Z","caller":"traceutil/trace.go:171","msg":"trace[1516027174] transaction","detail":"{read_only:false; response_revision:26266; number_of_response:1; }","duration":"144.402402ms","start":"2026-04-14T07:10:03.372653Z","end":"2026-04-14T07:10:03.517056Z","steps":["trace[1516027174] 'process raft request' (duration: 144.175355ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:03.676488Z","caller":"traceutil/trace.go:171","msg":"trace[1301069319] linearizableReadLoop","detail":"{readStateIndex:27128; appliedIndex:27127; }","duration":"155.815692ms","start":"2026-04-14T07:10:03.520646Z","end":"2026-04-14T07:10:03.676461Z","steps":["trace[1301069319] 'read index received' (duration: 155.637477ms)","trace[1301069319] 'applied index is now lower than readState.Index' (duration: 176.755µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:10:03.676794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.125331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/container-infra.18a62787649a99ac\" ","response":"range_response_count:1 size:695"} {"level":"info","ts":"2026-04-14T07:10:03.676843Z","caller":"traceutil/trace.go:171","msg":"trace[294784113] range","detail":"{range_begin:/registry/events/openstack/container-infra.18a62787649a99ac; range_end:; response_count:1; response_revision:26267; }","duration":"156.217864ms","start":"2026-04-14T07:10:03.520611Z","end":"2026-04-14T07:10:03.676829Z","steps":["trace[294784113] 'agreement among raft nodes before linearized reading' (duration: 155.950616ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:03.677272Z","caller":"traceutil/trace.go:171","msg":"trace[306959703] transaction","detail":"{read_only:false; response_revision:26267; number_of_response:1; }","duration":"302.576721ms","start":"2026-04-14T07:10:03.374679Z","end":"2026-04-14T07:10:03.677256Z","steps":["trace[306959703] 'process raft request' (duration: 301.611663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:03.677534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:03.374662Z","time spent":"302.766117ms","remote":"127.0.0.1:50074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":924,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:10:03.855028Z","caller":"traceutil/trace.go:171","msg":"trace[861004517] transaction","detail":"{read_only:false; response_revision:26268; number_of_response:1; }","duration":"175.050798ms","start":"2026-04-14T07:10:03.679949Z","end":"2026-04-14T07:10:03.855Z","steps":["trace[861004517] 'process raft request' (duration: 166.587013ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:04.773068Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a10.wal"} {"level":"info","ts":"2026-04-14T07:10:09.221095Z","caller":"traceutil/trace.go:171","msg":"trace[506727573] linearizableReadLoop","detail":"{readStateIndex:27195; appliedIndex:27194; }","duration":"101.669923ms","start":"2026-04-14T07:10:09.119406Z","end":"2026-04-14T07:10:09.221076Z","steps":["trace[506727573] 'read index received' (duration: 100.799328ms)","trace[506727573] 'applied index is now lower than readState.Index' (duration: 869.635µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:09.221338Z","caller":"traceutil/trace.go:171","msg":"trace[812442848] transaction","detail":"{read_only:false; response_revision:26332; number_of_response:1; }","duration":"103.749433ms","start":"2026-04-14T07:10:09.117516Z","end":"2026-04-14T07:10:09.221265Z","steps":["trace[812442848] 'process raft request' (duration: 103.413314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:09.221383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.945442ms","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-04-14T07:10:09.221467Z","caller":"traceutil/trace.go:171","msg":"trace[1086519355] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26332; }","duration":"102.084666ms","start":"2026-04-14T07:10:09.119363Z","end":"2026-04-14T07:10:09.221447Z","steps":["trace[1086519355] 'agreement among raft nodes before linearized reading' (duration: 101.849499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:09.414523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.930482ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:10:09.414668Z","caller":"traceutil/trace.go:171","msg":"trace[427637803] transaction","detail":"{read_only:false; response_revision:26333; number_of_response:1; }","duration":"189.908579ms","start":"2026-04-14T07:10:09.22474Z","end":"2026-04-14T07:10:09.414649Z","steps":["trace[427637803] 'process raft request' (duration: 58.767262ms)","trace[427637803] 'compare' (duration: 130.795047ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:09.433294Z","caller":"traceutil/trace.go:171","msg":"trace[939849480] linearizableReadLoop","detail":"{readStateIndex:27197; appliedIndex:27195; }","duration":"203.145862ms","start":"2026-04-14T07:10:09.230131Z","end":"2026-04-14T07:10:09.433277Z","steps":["trace[939849480] 'read index received' (duration: 53.397936ms)","trace[939849480] 'applied index is now lower than readState.Index' (duration: 149.747246ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:09.433427Z","caller":"traceutil/trace.go:171","msg":"trace[769028798] transaction","detail":"{read_only:false; response_revision:26334; number_of_response:1; }","duration":"208.077955ms","start":"2026-04-14T07:10:09.225333Z","end":"2026-04-14T07:10:09.433411Z","steps":["trace[769028798] 'process raft request' (duration: 207.858819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:09.433434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.286186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:09.433494Z","caller":"traceutil/trace.go:171","msg":"trace[893375146] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:26334; }","duration":"203.365889ms","start":"2026-04-14T07:10:09.230105Z","end":"2026-04-14T07:10:09.433471Z","steps":["trace[893375146] 'agreement among raft nodes before linearized reading' (duration: 203.273206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:12.170791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.124586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:12.170898Z","caller":"traceutil/trace.go:171","msg":"trace[1688087282] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:26353; }","duration":"257.259059ms","start":"2026-04-14T07:10:11.913613Z","end":"2026-04-14T07:10:12.170872Z","steps":["trace[1688087282] 'count revisions from in-memory index tree' (duration: 257.046864ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:12.329987Z","caller":"traceutil/trace.go:171","msg":"trace[379292380] transaction","detail":"{read_only:false; response_revision:26355; number_of_response:1; }","duration":"188.998683ms","start":"2026-04-14T07:10:12.14096Z","end":"2026-04-14T07:10:12.329959Z","steps":["trace[379292380] 'process raft request' (duration: 188.720234ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:12.330292Z","caller":"traceutil/trace.go:171","msg":"trace[1143757524] transaction","detail":"{read_only:false; response_revision:26356; number_of_response:1; }","duration":"188.500659ms","start":"2026-04-14T07:10:12.141767Z","end":"2026-04-14T07:10:12.330267Z","steps":["trace[1143757524] 'process raft request' (duration: 188.108157ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:12.330359Z","caller":"traceutil/trace.go:171","msg":"trace[2021432918] linearizableReadLoop","detail":"{readStateIndex:27222; appliedIndex:27216; }","duration":"184.436381ms","start":"2026-04-14T07:10:12.145899Z","end":"2026-04-14T07:10:12.330335Z","steps":["trace[2021432918] 'read index received' (duration: 75.096254ms)","trace[2021432918] 'applied index is now lower than readState.Index' (duration: 109.338927ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:12.330367Z","caller":"traceutil/trace.go:171","msg":"trace[854420885] transaction","detail":"{read_only:false; response_revision:26357; number_of_response:1; }","duration":"188.105817ms","start":"2026-04-14T07:10:12.142229Z","end":"2026-04-14T07:10:12.330334Z","steps":["trace[854420885] 'process raft request' (duration: 187.908261ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:12.330492Z","caller":"traceutil/trace.go:171","msg":"trace[539895947] transaction","detail":"{read_only:false; response_revision:26358; number_of_response:1; }","duration":"187.785527ms","start":"2026-04-14T07:10:12.142605Z","end":"2026-04-14T07:10:12.330391Z","steps":["trace[539895947] 'process raft request' (duration: 187.616952ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:12.330585Z","caller":"traceutil/trace.go:171","msg":"trace[1647160466] transaction","detail":"{read_only:false; response_revision:26359; number_of_response:1; }","duration":"186.169271ms","start":"2026-04-14T07:10:12.144386Z","end":"2026-04-14T07:10:12.330555Z","steps":["trace[1647160466] 'process raft request' (duration: 185.895473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:12.330694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.77862ms","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-04-14T07:10:12.330762Z","caller":"traceutil/trace.go:171","msg":"trace[923246559] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26359; }","duration":"184.896164ms","start":"2026-04-14T07:10:12.145848Z","end":"2026-04-14T07:10:12.330744Z","steps":["trace[923246559] 'agreement among raft nodes before linearized reading' (duration: 184.572935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:29.522826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.454727ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:29.522989Z","caller":"traceutil/trace.go:171","msg":"trace[936116609] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26506; }","duration":"102.632442ms","start":"2026-04-14T07:10:29.420331Z","end":"2026-04-14T07:10:29.522963Z","steps":["trace[936116609] 'range keys from in-memory index tree' (duration: 102.423865ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:29.702992Z","caller":"traceutil/trace.go:171","msg":"trace[2114003860] linearizableReadLoop","detail":"{readStateIndex:27374; appliedIndex:27373; }","duration":"163.526554ms","start":"2026-04-14T07:10:29.53944Z","end":"2026-04-14T07:10:29.702967Z","steps":["trace[2114003860] 'read index received' (duration: 163.225296ms)","trace[2114003860] 'applied index is now lower than readState.Index' (duration: 299.878µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:29.703093Z","caller":"traceutil/trace.go:171","msg":"trace[1989823930] transaction","detail":"{read_only:false; response_revision:26507; number_of_response:1; }","duration":"170.225519ms","start":"2026-04-14T07:10:29.532831Z","end":"2026-04-14T07:10:29.703056Z","steps":["trace[1989823930] 'process raft request' (duration: 169.897629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:29.703207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.74841ms","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-04-14T07:10:29.703244Z","caller":"traceutil/trace.go:171","msg":"trace[1191101609] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26507; }","duration":"163.802302ms","start":"2026-04-14T07:10:29.539431Z","end":"2026-04-14T07:10:29.703233Z","steps":["trace[1191101609] 'agreement among raft nodes before linearized reading' (duration: 163.630047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:29.956629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.813556ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:10:29.956746Z","caller":"traceutil/trace.go:171","msg":"trace[1850395896] transaction","detail":"{read_only:false; response_revision:26508; number_of_response:1; }","duration":"248.768842ms","start":"2026-04-14T07:10:29.707945Z","end":"2026-04-14T07:10:29.956714Z","steps":["trace[1850395896] 'process raft request' (duration: 96.794231ms)","trace[1850395896] 'compare' (duration: 151.357634ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:30.178464Z","caller":"traceutil/trace.go:171","msg":"trace[274843144] transaction","detail":"{read_only:false; response_revision:26509; number_of_response:1; }","duration":"119.225832ms","start":"2026-04-14T07:10:30.059211Z","end":"2026-04-14T07:10:30.178437Z","steps":["trace[274843144] 'process raft request' (duration: 101.742066ms)","trace[274843144] 'compare' (duration: 17.366263ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:10:32.181753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.83493ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:10:32.182131Z","caller":"traceutil/trace.go:171","msg":"trace[446055404] transaction","detail":"{read_only:false; response_revision:26524; number_of_response:1; }","duration":"213.179461ms","start":"2026-04-14T07:10:31.968905Z","end":"2026-04-14T07:10:32.182084Z","steps":["trace[446055404] 'process raft request' (duration: 92.880019ms)","trace[446055404] 'compare' (duration: 119.617503ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:32.493466Z","caller":"traceutil/trace.go:171","msg":"trace[670148805] linearizableReadLoop","detail":"{readStateIndex:27392; appliedIndex:27391; }","duration":"127.545902ms","start":"2026-04-14T07:10:32.365895Z","end":"2026-04-14T07:10:32.493441Z","steps":["trace[670148805] 'read index received' (duration: 127.417798ms)","trace[670148805] 'applied index is now lower than readState.Index' (duration: 127.024µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:32.493533Z","caller":"traceutil/trace.go:171","msg":"trace[2062310224] transaction","detail":"{read_only:false; response_revision:26525; number_of_response:1; }","duration":"188.666912ms","start":"2026-04-14T07:10:32.304813Z","end":"2026-04-14T07:10:32.49348Z","steps":["trace[2062310224] 'process raft request' (duration: 188.448296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:32.493633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.713717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:32.493685Z","caller":"traceutil/trace.go:171","msg":"trace[518542741] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:26525; }","duration":"127.8228ms","start":"2026-04-14T07:10:32.365846Z","end":"2026-04-14T07:10:32.493669Z","steps":["trace[518542741] 'agreement among raft nodes before linearized reading' (duration: 127.698287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:32.704313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.900957ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:10:32.704536Z","caller":"traceutil/trace.go:171","msg":"trace[1433003398] linearizableReadLoop","detail":"{readStateIndex:27397; appliedIndex:27392; }","duration":"210.99945ms","start":"2026-04-14T07:10:32.493522Z","end":"2026-04-14T07:10:32.704521Z","steps":["trace[1433003398] 'read index received' (duration: 77.70944ms)","trace[1433003398] 'applied index is now lower than readState.Index' (duration: 133.28731ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:32.704744Z","caller":"traceutil/trace.go:171","msg":"trace[112394006] transaction","detail":"{read_only:false; response_revision:26526; number_of_response:1; }","duration":"294.972331ms","start":"2026-04-14T07:10:32.409761Z","end":"2026-04-14T07:10:32.704733Z","steps":["trace[112394006] 'process raft request' (duration: 161.570308ms)","trace[112394006] 'compare' (duration: 132.755183ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:32.70487Z","caller":"traceutil/trace.go:171","msg":"trace[1979152745] transaction","detail":"{read_only:false; response_revision:26528; number_of_response:1; }","duration":"243.242042ms","start":"2026-04-14T07:10:32.46161Z","end":"2026-04-14T07:10:32.704852Z","steps":["trace[1979152745] 'process raft request' (duration: 242.82282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:32.704951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.018271ms","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-04-14T07:10:32.704972Z","caller":"traceutil/trace.go:171","msg":"trace[834844793] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26530; }","duration":"238.079722ms","start":"2026-04-14T07:10:32.466885Z","end":"2026-04-14T07:10:32.704965Z","steps":["trace[834844793] 'agreement among raft nodes before linearized reading' (duration: 237.98927ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:32.704969Z","caller":"traceutil/trace.go:171","msg":"trace[1505107286] transaction","detail":"{read_only:false; response_revision:26530; number_of_response:1; }","duration":"243.142699ms","start":"2026-04-14T07:10:32.46171Z","end":"2026-04-14T07:10:32.704853Z","steps":["trace[1505107286] 'process raft request' (duration: 242.773449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:32.705062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.411625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-14T07:10:32.705164Z","caller":"traceutil/trace.go:171","msg":"trace[820972613] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:26530; }","duration":"102.557489ms","start":"2026-04-14T07:10:32.602575Z","end":"2026-04-14T07:10:32.705133Z","steps":["trace[820972613] 'agreement among raft nodes before linearized reading' (duration: 102.375194ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:32.705151Z","caller":"traceutil/trace.go:171","msg":"trace[1570952790] transaction","detail":"{read_only:false; response_revision:26527; number_of_response:1; }","duration":"246.036863ms","start":"2026-04-14T07:10:32.458912Z","end":"2026-04-14T07:10:32.704949Z","steps":["trace[1570952790] 'process raft request' (duration: 245.477827ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:32.705004Z","caller":"traceutil/trace.go:171","msg":"trace[1465933549] transaction","detail":"{read_only:false; response_revision:26529; number_of_response:1; }","duration":"243.317544ms","start":"2026-04-14T07:10:32.461673Z","end":"2026-04-14T07:10:32.704991Z","steps":["trace[1465933549] 'process raft request' (duration: 242.782159ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:32.891865Z","caller":"traceutil/trace.go:171","msg":"trace[126203338] transaction","detail":"{read_only:false; response_revision:26531; number_of_response:1; }","duration":"180.632729ms","start":"2026-04-14T07:10:32.711209Z","end":"2026-04-14T07:10:32.891842Z","steps":["trace[126203338] 'process raft request' (duration: 180.507756ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:32.928695Z","caller":"traceutil/trace.go:171","msg":"trace[389646883] linearizableReadLoop","detail":"{readStateIndex:27400; appliedIndex:27398; }","duration":"106.327388ms","start":"2026-04-14T07:10:32.822345Z","end":"2026-04-14T07:10:32.928673Z","steps":["trace[389646883] 'read index received' (duration: 69.498241ms)","trace[389646883] 'applied index is now lower than readState.Index' (duration: 36.828117ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:32.928805Z","caller":"traceutil/trace.go:171","msg":"trace[757240634] transaction","detail":"{read_only:false; response_revision:26533; number_of_response:1; }","duration":"181.653309ms","start":"2026-04-14T07:10:32.747114Z","end":"2026-04-14T07:10:32.928767Z","steps":["trace[757240634] 'process raft request' (duration: 181.525395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:32.928855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.489253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-14T07:10:32.92882Z","caller":"traceutil/trace.go:171","msg":"trace[745656975] transaction","detail":"{read_only:false; response_revision:26532; number_of_response:1; }","duration":"186.803799ms","start":"2026-04-14T07:10:32.741942Z","end":"2026-04-14T07:10:32.928746Z","steps":["trace[745656975] 'process raft request' (duration: 186.574382ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:32.928881Z","caller":"traceutil/trace.go:171","msg":"trace[673541858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26533; }","duration":"106.554795ms","start":"2026-04-14T07:10:32.822317Z","end":"2026-04-14T07:10:32.928872Z","steps":["trace[673541858] 'agreement among raft nodes before linearized reading' (duration: 106.434572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:33.136694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.517788ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:10:33.137272Z","caller":"traceutil/trace.go:171","msg":"trace[993994353] transaction","detail":"{read_only:false; response_revision:26534; number_of_response:1; }","duration":"203.989856ms","start":"2026-04-14T07:10:32.933199Z","end":"2026-04-14T07:10:33.137189Z","steps":["trace[993994353] 'process raft request' (duration: 90.855031ms)","trace[993994353] 'compare' (duration: 111.884949ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:10:33.49155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.736292ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-14T07:10:33.491661Z","caller":"traceutil/trace.go:171","msg":"trace[713847235] linearizableReadLoop","detail":"{readStateIndex:27403; appliedIndex:27402; }","duration":"176.666735ms","start":"2026-04-14T07:10:33.314964Z","end":"2026-04-14T07:10:33.491631Z","steps":["trace[713847235] 'read index received' (duration: 32.894142ms)","trace[713847235] 'applied index is now lower than readState.Index' (duration: 143.771153ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:10:33.491824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.85587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-14T07:10:33.491884Z","caller":"traceutil/trace.go:171","msg":"trace[82522423] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26535; }","duration":"176.969535ms","start":"2026-04-14T07:10:33.314901Z","end":"2026-04-14T07:10:33.491871Z","steps":["trace[82522423] 'agreement among raft nodes before linearized reading' (duration: 176.80209ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:33.913808Z","caller":"traceutil/trace.go:171","msg":"trace[9617956] transaction","detail":"{read_only:false; response_revision:26537; number_of_response:1; }","duration":"122.328431ms","start":"2026-04-14T07:10:33.791458Z","end":"2026-04-14T07:10:33.913786Z","steps":["trace[9617956] 'process raft request' (duration: 122.159906ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:34.222982Z","caller":"traceutil/trace.go:171","msg":"trace[391546090] transaction","detail":"{read_only:false; response_revision:26538; number_of_response:1; }","duration":"129.774428ms","start":"2026-04-14T07:10:34.093183Z","end":"2026-04-14T07:10:34.222957Z","steps":["trace[391546090] 'process raft request' (duration: 129.614103ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:35.344494Z","caller":"traceutil/trace.go:171","msg":"trace[1475751571] transaction","detail":"{read_only:false; response_revision:26547; number_of_response:1; }","duration":"408.134376ms","start":"2026-04-14T07:10:34.936341Z","end":"2026-04-14T07:10:35.344475Z","steps":["trace[1475751571] 'process raft request' (duration: 408.100155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:35.344615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:34.936318Z","time spent":"408.23709ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:10:35.344909Z","caller":"traceutil/trace.go:171","msg":"trace[724710848] transaction","detail":"{read_only:false; response_revision:26546; number_of_response:1; }","duration":"408.548888ms","start":"2026-04-14T07:10:34.936351Z","end":"2026-04-14T07:10:35.3449Z","steps":["trace[724710848] 'process raft request' (duration: 407.971802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:35.344953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:34.936325Z","time spent":"408.6046ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:10:35.34523Z","caller":"traceutil/trace.go:171","msg":"trace[877609211] linearizableReadLoop","detail":"{readStateIndex:27415; appliedIndex:27415; }","duration":"262.436858ms","start":"2026-04-14T07:10:35.082787Z","end":"2026-04-14T07:10:35.345224Z","steps":["trace[877609211] 'read index received' (duration: 262.433848ms)","trace[877609211] 'applied index is now lower than readState.Index' (duration: 2.62µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:10:35.345329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.807289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-14T07:10:35.345348Z","caller":"traceutil/trace.go:171","msg":"trace[2106582936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26547; }","duration":"203.857491ms","start":"2026-04-14T07:10:35.141484Z","end":"2026-04-14T07:10:35.345341Z","steps":["trace[2106582936] 'agreement among raft nodes before linearized reading' (duration: 203.776749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:35.34547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.695255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:35.345485Z","caller":"traceutil/trace.go:171","msg":"trace[1581484704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26547; }","duration":"262.755106ms","start":"2026-04-14T07:10:35.082725Z","end":"2026-04-14T07:10:35.34548Z","steps":["trace[1581484704] 'agreement among raft nodes before linearized reading' (duration: 262.722445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:35.839456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.144325ms","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-04-14T07:10:35.83956Z","caller":"traceutil/trace.go:171","msg":"trace[1634858407] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26548; }","duration":"229.270267ms","start":"2026-04-14T07:10:35.610264Z","end":"2026-04-14T07:10:35.839534Z","steps":["trace[1634858407] 'range keys from in-memory index tree' (duration: 228.97154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:35.839487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.096185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-04-14T07:10:35.839649Z","caller":"traceutil/trace.go:171","msg":"trace[263243538] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:26548; }","duration":"322.378253ms","start":"2026-04-14T07:10:35.517254Z","end":"2026-04-14T07:10:35.839632Z","steps":["trace[263243538] 'count revisions from in-memory index tree' (duration: 321.960441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:35.839707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:35.517231Z","time spent":"322.456736ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":184,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-14T07:10:36.439028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.823566ms","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-04-14T07:10:36.439089Z","caller":"traceutil/trace.go:171","msg":"trace[1355074312] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26551; }","duration":"208.931249ms","start":"2026-04-14T07:10:36.230139Z","end":"2026-04-14T07:10:36.43907Z","steps":["trace[1355074312] 'range keys from in-memory index tree' (duration: 208.643902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:36.43939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.303362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-14T07:10:36.439371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.56341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-7f5b76776-sjfsx.18a62787b6bd7abb\" ","response":"range_response_count:1 size:887"} {"level":"warn","ts":"2026-04-14T07:10:36.439421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.47897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:36.439507Z","caller":"traceutil/trace.go:171","msg":"trace[85830496] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26551; }","duration":"356.652866ms","start":"2026-04-14T07:10:36.082833Z","end":"2026-04-14T07:10:36.439486Z","steps":["trace[85830496] 'range keys from in-memory index tree' (duration: 356.309006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:36.439553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:36.082807Z","time spent":"356.736198ms","remote":"127.0.0.1:49746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-14T07:10:36.43945Z","caller":"traceutil/trace.go:171","msg":"trace[319356219] range","detail":"{range_begin:/registry/events/openstack/magnum-api-7f5b76776-sjfsx.18a62787b6bd7abb; range_end:; response_count:1; response_revision:26551; }","duration":"302.702813ms","start":"2026-04-14T07:10:36.136723Z","end":"2026-04-14T07:10:36.439426Z","steps":["trace[319356219] 'range keys from in-memory index tree' (duration: 302.323833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:36.439636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:36.136701Z","time spent":"302.91713ms","remote":"127.0.0.1:49820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":909,"request content":"key:\"/registry/events/openstack/magnum-api-7f5b76776-sjfsx.18a62787b6bd7abb\" "} {"level":"info","ts":"2026-04-14T07:10:36.439413Z","caller":"traceutil/trace.go:171","msg":"trace[2062280855] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:26551; }","duration":"226.364164ms","start":"2026-04-14T07:10:36.213043Z","end":"2026-04-14T07:10:36.439407Z","steps":["trace[2062280855] 'count revisions from in-memory index tree' (duration: 226.194359ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:37.479087Z","caller":"traceutil/trace.go:171","msg":"trace[418590309] transaction","detail":"{read_only:false; response_revision:26564; number_of_response:1; }","duration":"173.999547ms","start":"2026-04-14T07:10:37.305067Z","end":"2026-04-14T07:10:37.479066Z","steps":["trace[418590309] 'process raft request' (duration: 173.865193ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:37.498596Z","caller":"traceutil/trace.go:171","msg":"trace[431133665] transaction","detail":"{read_only:false; response_revision:26565; number_of_response:1; }","duration":"149.083557ms","start":"2026-04-14T07:10:37.349449Z","end":"2026-04-14T07:10:37.498533Z","steps":["trace[431133665] 'process raft request' (duration: 148.762847ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:37.498653Z","caller":"traceutil/trace.go:171","msg":"trace[718874735] transaction","detail":"{read_only:false; response_revision:26566; number_of_response:1; }","duration":"148.52986ms","start":"2026-04-14T07:10:37.350086Z","end":"2026-04-14T07:10:37.498616Z","steps":["trace[718874735] 'process raft request' (duration: 148.262822ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:38.107002Z","caller":"traceutil/trace.go:171","msg":"trace[1142638733] linearizableReadLoop","detail":"{readStateIndex:27437; appliedIndex:27436; }","duration":"131.668882ms","start":"2026-04-14T07:10:37.975307Z","end":"2026-04-14T07:10:38.106976Z","steps":["trace[1142638733] 'read index received' (duration: 131.398994ms)","trace[1142638733] 'applied index is now lower than readState.Index' (duration: 268.668µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:38.107139Z","caller":"traceutil/trace.go:171","msg":"trace[878603466] transaction","detail":"{read_only:false; response_revision:26569; number_of_response:1; }","duration":"157.889411ms","start":"2026-04-14T07:10:37.949235Z","end":"2026-04-14T07:10:38.107125Z","steps":["trace[878603466] 'process raft request' (duration: 157.557382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:38.107433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.116205ms","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-04-14T07:10:38.107478Z","caller":"traceutil/trace.go:171","msg":"trace[1684713576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26569; }","duration":"132.212088ms","start":"2026-04-14T07:10:37.975252Z","end":"2026-04-14T07:10:38.107464Z","steps":["trace[1684713576] 'agreement among raft nodes before linearized reading' (duration: 132.054503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:38.502323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.875591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:10:38.50244Z","caller":"traceutil/trace.go:171","msg":"trace[1258913591] linearizableReadLoop","detail":"{readStateIndex:27438; appliedIndex:27437; }","duration":"362.457973ms","start":"2026-04-14T07:10:38.13996Z","end":"2026-04-14T07:10:38.502418Z","steps":["trace[1258913591] 'read index received' (duration: 100.309004ms)","trace[1258913591] 'applied index is now lower than readState.Index' (duration: 262.147179ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:38.502586Z","caller":"traceutil/trace.go:171","msg":"trace[844256039] transaction","detail":"{read_only:false; response_revision:26570; number_of_response:1; }","duration":"387.067756ms","start":"2026-04-14T07:10:38.115435Z","end":"2026-04-14T07:10:38.502502Z","steps":["trace[844256039] 'process raft request' (duration: 124.893896ms)","trace[844256039] 'compare' (duration: 261.633534ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:10:38.502769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:38.115409Z","time spent":"387.277082ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:10:38.502779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.426331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:1 size:140"} {"level":"warn","ts":"2026-04-14T07:10:38.502618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.650759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-14T07:10:38.502814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.722539ms","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-04-14T07:10:38.50285Z","caller":"traceutil/trace.go:171","msg":"trace[482269858] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/groups/; range_end:/registry/openstack.k-orc.cloud/groups0; response_count:0; response_revision:26570; }","duration":"163.75927ms","start":"2026-04-14T07:10:38.339078Z","end":"2026-04-14T07:10:38.502837Z","steps":["trace[482269858] 'agreement among raft nodes before linearized reading' (duration: 163.696279ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:38.502831Z","caller":"traceutil/trace.go:171","msg":"trace[1683136630] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:1; response_revision:26570; }","duration":"239.518234ms","start":"2026-04-14T07:10:38.263299Z","end":"2026-04-14T07:10:38.502817Z","steps":["trace[1683136630] 'agreement among raft nodes before linearized reading' (duration: 239.366589ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:38.502859Z","caller":"traceutil/trace.go:171","msg":"trace[269196594] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:26570; }","duration":"362.951517ms","start":"2026-04-14T07:10:38.139881Z","end":"2026-04-14T07:10:38.502832Z","steps":["trace[269196594] 'agreement among raft nodes before linearized reading' (duration: 362.638698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:38.502986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:10:38.139851Z","time spent":"363.106862ms","remote":"127.0.0.1:44524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":1,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true "} {"level":"warn","ts":"2026-04-14T07:10:40.367088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.328675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-04-14T07:10:40.367193Z","caller":"traceutil/trace.go:171","msg":"trace[1101244404] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:26584; }","duration":"158.495759ms","start":"2026-04-14T07:10:40.208672Z","end":"2026-04-14T07:10:40.367168Z","steps":["trace[1101244404] 'range keys from in-memory index tree' (duration: 158.097057ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:10:41.755348Z","caller":"traceutil/trace.go:171","msg":"trace[1705404445] linearizableReadLoop","detail":"{readStateIndex:27466; appliedIndex:27465; }","duration":"148.331054ms","start":"2026-04-14T07:10:41.606975Z","end":"2026-04-14T07:10:41.755307Z","steps":["trace[1705404445] 'read index received' (duration: 63.171149ms)","trace[1705404445] 'applied index is now lower than readState.Index' (duration: 85.158875ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:10:41.755475Z","caller":"traceutil/trace.go:171","msg":"trace[1776116922] transaction","detail":"{read_only:false; response_revision:26597; number_of_response:1; }","duration":"168.568111ms","start":"2026-04-14T07:10:41.586899Z","end":"2026-04-14T07:10:41.755467Z","steps":["trace[1776116922] 'process raft request' (duration: 83.253251ms)","trace[1776116922] 'compare' (duration: 84.96655ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:10:41.758947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.95114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:41.759065Z","caller":"traceutil/trace.go:171","msg":"trace[1776554374] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:26597; }","duration":"152.102604ms","start":"2026-04-14T07:10:41.606922Z","end":"2026-04-14T07:10:41.759025Z","steps":["trace[1776554374] 'agreement among raft nodes before linearized reading' (duration: 148.470808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:42.009925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.060028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:42.010014Z","caller":"traceutil/trace.go:171","msg":"trace[428003709] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:26597; }","duration":"146.204863ms","start":"2026-04-14T07:10:41.863787Z","end":"2026-04-14T07:10:42.009991Z","steps":["trace[428003709] 'count revisions from in-memory index tree' (duration: 145.949325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:47.096087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.493735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:10:47.096153Z","caller":"traceutil/trace.go:171","msg":"trace[1652412300] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:26638; }","duration":"105.639469ms","start":"2026-04-14T07:10:46.990498Z","end":"2026-04-14T07:10:47.096138Z","steps":["trace[1652412300] 'count revisions from in-memory index tree' (duration: 105.399742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:10:47.949641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.615434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-14T07:10:47.949746Z","caller":"traceutil/trace.go:171","msg":"trace[996230923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26643; }","duration":"156.749448ms","start":"2026-04-14T07:10:47.792957Z","end":"2026-04-14T07:10:47.949706Z","steps":["trace[996230923] 'range keys from in-memory index tree' (duration: 156.413898ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:11:10.99223Z","caller":"traceutil/trace.go:171","msg":"trace[114249011] transaction","detail":"{read_only:false; response_revision:26967; number_of_response:1; }","duration":"140.268911ms","start":"2026-04-14T07:11:10.851925Z","end":"2026-04-14T07:11:10.992194Z","steps":["trace[114249011] 'process raft request' (duration: 86.514145ms)","trace[114249011] 'compare' (duration: 53.499839ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:13:15.020273Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25205} {"level":"info","ts":"2026-04-14T07:13:15.15816Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25205,"took":"134.342456ms","hash":1406300934,"current-db-size-bytes":58875904,"current-db-size":"59 MB","current-db-size-in-use-bytes":37224448,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-04-14T07:13:15.158218Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1406300934,"revision":25205,"compact-revision":22033} {"level":"info","ts":"2026-04-14T07:14:03.802288Z","caller":"traceutil/trace.go:171","msg":"trace[1761609320] transaction","detail":"{read_only:false; response_revision:28615; number_of_response:1; }","duration":"110.700762ms","start":"2026-04-14T07:14:03.691541Z","end":"2026-04-14T07:14:03.802241Z","steps":["trace[1761609320] 'process raft request' (duration: 82.724692ms)","trace[1761609320] 'compare' (duration: 26.886299ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:14:03.819626Z","caller":"traceutil/trace.go:171","msg":"trace[1683950004] transaction","detail":"{read_only:false; response_revision:28616; number_of_response:1; }","duration":"124.808849ms","start":"2026-04-14T07:14:03.694781Z","end":"2026-04-14T07:14:03.819589Z","steps":["trace[1683950004] 'process raft request' (duration: 124.541492ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:14:51.413049Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f170c75a1d97cc","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-14T07:14:51.418446Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-14T07:14:51.418565Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-04-14T07:18:15.025954Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28046} {"level":"info","ts":"2026-04-14T07:18:15.173258Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28046,"took":"142.848787ms","hash":3724589722,"current-db-size-bytes":58875904,"current-db-size":"59 MB","current-db-size-in-use-bytes":32608256,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-14T07:18:15.173419Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3724589722,"revision":28046,"compact-revision":25205} {"level":"info","ts":"2026-04-14T07:23:15.032137Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30622} {"level":"info","ts":"2026-04-14T07:23:15.172686Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30622,"took":"137.430148ms","hash":4225703114,"current-db-size-bytes":58875904,"current-db-size":"59 MB","current-db-size-in-use-bytes":30236672,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-14T07:23:15.172775Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4225703114,"revision":30622,"compact-revision":28046} {"level":"info","ts":"2026-04-14T07:28:15.046859Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32879} {"level":"info","ts":"2026-04-14T07:28:15.199633Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32879,"took":"144.286351ms","hash":574487065,"current-db-size-bytes":59916288,"current-db-size":"60 MB","current-db-size-in-use-bytes":30081024,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-14T07:28:15.199705Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":574487065,"revision":32879,"compact-revision":30622} {"level":"info","ts":"2026-04-14T07:33:15.053167Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35140} {"level":"info","ts":"2026-04-14T07:33:15.189478Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35140,"took":"132.183189ms","hash":2859923142,"current-db-size-bytes":59916288,"current-db-size":"60 MB","current-db-size-in-use-bytes":31686656,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-14T07:33:15.189551Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2859923142,"revision":35140,"compact-revision":32879} {"level":"info","ts":"2026-04-14T07:36:12.848586Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f170c75a1d97cc","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-14T07:36:12.855571Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-04-14T07:36:12.855695Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-04-14T07:38:15.059407Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37410} {"level":"info","ts":"2026-04-14T07:38:15.183652Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37410,"took":"120.192343ms","hash":4278163313,"current-db-size-bytes":60338176,"current-db-size":"60 MB","current-db-size-in-use-bytes":31793152,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-14T07:38:15.183716Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4278163313,"revision":37410,"compact-revision":35140} {"level":"info","ts":"2026-04-14T07:41:55.815835Z","caller":"traceutil/trace.go:171","msg":"trace[1194272795] transaction","detail":"{read_only:false; response_revision:41349; number_of_response:1; }","duration":"105.871797ms","start":"2026-04-14T07:41:55.709941Z","end":"2026-04-14T07:41:55.815813Z","steps":["trace[1194272795] 'process raft request' (duration: 105.676811ms)"],"step_count":1}