{"level":"warn","ts":"2026-04-28T03:22:13.37742Z","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-28T03:22:13.377533Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216: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.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216: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-28T03:22:13.377612Z","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-28T03:22:13.377623Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-04-28T03:22:13.377649Z","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-28T03:22:13.378516Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-04-28T03:22:13.378611Z","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.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216: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-28T03:22:13.382026Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.127229ms"} {"level":"info","ts":"2026-04-28T03:22:13.393322Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-04-28T03:22:13.393503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T03:22:13.393575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-04-28T03:22:13.393603Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T03:22:13.39367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-04-28T03:22:13.39377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-04-28T03:22:13.403388Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T03:22:13.404778Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T03:22:13.406468Z","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-28T03:22:13.409861Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T03:22:13.410054Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T03:22:13.410149Z","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-28T03:22:13.410247Z","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-28T03:22:13.41026Z","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-28T03:22:13.410906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-04-28T03:22:13.411361Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-04-28T03:22:13.413999Z","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-28T03:22:13.414109Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-04-28T03:22:13.414134Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-04-28T03:22:13.414272Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T03:22:13.414303Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T03:22:13.795019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T03:22:13.795073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T03:22:13.795106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-04-28T03:22:13.795121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-04-28T03:22:13.795129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-04-28T03:22:13.795144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-04-28T03:22:13.795154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-04-28T03:22:13.795997Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:22:13.796755Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T03:22:13.796918Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T03:22:13.796981Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T03:22:13.797231Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:22:13.797312Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:22:13.79734Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:22:13.799159Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T03:22:13.79922Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T03:22:13.800322Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T03:22:13.800568Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-04-28T03:22:42.634508Z","caller":"traceutil/trace.go:171","msg":"trace[218183569] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"102.575248ms","start":"2026-04-28T03:22:42.531904Z","end":"2026-04-28T03:22:42.634479Z","steps":["trace[218183569] 'process raft request' (duration: 102.347824ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:42.634637Z","caller":"traceutil/trace.go:171","msg":"trace[124934703] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"102.515487ms","start":"2026-04-28T03:22:42.532094Z","end":"2026-04-28T03:22:42.634609Z","steps":["trace[124934703] 'process raft request' (duration: 102.280602ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:48.600078Z","caller":"traceutil/trace.go:171","msg":"trace[1456275122] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"111.187592ms","start":"2026-04-28T03:22:48.488819Z","end":"2026-04-28T03:22:48.600007Z","steps":["trace[1456275122] 'process raft request' (duration: 103.985245ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:48.770323Z","caller":"traceutil/trace.go:171","msg":"trace[924936127] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"115.968535ms","start":"2026-04-28T03:22:48.654311Z","end":"2026-04-28T03:22:48.770279Z","steps":["trace[924936127] 'process raft request' (duration: 115.7056ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:49.261155Z","caller":"traceutil/trace.go:171","msg":"trace[1373796297] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"109.245335ms","start":"2026-04-28T03:22:49.151885Z","end":"2026-04-28T03:22:49.261131Z","steps":["trace[1373796297] 'process raft request' (duration: 87.663993ms)","trace[1373796297] 'compare' (duration: 20.43079ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:23:03.955376Z","caller":"traceutil/trace.go:171","msg":"trace[832390666] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"160.273722ms","start":"2026-04-28T03:23:03.795061Z","end":"2026-04-28T03:23:03.955335Z","steps":["trace[832390666] 'process raft request' (duration: 160.033716ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:24:07.613036Z","caller":"traceutil/trace.go:171","msg":"trace[1165617736] linearizableReadLoop","detail":"{readStateIndex:1763; appliedIndex:1762; }","duration":"113.745791ms","start":"2026-04-28T03:24:07.499261Z","end":"2026-04-28T03:24:07.613007Z","steps":["trace[1165617736] 'read index received' (duration: 75.246035ms)","trace[1165617736] 'applied index is now lower than readState.Index' (duration: 38.498186ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:24:07.613125Z","caller":"traceutil/trace.go:171","msg":"trace[1605995058] transaction","detail":"{read_only:false; response_revision:1698; number_of_response:1; }","duration":"128.070765ms","start":"2026-04-28T03:24:07.48501Z","end":"2026-04-28T03:24:07.61308Z","steps":["trace[1605995058] 'process raft request' (duration: 89.473828ms)","trace[1605995058] 'compare' (duration: 38.360923ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:24:07.613212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.916575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:24:07.613311Z","caller":"traceutil/trace.go:171","msg":"trace[1699098882] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1698; }","duration":"114.076727ms","start":"2026-04-28T03:24:07.499217Z","end":"2026-04-28T03:24:07.613294Z","steps":["trace[1699098882] 'agreement among raft nodes before linearized reading' (duration: 113.917094ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:24:13.684309Z","caller":"traceutil/trace.go:171","msg":"trace[1029921424] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"171.189288ms","start":"2026-04-28T03:24:13.513092Z","end":"2026-04-28T03:24:13.684282Z","steps":["trace[1029921424] 'process raft request' (duration: 171.007135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:24:14.068743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.042247ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:24:14.068874Z","caller":"traceutil/trace.go:171","msg":"trace[1252565175] linearizableReadLoop","detail":"{readStateIndex:1877; appliedIndex:1876; }","duration":"113.977287ms","start":"2026-04-28T03:24:13.954879Z","end":"2026-04-28T03:24:14.068856Z","steps":["trace[1252565175] 'read index received' (duration: 76.882µs)","trace[1252565175] 'applied index is now lower than readState.Index' (duration: 113.898895ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:24:14.068928Z","caller":"traceutil/trace.go:171","msg":"trace[1710576649] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"166.644782ms","start":"2026-04-28T03:24:13.902229Z","end":"2026-04-28T03:24:14.068874Z","steps":["trace[1710576649] 'process raft request' (duration: 51.785278ms)","trace[1710576649] 'compare' (duration: 113.882485ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:24:14.069059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.183911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-14d6573d-52bc-490a-a14e-1d4ce063d9c5\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-28T03:24:14.069139Z","caller":"traceutil/trace.go:171","msg":"trace[231615598] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-14d6573d-52bc-490a-a14e-1d4ce063d9c5; range_end:; response_count:1; response_revision:1811; }","duration":"114.290002ms","start":"2026-04-28T03:24:13.954837Z","end":"2026-04-28T03:24:14.069127Z","steps":["trace[231615598] 'agreement among raft nodes before linearized reading' (duration: 114.075918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:24:58.426752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.136276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-28T03:24:58.426891Z","caller":"traceutil/trace.go:171","msg":"trace[224252762] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2107; }","duration":"228.299629ms","start":"2026-04-28T03:24:58.198563Z","end":"2026-04-28T03:24:58.426862Z","steps":["trace[224252762] 'range keys from in-memory index tree' (duration: 227.868301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:24:58.42726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.026366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T03:24:58.427351Z","caller":"traceutil/trace.go:171","msg":"trace[1045991513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2107; }","duration":"171.166158ms","start":"2026-04-28T03:24:58.256158Z","end":"2026-04-28T03:24:58.427324Z","steps":["trace[1045991513] 'range keys from in-memory index tree' (duration: 170.844332ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:03.73356Z","caller":"traceutil/trace.go:171","msg":"trace[742187180] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"118.162515ms","start":"2026-04-28T03:25:03.615365Z","end":"2026-04-28T03:25:03.733527Z","steps":["trace[742187180] 'process raft request' (duration: 117.8932ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:48.877932Z","caller":"traceutil/trace.go:171","msg":"trace[1938845485] transaction","detail":"{read_only:false; response_revision:2426; number_of_response:1; }","duration":"137.079221ms","start":"2026-04-28T03:25:48.740825Z","end":"2026-04-28T03:25:48.877904Z","steps":["trace[1938845485] 'process raft request' (duration: 136.699844ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:21.588981Z","caller":"traceutil/trace.go:171","msg":"trace[164166145] linearizableReadLoop","detail":"{readStateIndex:2697; appliedIndex:2696; }","duration":"114.475535ms","start":"2026-04-28T03:26:21.474477Z","end":"2026-04-28T03:26:21.588953Z","steps":["trace[164166145] 'read index received' (duration: 114.305732ms)","trace[164166145] 'applied index is now lower than readState.Index' (duration: 168.543µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:26:21.589052Z","caller":"traceutil/trace.go:171","msg":"trace[421991260] transaction","detail":"{read_only:false; response_revision:2604; number_of_response:1; }","duration":"180.018009ms","start":"2026-04-28T03:26:21.408996Z","end":"2026-04-28T03:26:21.589014Z","steps":["trace[421991260] 'process raft request' (duration: 179.810195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:21.589175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.69077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-28T03:26:21.589214Z","caller":"traceutil/trace.go:171","msg":"trace[1985665778] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2604; }","duration":"114.781941ms","start":"2026-04-28T03:26:21.474421Z","end":"2026-04-28T03:26:21.589203Z","steps":["trace[1985665778] 'agreement among raft nodes before linearized reading' (duration: 114.645728ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:30:14.50723Z","caller":"traceutil/trace.go:171","msg":"trace[1524056516] transaction","detail":"{read_only:false; response_revision:4652; number_of_response:1; }","duration":"149.671626ms","start":"2026-04-28T03:30:14.357526Z","end":"2026-04-28T03:30:14.507198Z","steps":["trace[1524056516] 'process raft request' (duration: 84.907ms)","trace[1524056516] 'compare' (duration: 64.505282ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:30:14.507267Z","caller":"traceutil/trace.go:171","msg":"trace[1029819974] transaction","detail":"{read_only:false; response_revision:4654; number_of_response:1; }","duration":"143.931986ms","start":"2026-04-28T03:30:14.363312Z","end":"2026-04-28T03:30:14.507244Z","steps":["trace[1029819974] 'process raft request' (duration: 143.836665ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:30:14.507293Z","caller":"traceutil/trace.go:171","msg":"trace[1035262042] transaction","detail":"{read_only:false; response_revision:4653; number_of_response:1; }","duration":"148.649707ms","start":"2026-04-28T03:30:14.358612Z","end":"2026-04-28T03:30:14.507262Z","steps":["trace[1035262042] 'process raft request' (duration: 148.458733ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:30:14.507576Z","caller":"traceutil/trace.go:171","msg":"trace[752551939] transaction","detail":"{read_only:false; response_revision:4655; number_of_response:1; }","duration":"142.94145ms","start":"2026-04-28T03:30:14.36462Z","end":"2026-04-28T03:30:14.507562Z","steps":["trace[752551939] 'process raft request' (duration: 142.579632ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:30:14.50765Z","caller":"traceutil/trace.go:171","msg":"trace[1000109715] transaction","detail":"{read_only:false; response_revision:4656; number_of_response:1; }","duration":"142.918378ms","start":"2026-04-28T03:30:14.364701Z","end":"2026-04-28T03:30:14.50762Z","steps":["trace[1000109715] 'process raft request' (duration: 142.725034ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:30:14.507709Z","caller":"traceutil/trace.go:171","msg":"trace[960126352] transaction","detail":"{read_only:false; response_revision:4657; number_of_response:1; }","duration":"142.818687ms","start":"2026-04-28T03:30:14.364878Z","end":"2026-04-28T03:30:14.507696Z","steps":["trace[960126352] 'process raft request' (duration: 142.666044ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:30:14.507871Z","caller":"traceutil/trace.go:171","msg":"trace[1228372436] transaction","detail":"{read_only:false; response_revision:4659; number_of_response:1; }","duration":"142.854007ms","start":"2026-04-28T03:30:14.364998Z","end":"2026-04-28T03:30:14.507852Z","steps":["trace[1228372436] 'process raft request' (duration: 142.705825ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:30:14.507875Z","caller":"traceutil/trace.go:171","msg":"trace[809244288] transaction","detail":"{read_only:false; response_revision:4660; number_of_response:1; }","duration":"142.735745ms","start":"2026-04-28T03:30:14.365114Z","end":"2026-04-28T03:30:14.507849Z","steps":["trace[809244288] 'process raft request' (duration: 142.643004ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:30:14.507997Z","caller":"traceutil/trace.go:171","msg":"trace[1110902586] transaction","detail":"{read_only:false; response_revision:4658; number_of_response:1; }","duration":"143.039421ms","start":"2026-04-28T03:30:14.364939Z","end":"2026-04-28T03:30:14.507979Z","steps":["trace[1110902586] 'process raft request' (duration: 142.663564ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:30:14.508114Z","caller":"traceutil/trace.go:171","msg":"trace[1391429698] transaction","detail":"{read_only:false; response_revision:4661; number_of_response:1; }","duration":"142.536711ms","start":"2026-04-28T03:30:14.365565Z","end":"2026-04-28T03:30:14.508102Z","steps":["trace[1391429698] 'process raft request' (duration: 142.241205ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:30:15.834173Z","caller":"traceutil/trace.go:171","msg":"trace[871742338] linearizableReadLoop","detail":"{readStateIndex:4898; appliedIndex:4897; }","duration":"154.187402ms","start":"2026-04-28T03:30:15.679959Z","end":"2026-04-28T03:30:15.834147Z","steps":["trace[871742338] 'read index received' (duration: 79.564288ms)","trace[871742338] 'applied index is now lower than readState.Index' (duration: 74.621974ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:30:15.834258Z","caller":"traceutil/trace.go:171","msg":"trace[2060943043] transaction","detail":"{read_only:false; response_revision:4726; number_of_response:1; }","duration":"196.167367ms","start":"2026-04-28T03:30:15.63804Z","end":"2026-04-28T03:30:15.834207Z","steps":["trace[2060943043] 'process raft request' (duration: 121.473532ms)","trace[2060943043] 'compare' (duration: 74.374119ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:30:15.834314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.339754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:30:15.834388Z","caller":"traceutil/trace.go:171","msg":"trace[915272810] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4726; }","duration":"154.432706ms","start":"2026-04-28T03:30:15.679937Z","end":"2026-04-28T03:30:15.83437Z","steps":["trace[915272810] 'agreement among raft nodes before linearized reading' (duration: 154.322724ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:30:25.986299Z","caller":"traceutil/trace.go:171","msg":"trace[1919416690] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"104.776826ms","start":"2026-04-28T03:30:25.881499Z","end":"2026-04-28T03:30:25.986276Z","steps":["trace[1919416690] 'process raft request' (duration: 104.621183ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:30:45.910012Z","caller":"traceutil/trace.go:171","msg":"trace[251359684] transaction","detail":"{read_only:false; response_revision:5266; number_of_response:1; }","duration":"100.427127ms","start":"2026-04-28T03:30:45.809558Z","end":"2026-04-28T03:30:45.909985Z","steps":["trace[251359684] 'process raft request' (duration: 99.961228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:30:46.193652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.894922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T03:30:46.193742Z","caller":"traceutil/trace.go:171","msg":"trace[443270469] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:5266; }","duration":"174.051225ms","start":"2026-04-28T03:30:46.019668Z","end":"2026-04-28T03:30:46.193719Z","steps":["trace[443270469] 'count revisions from in-memory index tree' (duration: 173.749679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:30:46.193862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.98183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T03:30:46.193979Z","caller":"traceutil/trace.go:171","msg":"trace[618259448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5266; }","duration":"108.163134ms","start":"2026-04-28T03:30:46.085796Z","end":"2026-04-28T03:30:46.193959Z","steps":["trace[618259448] 'range keys from in-memory index tree' (duration: 107.649224ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:30:46.366656Z","caller":"traceutil/trace.go:171","msg":"trace[1100545427] transaction","detail":"{read_only:false; response_revision:5271; number_of_response:1; }","duration":"112.304212ms","start":"2026-04-28T03:30:46.254327Z","end":"2026-04-28T03:30:46.366631Z","steps":["trace[1100545427] 'process raft request' (duration: 112.090128ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:30:46.366867Z","caller":"traceutil/trace.go:171","msg":"trace[1667353737] transaction","detail":"{read_only:false; response_revision:5272; number_of_response:1; }","duration":"112.102658ms","start":"2026-04-28T03:30:46.254754Z","end":"2026-04-28T03:30:46.366857Z","steps":["trace[1667353737] 'process raft request' (duration: 111.821503ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:30:46.367133Z","caller":"traceutil/trace.go:171","msg":"trace[910819749] transaction","detail":"{read_only:false; response_revision:5273; number_of_response:1; }","duration":"111.944556ms","start":"2026-04-28T03:30:46.25516Z","end":"2026-04-28T03:30:46.367104Z","steps":["trace[910819749] 'process raft request' (duration: 111.658451ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:31:33.348888Z","caller":"traceutil/trace.go:171","msg":"trace[506705120] transaction","detail":"{read_only:false; response_revision:5717; number_of_response:1; }","duration":"114.037455ms","start":"2026-04-28T03:31:33.234823Z","end":"2026-04-28T03:31:33.348861Z","steps":["trace[506705120] 'process raft request' (duration: 113.840361ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:32:13.826643Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2880} {"level":"info","ts":"2026-04-28T03:32:13.895865Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2880,"took":"67.055615ms","hash":3377813089,"current-db-size-bytes":58654720,"current-db-size":"59 MB","current-db-size-in-use-bytes":33714176,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-28T03:32:13.895928Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3377813089,"revision":2880,"compact-revision":-1} {"level":"info","ts":"2026-04-28T03:36:24.585115Z","caller":"traceutil/trace.go:171","msg":"trace[80858735] linearizableReadLoop","detail":"{readStateIndex:8159; appliedIndex:8158; }","duration":"143.443656ms","start":"2026-04-28T03:36:24.441642Z","end":"2026-04-28T03:36:24.585086Z","steps":["trace[80858735] 'read index received' (duration: 143.252841ms)","trace[80858735] 'applied index is now lower than readState.Index' (duration: 188.985µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:36:24.585176Z","caller":"traceutil/trace.go:171","msg":"trace[356030273] transaction","detail":"{read_only:false; response_revision:7889; number_of_response:1; }","duration":"264.268045ms","start":"2026-04-28T03:36:24.320874Z","end":"2026-04-28T03:36:24.585142Z","steps":["trace[356030273] 'process raft request' (duration: 264.025979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:36:24.58539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.733781ms","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-28T03:36:24.585469Z","caller":"traceutil/trace.go:171","msg":"trace[1343343792] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7889; }","duration":"143.878614ms","start":"2026-04-28T03:36:24.441579Z","end":"2026-04-28T03:36:24.585457Z","steps":["trace[1343343792] 'agreement among raft nodes before linearized reading' (duration: 143.6546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:36:24.585573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.917282ms","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-28T03:36:24.585677Z","caller":"traceutil/trace.go:171","msg":"trace[1843246653] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7889; }","duration":"104.069635ms","start":"2026-04-28T03:36:24.48159Z","end":"2026-04-28T03:36:24.58566Z","steps":["trace[1843246653] 'agreement among raft nodes before linearized reading' (duration: 103.740019ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:36:24.720334Z","caller":"traceutil/trace.go:171","msg":"trace[1563868010] transaction","detail":"{read_only:false; response_revision:7890; number_of_response:1; }","duration":"130.363108ms","start":"2026-04-28T03:36:24.589948Z","end":"2026-04-28T03:36:24.720311Z","steps":["trace[1563868010] 'process raft request' (duration: 130.210316ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:36:24.721034Z","caller":"traceutil/trace.go:171","msg":"trace[1875797316] transaction","detail":"{read_only:false; response_revision:7891; number_of_response:1; }","duration":"131.020672ms","start":"2026-04-28T03:36:24.589983Z","end":"2026-04-28T03:36:24.721003Z","steps":["trace[1875797316] 'process raft request' (duration: 130.880968ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:36:33.281349Z","caller":"traceutil/trace.go:171","msg":"trace[1539087804] transaction","detail":"{read_only:false; response_revision:7935; number_of_response:1; }","duration":"173.688734ms","start":"2026-04-28T03:36:33.107634Z","end":"2026-04-28T03:36:33.281323Z","steps":["trace[1539087804] 'process raft request' (duration: 173.53677ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:37:01.026854Z","caller":"traceutil/trace.go:171","msg":"trace[634206797] linearizableReadLoop","detail":"{readStateIndex:8415; appliedIndex:8414; }","duration":"107.388228ms","start":"2026-04-28T03:37:00.919427Z","end":"2026-04-28T03:37:01.026815Z","steps":["trace[634206797] 'read index received' (duration: 107.076212ms)","trace[634206797] 'applied index is now lower than readState.Index' (duration: 310.426µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:37:01.026917Z","caller":"traceutil/trace.go:171","msg":"trace[391385299] transaction","detail":"{read_only:false; response_revision:8136; number_of_response:1; }","duration":"269.778257ms","start":"2026-04-28T03:37:00.757109Z","end":"2026-04-28T03:37:01.026888Z","steps":["trace[391385299] 'process raft request' (duration: 269.39642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:37:01.027156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.692263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T03:37:01.027267Z","caller":"traceutil/trace.go:171","msg":"trace[1126434076] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:8136; }","duration":"107.819535ms","start":"2026-04-28T03:37:00.919421Z","end":"2026-04-28T03:37:01.027241Z","steps":["trace[1126434076] 'agreement among raft nodes before linearized reading' (duration: 107.597701ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:37:01.176772Z","caller":"traceutil/trace.go:171","msg":"trace[1532024077] transaction","detail":"{read_only:false; response_revision:8137; number_of_response:1; }","duration":"145.668026ms","start":"2026-04-28T03:37:01.031072Z","end":"2026-04-28T03:37:01.17674Z","steps":["trace[1532024077] 'process raft request' (duration: 88.036244ms)","trace[1532024077] 'compare' (duration: 57.479639ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:37:01.183024Z","caller":"traceutil/trace.go:171","msg":"trace[2117703718] transaction","detail":"{read_only:false; response_revision:8138; number_of_response:1; }","duration":"150.474225ms","start":"2026-04-28T03:37:01.032527Z","end":"2026-04-28T03:37:01.183001Z","steps":["trace[2117703718] 'process raft request' (duration: 150.119358ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:37:13.880262Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6031} {"level":"warn","ts":"2026-04-28T03:37:14.41343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.852873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:37:14.413517Z","caller":"traceutil/trace.go:171","msg":"trace[1777264537] linearizableReadLoop","detail":"{readStateIndex:8535; appliedIndex:8534; }","duration":"253.365947ms","start":"2026-04-28T03:37:14.16014Z","end":"2026-04-28T03:37:14.413506Z","steps":["trace[1777264537] 'read index received' (duration: 20.391092ms)","trace[1777264537] 'applied index is now lower than readState.Index' (duration: 232.973765ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:37:14.413645Z","caller":"traceutil/trace.go:171","msg":"trace[1374763559] transaction","detail":"{read_only:false; response_revision:8250; number_of_response:1; }","duration":"258.304081ms","start":"2026-04-28T03:37:14.155306Z","end":"2026-04-28T03:37:14.41361Z","steps":["trace[1374763559] 'process raft request' (duration: 25.193434ms)","trace[1374763559] 'compare' (duration: 231.863284ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:37:14.413715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.53944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:37:14.413801Z","caller":"traceutil/trace.go:171","msg":"trace[1219292790] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:8250; }","duration":"253.683283ms","start":"2026-04-28T03:37:14.160095Z","end":"2026-04-28T03:37:14.413779Z","steps":["trace[1219292790] 'agreement among raft nodes before linearized reading' (duration: 253.52241ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:37:14.476579Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6031,"took":"594.197807ms","hash":2422934613,"current-db-size-bytes":58654720,"current-db-size":"59 MB","current-db-size-in-use-bytes":22630400,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-28T03:37:14.476634Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2422934613,"revision":6031,"compact-revision":2880} {"level":"warn","ts":"2026-04-28T03:37:14.476958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.009746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T03:37:14.47699Z","caller":"traceutil/trace.go:171","msg":"trace[2091206624] transaction","detail":"{read_only:false; response_revision:8251; number_of_response:1; }","duration":"154.676694ms","start":"2026-04-28T03:37:14.322252Z","end":"2026-04-28T03:37:14.476929Z","steps":["trace[2091206624] 'process raft request' (duration: 107.474957ms)","trace[2091206624] 'compare' (duration: 46.916502ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:37:14.477022Z","caller":"traceutil/trace.go:171","msg":"trace[1981960932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8251; }","duration":"265.110567ms","start":"2026-04-28T03:37:14.211898Z","end":"2026-04-28T03:37:14.477009Z","steps":["trace[1981960932] 'agreement among raft nodes before linearized reading' (duration: 264.883903ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:38:17.702835Z","caller":"traceutil/trace.go:171","msg":"trace[1803326694] transaction","detail":"{read_only:false; response_revision:8866; number_of_response:1; }","duration":"163.353647ms","start":"2026-04-28T03:38:17.539441Z","end":"2026-04-28T03:38:17.702795Z","steps":["trace[1803326694] 'process raft request' (duration: 146.834356ms)","trace[1803326694] 'compare' (duration: 15.996191ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:38:25.851124Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000241a.wal"} {"level":"info","ts":"2026-04-28T03:39:54.515362Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e98569549cc430c","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-28T03:39:54.522503Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-28T03:39:54.5226Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-04-28T03:41:52.04127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.859812ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-28T03:41:52.041415Z","caller":"traceutil/trace.go:171","msg":"trace[932996223] linearizableReadLoop","detail":"{readStateIndex:11120; appliedIndex:11119; }","duration":"140.110202ms","start":"2026-04-28T03:41:51.90128Z","end":"2026-04-28T03:41:52.04139Z","steps":["trace[932996223] 'read index received' (duration: 4.068805ms)","trace[932996223] 'applied index is now lower than readState.Index' (duration: 136.039507ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:41:52.041668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.109316ms","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-28T03:41:52.041716Z","caller":"traceutil/trace.go:171","msg":"trace[732393141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10763; }","duration":"113.213058ms","start":"2026-04-28T03:41:51.928492Z","end":"2026-04-28T03:41:52.041705Z","steps":["trace[732393141] 'agreement among raft nodes before linearized reading' (duration: 113.027454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:41:52.041831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.260019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T03:41:52.041892Z","caller":"traceutil/trace.go:171","msg":"trace[1424580587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10763; }","duration":"113.384501ms","start":"2026-04-28T03:41:51.928493Z","end":"2026-04-28T03:41:52.041877Z","steps":["trace[1424580587] 'agreement among raft nodes before linearized reading' (duration: 113.229498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:41:52.042168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.874987ms","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-28T03:41:52.042266Z","caller":"traceutil/trace.go:171","msg":"trace[905255314] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10763; }","duration":"141.022559ms","start":"2026-04-28T03:41:51.901221Z","end":"2026-04-28T03:41:52.042244Z","steps":["trace[905255314] 'agreement among raft nodes before linearized reading' (duration: 140.253875ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:42:13.887425Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8248} {"level":"info","ts":"2026-04-28T03:42:13.971475Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8248,"took":"81.589203ms","hash":4156804272,"current-db-size-bytes":58654720,"current-db-size":"59 MB","current-db-size-in-use-bytes":26660864,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-28T03:42:13.971569Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4156804272,"revision":8248,"compact-revision":6031} {"level":"info","ts":"2026-04-28T03:47:13.893557Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10934} {"level":"info","ts":"2026-04-28T03:47:13.977912Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10934,"took":"80.8374ms","hash":342872025,"current-db-size-bytes":58654720,"current-db-size":"59 MB","current-db-size-in-use-bytes":27353088,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-28T03:47:13.978002Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":342872025,"revision":10934,"compact-revision":8248} {"level":"info","ts":"2026-04-28T03:47:42.795576Z","caller":"traceutil/trace.go:171","msg":"trace[1455845355] transaction","detail":"{read_only:false; response_revision:13167; number_of_response:1; }","duration":"113.508496ms","start":"2026-04-28T03:47:42.68203Z","end":"2026-04-28T03:47:42.795539Z","steps":["trace[1455845355] 'process raft request' (duration: 97.585526ms)","trace[1455845355] 'compare' (duration: 15.774086ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:48:01.405519Z","caller":"traceutil/trace.go:171","msg":"trace[1804077035] transaction","detail":"{read_only:false; response_revision:13263; number_of_response:1; }","duration":"172.943183ms","start":"2026-04-28T03:48:01.232546Z","end":"2026-04-28T03:48:01.405489Z","steps":["trace[1804077035] 'process raft request' (duration: 170.14864ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:48:15.5277Z","caller":"traceutil/trace.go:171","msg":"trace[2094809355] linearizableReadLoop","detail":"{readStateIndex:13795; appliedIndex:13794; }","duration":"155.639917ms","start":"2026-04-28T03:48:15.372027Z","end":"2026-04-28T03:48:15.527667Z","steps":["trace[2094809355] 'read index received' (duration: 155.382983ms)","trace[2094809355] 'applied index is now lower than readState.Index' (duration: 254.814µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:48:15.527858Z","caller":"traceutil/trace.go:171","msg":"trace[964204015] transaction","detail":"{read_only:false; response_revision:13348; number_of_response:1; }","duration":"355.973908ms","start":"2026-04-28T03:48:15.17187Z","end":"2026-04-28T03:48:15.527844Z","steps":["trace[964204015] 'process raft request' (duration: 355.547659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:48:15.528009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:48:15.171837Z","time spent":"356.048788ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:48:15.528081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.039936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-04-28T03:48:15.528171Z","caller":"traceutil/trace.go:171","msg":"trace[1657604410] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:13348; }","duration":"156.212379ms","start":"2026-04-28T03:48:15.371932Z","end":"2026-04-28T03:48:15.528145Z","steps":["trace[1657604410] 'agreement among raft nodes before linearized reading' (duration: 155.969335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:48:15.543711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.958687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-gt9rl\" ","response":"range_response_count:1 size:12364"} {"level":"info","ts":"2026-04-28T03:48:15.543787Z","caller":"traceutil/trace.go:171","msg":"trace[1173893587] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-gt9rl; range_end:; response_count:1; response_revision:13349; }","duration":"136.08589ms","start":"2026-04-28T03:48:15.407688Z","end":"2026-04-28T03:48:15.543774Z","steps":["trace[1173893587] 'agreement among raft nodes before linearized reading' (duration: 135.869586ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:52:13.89835Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12939} {"level":"info","ts":"2026-04-28T03:52:14.038846Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12939,"took":"136.198526ms","hash":276840994,"current-db-size-bytes":58654720,"current-db-size":"59 MB","current-db-size-in-use-bytes":31358976,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-28T03:52:14.038906Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":276840994,"revision":12939,"compact-revision":10934} {"level":"info","ts":"2026-04-28T03:55:18.746615Z","caller":"traceutil/trace.go:171","msg":"trace[1343348377] transaction","detail":"{read_only:false; response_revision:16885; number_of_response:1; }","duration":"103.95715ms","start":"2026-04-28T03:55:18.642631Z","end":"2026-04-28T03:55:18.746588Z","steps":["trace[1343348377] 'process raft request' (duration: 103.755236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:55:19.131427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.537512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T03:55:19.131528Z","caller":"traceutil/trace.go:171","msg":"trace[1902529101] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:16885; }","duration":"160.679365ms","start":"2026-04-28T03:55:18.970828Z","end":"2026-04-28T03:55:19.131507Z","steps":["trace[1902529101] 'count revisions from in-memory index tree' (duration: 160.479961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:55:19.132125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.389338ms","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-28T03:55:19.13219Z","caller":"traceutil/trace.go:171","msg":"trace[171385365] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:16885; }","duration":"169.501881ms","start":"2026-04-28T03:55:18.962674Z","end":"2026-04-28T03:55:19.132176Z","steps":["trace[171385365] 'count revisions from in-memory index tree' (duration: 169.282246ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:55:19.133161Z","caller":"traceutil/trace.go:171","msg":"trace[1684240165] transaction","detail":"{read_only:false; response_revision:16886; number_of_response:1; }","duration":"133.604817ms","start":"2026-04-28T03:55:18.999509Z","end":"2026-04-28T03:55:19.133114Z","steps":["trace[1684240165] 'process raft request' (duration: 120.862837ms)","trace[1684240165] 'compare' (duration: 10.999077ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:55:19.133457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.340095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-28T03:55:19.133541Z","caller":"traceutil/trace.go:171","msg":"trace[260355136] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16885; }","duration":"203.468017ms","start":"2026-04-28T03:55:18.930053Z","end":"2026-04-28T03:55:19.133521Z","steps":["trace[260355136] 'range keys from in-memory index tree' (duration: 202.049231ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:57:13.907135Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15471} {"level":"info","ts":"2026-04-28T03:57:14.009549Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15471,"took":"98.411537ms","hash":4186660342,"current-db-size-bytes":58654720,"current-db-size":"59 MB","current-db-size-in-use-bytes":33390592,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-28T03:57:14.009608Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4186660342,"revision":15471,"compact-revision":12939} {"level":"info","ts":"2026-04-28T03:59:55.578533Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e98569549cc430c","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-28T03:59:55.583724Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-28T03:59:55.583826Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-28T04:02:13.913879Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17988} {"level":"info","ts":"2026-04-28T04:02:14.031287Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17988,"took":"113.426589ms","hash":2821712934,"current-db-size-bytes":58654720,"current-db-size":"59 MB","current-db-size-in-use-bytes":31657984,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-28T04:02:14.031364Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2821712934,"revision":17988,"compact-revision":15471} {"level":"info","ts":"2026-04-28T04:02:32.741369Z","caller":"traceutil/trace.go:171","msg":"trace[695208246] transaction","detail":"{read_only:false; response_revision:20599; number_of_response:1; }","duration":"120.538731ms","start":"2026-04-28T04:02:32.620788Z","end":"2026-04-28T04:02:32.741327Z","steps":["trace[695208246] 'process raft request' (duration: 120.219105ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:02:32.992788Z","caller":"traceutil/trace.go:171","msg":"trace[1105852458] transaction","detail":"{read_only:false; response_revision:20600; number_of_response:1; }","duration":"208.082644ms","start":"2026-04-28T04:02:32.784667Z","end":"2026-04-28T04:02:32.992749Z","steps":["trace[1105852458] 'process raft request' (duration: 156.635499ms)","trace[1105852458] 'compare' (duration: 51.229861ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:02:34.249739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.757275ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T04:02:34.249957Z","caller":"traceutil/trace.go:171","msg":"trace[147542700] transaction","detail":"{read_only:false; response_revision:20605; number_of_response:1; }","duration":"197.388594ms","start":"2026-04-28T04:02:34.052552Z","end":"2026-04-28T04:02:34.249941Z","steps":["trace[147542700] 'process raft request' (duration: 197.346763ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:02:34.250092Z","caller":"traceutil/trace.go:171","msg":"trace[1752910702] transaction","detail":"{read_only:false; response_revision:20604; number_of_response:1; }","duration":"225.42919ms","start":"2026-04-28T04:02:34.024653Z","end":"2026-04-28T04:02:34.250082Z","steps":["trace[1752910702] 'process raft request' (duration: 225.192006ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:02:34.250165Z","caller":"traceutil/trace.go:171","msg":"trace[765923527] transaction","detail":"{read_only:false; response_revision:20603; number_of_response:1; }","duration":"225.510581ms","start":"2026-04-28T04:02:34.024638Z","end":"2026-04-28T04:02:34.250148Z","steps":["trace[765923527] 'process raft request' (duration: 79.259617ms)","trace[765923527] 'compare' (duration: 145.614723ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:06:06.019352Z","caller":"traceutil/trace.go:171","msg":"trace[1836042230] transaction","detail":"{read_only:false; response_revision:22969; number_of_response:1; }","duration":"125.163778ms","start":"2026-04-28T04:06:05.894151Z","end":"2026-04-28T04:06:06.019315Z","steps":["trace[1836042230] 'process raft request' (duration: 124.891552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:06:06.399828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.59335ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T04:06:06.399959Z","caller":"traceutil/trace.go:171","msg":"trace[2112208231] linearizableReadLoop","detail":"{readStateIndex:23717; appliedIndex:23716; }","duration":"374.78262ms","start":"2026-04-28T04:06:06.025151Z","end":"2026-04-28T04:06:06.399934Z","steps":["trace[2112208231] 'read index received' (duration: 27.993315ms)","trace[2112208231] 'applied index is now lower than readState.Index' (duration: 346.786825ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:06:06.400129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.982474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager\" ","response":"range_response_count:1 size:5182"} {"level":"info","ts":"2026-04-28T04:06:06.400161Z","caller":"traceutil/trace.go:171","msg":"trace[1120070330] range","detail":"{range_begin:/registry/deployments/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager; range_end:; response_count:1; response_revision:22970; }","duration":"375.057635ms","start":"2026-04-28T04:06:06.025091Z","end":"2026-04-28T04:06:06.400149Z","steps":["trace[1120070330] 'agreement among raft nodes before linearized reading' (duration: 374.906302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:06:06.400196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:06:06.025073Z","time spent":"375.115387ms","remote":"127.0.0.1:41166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":5204,"request content":"key:\"/registry/deployments/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager\" "} {"level":"info","ts":"2026-04-28T04:06:06.4002Z","caller":"traceutil/trace.go:171","msg":"trace[1661656096] transaction","detail":"{read_only:false; response_revision:22970; number_of_response:1; }","duration":"375.716058ms","start":"2026-04-28T04:06:06.024439Z","end":"2026-04-28T04:06:06.400155Z","steps":["trace[1661656096] 'process raft request' (duration: 28.698439ms)","trace[1661656096] 'compare' (duration: 346.156503ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:06:06.400274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:06:06.024411Z","time spent":"375.827731ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T04:06:06.439218Z","caller":"traceutil/trace.go:171","msg":"trace[457538247] transaction","detail":"{read_only:false; response_revision:22971; number_of_response:1; }","duration":"413.816443ms","start":"2026-04-28T04:06:06.025371Z","end":"2026-04-28T04:06:06.439188Z","steps":["trace[457538247] 'process raft request' (duration: 413.685309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:06:06.439382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:06:06.025343Z","time spent":"413.948976ms","remote":"127.0.0.1:41194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4559,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T04:06:06.440806Z","caller":"traceutil/trace.go:171","msg":"trace[1228881898] transaction","detail":"{read_only:false; response_revision:22972; number_of_response:1; }","duration":"149.415443ms","start":"2026-04-28T04:06:06.291373Z","end":"2026-04-28T04:06:06.440789Z","steps":["trace[1228881898] 'process raft request' (duration: 149.037096ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:07:13.932028Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20503} {"level":"info","ts":"2026-04-28T04:07:14.047194Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20503,"took":"111.550352ms","hash":2068133471,"current-db-size-bytes":58654720,"current-db-size":"59 MB","current-db-size-in-use-bytes":42852352,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-04-28T04:07:14.047251Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2068133471,"revision":20503,"compact-revision":17988} {"level":"warn","ts":"2026-04-28T04:09:28.604985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.299045ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T04:09:28.605102Z","caller":"traceutil/trace.go:171","msg":"trace[1046749431] transaction","detail":"{read_only:false; response_revision:25021; number_of_response:1; }","duration":"267.744412ms","start":"2026-04-28T04:09:28.337336Z","end":"2026-04-28T04:09:28.60508Z","steps":["trace[1046749431] 'process raft request' (duration: 73.251854ms)","trace[1046749431] 'compare' (duration: 194.069761ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:09:28.682272Z","caller":"traceutil/trace.go:171","msg":"trace[759956552] linearizableReadLoop","detail":"{readStateIndex:25824; appliedIndex:25821; }","duration":"302.375142ms","start":"2026-04-28T04:09:28.37987Z","end":"2026-04-28T04:09:28.682246Z","steps":["trace[759956552] 'read index received' (duration: 30.731946ms)","trace[759956552] 'applied index is now lower than readState.Index' (duration: 271.642076ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:09:28.682334Z","caller":"traceutil/trace.go:171","msg":"trace[1957263985] transaction","detail":"{read_only:false; response_revision:25023; number_of_response:1; }","duration":"340.324664ms","start":"2026-04-28T04:09:28.341982Z","end":"2026-04-28T04:09:28.682306Z","steps":["trace[1957263985] 'process raft request' (duration: 340.224922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:28.682478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:28.341962Z","time spent":"340.437265ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T04:09:28.682574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.680257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-04-28T04:09:28.682621Z","caller":"traceutil/trace.go:171","msg":"trace[814775300] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25023; }","duration":"302.774409ms","start":"2026-04-28T04:09:28.379833Z","end":"2026-04-28T04:09:28.682608Z","steps":["trace[814775300] 'agreement among raft nodes before linearized reading' (duration: 302.562275ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:28.682325Z","caller":"traceutil/trace.go:171","msg":"trace[625759784] transaction","detail":"{read_only:false; response_revision:25022; number_of_response:1; }","duration":"341.126088ms","start":"2026-04-28T04:09:28.341165Z","end":"2026-04-28T04:09:28.682291Z","steps":["trace[625759784] 'process raft request' (duration: 340.892964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:28.682659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:28.3798Z","time spent":"302.847181ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":596,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-28T04:09:28.682708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:28.341147Z","time spent":"341.530565ms","remote":"127.0.0.1:40970","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-28T04:09:28.682921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.20107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-28T04:09:28.682975Z","caller":"traceutil/trace.go:171","msg":"trace[2131919419] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25023; }","duration":"205.253891ms","start":"2026-04-28T04:09:28.477706Z","end":"2026-04-28T04:09:28.68296Z","steps":["trace[2131919419] 'agreement among raft nodes before linearized reading' (duration: 204.885373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:28.844718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.104016ms","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-28T04:09:28.844796Z","caller":"traceutil/trace.go:171","msg":"trace[183746222] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25025; }","duration":"118.230837ms","start":"2026-04-28T04:09:28.726544Z","end":"2026-04-28T04:09:28.844774Z","steps":["trace[183746222] 'range keys from in-memory index tree' (duration: 117.873211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:29.067327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.28405ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T04:09:29.067435Z","caller":"traceutil/trace.go:171","msg":"trace[1818500463] linearizableReadLoop","detail":"{readStateIndex:25828; appliedIndex:25827; }","duration":"221.963363ms","start":"2026-04-28T04:09:28.845455Z","end":"2026-04-28T04:09:29.067419Z","steps":["trace[1818500463] 'read index received' (duration: 120.419788ms)","trace[1818500463] 'applied index is now lower than readState.Index' (duration: 101.542595ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:09:29.067576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.117875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-04-28T04:09:29.067664Z","caller":"traceutil/trace.go:171","msg":"trace[465434357] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25027; }","duration":"222.237988ms","start":"2026-04-28T04:09:28.845417Z","end":"2026-04-28T04:09:29.067655Z","steps":["trace[465434357] 'agreement among raft nodes before linearized reading' (duration: 222.047224ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:29.067587Z","caller":"traceutil/trace.go:171","msg":"trace[1943455660] transaction","detail":"{read_only:false; response_revision:25027; number_of_response:1; }","duration":"238.150147ms","start":"2026-04-28T04:09:28.829403Z","end":"2026-04-28T04:09:29.067553Z","steps":["trace[1943455660] 'process raft request' (duration: 136.527021ms)","trace[1943455660] 'compare' (duration: 101.172378ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:09:29.528518Z","caller":"traceutil/trace.go:171","msg":"trace[1873319407] linearizableReadLoop","detail":"{readStateIndex:25829; appliedIndex:25828; }","duration":"123.071018ms","start":"2026-04-28T04:09:29.405427Z","end":"2026-04-28T04:09:29.528498Z","steps":["trace[1873319407] 'read index received' (duration: 122.901364ms)","trace[1873319407] 'applied index is now lower than readState.Index' (duration: 169.084µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:09:29.5286Z","caller":"traceutil/trace.go:171","msg":"trace[1166683283] transaction","detail":"{read_only:false; response_revision:25028; number_of_response:1; }","duration":"153.167894ms","start":"2026-04-28T04:09:29.375426Z","end":"2026-04-28T04:09:29.528593Z","steps":["trace[1166683283] 'process raft request' (duration: 152.956599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:29.528839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.388415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-28T04:09:29.528967Z","caller":"traceutil/trace.go:171","msg":"trace[1911228616] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25028; }","duration":"123.534647ms","start":"2026-04-28T04:09:29.405391Z","end":"2026-04-28T04:09:29.528926Z","steps":["trace[1911228616] 'agreement among raft nodes before linearized reading' (duration: 123.17671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:34.757528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.499742ms","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-28T04:09:34.757614Z","caller":"traceutil/trace.go:171","msg":"trace[240208714] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25062; }","duration":"118.597124ms","start":"2026-04-28T04:09:34.638995Z","end":"2026-04-28T04:09:34.757593Z","steps":["trace[240208714] 'range keys from in-memory index tree' (duration: 118.305759ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:34.905532Z","caller":"traceutil/trace.go:171","msg":"trace[984503370] transaction","detail":"{read_only:false; response_revision:25063; number_of_response:1; }","duration":"144.414469ms","start":"2026-04-28T04:09:34.761095Z","end":"2026-04-28T04:09:34.90551Z","steps":["trace[984503370] 'process raft request' (duration: 144.250126ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:35.177867Z","caller":"traceutil/trace.go:171","msg":"trace[1192472923] transaction","detail":"{read_only:false; response_revision:25064; number_of_response:1; }","duration":"415.385341ms","start":"2026-04-28T04:09:34.762449Z","end":"2026-04-28T04:09:35.177834Z","steps":["trace[1192472923] 'process raft request' (duration: 415.112956ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:35.177909Z","caller":"traceutil/trace.go:171","msg":"trace[939468213] transaction","detail":"{read_only:false; response_revision:25067; number_of_response:1; }","duration":"414.933793ms","start":"2026-04-28T04:09:34.762934Z","end":"2026-04-28T04:09:35.177868Z","steps":["trace[939468213] 'process raft request' (duration: 414.859362ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:35.178119Z","caller":"traceutil/trace.go:171","msg":"trace[928700981] linearizableReadLoop","detail":"{readStateIndex:25870; appliedIndex:25865; }","duration":"252.800982ms","start":"2026-04-28T04:09:34.925309Z","end":"2026-04-28T04:09:35.17811Z","steps":["trace[928700981] 'read index received' (duration: 252.224941ms)","trace[928700981] 'applied index is now lower than readState.Index' (duration: 572.011µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:09:35.17813Z","caller":"traceutil/trace.go:171","msg":"trace[2145118871] transaction","detail":"{read_only:false; response_revision:25066; number_of_response:1; }","duration":"415.571915ms","start":"2026-04-28T04:09:34.762545Z","end":"2026-04-28T04:09:35.178117Z","steps":["trace[2145118871] 'process raft request' (duration: 415.215338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:35.178201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.897414ms","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-28T04:09:35.178231Z","caller":"traceutil/trace.go:171","msg":"trace[173599724] 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:25068; }","duration":"252.928654ms","start":"2026-04-28T04:09:34.925293Z","end":"2026-04-28T04:09:35.178222Z","steps":["trace[173599724] 'agreement among raft nodes before linearized reading' (duration: 252.869283ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:35.178198Z","caller":"traceutil/trace.go:171","msg":"trace[1627435962] transaction","detail":"{read_only:false; response_revision:25068; number_of_response:1; }","duration":"293.699859ms","start":"2026-04-28T04:09:34.884457Z","end":"2026-04-28T04:09:35.178157Z","steps":["trace[1627435962] 'process raft request' (duration: 293.367582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:35.178338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:34.762528Z","time spent":"415.679797ms","remote":"127.0.0.1:40970","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-28T04:09:35.17836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:34.762923Z","time spent":"415.209479ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T04:09:35.178047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:34.762436Z","time spent":"415.503404ms","remote":"127.0.0.1:40970","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-28T04:09:35.177985Z","caller":"traceutil/trace.go:171","msg":"trace[530767823] transaction","detail":"{read_only:false; response_revision:25065; number_of_response:1; }","duration":"415.398381ms","start":"2026-04-28T04:09:34.762522Z","end":"2026-04-28T04:09:35.177921Z","steps":["trace[530767823] 'process raft request' (duration: 415.195447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:35.17872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:34.762514Z","time spent":"416.102325ms","remote":"127.0.0.1:40970","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-28T04:09:35.191121Z","caller":"traceutil/trace.go:171","msg":"trace[1393250449] transaction","detail":"{read_only:false; response_revision:25069; number_of_response:1; }","duration":"105.321246ms","start":"2026-04-28T04:09:35.085779Z","end":"2026-04-28T04:09:35.1911Z","steps":["trace[1393250449] 'process raft request' (duration: 105.139292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:35.191225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.733569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/securitygroups/\" range_end:\"/registry/openstack.k-orc.cloud/securitygroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T04:09:35.191377Z","caller":"traceutil/trace.go:171","msg":"trace[206914790] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/securitygroups/; range_end:/registry/openstack.k-orc.cloud/securitygroups0; response_count:0; response_revision:25069; }","duration":"221.982003ms","start":"2026-04-28T04:09:34.969366Z","end":"2026-04-28T04:09:35.191348Z","steps":["trace[206914790] 'agreement among raft nodes before linearized reading' (duration: 221.739599ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:35.683555Z","caller":"traceutil/trace.go:171","msg":"trace[154001723] transaction","detail":"{read_only:false; response_revision:25070; number_of_response:1; }","duration":"125.751729ms","start":"2026-04-28T04:09:35.557777Z","end":"2026-04-28T04:09:35.683529Z","steps":["trace[154001723] 'process raft request' (duration: 125.576855ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:35.926859Z","caller":"traceutil/trace.go:171","msg":"trace[158115822] transaction","detail":"{read_only:false; response_revision:25071; number_of_response:1; }","duration":"368.733607ms","start":"2026-04-28T04:09:35.5581Z","end":"2026-04-28T04:09:35.926833Z","steps":["trace[158115822] 'process raft request' (duration: 368.549754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:35.927019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:35.558084Z","time spent":"368.848499ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T04:09:35.969255Z","caller":"traceutil/trace.go:171","msg":"trace[160461173] linearizableReadLoop","detail":"{readStateIndex:25875; appliedIndex:25872; }","duration":"229.608087ms","start":"2026-04-28T04:09:35.739628Z","end":"2026-04-28T04:09:35.969236Z","steps":["trace[160461173] 'read index received' (duration: 187.1643ms)","trace[160461173] 'applied index is now lower than readState.Index' (duration: 42.442587ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:09:35.969291Z","caller":"traceutil/trace.go:171","msg":"trace[1022938517] transaction","detail":"{read_only:false; response_revision:25072; number_of_response:1; }","duration":"275.673291ms","start":"2026-04-28T04:09:35.693585Z","end":"2026-04-28T04:09:35.969258Z","steps":["trace[1022938517] 'process raft request' (duration: 267.932326ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:35.969372Z","caller":"traceutil/trace.go:171","msg":"trace[187026212] transaction","detail":"{read_only:false; response_revision:25073; number_of_response:1; }","duration":"254.450023ms","start":"2026-04-28T04:09:35.714826Z","end":"2026-04-28T04:09:35.969276Z","steps":["trace[187026212] 'process raft request' (duration: 254.363831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:35.969447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.82527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-04-28T04:09:35.969518Z","caller":"traceutil/trace.go:171","msg":"trace[1582652366] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25073; }","duration":"229.947123ms","start":"2026-04-28T04:09:35.739555Z","end":"2026-04-28T04:09:35.969503Z","steps":["trace[1582652366] 'agreement among raft nodes before linearized reading' (duration: 229.747209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:36.249229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.767534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-28T04:09:36.249308Z","caller":"traceutil/trace.go:171","msg":"trace[1900143778] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:25073; }","duration":"170.879935ms","start":"2026-04-28T04:09:36.078412Z","end":"2026-04-28T04:09:36.249292Z","steps":["trace[1900143778] 'count revisions from in-memory index tree' (duration: 170.712903ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:36.251762Z","caller":"traceutil/trace.go:171","msg":"trace[672129187] transaction","detail":"{read_only:false; response_revision:25074; number_of_response:1; }","duration":"101.972323ms","start":"2026-04-28T04:09:36.149765Z","end":"2026-04-28T04:09:36.251737Z","steps":["trace[672129187] 'process raft request' (duration: 101.777019ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:37.302222Z","caller":"traceutil/trace.go:171","msg":"trace[68375294] linearizableReadLoop","detail":"{readStateIndex:25878; appliedIndex:25877; }","duration":"376.246307ms","start":"2026-04-28T04:09:36.925952Z","end":"2026-04-28T04:09:37.302198Z","steps":["trace[68375294] 'read index received' (duration: 376.133885ms)","trace[68375294] 'applied index is now lower than readState.Index' (duration: 111.072µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:09:37.302289Z","caller":"traceutil/trace.go:171","msg":"trace[513693486] transaction","detail":"{read_only:false; response_revision:25076; number_of_response:1; }","duration":"387.122922ms","start":"2026-04-28T04:09:36.915127Z","end":"2026-04-28T04:09:37.30225Z","steps":["trace[513693486] 'process raft request' (duration: 386.913618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:37.302432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:36.915102Z","time spent":"387.252504ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T04:09:37.302442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.474041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-04-28T04:09:37.302474Z","caller":"traceutil/trace.go:171","msg":"trace[1852681493] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:25076; }","duration":"376.559173ms","start":"2026-04-28T04:09:36.925906Z","end":"2026-04-28T04:09:37.302465Z","steps":["trace[1852681493] 'agreement among raft nodes before linearized reading' (duration: 376.41377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:37.302507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:36.925887Z","time spent":"376.612444ms","remote":"127.0.0.1:40726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":162,"request content":"key:\"/registry/masterleases/199.204.45.216\" "} {"level":"info","ts":"2026-04-28T04:09:37.468326Z","caller":"traceutil/trace.go:171","msg":"trace[1377625761] transaction","detail":"{read_only:false; response_revision:25077; number_of_response:1; }","duration":"284.069088ms","start":"2026-04-28T04:09:37.184232Z","end":"2026-04-28T04:09:37.468301Z","steps":["trace[1377625761] 'process raft request' (duration: 204.875023ms)","trace[1377625761] 'compare' (duration: 79.001202ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:09:37.468557Z","caller":"traceutil/trace.go:171","msg":"trace[578778375] transaction","detail":"{read_only:false; response_revision:25078; number_of_response:1; }","duration":"271.574093ms","start":"2026-04-28T04:09:37.196944Z","end":"2026-04-28T04:09:37.468518Z","steps":["trace[578778375] 'process raft request' (duration: 271.307308ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:37.468608Z","caller":"traceutil/trace.go:171","msg":"trace[765696444] linearizableReadLoop","detail":"{readStateIndex:25880; appliedIndex:25878; }","duration":"166.299089ms","start":"2026-04-28T04:09:37.302286Z","end":"2026-04-28T04:09:37.468585Z","steps":["trace[765696444] 'read index received' (duration: 86.818218ms)","trace[765696444] 'applied index is now lower than readState.Index' (duration: 79.478461ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:09:37.46884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.504177ms","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-28T04:09:37.468884Z","caller":"traceutil/trace.go:171","msg":"trace[344605722] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25078; }","duration":"284.560629ms","start":"2026-04-28T04:09:37.184313Z","end":"2026-04-28T04:09:37.468873Z","steps":["trace[344605722] 'agreement among raft nodes before linearized reading' (duration: 284.435736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:37.468876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.555728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-04-28T04:09:37.468886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.728862ms","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-28T04:09:37.468921Z","caller":"traceutil/trace.go:171","msg":"trace[138314365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25078; }","duration":"284.623189ms","start":"2026-04-28T04:09:37.184287Z","end":"2026-04-28T04:09:37.46891Z","steps":["trace[138314365] 'agreement among raft nodes before linearized reading' (duration: 284.484657ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:37.468929Z","caller":"traceutil/trace.go:171","msg":"trace[1807777075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25078; }","duration":"284.795253ms","start":"2026-04-28T04:09:37.184123Z","end":"2026-04-28T04:09:37.468918Z","steps":["trace[1807777075] 'agreement among raft nodes before linearized reading' (duration: 284.68321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:37.46884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.658143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-28T04:09:37.468983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.609468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-28T04:09:37.469009Z","caller":"traceutil/trace.go:171","msg":"trace[1947959818] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:25078; }","duration":"242.848576ms","start":"2026-04-28T04:09:37.226147Z","end":"2026-04-28T04:09:37.468996Z","steps":["trace[1947959818] 'agreement among raft nodes before linearized reading' (duration: 242.616932ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:37.469032Z","caller":"traceutil/trace.go:171","msg":"trace[1218475826] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25078; }","duration":"284.678729ms","start":"2026-04-28T04:09:37.184337Z","end":"2026-04-28T04:09:37.469016Z","steps":["trace[1218475826] 'agreement among raft nodes before linearized reading' (duration: 284.489256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:37.469345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.045677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-28T04:09:37.469405Z","caller":"traceutil/trace.go:171","msg":"trace[1068087547] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25078; }","duration":"309.77612ms","start":"2026-04-28T04:09:37.159618Z","end":"2026-04-28T04:09:37.469394Z","steps":["trace[1068087547] 'agreement among raft nodes before linearized reading' (duration: 309.044887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:37.469445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:37.159598Z","time spent":"309.834212ms","remote":"127.0.0.1:33140","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":"info","ts":"2026-04-28T04:09:37.66534Z","caller":"traceutil/trace.go:171","msg":"trace[1585667019] transaction","detail":"{read_only:false; response_revision:25080; number_of_response:1; }","duration":"192.770436ms","start":"2026-04-28T04:09:37.472546Z","end":"2026-04-28T04:09:37.665317Z","steps":["trace[1585667019] 'process raft request' (duration: 191.815068ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:37.665702Z","caller":"traceutil/trace.go:171","msg":"trace[383809961] transaction","detail":"{read_only:false; response_revision:25081; number_of_response:1; }","duration":"193.085562ms","start":"2026-04-28T04:09:37.472564Z","end":"2026-04-28T04:09:37.66565Z","steps":["trace[383809961] 'process raft request' (duration: 192.709676ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:37.666174Z","caller":"traceutil/trace.go:171","msg":"trace[1121266721] transaction","detail":"{read_only:false; response_revision:25082; number_of_response:1; }","duration":"193.565151ms","start":"2026-04-28T04:09:37.472575Z","end":"2026-04-28T04:09:37.66614Z","steps":["trace[1121266721] 'process raft request' (duration: 193.335356ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:37.666371Z","caller":"traceutil/trace.go:171","msg":"trace[1785364828] transaction","detail":"{read_only:false; response_revision:25083; number_of_response:1; }","duration":"193.628283ms","start":"2026-04-28T04:09:37.472719Z","end":"2026-04-28T04:09:37.666347Z","steps":["trace[1785364828] 'process raft request' (duration: 193.338728ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:37.666485Z","caller":"traceutil/trace.go:171","msg":"trace[437225580] linearizableReadLoop","detail":"{readStateIndex:25886; appliedIndex:25882; }","duration":"157.7964ms","start":"2026-04-28T04:09:37.508678Z","end":"2026-04-28T04:09:37.666474Z","steps":["trace[437225580] 'read index received' (duration: 155.746651ms)","trace[437225580] 'applied index is now lower than readState.Index' (duration: 2.048399ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:09:37.666628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.932903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-04-28T04:09:37.666662Z","caller":"traceutil/trace.go:171","msg":"trace[860849649] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25083; }","duration":"158.006815ms","start":"2026-04-28T04:09:37.508647Z","end":"2026-04-28T04:09:37.666654Z","steps":["trace[860849649] 'agreement among raft nodes before linearized reading' (duration: 157.863952ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:37.678809Z","caller":"traceutil/trace.go:171","msg":"trace[1172537174] transaction","detail":"{read_only:false; response_revision:25084; number_of_response:1; }","duration":"169.850637ms","start":"2026-04-28T04:09:37.508938Z","end":"2026-04-28T04:09:37.678788Z","steps":["trace[1172537174] 'process raft request' (duration: 169.689854ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:37.832428Z","caller":"traceutil/trace.go:171","msg":"trace[1499857998] transaction","detail":"{read_only:false; response_revision:25085; number_of_response:1; }","duration":"140.910462ms","start":"2026-04-28T04:09:37.691491Z","end":"2026-04-28T04:09:37.832401Z","steps":["trace[1499857998] 'process raft request' (duration: 122.487467ms)","trace[1499857998] 'compare' (duration: 18.202461ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:09:38.303402Z","caller":"traceutil/trace.go:171","msg":"trace[1408647241] transaction","detail":"{read_only:false; response_revision:25087; number_of_response:1; }","duration":"367.134806ms","start":"2026-04-28T04:09:37.936242Z","end":"2026-04-28T04:09:38.303377Z","steps":["trace[1408647241] 'process raft request' (duration: 366.971033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:38.303552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:37.936214Z","time spent":"367.259479ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T04:09:38.323181Z","caller":"traceutil/trace.go:171","msg":"trace[801980708] transaction","detail":"{read_only:false; response_revision:25088; number_of_response:1; }","duration":"348.879864ms","start":"2026-04-28T04:09:37.97427Z","end":"2026-04-28T04:09:38.32315Z","steps":["trace[801980708] 'process raft request' (duration: 348.603889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:38.323331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:37.974246Z","time spent":"349.008746ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T04:09:38.323423Z","caller":"traceutil/trace.go:171","msg":"trace[1281102132] linearizableReadLoop","detail":"{readStateIndex:25892; appliedIndex:25889; }","duration":"164.023956ms","start":"2026-04-28T04:09:38.159376Z","end":"2026-04-28T04:09:38.3234Z","steps":["trace[1281102132] 'read index received' (duration: 143.797357ms)","trace[1281102132] 'applied index is now lower than readState.Index' (duration: 20.224859ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:09:38.323206Z","caller":"traceutil/trace.go:171","msg":"trace[1863196919] transaction","detail":"{read_only:false; response_revision:25089; number_of_response:1; }","duration":"348.610489ms","start":"2026-04-28T04:09:37.974563Z","end":"2026-04-28T04:09:38.323173Z","steps":["trace[1863196919] 'process raft request' (duration: 348.461567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:38.323603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.211681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T04:09:38.32367Z","caller":"traceutil/trace.go:171","msg":"trace[2007410247] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:25090; }","duration":"164.335892ms","start":"2026-04-28T04:09:38.159315Z","end":"2026-04-28T04:09:38.323651Z","steps":["trace[2007410247] 'agreement among raft nodes before linearized reading' (duration: 164.21217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:38.32382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:37.974536Z","time spent":"349.157609ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T04:09:38.606313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.211956ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T04:09:38.606391Z","caller":"traceutil/trace.go:171","msg":"trace[936471513] linearizableReadLoop","detail":"{readStateIndex:25894; appliedIndex:25893; }","duration":"128.622623ms","start":"2026-04-28T04:09:38.477756Z","end":"2026-04-28T04:09:38.606379Z","steps":["trace[936471513] 'read index received' (duration: 25.295105ms)","trace[936471513] 'applied index is now lower than readState.Index' (duration: 103.326478ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:09:38.606465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.713655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-28T04:09:38.60649Z","caller":"traceutil/trace.go:171","msg":"trace[2021877391] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:25091; }","duration":"128.777475ms","start":"2026-04-28T04:09:38.477704Z","end":"2026-04-28T04:09:38.606482Z","steps":["trace[2021877391] 'agreement among raft nodes before linearized reading' (duration: 128.707204ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:38.606645Z","caller":"traceutil/trace.go:171","msg":"trace[1588885863] transaction","detail":"{read_only:false; response_revision:25091; number_of_response:1; }","duration":"206.296129ms","start":"2026-04-28T04:09:38.400337Z","end":"2026-04-28T04:09:38.606633Z","steps":["trace[1588885863] 'process raft request' (duration: 102.697186ms)","trace[1588885863] 'compare' (duration: 103.106554ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:09:47.63315Z","caller":"traceutil/trace.go:171","msg":"trace[1861268539] transaction","detail":"{read_only:false; response_revision:25150; number_of_response:1; }","duration":"267.733891ms","start":"2026-04-28T04:09:47.365394Z","end":"2026-04-28T04:09:47.633128Z","steps":["trace[1861268539] 'process raft request' (duration: 267.623029ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:48.025992Z","caller":"traceutil/trace.go:171","msg":"trace[66941933] transaction","detail":"{read_only:false; response_revision:25152; number_of_response:1; }","duration":"526.389144ms","start":"2026-04-28T04:09:47.499571Z","end":"2026-04-28T04:09:48.02596Z","steps":["trace[66941933] 'process raft request' (duration: 526.287142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:48.026356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.40035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-28T04:09:48.026148Z","caller":"traceutil/trace.go:171","msg":"trace[788304704] transaction","detail":"{read_only:false; response_revision:25151; number_of_response:1; }","duration":"660.151244ms","start":"2026-04-28T04:09:47.365967Z","end":"2026-04-28T04:09:48.026118Z","steps":["trace[788304704] 'process raft request' (duration: 659.709576ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:48.026432Z","caller":"traceutil/trace.go:171","msg":"trace[1410106383] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:25153; }","duration":"633.499113ms","start":"2026-04-28T04:09:47.392923Z","end":"2026-04-28T04:09:48.026422Z","steps":["trace[1410106383] 'agreement among raft nodes before linearized reading' (duration: 633.32034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:48.026501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:47.365955Z","time spent":"660.489519ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T04:09:48.02619Z","caller":"traceutil/trace.go:171","msg":"trace[1906005802] linearizableReadLoop","detail":"{readStateIndex:25956; appliedIndex:25955; }","duration":"633.228937ms","start":"2026-04-28T04:09:47.392932Z","end":"2026-04-28T04:09:48.026161Z","steps":["trace[1906005802] 'read index received' (duration: 240.335537ms)","trace[1906005802] 'applied index is now lower than readState.Index' (duration: 392.89208ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:09:48.0265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:47.392871Z","time spent":"633.616214ms","remote":"127.0.0.1:41122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-04-28T04:09:48.026278Z","caller":"traceutil/trace.go:171","msg":"trace[645662468] transaction","detail":"{read_only:false; response_revision:25153; number_of_response:1; }","duration":"524.808645ms","start":"2026-04-28T04:09:47.501434Z","end":"2026-04-28T04:09:48.026242Z","steps":["trace[645662468] 'process raft request' (duration: 524.475099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:48.026516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:47.499546Z","time spent":"526.801772ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T04:09:48.026614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:47.501419Z","time spent":"525.146911ms","remote":"127.0.0.1:40970","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-28T04:09:48.06519Z","caller":"traceutil/trace.go:171","msg":"trace[1243198102] transaction","detail":"{read_only:false; response_revision:25154; number_of_response:1; }","duration":"293.570497ms","start":"2026-04-28T04:09:47.771603Z","end":"2026-04-28T04:09:48.065174Z","steps":["trace[1243198102] 'process raft request' (duration: 293.418044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:48.065487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.564173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-28T04:09:48.065531Z","caller":"traceutil/trace.go:171","msg":"trace[415126685] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25155; }","duration":"345.647804ms","start":"2026-04-28T04:09:47.719875Z","end":"2026-04-28T04:09:48.065523Z","steps":["trace[415126685] 'agreement among raft nodes before linearized reading' (duration: 345.541772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:48.065549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:47.719854Z","time spent":"345.691075ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-28T04:09:48.065569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.727275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-04-28T04:09:48.065638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.791948ms","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-28T04:09:48.065656Z","caller":"traceutil/trace.go:171","msg":"trace[1050299682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25155; }","duration":"344.831988ms","start":"2026-04-28T04:09:47.720819Z","end":"2026-04-28T04:09:48.065651Z","steps":["trace[1050299682] 'agreement among raft nodes before linearized reading' (duration: 344.776517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:48.06567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:47.720808Z","time spent":"344.858369ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T04:09:48.065655Z","caller":"traceutil/trace.go:171","msg":"trace[464415340] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25155; }","duration":"337.848477ms","start":"2026-04-28T04:09:47.727761Z","end":"2026-04-28T04:09:48.06561Z","steps":["trace[464415340] 'agreement among raft nodes before linearized reading' (duration: 337.631783ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:48.065676Z","caller":"traceutil/trace.go:171","msg":"trace[1672060059] transaction","detail":"{read_only:false; response_revision:25155; number_of_response:1; }","duration":"218.083561ms","start":"2026-04-28T04:09:47.847551Z","end":"2026-04-28T04:09:48.065634Z","steps":["trace[1672060059] 'process raft request' (duration: 217.581791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:48.06566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.248774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1579"} {"level":"warn","ts":"2026-04-28T04:09:48.065756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.207285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T04:09:48.065783Z","caller":"traceutil/trace.go:171","msg":"trace[452464651] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25155; }","duration":"386.282746ms","start":"2026-04-28T04:09:47.679494Z","end":"2026-04-28T04:09:48.065777Z","steps":["trace[452464651] 'agreement among raft nodes before linearized reading' (duration: 386.251076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:48.0658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:47.679482Z","time spent":"386.314847ms","remote":"127.0.0.1:40710","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-28T04:09:48.065742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:47.727742Z","time spent":"337.985239ms","remote":"127.0.0.1:40970","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":"warn","ts":"2026-04-28T04:09:48.065806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.719504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T04:09:48.065823Z","caller":"traceutil/trace.go:171","msg":"trace[1932468843] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25155; }","duration":"427.434547ms","start":"2026-04-28T04:09:47.63834Z","end":"2026-04-28T04:09:48.065775Z","steps":["trace[1932468843] 'agreement among raft nodes before linearized reading' (duration: 427.04487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:48.065831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.340271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-04-28T04:09:48.065888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:47.638308Z","time spent":"427.56521ms","remote":"127.0.0.1:33008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":1601,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-04-28T04:09:48.065895Z","caller":"traceutil/trace.go:171","msg":"trace[1816895741] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:25155; }","duration":"273.846266ms","start":"2026-04-28T04:09:47.792003Z","end":"2026-04-28T04:09:48.065849Z","steps":["trace[1816895741] 'agreement among raft nodes before linearized reading' (duration: 273.722104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:48.065495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.036028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-28T04:09:48.0659Z","caller":"traceutil/trace.go:171","msg":"trace[61805666] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25155; }","duration":"190.454513ms","start":"2026-04-28T04:09:47.875424Z","end":"2026-04-28T04:09:48.065879Z","steps":["trace[61805666] 'agreement among raft nodes before linearized reading' (duration: 190.26211ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:48.065944Z","caller":"traceutil/trace.go:171","msg":"trace[1867638094] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:25155; }","duration":"148.542626ms","start":"2026-04-28T04:09:47.917394Z","end":"2026-04-28T04:09:48.065936Z","steps":["trace[1867638094] 'agreement among raft nodes before linearized reading' (duration: 148.025407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:48.065913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.989389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-04-28T04:09:48.065724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.678096ms","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-28T04:09:48.066064Z","caller":"traceutil/trace.go:171","msg":"trace[472545115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25155; }","duration":"339.165542ms","start":"2026-04-28T04:09:47.726859Z","end":"2026-04-28T04:09:48.066025Z","steps":["trace[472545115] 'agreement among raft nodes before linearized reading' (duration: 338.700603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:48.066136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.522321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1579"} {"level":"info","ts":"2026-04-28T04:09:48.066079Z","caller":"traceutil/trace.go:171","msg":"trace[1290598800] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:25155; }","duration":"111.038402ms","start":"2026-04-28T04:09:47.955019Z","end":"2026-04-28T04:09:48.066057Z","steps":["trace[1290598800] 'agreement among raft nodes before linearized reading' (duration: 110.686156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:48.066151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:47.72685Z","time spent":"339.283564ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T04:09:48.066176Z","caller":"traceutil/trace.go:171","msg":"trace[1116101] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25155; }","duration":"425.625034ms","start":"2026-04-28T04:09:47.64054Z","end":"2026-04-28T04:09:48.066165Z","steps":["trace[1116101] 'agreement among raft nodes before linearized reading' (duration: 425.413539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:48.066326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:47.640514Z","time spent":"425.803887ms","remote":"127.0.0.1:33008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":1601,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-04-28T04:09:48.327568Z","caller":"traceutil/trace.go:171","msg":"trace[1561649564] transaction","detail":"{read_only:false; response_revision:25156; number_of_response:1; }","duration":"256.818178ms","start":"2026-04-28T04:09:48.070719Z","end":"2026-04-28T04:09:48.327537Z","steps":["trace[1561649564] 'process raft request' (duration: 256.300158ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:48.590536Z","caller":"traceutil/trace.go:171","msg":"trace[1096410356] transaction","detail":"{read_only:false; response_revision:25157; number_of_response:1; }","duration":"519.298522ms","start":"2026-04-28T04:09:48.071217Z","end":"2026-04-28T04:09:48.590516Z","steps":["trace[1096410356] 'process raft request' (duration: 519.121528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:48.590662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:48.071207Z","time spent":"519.397673ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T04:09:48.590958Z","caller":"traceutil/trace.go:171","msg":"trace[1173320138] transaction","detail":"{read_only:false; response_revision:25158; number_of_response:1; }","duration":"519.606827ms","start":"2026-04-28T04:09:48.07127Z","end":"2026-04-28T04:09:48.590877Z","steps":["trace[1173320138] 'process raft request' (duration: 519.20759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:48.591347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:48.071265Z","time spent":"519.989395ms","remote":"127.0.0.1:40970","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-28T04:09:48.591372Z","caller":"traceutil/trace.go:171","msg":"trace[633744443] transaction","detail":"{read_only:false; response_revision:25159; number_of_response:1; }","duration":"519.985185ms","start":"2026-04-28T04:09:48.071306Z","end":"2026-04-28T04:09:48.591291Z","steps":["trace[633744443] 'process raft request' (duration: 519.440494ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:48.591437Z","caller":"traceutil/trace.go:171","msg":"trace[255930776] transaction","detail":"{read_only:false; response_revision:25160; number_of_response:1; }","duration":"519.911494ms","start":"2026-04-28T04:09:48.071498Z","end":"2026-04-28T04:09:48.59141Z","steps":["trace[255930776] 'process raft request' (duration: 519.594207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:48.591504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:48.071446Z","time spent":"520.025526ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T04:09:48.591539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:48.071301Z","time spent":"520.152138ms","remote":"127.0.0.1:40970","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-28T04:09:48.591592Z","caller":"traceutil/trace.go:171","msg":"trace[475711694] linearizableReadLoop","detail":"{readStateIndex:25965; appliedIndex:25960; }","duration":"518.976836ms","start":"2026-04-28T04:09:48.072602Z","end":"2026-04-28T04:09:48.591579Z","steps":["trace[475711694] 'read index received' (duration: 254.664548ms)","trace[475711694] 'applied index is now lower than readState.Index' (duration: 264.311378ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:09:48.591728Z","caller":"traceutil/trace.go:171","msg":"trace[797335673] transaction","detail":"{read_only:false; response_revision:25161; number_of_response:1; }","duration":"516.561809ms","start":"2026-04-28T04:09:48.075154Z","end":"2026-04-28T04:09:48.591716Z","steps":["trace[797335673] 'process raft request' (duration: 516.201923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:48.591779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.175999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T04:09:48.591819Z","caller":"traceutil/trace.go:171","msg":"trace[961341453] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:25161; }","duration":"519.24532ms","start":"2026-04-28T04:09:48.072561Z","end":"2026-04-28T04:09:48.591806Z","steps":["trace[961341453] 'agreement among raft nodes before linearized reading' (duration: 519.166628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:48.591825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:48.075148Z","time spent":"516.608531ms","remote":"127.0.0.1:33008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1874,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T04:09:48.591859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:48.072544Z","time spent":"519.306272ms","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-04-28T04:09:48.592195Z","caller":"traceutil/trace.go:171","msg":"trace[1378237047] transaction","detail":"{read_only:false; response_revision:25164; number_of_response:1; }","duration":"232.40954ms","start":"2026-04-28T04:09:48.359768Z","end":"2026-04-28T04:09:48.592178Z","steps":["trace[1378237047] 'process raft request' (duration: 232.342408ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:48.592191Z","caller":"traceutil/trace.go:171","msg":"trace[622690141] transaction","detail":"{read_only:false; response_revision:25163; number_of_response:1; }","duration":"236.428875ms","start":"2026-04-28T04:09:48.355744Z","end":"2026-04-28T04:09:48.592173Z","steps":["trace[622690141] 'process raft request' (duration: 236.325404ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:48.592329Z","caller":"traceutil/trace.go:171","msg":"trace[1787076667] transaction","detail":"{read_only:false; response_revision:25162; number_of_response:1; }","duration":"237.433173ms","start":"2026-04-28T04:09:48.354873Z","end":"2026-04-28T04:09:48.592306Z","steps":["trace[1787076667] 'process raft request' (duration: 237.082377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:48.592855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.975421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-28T04:09:48.592912Z","caller":"traceutil/trace.go:171","msg":"trace[1408591486] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25164; }","duration":"233.069332ms","start":"2026-04-28T04:09:48.359821Z","end":"2026-04-28T04:09:48.592891Z","steps":["trace[1408591486] 'agreement among raft nodes before linearized reading' (duration: 232.331598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:49.074919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.298071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T04:09:49.075213Z","caller":"traceutil/trace.go:171","msg":"trace[1634324066] transaction","detail":"{read_only:false; response_revision:25165; number_of_response:1; }","duration":"477.702961ms","start":"2026-04-28T04:09:48.597479Z","end":"2026-04-28T04:09:49.075182Z","steps":["trace[1634324066] 'process raft request' (duration: 296.056173ms)","trace[1634324066] 'compare' (duration: 180.722181ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:09:49.075266Z","caller":"traceutil/trace.go:171","msg":"trace[106825539] linearizableReadLoop","detail":"{readStateIndex:25972; appliedIndex:25969; }","duration":"401.240456ms","start":"2026-04-28T04:09:48.674008Z","end":"2026-04-28T04:09:49.075248Z","steps":["trace[106825539] 'read index received' (duration: 219.558157ms)","trace[106825539] 'applied index is now lower than readState.Index' (duration: 181.680909ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:09:49.075369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:48.597456Z","time spent":"477.826043ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T04:09:49.075541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.526351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-28T04:09:49.075522Z","caller":"traceutil/trace.go:171","msg":"trace[253980420] transaction","detail":"{read_only:false; response_revision:25167; number_of_response:1; }","duration":"421.539877ms","start":"2026-04-28T04:09:48.653949Z","end":"2026-04-28T04:09:49.075489Z","steps":["trace[253980420] 'process raft request' (duration: 421.231342ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:49.075592Z","caller":"traceutil/trace.go:171","msg":"trace[211430507] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:25167; }","duration":"401.618603ms","start":"2026-04-28T04:09:48.673959Z","end":"2026-04-28T04:09:49.075577Z","steps":["trace[211430507] 'agreement among raft nodes before linearized reading' (duration: 401.542821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:49.075633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:48.67394Z","time spent":"401.682514ms","remote":"127.0.0.1:36992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-28T04:09:49.075672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:48.653924Z","time spent":"421.67268ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T04:09:49.07576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.207514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T04:09:49.075759Z","caller":"traceutil/trace.go:171","msg":"trace[132917485] transaction","detail":"{read_only:false; response_revision:25166; number_of_response:1; }","duration":"476.356816ms","start":"2026-04-28T04:09:48.599366Z","end":"2026-04-28T04:09:49.075723Z","steps":["trace[132917485] 'process raft request' (duration: 475.685553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:49.075797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.697021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T04:09:49.07583Z","caller":"traceutil/trace.go:171","msg":"trace[1096183826] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25167; }","duration":"395.304996ms","start":"2026-04-28T04:09:48.680505Z","end":"2026-04-28T04:09:49.07581Z","steps":["trace[1096183826] 'agreement among raft nodes before linearized reading' (duration: 395.189444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:49.07581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.288004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-28T04:09:49.075876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:48.680492Z","time spent":"395.375967ms","remote":"127.0.0.1:40710","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-28T04:09:49.075862Z","caller":"traceutil/trace.go:171","msg":"trace[332753357] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:25167; }","duration":"243.818334ms","start":"2026-04-28T04:09:48.832025Z","end":"2026-04-28T04:09:49.075843Z","steps":["trace[332753357] 'agreement among raft nodes before linearized reading' (duration: 243.690511ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:49.075889Z","caller":"traceutil/trace.go:171","msg":"trace[152174558] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:25167; }","duration":"364.426087ms","start":"2026-04-28T04:09:48.711444Z","end":"2026-04-28T04:09:49.07587Z","steps":["trace[152174558] 'agreement among raft nodes before linearized reading' (duration: 364.275054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:49.075942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:48.711424Z","time spent":"364.507658ms","remote":"127.0.0.1:37468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":28,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true "} {"level":"warn","ts":"2026-04-28T04:09:49.075934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:48.599343Z","time spent":"476.506069ms","remote":"127.0.0.1:33008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2721,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T04:09:49.077042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.596404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-04-28T04:09:49.077095Z","caller":"traceutil/trace.go:171","msg":"trace[255313239] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:25167; }","duration":"159.652826ms","start":"2026-04-28T04:09:48.91743Z","end":"2026-04-28T04:09:49.077083Z","steps":["trace[255313239] 'agreement among raft nodes before linearized reading' (duration: 158.29253ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:49.862557Z","caller":"traceutil/trace.go:171","msg":"trace[729904159] linearizableReadLoop","detail":"{readStateIndex:25973; appliedIndex:25972; }","duration":"421.042848ms","start":"2026-04-28T04:09:49.441488Z","end":"2026-04-28T04:09:49.862531Z","steps":["trace[729904159] 'read index received' (duration: 420.853184ms)","trace[729904159] 'applied index is now lower than readState.Index' (duration: 188.364µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:09:49.862644Z","caller":"traceutil/trace.go:171","msg":"trace[1552080411] transaction","detail":"{read_only:false; response_revision:25168; number_of_response:1; }","duration":"777.463345ms","start":"2026-04-28T04:09:49.085146Z","end":"2026-04-28T04:09:49.862609Z","steps":["trace[1552080411] 'process raft request' (duration: 777.219859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:49.862804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:49.085131Z","time spent":"777.580096ms","remote":"127.0.0.1:40886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1240,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T04:09:49.862974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.452965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-56fffcff94-c9ftx.18aa680659f1789a\" ","response":"range_response_count:1 size:1138"} {"level":"info","ts":"2026-04-28T04:09:49.863071Z","caller":"traceutil/trace.go:171","msg":"trace[1024631479] range","detail":"{range_begin:/registry/events/openstack/barbican-api-56fffcff94-c9ftx.18aa680659f1789a; range_end:; response_count:1; response_revision:25168; }","duration":"421.593948ms","start":"2026-04-28T04:09:49.441453Z","end":"2026-04-28T04:09:49.863047Z","steps":["trace[1024631479] 'agreement among raft nodes before linearized reading' (duration: 421.218252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:49.863143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:49.441438Z","time spent":"421.68962ms","remote":"127.0.0.1:40768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":1160,"request content":"key:\"/registry/events/openstack/barbican-api-56fffcff94-c9ftx.18aa680659f1789a\" "} {"level":"info","ts":"2026-04-28T04:09:49.87262Z","caller":"traceutil/trace.go:171","msg":"trace[1154565264] transaction","detail":"{read_only:false; response_revision:25169; number_of_response:1; }","duration":"363.070011ms","start":"2026-04-28T04:09:49.509534Z","end":"2026-04-28T04:09:49.872604Z","steps":["trace[1154565264] 'process raft request' (duration: 362.824657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:49.872787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:49.509512Z","time spent":"363.191014ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T04:09:49.873242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.279753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-04-28T04:09:49.873327Z","caller":"traceutil/trace.go:171","msg":"trace[42630859] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:25169; }","duration":"171.395435ms","start":"2026-04-28T04:09:49.701921Z","end":"2026-04-28T04:09:49.873316Z","steps":["trace[42630859] 'agreement among raft nodes before linearized reading' (duration: 171.228252ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:50.271508Z","caller":"traceutil/trace.go:171","msg":"trace[1157448851] transaction","detail":"{read_only:false; response_revision:25170; number_of_response:1; }","duration":"404.166031ms","start":"2026-04-28T04:09:49.867311Z","end":"2026-04-28T04:09:50.271477Z","steps":["trace[1157448851] 'process raft request' (duration: 403.926386ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:50.271627Z","caller":"traceutil/trace.go:171","msg":"trace[1750772643] transaction","detail":"{read_only:false; response_revision:25172; number_of_response:1; }","duration":"402.920229ms","start":"2026-04-28T04:09:49.868682Z","end":"2026-04-28T04:09:50.271602Z","steps":["trace[1750772643] 'process raft request' (duration: 402.761396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:50.271687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:49.867272Z","time spent":"404.311904ms","remote":"127.0.0.1:40768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T04:09:50.271724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:49.868665Z","time spent":"403.01513ms","remote":"127.0.0.1:40768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":724,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T04:09:50.271559Z","caller":"traceutil/trace.go:171","msg":"trace[1335937938] linearizableReadLoop","detail":"{readStateIndex:25978; appliedIndex:25974; }","duration":"399.002755ms","start":"2026-04-28T04:09:49.872535Z","end":"2026-04-28T04:09:50.271538Z","steps":["trace[1335937938] 'read index received' (duration: 398.776009ms)","trace[1335937938] 'applied index is now lower than readState.Index' (duration: 225.485µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:09:50.271843Z","caller":"traceutil/trace.go:171","msg":"trace[1913212204] transaction","detail":"{read_only:false; response_revision:25171; number_of_response:1; }","duration":"403.107492ms","start":"2026-04-28T04:09:49.868668Z","end":"2026-04-28T04:09:50.271776Z","steps":["trace[1913212204] 'process raft request' (duration: 402.726175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:50.272135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.369677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130655"} {"level":"info","ts":"2026-04-28T04:09:50.272191Z","caller":"traceutil/trace.go:171","msg":"trace[374651083] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:25173; }","duration":"403.54593ms","start":"2026-04-28T04:09:49.868634Z","end":"2026-04-28T04:09:50.272179Z","steps":["trace[374651083] 'agreement among raft nodes before linearized reading' (duration: 403.188834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:50.272228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:49.868612Z","time spent":"403.607541ms","remote":"127.0.0.1:40748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":130677,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-04-28T04:09:50.271757Z","caller":"traceutil/trace.go:171","msg":"trace[22094827] transaction","detail":"{read_only:false; response_revision:25173; number_of_response:1; }","duration":"402.908158ms","start":"2026-04-28T04:09:49.868812Z","end":"2026-04-28T04:09:50.27172Z","steps":["trace[22094827] 'process raft request' (duration: 402.679794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:50.272131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:49.868641Z","time spent":"403.330895ms","remote":"127.0.0.1:40844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":744,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T04:09:50.272914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:49.868802Z","time spent":"404.009688ms","remote":"127.0.0.1:40980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1237,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T04:09:50.30246Z","caller":"traceutil/trace.go:171","msg":"trace[1927992907] transaction","detail":"{read_only:false; response_revision:25174; number_of_response:1; }","duration":"269.881193ms","start":"2026-04-28T04:09:50.032555Z","end":"2026-04-28T04:09:50.302436Z","steps":["trace[1927992907] 'process raft request' (duration: 268.766622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:50.302806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.294856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-ab3463f7-7b2e-40a7-8279-f2916ff41e6e\" ","response":"range_response_count:1 size:1323"} {"level":"info","ts":"2026-04-28T04:09:50.302878Z","caller":"traceutil/trace.go:171","msg":"trace[1251768183] range","detail":"{range_begin:/registry/persistentvolumes/pvc-ab3463f7-7b2e-40a7-8279-f2916ff41e6e; range_end:; response_count:1; response_revision:25175; }","duration":"426.417518ms","start":"2026-04-28T04:09:49.876444Z","end":"2026-04-28T04:09:50.302861Z","steps":["trace[1251768183] 'agreement among raft nodes before linearized reading' (duration: 426.196374ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:50.30285Z","caller":"traceutil/trace.go:171","msg":"trace[706080466] transaction","detail":"{read_only:false; response_revision:25175; number_of_response:1; }","duration":"270.185797ms","start":"2026-04-28T04:09:50.03263Z","end":"2026-04-28T04:09:50.302816Z","steps":["trace[706080466] 'process raft request' (duration: 269.75866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:50.302912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:49.876436Z","time spent":"426.46799ms","remote":"127.0.0.1:40836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1345,"request content":"key:\"/registry/persistentvolumes/pvc-ab3463f7-7b2e-40a7-8279-f2916ff41e6e\" "} {"level":"warn","ts":"2026-04-28T04:09:50.302934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.123368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-28T04:09:50.303004Z","caller":"traceutil/trace.go:171","msg":"trace[512378961] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:25175; }","duration":"357.251491ms","start":"2026-04-28T04:09:49.945735Z","end":"2026-04-28T04:09:50.302986Z","steps":["trace[512378961] 'agreement among raft nodes before linearized reading' (duration: 357.095928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:50.30306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:49.945708Z","time spent":"357.337233ms","remote":"127.0.0.1:41072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-28T04:09:50.303027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.669718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-28T04:09:50.303237Z","caller":"traceutil/trace.go:171","msg":"trace[1871043102] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:25175; }","duration":"343.018644ms","start":"2026-04-28T04:09:49.960177Z","end":"2026-04-28T04:09:50.303195Z","steps":["trace[1871043102] 'agreement among raft nodes before linearized reading' (duration: 342.569166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:50.303418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:09:49.96011Z","time spent":"343.269369ms","remote":"127.0.0.1:33194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":4,"response size":30,"request content":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true "} {"level":"warn","ts":"2026-04-28T04:09:50.302917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.807768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-04-28T04:09:50.30456Z","caller":"traceutil/trace.go:171","msg":"trace[1002223661] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25175; }","duration":"273.50149ms","start":"2026-04-28T04:09:50.031038Z","end":"2026-04-28T04:09:50.304539Z","steps":["trace[1002223661] 'agreement among raft nodes before linearized reading' (duration: 271.697906ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:56.790126Z","caller":"traceutil/trace.go:171","msg":"trace[1260105748] transaction","detail":"{read_only:false; response_revision:25303; number_of_response:1; }","duration":"139.261561ms","start":"2026-04-28T04:09:56.650839Z","end":"2026-04-28T04:09:56.790101Z","steps":["trace[1260105748] 'process raft request' (duration: 133.85448ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:56.82808Z","caller":"traceutil/trace.go:171","msg":"trace[1875787016] transaction","detail":"{read_only:false; response_revision:25304; number_of_response:1; }","duration":"177.00894ms","start":"2026-04-28T04:09:56.651045Z","end":"2026-04-28T04:09:56.828054Z","steps":["trace[1875787016] 'process raft request' (duration: 176.840547ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:56.828294Z","caller":"traceutil/trace.go:171","msg":"trace[1429073936] linearizableReadLoop","detail":"{readStateIndex:26111; appliedIndex:26108; }","duration":"147.700711ms","start":"2026-04-28T04:09:56.680569Z","end":"2026-04-28T04:09:56.82827Z","steps":["trace[1429073936] 'read index received' (duration: 104.102102ms)","trace[1429073936] 'applied index is now lower than readState.Index' (duration: 43.597029ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:09:56.828466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.824094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T04:09:56.828504Z","caller":"traceutil/trace.go:171","msg":"trace[915667527] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25305; }","duration":"146.895985ms","start":"2026-04-28T04:09:56.681598Z","end":"2026-04-28T04:09:56.828494Z","steps":["trace[915667527] 'agreement among raft nodes before linearized reading' (duration: 146.813984ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:56.828468Z","caller":"traceutil/trace.go:171","msg":"trace[532634773] transaction","detail":"{read_only:false; response_revision:25305; number_of_response:1; }","duration":"176.639404ms","start":"2026-04-28T04:09:56.651771Z","end":"2026-04-28T04:09:56.82841Z","steps":["trace[532634773] 'process raft request' (duration: 176.231685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:09:56.828531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.956155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-28T04:09:56.828604Z","caller":"traceutil/trace.go:171","msg":"trace[1466242087] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25305; }","duration":"148.091999ms","start":"2026-04-28T04:09:56.680478Z","end":"2026-04-28T04:09:56.82857Z","steps":["trace[1466242087] 'agreement among raft nodes before linearized reading' (duration: 147.929666ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:09:57.037178Z","caller":"traceutil/trace.go:171","msg":"trace[1211135753] linearizableReadLoop","detail":"{readStateIndex:26112; appliedIndex:26111; }","duration":"109.349101ms","start":"2026-04-28T04:09:56.9278Z","end":"2026-04-28T04:09:57.03715Z","steps":["trace[1211135753] 'read index received' (duration: 36.119047ms)","trace[1211135753] 'applied index is now lower than readState.Index' (duration: 73.228424ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:09:57.03722Z","caller":"traceutil/trace.go:171","msg":"trace[393071618] transaction","detail":"{read_only:false; response_revision:25306; number_of_response:1; }","duration":"202.685702ms","start":"2026-04-28T04:09:56.83448Z","end":"2026-04-28T04:09:57.037165Z","steps":["trace[393071618] 'process raft request' (duration: 129.484439ms)","trace[393071618] 'compare' (duration: 72.893248ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:09:57.037434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.615316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-04-28T04:09:57.037518Z","caller":"traceutil/trace.go:171","msg":"trace[702201071] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:25306; }","duration":"109.764148ms","start":"2026-04-28T04:09:56.927735Z","end":"2026-04-28T04:09:57.037499Z","steps":["trace[702201071] 'agreement among raft nodes before linearized reading' (duration: 109.502424ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:10:03.267119Z","caller":"traceutil/trace.go:171","msg":"trace[289452529] linearizableReadLoop","detail":"{readStateIndex:26200; appliedIndex:26199; }","duration":"131.875213ms","start":"2026-04-28T04:10:03.135221Z","end":"2026-04-28T04:10:03.267096Z","steps":["trace[289452529] 'read index received' (duration: 111.655325ms)","trace[289452529] 'applied index is now lower than readState.Index' (duration: 20.218798ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:10:03.267331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.091777ms","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-28T04:10:03.267345Z","caller":"traceutil/trace.go:171","msg":"trace[68492098] transaction","detail":"{read_only:false; response_revision:25392; number_of_response:1; }","duration":"147.12285ms","start":"2026-04-28T04:10:03.120173Z","end":"2026-04-28T04:10:03.267296Z","steps":["trace[68492098] 'process raft request' (duration: 126.746349ms)","trace[68492098] 'compare' (duration: 19.495545ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:10:03.267382Z","caller":"traceutil/trace.go:171","msg":"trace[2134843343] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25392; }","duration":"132.180299ms","start":"2026-04-28T04:10:03.135184Z","end":"2026-04-28T04:10:03.267364Z","steps":["trace[2134843343] 'agreement among raft nodes before linearized reading' (duration: 132.006616ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:10:04.552506Z","caller":"traceutil/trace.go:171","msg":"trace[282154636] transaction","detail":"{read_only:false; response_revision:25394; number_of_response:1; }","duration":"204.404074ms","start":"2026-04-28T04:10:04.348064Z","end":"2026-04-28T04:10:04.552468Z","steps":["trace[282154636] 'process raft request' (duration: 204.215131ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:10:04.683254Z","caller":"traceutil/trace.go:171","msg":"trace[456572850] linearizableReadLoop","detail":"{readStateIndex:26203; appliedIndex:26201; }","duration":"315.403617ms","start":"2026-04-28T04:10:04.36783Z","end":"2026-04-28T04:10:04.683234Z","steps":["trace[456572850] 'read index received' (duration: 184.402759ms)","trace[456572850] 'applied index is now lower than readState.Index' (duration: 130.999808ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:10:04.683321Z","caller":"traceutil/trace.go:171","msg":"trace[14157160] transaction","detail":"{read_only:false; response_revision:25395; number_of_response:1; }","duration":"335.125007ms","start":"2026-04-28T04:10:04.348158Z","end":"2026-04-28T04:10:04.683283Z","steps":["trace[14157160] 'process raft request' (duration: 334.923933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:10:04.683466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.6275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"warn","ts":"2026-04-28T04:10:04.683502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:10:04.348122Z","time spent":"335.27894ms","remote":"127.0.0.1:40970","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-28T04:10:04.683523Z","caller":"traceutil/trace.go:171","msg":"trace[1650202113] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25395; }","duration":"315.727633ms","start":"2026-04-28T04:10:04.367775Z","end":"2026-04-28T04:10:04.683503Z","steps":["trace[1650202113] 'agreement among raft nodes before linearized reading' (duration: 315.57675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:10:04.683563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:10:04.367756Z","time spent":"315.798504ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":596,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-28T04:10:04.717361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.771146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T04:10:04.717442Z","caller":"traceutil/trace.go:171","msg":"trace[1709603753] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:25398; }","duration":"345.923629ms","start":"2026-04-28T04:10:04.3715Z","end":"2026-04-28T04:10:04.717423Z","steps":["trace[1709603753] 'agreement among raft nodes before linearized reading' (duration: 345.602083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:10:04.717485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:10:04.371478Z","time spent":"345.99763ms","remote":"127.0.0.1:40834","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-28T04:10:04.717516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.845099ms","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-28T04:10:04.71759Z","caller":"traceutil/trace.go:171","msg":"trace[1916156319] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25398; }","duration":"319.985582ms","start":"2026-04-28T04:10:04.397587Z","end":"2026-04-28T04:10:04.717573Z","steps":["trace[1916156319] 'agreement among raft nodes before linearized reading' (duration: 319.777778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:10:04.717634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:10:04.397569Z","time spent":"320.052703ms","remote":"127.0.0.1:40970","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-28T04:10:08.486587Z","caller":"traceutil/trace.go:171","msg":"trace[121110642] transaction","detail":"{read_only:false; response_revision:25439; number_of_response:1; }","duration":"142.728937ms","start":"2026-04-28T04:10:08.343838Z","end":"2026-04-28T04:10:08.486567Z","steps":["trace[121110642] 'process raft request' (duration: 142.604265ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:10:08.971824Z","caller":"traceutil/trace.go:171","msg":"trace[1340011261] linearizableReadLoop","detail":"{readStateIndex:26249; appliedIndex:26248; }","duration":"321.775286ms","start":"2026-04-28T04:10:08.650025Z","end":"2026-04-28T04:10:08.971786Z","steps":["trace[1340011261] 'read index received' (duration: 292.344403ms)","trace[1340011261] 'applied index is now lower than readState.Index' (duration: 29.415652ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:10:08.972071Z","caller":"traceutil/trace.go:171","msg":"trace[1987096402] transaction","detail":"{read_only:false; response_revision:25440; number_of_response:1; }","duration":"408.322559ms","start":"2026-04-28T04:10:08.563734Z","end":"2026-04-28T04:10:08.972057Z","steps":["trace[1987096402] 'process raft request' (duration: 378.618592ms)","trace[1987096402] 'compare' (duration: 29.211788ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:10:08.972364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.328856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T04:10:08.972421Z","caller":"traceutil/trace.go:171","msg":"trace[2063318630] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25440; }","duration":"322.397087ms","start":"2026-04-28T04:10:08.65001Z","end":"2026-04-28T04:10:08.972407Z","steps":["trace[2063318630] 'agreement among raft nodes before linearized reading' (duration: 322.290596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:10:08.972461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:10:08.649991Z","time spent":"322.459288ms","remote":"127.0.0.1:37122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true "} {"level":"warn","ts":"2026-04-28T04:10:08.97288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:10:08.56371Z","time spent":"408.399201ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T04:10:09.146808Z","caller":"traceutil/trace.go:171","msg":"trace[163829957] transaction","detail":"{read_only:false; response_revision:25441; number_of_response:1; }","duration":"454.096528ms","start":"2026-04-28T04:10:08.692647Z","end":"2026-04-28T04:10:09.146743Z","steps":["trace[163829957] 'process raft request' (duration: 453.857883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:10:09.14707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:10:08.692609Z","time spent":"454.280092ms","remote":"127.0.0.1:40970","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-28T04:10:09.147103Z","caller":"traceutil/trace.go:171","msg":"trace[838573567] transaction","detail":"{read_only:false; response_revision:25442; number_of_response:1; }","duration":"419.353937ms","start":"2026-04-28T04:10:08.727717Z","end":"2026-04-28T04:10:09.147071Z","steps":["trace[838573567] 'process raft request' (duration: 418.97421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:10:09.147246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:10:08.727706Z","time spent":"419.470829ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T04:10:09.147281Z","caller":"traceutil/trace.go:171","msg":"trace[333011694] linearizableReadLoop","detail":"{readStateIndex:26253; appliedIndex:26249; }","duration":"175.362999ms","start":"2026-04-28T04:10:08.971888Z","end":"2026-04-28T04:10:09.147251Z","steps":["trace[333011694] 'read index received' (duration: 174.553404ms)","trace[333011694] 'applied index is now lower than readState.Index' (duration: 808.265µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:10:09.147447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.894073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-28T04:10:09.147512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.495012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-04-28T04:10:09.147526Z","caller":"traceutil/trace.go:171","msg":"trace[1666827736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25444; }","duration":"269.016006ms","start":"2026-04-28T04:10:08.878491Z","end":"2026-04-28T04:10:09.147507Z","steps":["trace[1666827736] 'agreement among raft nodes before linearized reading' (duration: 268.814722ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:10:09.147565Z","caller":"traceutil/trace.go:171","msg":"trace[2014244198] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25444; }","duration":"368.569664ms","start":"2026-04-28T04:10:08.77898Z","end":"2026-04-28T04:10:09.14755Z","steps":["trace[2014244198] 'agreement among raft nodes before linearized reading' (duration: 368.417421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:10:09.147576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.578891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-28T04:10:09.147603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:10:08.778966Z","time spent":"368.628555ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":596,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-04-28T04:10:09.14762Z","caller":"traceutil/trace.go:171","msg":"trace[830934140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25444; }","duration":"467.645952ms","start":"2026-04-28T04:10:08.67996Z","end":"2026-04-28T04:10:09.147606Z","steps":["trace[830934140] 'agreement among raft nodes before linearized reading' (duration: 467.579971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:10:09.147664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:10:08.679946Z","time spent":"467.708874ms","remote":"127.0.0.1:40710","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-28T04:10:09.147686Z","caller":"traceutil/trace.go:171","msg":"trace[541483089] transaction","detail":"{read_only:false; response_revision:25444; number_of_response:1; }","duration":"409.862037ms","start":"2026-04-28T04:10:08.737809Z","end":"2026-04-28T04:10:09.147671Z","steps":["trace[541483089] 'process raft request' (duration: 409.345558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:10:09.147762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:10:08.737782Z","time spent":"409.942559ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T04:10:09.147774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.591143ms","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-28T04:10:09.147805Z","caller":"traceutil/trace.go:171","msg":"trace[470751703] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25444; }","duration":"312.641034ms","start":"2026-04-28T04:10:08.835155Z","end":"2026-04-28T04:10:09.147796Z","steps":["trace[470751703] 'agreement among raft nodes before linearized reading' (duration: 312.519932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:10:09.147809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.183889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-04-28T04:10:09.147838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:10:08.835143Z","time spent":"312.688705ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T04:10:09.147875Z","caller":"traceutil/trace.go:171","msg":"trace[1995502858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25444; }","duration":"269.313421ms","start":"2026-04-28T04:10:08.878542Z","end":"2026-04-28T04:10:09.147856Z","steps":["trace[1995502858] 'agreement among raft nodes before linearized reading' (duration: 269.147618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:10:09.147513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.487561ms","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-28T04:10:09.147903Z","caller":"traceutil/trace.go:171","msg":"trace[1906337735] transaction","detail":"{read_only:false; response_revision:25443; number_of_response:1; }","duration":"418.53544ms","start":"2026-04-28T04:10:08.729354Z","end":"2026-04-28T04:10:09.14789Z","steps":["trace[1906337735] 'process raft request' (duration: 417.627193ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:10:09.147922Z","caller":"traceutil/trace.go:171","msg":"trace[58360145] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25444; }","duration":"369.930989ms","start":"2026-04-28T04:10:08.777982Z","end":"2026-04-28T04:10:09.147913Z","steps":["trace[58360145] 'agreement among raft nodes before linearized reading' (duration: 369.42818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:10:09.147956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:10:08.777969Z","time spent":"369.97964ms","remote":"127.0.0.1:40970","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":"warn","ts":"2026-04-28T04:10:09.147989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:10:08.729337Z","time spent":"418.595651ms","remote":"127.0.0.1:40970","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-28T04:10:10.845488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.655197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T04:10:10.845775Z","caller":"traceutil/trace.go:171","msg":"trace[719502023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25458; }","duration":"165.995973ms","start":"2026-04-28T04:10:10.679758Z","end":"2026-04-28T04:10:10.845754Z","steps":["trace[719502023] 'range keys from in-memory index tree' (duration: 165.555015ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:10:32.236444Z","caller":"traceutil/trace.go:171","msg":"trace[1188643640] linearizableReadLoop","detail":"{readStateIndex:26457; appliedIndex:26456; }","duration":"158.173687ms","start":"2026-04-28T04:10:32.078245Z","end":"2026-04-28T04:10:32.236419Z","steps":["trace[1188643640] 'read index received' (duration: 80.918468ms)","trace[1188643640] 'applied index is now lower than readState.Index' (duration: 77.253539ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:10:32.236583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.313489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T04:10:32.236614Z","caller":"traceutil/trace.go:171","msg":"trace[180296587] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25643; }","duration":"158.393111ms","start":"2026-04-28T04:10:32.078212Z","end":"2026-04-28T04:10:32.236605Z","steps":["trace[180296587] 'agreement among raft nodes before linearized reading' (duration: 158.30513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:10:33.348321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.236135ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T04:10:33.348595Z","caller":"traceutil/trace.go:171","msg":"trace[532169491] transaction","detail":"{read_only:false; response_revision:25646; number_of_response:1; }","duration":"192.706825ms","start":"2026-04-28T04:10:33.155859Z","end":"2026-04-28T04:10:33.348566Z","steps":["trace[532169491] 'process raft request' (duration: 192.630944ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:10:33.348686Z","caller":"traceutil/trace.go:171","msg":"trace[1978711869] transaction","detail":"{read_only:false; response_revision:25645; number_of_response:1; }","duration":"281.652853ms","start":"2026-04-28T04:10:33.067005Z","end":"2026-04-28T04:10:33.348657Z","steps":["trace[1978711869] 'process raft request' (duration: 143.92224ms)","trace[1978711869] 'compare' (duration: 137.0269ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:10:33.417341Z","caller":"traceutil/trace.go:171","msg":"trace[2107311869] transaction","detail":"{read_only:false; response_revision:25647; number_of_response:1; }","duration":"180.620598ms","start":"2026-04-28T04:10:33.236578Z","end":"2026-04-28T04:10:33.417198Z","steps":["trace[2107311869] 'process raft request' (duration: 180.15668ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:10:33.417488Z","caller":"traceutil/trace.go:171","msg":"trace[938328986] transaction","detail":"{read_only:false; response_revision:25650; number_of_response:1; }","duration":"173.685527ms","start":"2026-04-28T04:10:33.243568Z","end":"2026-04-28T04:10:33.417253Z","steps":["trace[938328986] 'process raft request' (duration: 173.370952ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:10:33.417652Z","caller":"traceutil/trace.go:171","msg":"trace[301798466] transaction","detail":"{read_only:false; response_revision:25648; number_of_response:1; }","duration":"175.693485ms","start":"2026-04-28T04:10:33.241584Z","end":"2026-04-28T04:10:33.417278Z","steps":["trace[301798466] 'process raft request' (duration: 175.275387ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:10:33.417802Z","caller":"traceutil/trace.go:171","msg":"trace[1440110667] transaction","detail":"{read_only:false; response_revision:25649; number_of_response:1; }","duration":"175.581253ms","start":"2026-04-28T04:10:33.241714Z","end":"2026-04-28T04:10:33.417295Z","steps":["trace[1440110667] 'process raft request' (duration: 175.188096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:10:33.696318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.733483ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T04:10:33.696432Z","caller":"traceutil/trace.go:171","msg":"trace[633421815] linearizableReadLoop","detail":"{readStateIndex:26465; appliedIndex:26464; }","duration":"279.425091ms","start":"2026-04-28T04:10:33.416988Z","end":"2026-04-28T04:10:33.696413Z","steps":["trace[633421815] 'read index received' (duration: 133.511604ms)","trace[633421815] 'applied index is now lower than readState.Index' (duration: 145.912156ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:10:33.696578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.700563ms","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-28T04:10:33.69663Z","caller":"traceutil/trace.go:171","msg":"trace[315912405] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25651; }","duration":"347.787664ms","start":"2026-04-28T04:10:33.348828Z","end":"2026-04-28T04:10:33.696615Z","steps":["trace[315912405] 'agreement among raft nodes before linearized reading' (duration: 347.649601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:10:33.696658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:10:33.348812Z","time spent":"347.839875ms","remote":"127.0.0.1:40970","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-28T04:10:33.696797Z","caller":"traceutil/trace.go:171","msg":"trace[533709791] transaction","detail":"{read_only:false; response_revision:25651; number_of_response:1; }","duration":"340.169651ms","start":"2026-04-28T04:10:33.356618Z","end":"2026-04-28T04:10:33.696787Z","steps":["trace[533709791] 'process raft request' (duration: 193.893047ms)","trace[533709791] 'compare' (duration: 145.597241ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:10:33.696815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.508636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-28T04:10:33.696863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:10:33.356592Z","time spent":"340.227112ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T04:10:33.696997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.149313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-28T04:10:33.697025Z","caller":"traceutil/trace.go:171","msg":"trace[529792422] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25651; }","duration":"346.182863ms","start":"2026-04-28T04:10:33.350832Z","end":"2026-04-28T04:10:33.697014Z","steps":["trace[529792422] 'agreement among raft nodes before linearized reading' (duration: 346.115832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:10:33.697044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:10:33.350827Z","time spent":"346.212714ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-28T04:10:33.697172Z","caller":"traceutil/trace.go:171","msg":"trace[178448430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25651; }","duration":"349.577668ms","start":"2026-04-28T04:10:33.347261Z","end":"2026-04-28T04:10:33.696839Z","steps":["trace[178448430] 'agreement among raft nodes before linearized reading' (duration: 349.348754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:10:33.697243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:10:33.34725Z","time spent":"349.979935ms","remote":"127.0.0.1:40970","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-28T04:10:33.697389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.614033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-04-28T04:10:33.697422Z","caller":"traceutil/trace.go:171","msg":"trace[1105958508] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25651; }","duration":"353.672153ms","start":"2026-04-28T04:10:33.343739Z","end":"2026-04-28T04:10:33.697411Z","steps":["trace[1105958508] 'agreement among raft nodes before linearized reading' (duration: 353.588642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:10:33.697438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:10:33.343726Z","time spent":"353.708364ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":596,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-28T04:10:33.697434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.589762ms","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-28T04:10:33.697496Z","caller":"traceutil/trace.go:171","msg":"trace[160110447] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25651; }","duration":"353.658303ms","start":"2026-04-28T04:10:33.343801Z","end":"2026-04-28T04:10:33.697459Z","steps":["trace[160110447] 'agreement among raft nodes before linearized reading' (duration: 353.563922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:10:33.697521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:10:33.343785Z","time spent":"353.730834ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T04:10:33.858053Z","caller":"traceutil/trace.go:171","msg":"trace[1963563415] linearizableReadLoop","detail":"{readStateIndex:26466; appliedIndex:26465; }","duration":"161.446059ms","start":"2026-04-28T04:10:33.696574Z","end":"2026-04-28T04:10:33.85802Z","steps":["trace[1963563415] 'read index received' (duration: 127.342949ms)","trace[1963563415] 'applied index is now lower than readState.Index' (duration: 34.10214ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:10:33.858127Z","caller":"traceutil/trace.go:171","msg":"trace[993446111] transaction","detail":"{read_only:false; response_revision:25652; number_of_response:1; }","duration":"426.734645ms","start":"2026-04-28T04:10:33.43137Z","end":"2026-04-28T04:10:33.858104Z","steps":["trace[993446111] 'process raft request' (duration: 392.474842ms)","trace[993446111] 'compare' (duration: 34.039629ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:10:33.858163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.304827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-28T04:10:33.858218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:10:33.431354Z","time spent":"426.813456ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T04:10:33.858246Z","caller":"traceutil/trace.go:171","msg":"trace[204115258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25652; }","duration":"177.437838ms","start":"2026-04-28T04:10:33.680788Z","end":"2026-04-28T04:10:33.858226Z","steps":["trace[204115258] 'agreement among raft nodes before linearized reading' (duration: 177.287036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:10:33.858418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.467609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-28T04:10:33.858469Z","caller":"traceutil/trace.go:171","msg":"trace[1866044952] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:25652; }","duration":"386.595451ms","start":"2026-04-28T04:10:33.471861Z","end":"2026-04-28T04:10:33.858457Z","steps":["trace[1866044952] 'agreement among raft nodes before linearized reading' (duration: 386.450099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:10:33.858506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:10:33.471837Z","time spent":"386.660832ms","remote":"127.0.0.1:58500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":36,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-04-28T04:10:33.858742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.932466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-28T04:10:33.858841Z","caller":"traceutil/trace.go:171","msg":"trace[1309717203] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25652; }","duration":"404.079729ms","start":"2026-04-28T04:10:33.454745Z","end":"2026-04-28T04:10:33.858825Z","steps":["trace[1309717203] 'agreement among raft nodes before linearized reading' (duration: 403.390807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:10:33.858879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:10:33.454728Z","time spent":"404.14126ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-28T04:10:33.899757Z","caller":"traceutil/trace.go:171","msg":"trace[1972145262] transaction","detail":"{read_only:false; response_revision:25654; number_of_response:1; }","duration":"197.991234ms","start":"2026-04-28T04:10:33.70174Z","end":"2026-04-28T04:10:33.899731Z","steps":["trace[1972145262] 'process raft request' (duration: 197.814911ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:10:33.899788Z","caller":"traceutil/trace.go:171","msg":"trace[642597867] transaction","detail":"{read_only:false; response_revision:25653; number_of_response:1; }","duration":"198.480484ms","start":"2026-04-28T04:10:33.701271Z","end":"2026-04-28T04:10:33.899751Z","steps":["trace[642597867] 'process raft request' (duration: 198.099226ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:10:33.900013Z","caller":"traceutil/trace.go:171","msg":"trace[1472874352] transaction","detail":"{read_only:false; response_revision:25656; number_of_response:1; }","duration":"197.678008ms","start":"2026-04-28T04:10:33.702318Z","end":"2026-04-28T04:10:33.899996Z","steps":["trace[1472874352] 'process raft request' (duration: 197.542286ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:10:33.900059Z","caller":"traceutil/trace.go:171","msg":"trace[683601577] transaction","detail":"{read_only:false; response_revision:25655; number_of_response:1; }","duration":"198.28048ms","start":"2026-04-28T04:10:33.701763Z","end":"2026-04-28T04:10:33.900043Z","steps":["trace[683601577] 'process raft request' (duration: 197.914673ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:10:33.900147Z","caller":"traceutil/trace.go:171","msg":"trace[1266300440] transaction","detail":"{read_only:false; response_revision:25657; number_of_response:1; }","duration":"196.546877ms","start":"2026-04-28T04:10:33.703589Z","end":"2026-04-28T04:10:33.900136Z","steps":["trace[1266300440] 'process raft request' (duration: 196.367923ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:10:35.023246Z","caller":"traceutil/trace.go:171","msg":"trace[1889000445] transaction","detail":"{read_only:false; response_revision:25659; number_of_response:1; }","duration":"110.747987ms","start":"2026-04-28T04:10:34.912474Z","end":"2026-04-28T04:10:35.023222Z","steps":["trace[1889000445] 'process raft request' (duration: 110.572404ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:10:35.264626Z","caller":"traceutil/trace.go:171","msg":"trace[916437219] transaction","detail":"{read_only:false; response_revision:25660; number_of_response:1; }","duration":"131.523626ms","start":"2026-04-28T04:10:35.133068Z","end":"2026-04-28T04:10:35.264592Z","steps":["trace[916437219] 'process raft request' (duration: 49.771063ms)","trace[916437219] 'compare' (duration: 81.440848ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:10:35.66844Z","caller":"traceutil/trace.go:171","msg":"trace[779831423] transaction","detail":"{read_only:false; response_revision:25662; number_of_response:1; }","duration":"242.496359ms","start":"2026-04-28T04:10:35.425917Z","end":"2026-04-28T04:10:35.668414Z","steps":["trace[779831423] 'process raft request' (duration: 242.306005ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:10:35.668593Z","caller":"traceutil/trace.go:171","msg":"trace[1370302838] transaction","detail":"{read_only:false; response_revision:25663; number_of_response:1; }","duration":"240.994181ms","start":"2026-04-28T04:10:35.427588Z","end":"2026-04-28T04:10:35.668582Z","steps":["trace[1370302838] 'process raft request' (duration: 240.789567ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:10:35.66869Z","caller":"traceutil/trace.go:171","msg":"trace[93496981] linearizableReadLoop","detail":"{readStateIndex:26478; appliedIndex:26474; }","duration":"198.002934ms","start":"2026-04-28T04:10:35.470677Z","end":"2026-04-28T04:10:35.66868Z","steps":["trace[93496981] 'read index received' (duration: 42.995706ms)","trace[93496981] 'applied index is now lower than readState.Index' (duration: 155.006508ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:10:35.668746Z","caller":"traceutil/trace.go:171","msg":"trace[1702217217] transaction","detail":"{read_only:false; response_revision:25664; number_of_response:1; }","duration":"237.351202ms","start":"2026-04-28T04:10:35.431388Z","end":"2026-04-28T04:10:35.66874Z","steps":["trace[1702217217] 'process raft request' (duration: 237.149618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:10:35.669108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.180907ms","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-28T04:10:35.669177Z","caller":"traceutil/trace.go:171","msg":"trace[484891498] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25664; }","duration":"198.702266ms","start":"2026-04-28T04:10:35.470458Z","end":"2026-04-28T04:10:35.66916Z","steps":["trace[484891498] 'agreement among raft nodes before linearized reading' (duration: 198.34732ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:10:35.805218Z","caller":"traceutil/trace.go:171","msg":"trace[270606150] transaction","detail":"{read_only:false; response_revision:25665; number_of_response:1; }","duration":"102.729437ms","start":"2026-04-28T04:10:35.702446Z","end":"2026-04-28T04:10:35.805176Z","steps":["trace[270606150] 'process raft request' (duration: 44.409683ms)","trace[270606150] 'compare' (duration: 58.058489ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:10:36.205891Z","caller":"traceutil/trace.go:171","msg":"trace[11849077] transaction","detail":"{read_only:false; response_revision:25668; number_of_response:1; }","duration":"244.054919ms","start":"2026-04-28T04:10:35.961815Z","end":"2026-04-28T04:10:36.20587Z","steps":["trace[11849077] 'process raft request' (duration: 229.683458ms)","trace[11849077] 'compare' (duration: 13.916031ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:10:36.206438Z","caller":"traceutil/trace.go:171","msg":"trace[951569039] transaction","detail":"{read_only:false; response_revision:25669; number_of_response:1; }","duration":"244.600368ms","start":"2026-04-28T04:10:35.961807Z","end":"2026-04-28T04:10:36.206408Z","steps":["trace[951569039] 'process raft request' (duration: 243.981027ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:10:36.206595Z","caller":"traceutil/trace.go:171","msg":"trace[1124912102] transaction","detail":"{read_only:false; response_revision:25670; number_of_response:1; }","duration":"244.734341ms","start":"2026-04-28T04:10:35.961838Z","end":"2026-04-28T04:10:36.206573Z","steps":["trace[1124912102] 'process raft request' (duration: 244.202772ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:10:36.206607Z","caller":"traceutil/trace.go:171","msg":"trace[1412286326] transaction","detail":"{read_only:false; response_revision:25672; number_of_response:1; }","duration":"244.441675ms","start":"2026-04-28T04:10:35.962149Z","end":"2026-04-28T04:10:36.206591Z","steps":["trace[1412286326] 'process raft request' (duration: 244.403744ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:10:36.206711Z","caller":"traceutil/trace.go:171","msg":"trace[929400017] transaction","detail":"{read_only:false; response_revision:25671; number_of_response:1; }","duration":"244.698399ms","start":"2026-04-28T04:10:35.961922Z","end":"2026-04-28T04:10:36.20662Z","steps":["trace[929400017] 'process raft request' (duration: 244.522686ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:10:38.099169Z","caller":"traceutil/trace.go:171","msg":"trace[679006719] transaction","detail":"{read_only:false; response_revision:25680; number_of_response:1; }","duration":"100.351853ms","start":"2026-04-28T04:10:37.998779Z","end":"2026-04-28T04:10:38.099131Z","steps":["trace[679006719] 'process raft request' (duration: 100.072648ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:11:00.401475Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006873.wal"} {"level":"info","ts":"2026-04-28T04:12:13.938448Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23696} {"level":"info","ts":"2026-04-28T04:12:14.068054Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23696,"took":"126.049438ms","hash":2486984361,"current-db-size-bytes":59076608,"current-db-size":"59 MB","current-db-size-in-use-bytes":37212160,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-04-28T04:12:14.068143Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2486984361,"revision":23696,"compact-revision":20503} {"level":"info","ts":"2026-04-28T04:14:00.554416Z","caller":"traceutil/trace.go:171","msg":"trace[462108962] transaction","detail":"{read_only:false; response_revision:27784; number_of_response:1; }","duration":"112.110416ms","start":"2026-04-28T04:14:00.442276Z","end":"2026-04-28T04:14:00.554387Z","steps":["trace[462108962] 'process raft request' (duration: 95.080038ms)","trace[462108962] 'compare' (duration: 16.825925ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:16:46.286984Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e98569549cc430c","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-28T04:16:46.29096Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-28T04:16:46.291057Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-04-28T04:17:13.942843Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26628} {"level":"info","ts":"2026-04-28T04:17:14.080071Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26628,"took":"133.63719ms","hash":1888065648,"current-db-size-bytes":59076608,"current-db-size":"59 MB","current-db-size-in-use-bytes":34717696,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-04-28T04:17:14.080142Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1888065648,"revision":26628,"compact-revision":23696} {"level":"info","ts":"2026-04-28T04:22:13.948233Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29302} {"level":"info","ts":"2026-04-28T04:22:14.082737Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29302,"took":"130.037899ms","hash":3493937208,"current-db-size-bytes":59076608,"current-db-size":"59 MB","current-db-size-in-use-bytes":30937088,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-28T04:22:14.082905Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3493937208,"revision":29302,"compact-revision":26628} {"level":"info","ts":"2026-04-28T04:27:13.96059Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31564} {"level":"info","ts":"2026-04-28T04:27:14.093663Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31564,"took":"128.719425ms","hash":145097827,"current-db-size-bytes":59076608,"current-db-size":"59 MB","current-db-size-in-use-bytes":31977472,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-28T04:27:14.09372Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":145097827,"revision":31564,"compact-revision":29302} {"level":"info","ts":"2026-04-28T04:32:13.966285Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33937} {"level":"info","ts":"2026-04-28T04:32:14.110072Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33937,"took":"139.627119ms","hash":478670025,"current-db-size-bytes":60121088,"current-db-size":"60 MB","current-db-size-in-use-bytes":33550336,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-28T04:32:14.110115Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":478670025,"revision":33937,"compact-revision":31564} {"level":"info","ts":"2026-04-28T04:37:13.970752Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36143} {"level":"info","ts":"2026-04-28T04:37:14.115059Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36143,"took":"141.244799ms","hash":1231243779,"current-db-size-bytes":60121088,"current-db-size":"60 MB","current-db-size-in-use-bytes":32305152,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-28T04:37:14.115135Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1231243779,"revision":36143,"compact-revision":33937}