{"level":"warn","ts":"2026-07-01T10:43:06.406801Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T10:43:06.406939Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.238: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.238:2380","--initial-cluster=instance=https://199.204.45.238:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.238:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.238: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-07-01T10:43:06.407031Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T10:43:06.407052Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-07-01T10:43:06.407084Z","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-07-01T10:43:06.407983Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"]} {"level":"info","ts":"2026-07-01T10:43:06.408088Z","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.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.238: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-07-01T10:43:06.430975Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"22.583146ms"} {"level":"info","ts":"2026-07-01T10:43:06.44034Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d7dbc9f5893cc1f6","cluster-id":"823d1d08ab21430c"} {"level":"info","ts":"2026-07-01T10:43:06.440457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=()"} {"level":"info","ts":"2026-07-01T10:43:06.440503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 0"} {"level":"info","ts":"2026-07-01T10:43:06.440523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d7dbc9f5893cc1f6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-01T10:43:06.440546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 1"} {"level":"info","ts":"2026-07-01T10:43:06.440604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"warn","ts":"2026-07-01T10:43:06.452707Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-01T10:43:06.454391Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-01T10:43:06.455621Z","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-07-01T10:43:06.459617Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d7dbc9f5893cc1f6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-01T10:43:06.459926Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d7dbc9f5893cc1f6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-01T10:43:06.460019Z","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-07-01T10:43:06.460203Z","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-07-01T10:43:06.460229Z","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-07-01T10:43:06.461695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"info","ts":"2026-07-01T10:43:06.46193Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","added-peer-id":"d7dbc9f5893cc1f6","added-peer-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-07-01T10:43:06.465372Z","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-07-01T10:43:06.465509Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-07-01T10:43:06.465606Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-07-01T10:43:06.465726Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d7dbc9f5893cc1f6","initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-01T10:43:06.465781Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-01T10:43:06.541802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 is starting a new election at term 1"} {"level":"info","ts":"2026-07-01T10:43:06.541902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-01T10:43:06.54196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgPreVoteResp from d7dbc9f5893cc1f6 at term 1"} {"level":"info","ts":"2026-07-01T10:43:06.541992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became candidate at term 2"} {"level":"info","ts":"2026-07-01T10:43:06.542016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgVoteResp from d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-07-01T10:43:06.542034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became leader at term 2"} {"level":"info","ts":"2026-07-01T10:43:06.542049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d7dbc9f5893cc1f6 elected leader d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-07-01T10:43:06.543004Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T10:43:06.543743Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T10:43:06.543738Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d7dbc9f5893cc1f6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.238:2379]}","request-path":"/0/members/d7dbc9f5893cc1f6/attributes","cluster-id":"823d1d08ab21430c","publish-timeout":"7s"} {"level":"info","ts":"2026-07-01T10:43:06.543812Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T10:43:06.54402Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-01T10:43:06.544049Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-01T10:43:06.544249Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T10:43:06.544371Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T10:43:06.544415Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T10:43:06.546418Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.238:2379"} {"level":"info","ts":"2026-07-01T10:43:06.547269Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-01T10:43:50.200374Z","caller":"traceutil/trace.go:171","msg":"trace[1139192494] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"212.567864ms","start":"2026-07-01T10:43:49.987395Z","end":"2026-07-01T10:43:50.199963Z","steps":["trace[1139192494] 'process raft request' (duration: 179.280519ms)","trace[1139192494] 'compare' (duration: 32.818772ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:43:50.436539Z","caller":"traceutil/trace.go:171","msg":"trace[1518498594] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"174.831966ms","start":"2026-07-01T10:43:50.261685Z","end":"2026-07-01T10:43:50.436517Z","steps":["trace[1518498594] 'read index received' (duration: 164.238609ms)","trace[1518498594] 'applied index is now lower than readState.Index' (duration: 10.592007ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:43:50.436666Z","caller":"traceutil/trace.go:171","msg":"trace[1153789612] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"180.98515ms","start":"2026-07-01T10:43:50.255657Z","end":"2026-07-01T10:43:50.436643Z","steps":["trace[1153789612] 'process raft request' (duration: 170.29382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:43:50.436751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.708427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-01T10:43:50.436866Z","caller":"traceutil/trace.go:171","msg":"trace[1485025608] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:1; response_revision:632; }","duration":"129.884722ms","start":"2026-07-01T10:43:50.30697Z","end":"2026-07-01T10:43:50.436855Z","steps":["trace[1485025608] 'agreement among raft nodes before linearized reading' (duration: 129.702177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:43:50.436936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.209047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-82q87\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-01T10:43:50.437036Z","caller":"traceutil/trace.go:171","msg":"trace[1519950137] range","detail":"{range_begin:/registry/pods/kube-system/cilium-82q87; range_end:; response_count:1; response_revision:632; }","duration":"175.370292ms","start":"2026-07-01T10:43:50.261641Z","end":"2026-07-01T10:43:50.437011Z","steps":["trace[1519950137] 'agreement among raft nodes before linearized reading' (duration: 175.044212ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:45:22.140642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.627594ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T10:45:22.140754Z","caller":"traceutil/trace.go:171","msg":"trace[2051602262] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1937; }","duration":"364.565422ms","start":"2026-07-01T10:45:21.776174Z","end":"2026-07-01T10:45:22.14074Z","steps":["trace[2051602262] 'read index received' (duration: 2.381271ms)","trace[2051602262] 'applied index is now lower than readState.Index' (duration: 362.183221ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:45:22.140813Z","caller":"traceutil/trace.go:171","msg":"trace[1179832015] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"408.675171ms","start":"2026-07-01T10:45:21.732129Z","end":"2026-07-01T10:45:22.140804Z","steps":["trace[1179832015] 'process raft request' (duration: 46.456089ms)","trace[1179832015] 'compare' (duration: 361.500801ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:45:22.140859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:45:21.732112Z","time spent":"408.727162ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:45:22.141306Z","caller":"traceutil/trace.go:171","msg":"trace[1522541637] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"221.35387ms","start":"2026-07-01T10:45:21.919914Z","end":"2026-07-01T10:45:22.141268Z","steps":["trace[1522541637] 'process raft request' (duration: 221.153123ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:45:22.141409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.03951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-01T10:45:22.141445Z","caller":"traceutil/trace.go:171","msg":"trace[924183569] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1865; }","duration":"115.177905ms","start":"2026-07-01T10:45:22.026257Z","end":"2026-07-01T10:45:22.141435Z","steps":["trace[924183569] 'agreement among raft nodes before linearized reading' (duration: 115.00143ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:45:22.141513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.565583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-01T10:45:22.141669Z","caller":"traceutil/trace.go:171","msg":"trace[648368957] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1865; }","duration":"363.759969ms","start":"2026-07-01T10:45:21.777877Z","end":"2026-07-01T10:45:22.141637Z","steps":["trace[648368957] 'agreement among raft nodes before linearized reading' (duration: 363.383038ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:45:22.141746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:45:21.777867Z","time spent":"363.862142ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-01T10:45:22.14172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.529422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-17c02649-1995-4c25-96f5-4081c7a59139\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-01T10:45:22.141844Z","caller":"traceutil/trace.go:171","msg":"trace[256069123] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-17c02649-1995-4c25-96f5-4081c7a59139; range_end:; response_count:1; response_revision:1864; }","duration":"365.632905ms","start":"2026-07-01T10:45:21.776165Z","end":"2026-07-01T10:45:22.141798Z","steps":["trace[256069123] 'agreement among raft nodes before linearized reading' (duration: 364.703106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:45:22.141923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:45:21.776086Z","time spent":"365.79843ms","remote":"127.0.0.1:35018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-17c02649-1995-4c25-96f5-4081c7a59139\" "} {"level":"info","ts":"2026-07-01T10:46:06.487669Z","caller":"traceutil/trace.go:171","msg":"trace[681807615] linearizableReadLoop","detail":"{readStateIndex:2246; appliedIndex:2245; }","duration":"136.592106ms","start":"2026-07-01T10:46:06.351049Z","end":"2026-07-01T10:46:06.487641Z","steps":["trace[681807615] 'read index received' (duration: 136.405691ms)","trace[681807615] 'applied index is now lower than readState.Index' (duration: 185.495µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:46:06.488065Z","caller":"traceutil/trace.go:171","msg":"trace[2060832311] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"163.369907ms","start":"2026-07-01T10:46:06.324638Z","end":"2026-07-01T10:46:06.488008Z","steps":["trace[2060832311] 'process raft request' (duration: 162.845852ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:46:06.488407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.338868ms","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-07-01T10:46:06.488456Z","caller":"traceutil/trace.go:171","msg":"trace[1909857985] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2162; }","duration":"137.438521ms","start":"2026-07-01T10:46:06.351005Z","end":"2026-07-01T10:46:06.488443Z","steps":["trace[1909857985] 'agreement among raft nodes before linearized reading' (duration: 137.287477ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:46:06.488638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.209345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-01T10:46:06.488704Z","caller":"traceutil/trace.go:171","msg":"trace[844067001] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2162; }","duration":"128.306708ms","start":"2026-07-01T10:46:06.360381Z","end":"2026-07-01T10:46:06.488687Z","steps":["trace[844067001] 'agreement among raft nodes before linearized reading' (duration: 128.114333ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:46:06.636706Z","caller":"traceutil/trace.go:171","msg":"trace[1441876139] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"142.75629ms","start":"2026-07-01T10:46:06.493924Z","end":"2026-07-01T10:46:06.63668Z","steps":["trace[1441876139] 'process raft request' (duration: 142.613836ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:46:07.156962Z","caller":"traceutil/trace.go:171","msg":"trace[1357148699] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"302.306662ms","start":"2026-07-01T10:46:06.854621Z","end":"2026-07-01T10:46:07.156927Z","steps":["trace[1357148699] 'process raft request' (duration: 302.113657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:46:07.157213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:46:06.854581Z","time spent":"302.499848ms","remote":"127.0.0.1:35098","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-07-01T10:46:07.567209Z","caller":"traceutil/trace.go:171","msg":"trace[1004781933] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"708.011778ms","start":"2026-07-01T10:46:06.859153Z","end":"2026-07-01T10:46:07.567165Z","steps":["trace[1004781933] 'process raft request' (duration: 686.082131ms)","trace[1004781933] 'compare' (duration: 20.598117ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:46:07.567246Z","caller":"traceutil/trace.go:171","msg":"trace[71316723] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"655.885558ms","start":"2026-07-01T10:46:06.911315Z","end":"2026-07-01T10:46:07.5672Z","steps":["trace[71316723] 'process raft request' (duration: 655.208298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:46:07.56745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:46:06.911275Z","time spent":"656.066313ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T10:46:07.567501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:46:06.859115Z","time spent":"708.260235ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:46:13.965569Z","caller":"traceutil/trace.go:171","msg":"trace[1680810229] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"292.429507ms","start":"2026-07-01T10:46:13.673085Z","end":"2026-07-01T10:46:13.965514Z","steps":["trace[1680810229] 'process raft request' (duration: 291.579382ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:47:42.143838Z","caller":"traceutil/trace.go:171","msg":"trace[136287612] transaction","detail":"{read_only:false; response_revision:2875; number_of_response:1; }","duration":"104.735985ms","start":"2026-07-01T10:47:42.039071Z","end":"2026-07-01T10:47:42.143807Z","steps":["trace[136287612] 'process raft request' (duration: 104.541149ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:47:42.624877Z","caller":"traceutil/trace.go:171","msg":"trace[484291706] transaction","detail":"{read_only:false; response_revision:2876; number_of_response:1; }","duration":"161.089802ms","start":"2026-07-01T10:47:42.46376Z","end":"2026-07-01T10:47:42.62485Z","steps":["trace[484291706] 'process raft request' (duration: 160.938188ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:47:42.625195Z","caller":"traceutil/trace.go:171","msg":"trace[1755932702] linearizableReadLoop","detail":"{readStateIndex:2987; appliedIndex:2987; }","duration":"161.078832ms","start":"2026-07-01T10:47:42.464098Z","end":"2026-07-01T10:47:42.625177Z","steps":["trace[1755932702] 'read index received' (duration: 161.072842ms)","trace[1755932702] 'applied index is now lower than readState.Index' (duration: 4.86µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:47:42.625437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.31101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7462"} {"level":"info","ts":"2026-07-01T10:47:42.625567Z","caller":"traceutil/trace.go:171","msg":"trace[416324651] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:2876; }","duration":"161.471884ms","start":"2026-07-01T10:47:42.464082Z","end":"2026-07-01T10:47:42.625554Z","steps":["trace[416324651] 'agreement among raft nodes before linearized reading' (duration: 161.223036ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:47:42.62578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.729784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:47:42.625863Z","caller":"traceutil/trace.go:171","msg":"trace[324336501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2876; }","duration":"136.856098ms","start":"2026-07-01T10:47:42.488986Z","end":"2026-07-01T10:47:42.625842Z","steps":["trace[324336501] 'agreement among raft nodes before linearized reading' (duration: 136.709924ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:51:59.114356Z","caller":"traceutil/trace.go:171","msg":"trace[1740517327] linearizableReadLoop","detail":"{readStateIndex:5581; appliedIndex:5579; }","duration":"118.828329ms","start":"2026-07-01T10:51:58.995505Z","end":"2026-07-01T10:51:59.114333Z","steps":["trace[1740517327] 'read index received' (duration: 40.966546ms)","trace[1740517327] 'applied index is now lower than readState.Index' (duration: 77.860863ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:51:59.114447Z","caller":"traceutil/trace.go:171","msg":"trace[895897158] transaction","detail":"{read_only:false; response_revision:5378; number_of_response:1; }","duration":"123.370762ms","start":"2026-07-01T10:51:58.991047Z","end":"2026-07-01T10:51:59.114418Z","steps":["trace[895897158] 'process raft request' (duration: 97.569423ms)","trace[895897158] 'compare' (duration: 25.607734ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:51:59.114495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.977073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/memcached-metrics\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:51:59.114793Z","caller":"traceutil/trace.go:171","msg":"trace[1094028079] range","detail":"{range_begin:/registry/services/specs/openstack/memcached-metrics; range_end:; response_count:0; response_revision:5378; }","duration":"119.297712ms","start":"2026-07-01T10:51:58.995469Z","end":"2026-07-01T10:51:59.114767Z","steps":["trace[1094028079] 'agreement among raft nodes before linearized reading' (duration: 118.949302ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:51:59.308564Z","caller":"traceutil/trace.go:171","msg":"trace[461853243] transaction","detail":"{read_only:false; response_revision:5383; number_of_response:1; }","duration":"101.523439ms","start":"2026-07-01T10:51:59.207009Z","end":"2026-07-01T10:51:59.308532Z","steps":["trace[461853243] 'process raft request' (duration: 61.323026ms)","trace[461853243] 'compare' (duration: 39.960936ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:52:10.204605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.215457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-07-01T10:52:10.204702Z","caller":"traceutil/trace.go:171","msg":"trace[996001639] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5580; }","duration":"106.33285ms","start":"2026-07-01T10:52:10.098352Z","end":"2026-07-01T10:52:10.204685Z","steps":["trace[996001639] 'range keys from in-memory index tree' (duration: 105.901708ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:52:20.403171Z","caller":"traceutil/trace.go:171","msg":"trace[1986125442] transaction","detail":"{read_only:false; response_revision:5714; number_of_response:1; }","duration":"118.91417ms","start":"2026-07-01T10:52:20.284202Z","end":"2026-07-01T10:52:20.403116Z","steps":["trace[1986125442] 'process raft request' (duration: 118.752084ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:52:20.403443Z","caller":"traceutil/trace.go:171","msg":"trace[600560335] transaction","detail":"{read_only:false; response_revision:5716; number_of_response:1; }","duration":"117.949831ms","start":"2026-07-01T10:52:20.285474Z","end":"2026-07-01T10:52:20.403424Z","steps":["trace[600560335] 'process raft request' (duration: 117.826357ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:52:20.403557Z","caller":"traceutil/trace.go:171","msg":"trace[1494038957] transaction","detail":"{read_only:false; response_revision:5715; number_of_response:1; }","duration":"119.134446ms","start":"2026-07-01T10:52:20.284401Z","end":"2026-07-01T10:52:20.403536Z","steps":["trace[1494038957] 'process raft request' (duration: 118.667512ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:53:07.082855Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3011} {"level":"info","ts":"2026-07-01T10:53:07.176987Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3011,"took":"92.748553ms","hash":4252720674,"current-db-size-bytes":57274368,"current-db-size":"57 MB","current-db-size-in-use-bytes":31629312,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-01T10:53:07.177087Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4252720674,"revision":3011,"compact-revision":-1} {"level":"info","ts":"2026-07-01T10:58:07.090844Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6126} {"level":"info","ts":"2026-07-01T10:58:07.186508Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6126,"took":"94.125703ms","hash":1724367979,"current-db-size-bytes":57274368,"current-db-size":"57 MB","current-db-size-in-use-bytes":21987328,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-07-01T10:58:07.186585Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1724367979,"revision":6126,"compact-revision":3011} {"level":"info","ts":"2026-07-01T10:58:17.105417Z","caller":"traceutil/trace.go:171","msg":"trace[104011868] transaction","detail":"{read_only:false; response_revision:8406; number_of_response:1; }","duration":"133.286131ms","start":"2026-07-01T10:58:16.972105Z","end":"2026-07-01T10:58:17.105391Z","steps":["trace[104011868] 'process raft request' (duration: 133.109516ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:58:28.866112Z","caller":"traceutil/trace.go:171","msg":"trace[2068412640] transaction","detail":"{read_only:false; response_revision:8466; number_of_response:1; }","duration":"204.487539ms","start":"2026-07-01T10:58:28.661602Z","end":"2026-07-01T10:58:28.86609Z","steps":["trace[2068412640] 'process raft request' (duration: 204.324474ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:58:28.86609Z","caller":"traceutil/trace.go:171","msg":"trace[749341432] linearizableReadLoop","detail":"{readStateIndex:8764; appliedIndex:8763; }","duration":"172.669915ms","start":"2026-07-01T10:58:28.693397Z","end":"2026-07-01T10:58:28.866067Z","steps":["trace[749341432] 'read index received' (duration: 172.460419ms)","trace[749341432] 'applied index is now lower than readState.Index' (duration: 208.636µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:58:28.866278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.864281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-01T10:58:28.866328Z","caller":"traceutil/trace.go:171","msg":"trace[1291265231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8466; }","duration":"172.952463ms","start":"2026-07-01T10:58:28.69336Z","end":"2026-07-01T10:58:28.866312Z","steps":["trace[1291265231] 'agreement among raft nodes before linearized reading' (duration: 172.788788ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:58:28.866373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.653374ms","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-07-01T10:58:28.866424Z","caller":"traceutil/trace.go:171","msg":"trace[2052117771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8466; }","duration":"172.728506ms","start":"2026-07-01T10:58:28.693687Z","end":"2026-07-01T10:58:28.866416Z","steps":["trace[2052117771] 'agreement among raft nodes before linearized reading' (duration: 172.627393ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:58:29.81135Z","caller":"traceutil/trace.go:171","msg":"trace[1273773097] transaction","detail":"{read_only:false; response_revision:8471; number_of_response:1; }","duration":"220.187945ms","start":"2026-07-01T10:58:29.591137Z","end":"2026-07-01T10:58:29.811325Z","steps":["trace[1273773097] 'process raft request' (duration: 220.03325ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:58:57.373544Z","caller":"traceutil/trace.go:171","msg":"trace[660829144] transaction","detail":"{read_only:false; response_revision:8673; number_of_response:1; }","duration":"259.645649ms","start":"2026-07-01T10:58:57.113873Z","end":"2026-07-01T10:58:57.373519Z","steps":["trace[660829144] 'process raft request' (duration: 259.507695ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:58:57.384877Z","caller":"traceutil/trace.go:171","msg":"trace[815263279] transaction","detail":"{read_only:false; response_revision:8674; number_of_response:1; }","duration":"270.939598ms","start":"2026-07-01T10:58:57.113916Z","end":"2026-07-01T10:58:57.384856Z","steps":["trace[815263279] 'process raft request' (duration: 270.851946ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:58:57.574148Z","caller":"traceutil/trace.go:171","msg":"trace[607375355] transaction","detail":"{read_only:false; response_revision:8675; number_of_response:1; }","duration":"125.107698ms","start":"2026-07-01T10:58:57.44901Z","end":"2026-07-01T10:58:57.574117Z","steps":["trace[607375355] 'process raft request' (duration: 102.389135ms)","trace[607375355] 'compare' (duration: 22.180377ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:59:01.65943Z","caller":"traceutil/trace.go:171","msg":"trace[1634063184] transaction","detail":"{read_only:false; response_revision:8695; number_of_response:1; }","duration":"238.283857ms","start":"2026-07-01T10:59:01.421035Z","end":"2026-07-01T10:59:01.659318Z","steps":["trace[1634063184] 'process raft request' (duration: 237.826624ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:59:01.671118Z","caller":"traceutil/trace.go:171","msg":"trace[723303925] linearizableReadLoop","detail":"{readStateIndex:9003; appliedIndex:9001; }","duration":"182.50543ms","start":"2026-07-01T10:59:01.488588Z","end":"2026-07-01T10:59:01.671094Z","steps":["trace[723303925] 'read index received' (duration: 170.231223ms)","trace[723303925] 'applied index is now lower than readState.Index' (duration: 12.273067ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:59:01.671171Z","caller":"traceutil/trace.go:171","msg":"trace[885707700] transaction","detail":"{read_only:false; response_revision:8696; number_of_response:1; }","duration":"249.461563ms","start":"2026-07-01T10:59:01.421678Z","end":"2026-07-01T10:59:01.67114Z","steps":["trace[885707700] 'process raft request' (duration: 249.211495ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:59:01.671279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.683386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:59:01.671318Z","caller":"traceutil/trace.go:171","msg":"trace[1230911293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8697; }","duration":"182.774578ms","start":"2026-07-01T10:59:01.488531Z","end":"2026-07-01T10:59:01.671306Z","steps":["trace[1230911293] 'agreement among raft nodes before linearized reading' (duration: 182.661475ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:59:27.345479Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002424.wal"} {"level":"info","ts":"2026-07-01T11:00:41.78383Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d7dbc9f5893cc1f6","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-01T11:00:41.790487Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-01T11:00:41.790607Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-01T11:03:07.101319Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8216} {"level":"info","ts":"2026-07-01T11:03:07.163262Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8216,"took":"59.559341ms","hash":958674266,"current-db-size-bytes":57274368,"current-db-size":"57 MB","current-db-size-in-use-bytes":28434432,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-01T11:03:07.163318Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":958674266,"revision":8216,"compact-revision":6126} {"level":"info","ts":"2026-07-01T11:04:37.073851Z","caller":"traceutil/trace.go:171","msg":"trace[1243198203] transaction","detail":"{read_only:false; response_revision:11152; number_of_response:1; }","duration":"112.008141ms","start":"2026-07-01T11:04:36.961819Z","end":"2026-07-01T11:04:37.073827Z","steps":["trace[1243198203] 'process raft request' (duration: 35.770091ms)","trace[1243198203] 'compare' (duration: 76.132517ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:08:07.10784Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10582} {"level":"info","ts":"2026-07-01T11:08:07.187175Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10582,"took":"76.886691ms","hash":4060676281,"current-db-size-bytes":57274368,"current-db-size":"57 MB","current-db-size-in-use-bytes":26935296,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-01T11:08:07.187242Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4060676281,"revision":10582,"compact-revision":8216} {"level":"warn","ts":"2026-07-01T11:12:41.207879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.26052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T11:12:41.207911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.47088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/openvswitch-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T11:12:41.207987Z","caller":"traceutil/trace.go:171","msg":"trace[1457095587] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:14592; }","duration":"143.422165ms","start":"2026-07-01T11:12:41.064543Z","end":"2026-07-01T11:12:41.207965Z","steps":["trace[1457095587] 'count revisions from in-memory index tree' (duration: 143.192789ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:12:41.208009Z","caller":"traceutil/trace.go:171","msg":"trace[312135202] range","detail":"{range_begin:/registry/serviceaccounts/openstack/openvswitch-server; range_end:; response_count:0; response_revision:14592; }","duration":"101.601064ms","start":"2026-07-01T11:12:41.106383Z","end":"2026-07-01T11:12:41.207984Z","steps":["trace[312135202] 'range keys from in-memory index tree' (duration: 101.414489ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:12:46.419275Z","caller":"traceutil/trace.go:171","msg":"trace[1623501921] transaction","detail":"{read_only:false; response_revision:14647; number_of_response:1; }","duration":"143.334122ms","start":"2026-07-01T11:12:46.27588Z","end":"2026-07-01T11:12:46.419214Z","steps":["trace[1623501921] 'process raft request' (duration: 143.025793ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:13:04.727268Z","caller":"traceutil/trace.go:171","msg":"trace[3810546] transaction","detail":"{read_only:false; response_revision:14924; number_of_response:1; }","duration":"199.50208ms","start":"2026-07-01T11:13:04.527735Z","end":"2026-07-01T11:13:04.727237Z","steps":["trace[3810546] 'process raft request' (duration: 199.252703ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:13:07.114439Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12812} {"level":"info","ts":"2026-07-01T11:13:07.191373Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12812,"took":"73.912221ms","hash":2725559083,"current-db-size-bytes":57274368,"current-db-size":"57 MB","current-db-size-in-use-bytes":29196288,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-01T11:13:07.191428Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2725559083,"revision":12812,"compact-revision":10582} {"level":"info","ts":"2026-07-01T11:14:29.496492Z","caller":"traceutil/trace.go:171","msg":"trace[455657293] transaction","detail":"{read_only:false; response_revision:15728; number_of_response:1; }","duration":"120.158132ms","start":"2026-07-01T11:14:29.376312Z","end":"2026-07-01T11:14:29.496471Z","steps":["trace[455657293] 'process raft request' (duration: 120.026578ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:14:29.673976Z","caller":"traceutil/trace.go:171","msg":"trace[855970042] linearizableReadLoop","detail":"{readStateIndex:16274; appliedIndex:16273; }","duration":"200.2284ms","start":"2026-07-01T11:14:29.473722Z","end":"2026-07-01T11:14:29.673951Z","steps":["trace[855970042] 'read index received' (duration: 23.316725ms)","trace[855970042] 'applied index is now lower than readState.Index' (duration: 176.910735ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:14:29.674091Z","caller":"traceutil/trace.go:171","msg":"trace[957390402] transaction","detail":"{read_only:false; response_revision:15729; number_of_response:1; }","duration":"292.381372ms","start":"2026-07-01T11:14:29.381699Z","end":"2026-07-01T11:14:29.67408Z","steps":["trace[957390402] 'process raft request' (duration: 292.097743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:14:29.674356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.363711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T11:14:29.674393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.648254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T11:14:29.674442Z","caller":"traceutil/trace.go:171","msg":"trace[1300134381] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15729; }","duration":"185.479624ms","start":"2026-07-01T11:14:29.48894Z","end":"2026-07-01T11:14:29.67442Z","steps":["trace[1300134381] 'agreement among raft nodes before linearized reading' (duration: 185.344471ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:14:29.674471Z","caller":"traceutil/trace.go:171","msg":"trace[747744291] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:15729; }","duration":"200.775927ms","start":"2026-07-01T11:14:29.473669Z","end":"2026-07-01T11:14:29.674445Z","steps":["trace[747744291] 'agreement among raft nodes before linearized reading' (duration: 200.650274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:14:30.540939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.621846ms","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-07-01T11:14:30.542276Z","caller":"traceutil/trace.go:171","msg":"trace[275853223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15732; }","duration":"111.986134ms","start":"2026-07-01T11:14:30.430272Z","end":"2026-07-01T11:14:30.542258Z","steps":["trace[275853223] 'range keys from in-memory index tree' (duration: 110.496992ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:14:30.542247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.117107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-01T11:14:30.542602Z","caller":"traceutil/trace.go:171","msg":"trace[692486751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15732; }","duration":"113.52933ms","start":"2026-07-01T11:14:30.42906Z","end":"2026-07-01T11:14:30.54259Z","steps":["trace[692486751] 'range keys from in-memory index tree' (duration: 112.973762ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:14:30.541237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.761282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-cz2cz\" ","response":"range_response_count:1 size:20659"} {"level":"info","ts":"2026-07-01T11:14:30.542789Z","caller":"traceutil/trace.go:171","msg":"trace[2048397791] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-cz2cz; range_end:; response_count:1; response_revision:15732; }","duration":"109.330187ms","start":"2026-07-01T11:14:30.433446Z","end":"2026-07-01T11:14:30.542776Z","steps":["trace[2048397791] 'range keys from in-memory index tree' (duration: 107.668178ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:14:30.541369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.66646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-b7rzh\" ","response":"range_response_count:1 size:22132"} {"level":"info","ts":"2026-07-01T11:14:30.543034Z","caller":"traceutil/trace.go:171","msg":"trace[723968114] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-b7rzh; range_end:; response_count:1; response_revision:15732; }","duration":"108.391941ms","start":"2026-07-01T11:14:30.434631Z","end":"2026-07-01T11:14:30.543023Z","steps":["trace[723968114] 'range keys from in-memory index tree' (duration: 106.499475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:14:30.542143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.114507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:1 size:138"} {"level":"info","ts":"2026-07-01T11:14:30.543189Z","caller":"traceutil/trace.go:171","msg":"trace[1333751106] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:1; response_revision:15732; }","duration":"123.226929ms","start":"2026-07-01T11:14:30.419951Z","end":"2026-07-01T11:14:30.543178Z","steps":["trace[1333751106] 'range keys from in-memory index tree' (duration: 121.925552ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:14:30.541153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.652914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-07-01T11:14:30.54331Z","caller":"traceutil/trace.go:171","msg":"trace[629092655] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:15732; }","duration":"104.821338ms","start":"2026-07-01T11:14:30.438471Z","end":"2026-07-01T11:14:30.543292Z","steps":["trace[629092655] 'range keys from in-memory index tree' (duration: 102.569232ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:14:30.702661Z","caller":"traceutil/trace.go:171","msg":"trace[1277805332] linearizableReadLoop","detail":"{readStateIndex:16280; appliedIndex:16278; }","duration":"152.465707ms","start":"2026-07-01T11:14:30.550172Z","end":"2026-07-01T11:14:30.702638Z","steps":["trace[1277805332] 'read index received' (duration: 85.23441ms)","trace[1277805332] 'applied index is now lower than readState.Index' (duration: 67.230487ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:14:30.703315Z","caller":"traceutil/trace.go:171","msg":"trace[1652710554] transaction","detail":"{read_only:false; response_revision:15733; number_of_response:1; }","duration":"156.174385ms","start":"2026-07-01T11:14:30.547122Z","end":"2026-07-01T11:14:30.703296Z","steps":["trace[1652710554] 'process raft request' (duration: 154.735483ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:14:30.703446Z","caller":"traceutil/trace.go:171","msg":"trace[478207034] transaction","detail":"{read_only:false; response_revision:15734; number_of_response:1; }","duration":"156.067792ms","start":"2026-07-01T11:14:30.54736Z","end":"2026-07-01T11:14:30.703427Z","steps":["trace[478207034] 'process raft request' (duration: 155.224258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:14:30.703525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.425807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T11:14:30.704365Z","caller":"traceutil/trace.go:171","msg":"trace[25266881] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:15735; }","duration":"143.301452ms","start":"2026-07-01T11:14:30.561052Z","end":"2026-07-01T11:14:30.704353Z","steps":["trace[25266881] 'agreement among raft nodes before linearized reading' (duration: 142.427877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:14:30.70379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.610651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:746327"} {"level":"info","ts":"2026-07-01T11:14:30.704562Z","caller":"traceutil/trace.go:171","msg":"trace[1083320964] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:15735; }","duration":"154.408243ms","start":"2026-07-01T11:14:30.550145Z","end":"2026-07-01T11:14:30.704553Z","steps":["trace[1083320964] 'agreement among raft nodes before linearized reading' (duration: 152.937721ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:14:30.703911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.663702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:746327"} {"level":"info","ts":"2026-07-01T11:14:30.704893Z","caller":"traceutil/trace.go:171","msg":"trace[1751904545] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:15735; }","duration":"154.682182ms","start":"2026-07-01T11:14:30.550203Z","end":"2026-07-01T11:14:30.704885Z","steps":["trace[1751904545] 'agreement among raft nodes before linearized reading' (duration: 152.899701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:14:30.70398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.58118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:746327"} {"level":"info","ts":"2026-07-01T11:14:30.708138Z","caller":"traceutil/trace.go:171","msg":"trace[290653374] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:15735; }","duration":"157.740161ms","start":"2026-07-01T11:14:30.550379Z","end":"2026-07-01T11:14:30.708119Z","steps":["trace[290653374] 'agreement among raft nodes before linearized reading' (duration: 153.169868ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:18:07.125111Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14943} {"level":"info","ts":"2026-07-01T11:18:07.241911Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14943,"took":"113.875569ms","hash":2005187105,"current-db-size-bytes":57274368,"current-db-size":"57 MB","current-db-size-in-use-bytes":30543872,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-01T11:18:07.241997Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2005187105,"revision":14943,"compact-revision":12812} {"level":"warn","ts":"2026-07-01T11:20:12.622877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.041427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-01T11:20:12.622936Z","caller":"traceutil/trace.go:171","msg":"trace[923836276] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18273; }","duration":"149.14591ms","start":"2026-07-01T11:20:12.473778Z","end":"2026-07-01T11:20:12.622924Z","steps":["trace[923836276] 'range keys from in-memory index tree' (duration: 148.852591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:20:12.623087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.679709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:20:12.623105Z","caller":"traceutil/trace.go:171","msg":"trace[2138924805] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18273; }","duration":"137.71871ms","start":"2026-07-01T11:20:12.485382Z","end":"2026-07-01T11:20:12.623101Z","steps":["trace[2138924805] 'range keys from in-memory index tree' (duration: 137.636497ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:20:19.634518Z","caller":"traceutil/trace.go:171","msg":"trace[726164239] transaction","detail":"{read_only:false; response_revision:18317; number_of_response:1; }","duration":"141.966521ms","start":"2026-07-01T11:20:19.492523Z","end":"2026-07-01T11:20:19.634489Z","steps":["trace[726164239] 'process raft request' (duration: 141.730974ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:20:19.689824Z","caller":"traceutil/trace.go:171","msg":"trace[1806354161] transaction","detail":"{read_only:false; response_revision:18318; number_of_response:1; }","duration":"119.13739ms","start":"2026-07-01T11:20:19.570656Z","end":"2026-07-01T11:20:19.689794Z","steps":["trace[1806354161] 'process raft request' (duration: 72.757218ms)","trace[1806354161] 'compare' (duration: 46.256989ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:22:01.286787Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d7dbc9f5893cc1f6","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-01T11:22:01.331113Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-01T11:22:01.331334Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-01T11:23:07.132405Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17414} {"level":"info","ts":"2026-07-01T11:23:07.225489Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17414,"took":"89.679618ms","hash":565792870,"current-db-size-bytes":57274368,"current-db-size":"57 MB","current-db-size-in-use-bytes":34877440,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-01T11:23:07.225568Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":565792870,"revision":17414,"compact-revision":14943} {"level":"info","ts":"2026-07-01T11:25:56.353846Z","caller":"traceutil/trace.go:171","msg":"trace[2094073761] linearizableReadLoop","detail":"{readStateIndex:21670; appliedIndex:21669; }","duration":"101.462479ms","start":"2026-07-01T11:25:56.252352Z","end":"2026-07-01T11:25:56.353815Z","steps":["trace[2094073761] 'read index received' (duration: 101.370706ms)","trace[2094073761] 'applied index is now lower than readState.Index' (duration: 90.453µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:25:56.35398Z","caller":"traceutil/trace.go:171","msg":"trace[181128828] transaction","detail":"{read_only:false; response_revision:20952; number_of_response:1; }","duration":"130.835829ms","start":"2026-07-01T11:25:56.223132Z","end":"2026-07-01T11:25:56.353968Z","steps":["trace[181128828] 'process raft request' (duration: 130.460578ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:25:56.354204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.82839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-01T11:25:56.354308Z","caller":"traceutil/trace.go:171","msg":"trace[1664661503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20952; }","duration":"101.982704ms","start":"2026-07-01T11:25:56.252299Z","end":"2026-07-01T11:25:56.354281Z","steps":["trace[1664661503] 'agreement among raft nodes before linearized reading' (duration: 101.686186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:25:56.683155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.280566ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:25:56.683354Z","caller":"traceutil/trace.go:171","msg":"trace[526046429] transaction","detail":"{read_only:false; response_revision:20954; number_of_response:1; }","duration":"324.694133ms","start":"2026-07-01T11:25:56.358642Z","end":"2026-07-01T11:25:56.683336Z","steps":["trace[526046429] 'process raft request' (duration: 324.62062ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:25:56.683329Z","caller":"traceutil/trace.go:171","msg":"trace[1879184473] transaction","detail":"{read_only:false; response_revision:20953; number_of_response:1; }","duration":"383.120785ms","start":"2026-07-01T11:25:56.300159Z","end":"2026-07-01T11:25:56.68328Z","steps":["trace[1879184473] 'process raft request' (duration: 187.17221ms)","trace[1879184473] 'compare' (duration: 195.08813ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:25:56.68351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:25:56.300132Z","time spent":"383.284789ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:25:56.683521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:25:56.358615Z","time spent":"324.827367ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:25:56.683561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.113868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:25:56.683663Z","caller":"traceutil/trace.go:171","msg":"trace[654776454] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:20954; }","duration":"379.247882ms","start":"2026-07-01T11:25:56.304396Z","end":"2026-07-01T11:25:56.683644Z","steps":["trace[654776454] 'agreement among raft nodes before linearized reading' (duration: 379.080008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:25:56.683743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:25:56.304387Z","time spent":"379.341425ms","remote":"127.0.0.1:49396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-07-01T11:25:56.683414Z","caller":"traceutil/trace.go:171","msg":"trace[296123305] linearizableReadLoop","detail":"{readStateIndex:21671; appliedIndex:21670; }","duration":"329.45064ms","start":"2026-07-01T11:25:56.353936Z","end":"2026-07-01T11:25:56.683387Z","steps":["trace[296123305] 'read index received' (duration: 133.397972ms)","trace[296123305] 'applied index is now lower than readState.Index' (duration: 196.050128ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:25:56.684461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.156942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-01T11:25:56.684489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.470106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-01T11:25:56.684528Z","caller":"traceutil/trace.go:171","msg":"trace[917535332] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20954; }","duration":"286.550168ms","start":"2026-07-01T11:25:56.397966Z","end":"2026-07-01T11:25:56.684516Z","steps":["trace[917535332] 'agreement among raft nodes before linearized reading' (duration: 286.410204ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:25:56.684529Z","caller":"traceutil/trace.go:171","msg":"trace[1107942012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20954; }","duration":"195.248344ms","start":"2026-07-01T11:25:56.489262Z","end":"2026-07-01T11:25:56.68451Z","steps":["trace[1107942012] 'agreement among raft nodes before linearized reading' (duration: 195.158682ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:25:56.684664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.037449ms","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-07-01T11:25:56.684768Z","caller":"traceutil/trace.go:171","msg":"trace[1057990492] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20954; }","duration":"205.171992ms","start":"2026-07-01T11:25:56.479555Z","end":"2026-07-01T11:25:56.684727Z","steps":["trace[1057990492] 'agreement among raft nodes before linearized reading' (duration: 204.910535ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:25:56.685023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.898417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-01T11:25:56.685077Z","caller":"traceutil/trace.go:171","msg":"trace[2009891431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20954; }","duration":"431.99974ms","start":"2026-07-01T11:25:56.253063Z","end":"2026-07-01T11:25:56.685063Z","steps":["trace[2009891431] 'agreement among raft nodes before linearized reading' (duration: 430.770514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:25:56.685116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:25:56.25304Z","time spent":"432.068152ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-01T11:26:12.890582Z","caller":"traceutil/trace.go:171","msg":"trace[55214087] linearizableReadLoop","detail":"{readStateIndex:21758; appliedIndex:21757; }","duration":"114.511696ms","start":"2026-07-01T11:26:12.776043Z","end":"2026-07-01T11:26:12.890555Z","steps":["trace[55214087] 'read index received' (duration: 114.323601ms)","trace[55214087] 'applied index is now lower than readState.Index' (duration: 186.685µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:26:12.890666Z","caller":"traceutil/trace.go:171","msg":"trace[1777840403] transaction","detail":"{read_only:false; response_revision:21036; number_of_response:1; }","duration":"126.262266ms","start":"2026-07-01T11:26:12.764364Z","end":"2026-07-01T11:26:12.890627Z","steps":["trace[1777840403] 'process raft request' (duration: 125.994279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:26:12.890865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.797554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-01T11:26:12.890939Z","caller":"traceutil/trace.go:171","msg":"trace[874705174] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21036; }","duration":"114.911208ms","start":"2026-07-01T11:26:12.776007Z","end":"2026-07-01T11:26:12.890918Z","steps":["trace[874705174] 'agreement among raft nodes before linearized reading' (duration: 114.65864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:26:12.89087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.787804ms","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-07-01T11:26:12.891039Z","caller":"traceutil/trace.go:171","msg":"trace[1642665568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21036; }","duration":"114.98448ms","start":"2026-07-01T11:26:12.776036Z","end":"2026-07-01T11:26:12.89102Z","steps":["trace[1642665568] 'agreement among raft nodes before linearized reading' (duration: 114.690251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:26:12.890974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.654012ms","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-07-01T11:26:12.891158Z","caller":"traceutil/trace.go:171","msg":"trace[928416127] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21036; }","duration":"112.858278ms","start":"2026-07-01T11:26:12.778259Z","end":"2026-07-01T11:26:12.891117Z","steps":["trace[928416127] 'agreement among raft nodes before linearized reading' (duration: 112.57976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:26:13.052991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.298567ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:26:13.053136Z","caller":"traceutil/trace.go:171","msg":"trace[138081354] transaction","detail":"{read_only:false; response_revision:21037; number_of_response:1; }","duration":"158.893471ms","start":"2026-07-01T11:26:12.894211Z","end":"2026-07-01T11:26:13.053104Z","steps":["trace[138081354] 'process raft request' (duration: 41.344707ms)","trace[138081354] 'compare' (duration: 117.044489ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:26:13.091124Z","caller":"traceutil/trace.go:171","msg":"trace[403352890] transaction","detail":"{read_only:false; response_revision:21039; number_of_response:1; }","duration":"195.551302ms","start":"2026-07-01T11:26:12.895539Z","end":"2026-07-01T11:26:13.09109Z","steps":["trace[403352890] 'process raft request' (duration: 195.438949ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:26:13.091355Z","caller":"traceutil/trace.go:171","msg":"trace[2064520952] transaction","detail":"{read_only:false; response_revision:21038; number_of_response:1; }","duration":"195.829561ms","start":"2026-07-01T11:26:12.895458Z","end":"2026-07-01T11:26:13.091288Z","steps":["trace[2064520952] 'process raft request' (duration: 195.244394ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:26:13.091435Z","caller":"traceutil/trace.go:171","msg":"trace[128606162] transaction","detail":"{read_only:false; response_revision:21040; number_of_response:1; }","duration":"195.426438ms","start":"2026-07-01T11:26:12.895974Z","end":"2026-07-01T11:26:13.0914Z","steps":["trace[128606162] 'process raft request' (duration: 195.059218ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:26:13.324377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.572534ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:26:13.324553Z","caller":"traceutil/trace.go:171","msg":"trace[1796081075] linearizableReadLoop","detail":"{readStateIndex:21764; appliedIndex:21762; }","duration":"116.083102ms","start":"2026-07-01T11:26:13.208453Z","end":"2026-07-01T11:26:13.324536Z","steps":["trace[1796081075] 'read index received' (duration: 24.775628ms)","trace[1796081075] 'applied index is now lower than readState.Index' (duration: 91.306694ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:26:13.324666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.202986ms","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-07-01T11:26:13.324695Z","caller":"traceutil/trace.go:171","msg":"trace[1693084858] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:21042; }","duration":"116.265058ms","start":"2026-07-01T11:26:13.208419Z","end":"2026-07-01T11:26:13.324684Z","steps":["trace[1693084858] 'agreement among raft nodes before linearized reading' (duration: 116.206366ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:26:13.324815Z","caller":"traceutil/trace.go:171","msg":"trace[528065071] transaction","detail":"{read_only:false; response_revision:21042; number_of_response:1; }","duration":"141.804196ms","start":"2026-07-01T11:26:13.182978Z","end":"2026-07-01T11:26:13.324782Z","steps":["trace[528065071] 'process raft request' (duration: 141.499457ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:26:13.324865Z","caller":"traceutil/trace.go:171","msg":"trace[730375608] transaction","detail":"{read_only:false; response_revision:21041; number_of_response:1; }","duration":"181.414084ms","start":"2026-07-01T11:26:13.143415Z","end":"2026-07-01T11:26:13.324829Z","steps":["trace[730375608] 'process raft request' (duration: 43.295364ms)","trace[730375608] 'compare' (duration: 137.44883ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:26:13.501266Z","caller":"traceutil/trace.go:171","msg":"trace[158331791] transaction","detail":"{read_only:false; response_revision:21043; number_of_response:1; }","duration":"134.086593ms","start":"2026-07-01T11:26:13.367149Z","end":"2026-07-01T11:26:13.501235Z","steps":["trace[158331791] 'process raft request' (duration: 102.461807ms)","trace[158331791] 'compare' (duration: 31.522063ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:26:13.749381Z","caller":"traceutil/trace.go:171","msg":"trace[1262762481] transaction","detail":"{read_only:false; response_revision:21044; number_of_response:1; }","duration":"248.62918ms","start":"2026-07-01T11:26:13.500731Z","end":"2026-07-01T11:26:13.74936Z","steps":["trace[1262762481] 'process raft request' (duration: 241.173264ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:28:07.139729Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19777} {"level":"info","ts":"2026-07-01T11:28:07.252597Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19777,"took":"109.832741ms","hash":627214116,"current-db-size-bytes":57274368,"current-db-size":"57 MB","current-db-size-in-use-bytes":30543872,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-01T11:28:07.252665Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":627214116,"revision":19777,"compact-revision":17414} {"level":"info","ts":"2026-07-01T11:30:03.440806Z","caller":"traceutil/trace.go:171","msg":"trace[1182901113] transaction","detail":"{read_only:false; response_revision:23422; number_of_response:1; }","duration":"131.87122ms","start":"2026-07-01T11:30:03.308904Z","end":"2026-07-01T11:30:03.440775Z","steps":["trace[1182901113] 'process raft request' (duration: 120.936583ms)","trace[1182901113] 'compare' (duration: 10.444353ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:30:03.44522Z","caller":"traceutil/trace.go:171","msg":"trace[148032369] transaction","detail":"{read_only:false; response_revision:23423; number_of_response:1; }","duration":"132.88832ms","start":"2026-07-01T11:30:03.312306Z","end":"2026-07-01T11:30:03.445195Z","steps":["trace[148032369] 'process raft request' (duration: 132.613052ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:57.098579Z","caller":"traceutil/trace.go:171","msg":"trace[1270888440] transaction","detail":"{read_only:false; response_revision:23986; number_of_response:1; }","duration":"101.329766ms","start":"2026-07-01T11:30:56.99722Z","end":"2026-07-01T11:30:57.09855Z","steps":["trace[1270888440] 'process raft request' (duration: 72.862261ms)","trace[1270888440] 'compare' (duration: 28.359481ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:31:10.860038Z","caller":"traceutil/trace.go:171","msg":"trace[15943461] transaction","detail":"{read_only:false; response_revision:24232; number_of_response:1; }","duration":"141.455728ms","start":"2026-07-01T11:31:10.718556Z","end":"2026-07-01T11:31:10.860012Z","steps":["trace[15943461] 'process raft request' (duration: 140.279844ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:07.145793Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21985} {"level":"info","ts":"2026-07-01T11:33:07.257322Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21985,"took":"107.921987ms","hash":1088796619,"current-db-size-bytes":57274368,"current-db-size":"57 MB","current-db-size-in-use-bytes":44769280,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-07-01T11:33:07.257402Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1088796619,"revision":21985,"compact-revision":19777} {"level":"info","ts":"2026-07-01T11:33:26.652687Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000066ee.wal"} {"level":"info","ts":"2026-07-01T11:33:29.216393Z","caller":"traceutil/trace.go:171","msg":"trace[1338498339] transaction","detail":"{read_only:false; response_revision:25541; number_of_response:1; }","duration":"248.903813ms","start":"2026-07-01T11:33:28.967312Z","end":"2026-07-01T11:33:29.216215Z","steps":["trace[1338498339] 'process raft request' (duration: 248.695687ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:39.782599Z","caller":"traceutil/trace.go:171","msg":"trace[410328048] transaction","detail":"{read_only:false; response_revision:25626; number_of_response:1; }","duration":"135.870917ms","start":"2026-07-01T11:33:39.646701Z","end":"2026-07-01T11:33:39.782572Z","steps":["trace[410328048] 'process raft request' (duration: 135.734213ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:39.961066Z","caller":"traceutil/trace.go:171","msg":"trace[157598074] transaction","detail":"{read_only:false; response_revision:25627; number_of_response:1; }","duration":"173.959721ms","start":"2026-07-01T11:33:39.787074Z","end":"2026-07-01T11:33:39.961034Z","steps":["trace[157598074] 'process raft request' (duration: 173.849588ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:39.962908Z","caller":"traceutil/trace.go:171","msg":"trace[1898221154] transaction","detail":"{read_only:false; response_revision:25629; number_of_response:1; }","duration":"148.03655ms","start":"2026-07-01T11:33:39.814861Z","end":"2026-07-01T11:33:39.962897Z","steps":["trace[1898221154] 'process raft request' (duration: 147.974608ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:39.96307Z","caller":"traceutil/trace.go:171","msg":"trace[877562337] transaction","detail":"{read_only:false; response_revision:25628; number_of_response:1; }","duration":"150.13645ms","start":"2026-07-01T11:33:39.812926Z","end":"2026-07-01T11:33:39.963063Z","steps":["trace[877562337] 'process raft request' (duration: 149.849672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:41.675397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.395312ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:33:41.675572Z","caller":"traceutil/trace.go:171","msg":"trace[1669788294] transaction","detail":"{read_only:false; response_revision:25636; number_of_response:1; }","duration":"135.380843ms","start":"2026-07-01T11:33:41.540178Z","end":"2026-07-01T11:33:41.675559Z","steps":["trace[1669788294] 'process raft request' (duration: 135.306811ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:41.675579Z","caller":"traceutil/trace.go:171","msg":"trace[1256699935] linearizableReadLoop","detail":"{readStateIndex:26485; appliedIndex:26484; }","duration":"186.671779ms","start":"2026-07-01T11:33:41.488882Z","end":"2026-07-01T11:33:41.675554Z","steps":["trace[1256699935] 'read index received' (duration: 9.992089ms)","trace[1256699935] 'applied index is now lower than readState.Index' (duration: 176.67806ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:33:41.675707Z","caller":"traceutil/trace.go:171","msg":"trace[1486933977] transaction","detail":"{read_only:false; response_revision:25635; number_of_response:1; }","duration":"321.896707ms","start":"2026-07-01T11:33:41.353801Z","end":"2026-07-01T11:33:41.675698Z","steps":["trace[1486933977] 'process raft request' (duration: 145.125075ms)","trace[1486933977] 'compare' (duration: 176.092922ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:33:41.675792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:41.353791Z","time spent":"321.937768ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:33:41.675873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.933238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-01T11:33:41.675896Z","caller":"traceutil/trace.go:171","msg":"trace[1901188373] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25636; }","duration":"128.01519ms","start":"2026-07-01T11:33:41.547875Z","end":"2026-07-01T11:33:41.675891Z","steps":["trace[1901188373] 'agreement among raft nodes before linearized reading' (duration: 127.931817ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:41.676039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.141133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-01T11:33:41.676092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.291763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-01T11:33:41.676108Z","caller":"traceutil/trace.go:171","msg":"trace[168123074] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25636; }","duration":"187.268106ms","start":"2026-07-01T11:33:41.488823Z","end":"2026-07-01T11:33:41.676091Z","steps":["trace[168123074] 'agreement among raft nodes before linearized reading' (duration: 187.165383ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:41.67613Z","caller":"traceutil/trace.go:171","msg":"trace[1618044421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25636; }","duration":"123.349305ms","start":"2026-07-01T11:33:41.55277Z","end":"2026-07-01T11:33:41.676119Z","steps":["trace[1618044421] 'agreement among raft nodes before linearized reading' (duration: 123.213941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:41.676196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.208797ms","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-07-01T11:33:41.676278Z","caller":"traceutil/trace.go:171","msg":"trace[596695708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25636; }","duration":"126.364102ms","start":"2026-07-01T11:33:41.549897Z","end":"2026-07-01T11:33:41.676261Z","steps":["trace[596695708] 'agreement among raft nodes before linearized reading' (duration: 126.116175ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:42.121152Z","caller":"traceutil/trace.go:171","msg":"trace[2056982599] linearizableReadLoop","detail":"{readStateIndex:26489; appliedIndex:26487; }","duration":"333.544016ms","start":"2026-07-01T11:33:41.787587Z","end":"2026-07-01T11:33:42.121131Z","steps":["trace[2056982599] 'read index received' (duration: 333.34947ms)","trace[2056982599] 'applied index is now lower than readState.Index' (duration: 193.936µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:33:42.121345Z","caller":"traceutil/trace.go:171","msg":"trace[1894309173] transaction","detail":"{read_only:false; response_revision:25639; number_of_response:1; }","duration":"440.321941ms","start":"2026-07-01T11:33:41.680986Z","end":"2026-07-01T11:33:42.121308Z","steps":["trace[1894309173] 'process raft request' (duration: 440.114704ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:42.121417Z","caller":"traceutil/trace.go:171","msg":"trace[156086691] transaction","detail":"{read_only:false; response_revision:25638; number_of_response:1; }","duration":"441.191925ms","start":"2026-07-01T11:33:41.680204Z","end":"2026-07-01T11:33:42.121396Z","steps":["trace[156086691] 'process raft request' (duration: 440.782222ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:42.121458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.855475ms","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-07-01T11:33:42.121484Z","caller":"traceutil/trace.go:171","msg":"trace[1583948341] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25639; }","duration":"333.926907ms","start":"2026-07-01T11:33:41.787548Z","end":"2026-07-01T11:33:42.121475Z","steps":["trace[1583948341] 'agreement among raft nodes before linearized reading' (duration: 333.821114ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:42.121501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:41.78753Z","time spent":"333.967148ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-01T11:33:42.121519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:41.680966Z","time spent":"440.477585ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:33:42.121523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:41.680163Z","time spent":"441.299748ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:33:42.438399Z","caller":"traceutil/trace.go:171","msg":"trace[1114191101] transaction","detail":"{read_only:false; response_revision:25640; number_of_response:1; }","duration":"535.276161ms","start":"2026-07-01T11:33:41.903089Z","end":"2026-07-01T11:33:42.438365Z","steps":["trace[1114191101] 'process raft request' (duration: 535.033114ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:42.438576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:41.903064Z","time spent":"535.422266ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:33:42.43885Z","caller":"traceutil/trace.go:171","msg":"trace[1557671627] transaction","detail":"{read_only:false; response_revision:25641; number_of_response:1; }","duration":"472.968596ms","start":"2026-07-01T11:33:41.965851Z","end":"2026-07-01T11:33:42.438819Z","steps":["trace[1557671627] 'process raft request' (duration: 472.460202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:42.439021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:41.965829Z","time spent":"473.10441ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:33:42.439205Z","caller":"traceutil/trace.go:171","msg":"trace[1170443388] transaction","detail":"{read_only:false; response_revision:25642; number_of_response:1; }","duration":"471.03621ms","start":"2026-07-01T11:33:41.968121Z","end":"2026-07-01T11:33:42.439157Z","steps":["trace[1170443388] 'process raft request' (duration: 470.581607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:42.439292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:41.968098Z","time spent":"471.146792ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:33:42.439434Z","caller":"traceutil/trace.go:171","msg":"trace[1064910444] transaction","detail":"{read_only:false; response_revision:25643; number_of_response:1; }","duration":"471.121682ms","start":"2026-07-01T11:33:41.968285Z","end":"2026-07-01T11:33:42.439406Z","steps":["trace[1064910444] 'process raft request' (duration: 470.808903ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:42.439491Z","caller":"traceutil/trace.go:171","msg":"trace[502322731] linearizableReadLoop","detail":"{readStateIndex:26494; appliedIndex:26490; }","duration":"318.264363ms","start":"2026-07-01T11:33:42.121213Z","end":"2026-07-01T11:33:42.439477Z","steps":["trace[502322731] 'read index received' (duration: 317.10313ms)","trace[502322731] 'applied index is now lower than readState.Index' (duration: 1.160173ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:33:42.439572Z","caller":"traceutil/trace.go:171","msg":"trace[1232313383] transaction","detail":"{read_only:false; response_revision:25644; number_of_response:1; }","duration":"470.291639ms","start":"2026-07-01T11:33:41.969269Z","end":"2026-07-01T11:33:42.439561Z","steps":["trace[1232313383] 'process raft request' (duration: 470.045461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:42.439591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:41.968276Z","time spent":"471.227545ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:33:42.439631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:41.969261Z","time spent":"470.3337ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:33:42.439793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.93228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:33:42.439832Z","caller":"traceutil/trace.go:171","msg":"trace[650960134] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:25644; }","duration":"604.973381ms","start":"2026-07-01T11:33:41.834846Z","end":"2026-07-01T11:33:42.439819Z","steps":["trace[650960134] 'agreement among raft nodes before linearized reading' (duration: 604.908059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:42.439834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.470404ms","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-07-01T11:33:42.439864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:41.834818Z","time spent":"605.038103ms","remote":"127.0.0.1:36176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":30,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true "} {"level":"info","ts":"2026-07-01T11:33:42.439873Z","caller":"traceutil/trace.go:171","msg":"trace[1222687911] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25644; }","duration":"469.531226ms","start":"2026-07-01T11:33:41.97033Z","end":"2026-07-01T11:33:42.439861Z","steps":["trace[1222687911] 'agreement among raft nodes before linearized reading' (duration: 469.396682ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:42.439909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:41.970322Z","time spent":"469.579377ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-01T11:33:42.460215Z","caller":"traceutil/trace.go:171","msg":"trace[762007750] transaction","detail":"{read_only:false; response_revision:25645; number_of_response:1; }","duration":"334.497133ms","start":"2026-07-01T11:33:42.125692Z","end":"2026-07-01T11:33:42.460189Z","steps":["trace[762007750] 'process raft request' (duration: 334.329728ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:42.460353Z","caller":"traceutil/trace.go:171","msg":"trace[1828310771] transaction","detail":"{read_only:false; response_revision:25646; number_of_response:1; }","duration":"201.850881ms","start":"2026-07-01T11:33:42.258488Z","end":"2026-07-01T11:33:42.460339Z","steps":["trace[1828310771] 'process raft request' (duration: 201.649925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:42.460358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:42.125672Z","time spent":"334.616877ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:33:42.460491Z","caller":"traceutil/trace.go:171","msg":"trace[889057492] transaction","detail":"{read_only:false; response_revision:25647; number_of_response:1; }","duration":"184.278201ms","start":"2026-07-01T11:33:42.2762Z","end":"2026-07-01T11:33:42.460479Z","steps":["trace[889057492] 'process raft request' (duration: 184.073156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:42.460522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.048511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T11:33:42.460544Z","caller":"traceutil/trace.go:171","msg":"trace[1618925875] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:25647; }","duration":"304.111593ms","start":"2026-07-01T11:33:42.156427Z","end":"2026-07-01T11:33:42.460538Z","steps":["trace[1618925875] 'agreement among raft nodes before linearized reading' (duration: 304.031801ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:42.460564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:42.156409Z","time spent":"304.149295ms","remote":"127.0.0.1:34968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":14,"response size":32,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-07-01T11:33:42.460598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.562986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:33:42.460663Z","caller":"traceutil/trace.go:171","msg":"trace[746437942] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:25647; }","duration":"109.68561ms","start":"2026-07-01T11:33:42.350959Z","end":"2026-07-01T11:33:42.460645Z","steps":["trace[746437942] 'agreement among raft nodes before linearized reading' (duration: 109.560847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:42.460677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.706962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T11:33:42.460712Z","caller":"traceutil/trace.go:171","msg":"trace[78559815] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:25647; }","duration":"293.814135ms","start":"2026-07-01T11:33:42.166889Z","end":"2026-07-01T11:33:42.460703Z","steps":["trace[78559815] 'agreement among raft nodes before linearized reading' (duration: 293.696341ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:42.770045Z","caller":"traceutil/trace.go:171","msg":"trace[997896691] transaction","detail":"{read_only:false; response_revision:25648; number_of_response:1; }","duration":"325.989917ms","start":"2026-07-01T11:33:42.444033Z","end":"2026-07-01T11:33:42.770023Z","steps":["trace[997896691] 'process raft request' (duration: 325.837433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:42.770168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:42.444018Z","time spent":"326.0912ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:33:42.77026Z","caller":"traceutil/trace.go:171","msg":"trace[1986301383] linearizableReadLoop","detail":"{readStateIndex:26499; appliedIndex:26497; }","duration":"298.888292ms","start":"2026-07-01T11:33:42.471334Z","end":"2026-07-01T11:33:42.770223Z","steps":["trace[1986301383] 'read index received' (duration: 298.513921ms)","trace[1986301383] 'applied index is now lower than readState.Index' (duration: 371.321µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:33:42.770332Z","caller":"traceutil/trace.go:171","msg":"trace[1422519948] transaction","detail":"{read_only:false; response_revision:25649; number_of_response:1; }","duration":"317.777158ms","start":"2026-07-01T11:33:42.452546Z","end":"2026-07-01T11:33:42.770323Z","steps":["trace[1422519948] 'process raft request' (duration: 317.436858ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:42.770371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:42.452532Z","time spent":"317.817649ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:33:42.770443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.090047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:33:42.77053Z","caller":"traceutil/trace.go:171","msg":"trace[727634056] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:25649; }","duration":"299.216031ms","start":"2026-07-01T11:33:42.471294Z","end":"2026-07-01T11:33:42.77051Z","steps":["trace[727634056] 'agreement among raft nodes before linearized reading' (duration: 299.067847ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:43.574628Z","caller":"traceutil/trace.go:171","msg":"trace[568820347] linearizableReadLoop","detail":"{readStateIndex:26500; appliedIndex:26499; }","duration":"111.725647ms","start":"2026-07-01T11:33:43.46287Z","end":"2026-07-01T11:33:43.574596Z","steps":["trace[568820347] 'read index received' (duration: 111.534952ms)","trace[568820347] 'applied index is now lower than readState.Index' (duration: 189.775µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:33:43.574821Z","caller":"traceutil/trace.go:171","msg":"trace[189907347] transaction","detail":"{read_only:false; response_revision:25650; number_of_response:1; }","duration":"127.788143ms","start":"2026-07-01T11:33:43.446993Z","end":"2026-07-01T11:33:43.574781Z","steps":["trace[189907347] 'process raft request' (duration: 127.458893ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:43.574888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.994215ms","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-07-01T11:33:43.574929Z","caller":"traceutil/trace.go:171","msg":"trace[2122113669] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:25650; }","duration":"112.089218ms","start":"2026-07-01T11:33:43.462825Z","end":"2026-07-01T11:33:43.574915Z","steps":["trace[2122113669] 'agreement among raft nodes before linearized reading' (duration: 111.987695ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:43.901536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.714792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-01T11:33:43.901844Z","caller":"traceutil/trace.go:171","msg":"trace[1435467818] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25650; }","duration":"264.061351ms","start":"2026-07-01T11:33:43.637755Z","end":"2026-07-01T11:33:43.901817Z","steps":["trace[1435467818] 'range keys from in-memory index tree' (duration: 263.525205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:43.901552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.615475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:33:43.902275Z","caller":"traceutil/trace.go:171","msg":"trace[1274050114] transaction","detail":"{read_only:false; response_revision:25651; number_of_response:1; }","duration":"221.805546ms","start":"2026-07-01T11:33:43.680427Z","end":"2026-07-01T11:33:43.902233Z","steps":["trace[1274050114] 'process raft request' (duration: 90.411999ms)","trace[1274050114] 'compare' (duration: 130.308906ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:33:43.927571Z","caller":"traceutil/trace.go:171","msg":"trace[932119377] linearizableReadLoop","detail":"{readStateIndex:26502; appliedIndex:26500; }","duration":"201.398136ms","start":"2026-07-01T11:33:43.726143Z","end":"2026-07-01T11:33:43.927541Z","steps":["trace[932119377] 'read index received' (duration: 44.595442ms)","trace[932119377] 'applied index is now lower than readState.Index' (duration: 156.801514ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:33:43.927738Z","caller":"traceutil/trace.go:171","msg":"trace[1949592918] transaction","detail":"{read_only:false; response_revision:25652; number_of_response:1; }","duration":"246.702238ms","start":"2026-07-01T11:33:43.680991Z","end":"2026-07-01T11:33:43.927694Z","steps":["trace[1949592918] 'process raft request' (duration: 246.394599ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:43.927788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.616632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:33:43.927952Z","caller":"traceutil/trace.go:171","msg":"trace[1357566256] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:25652; }","duration":"201.837448ms","start":"2026-07-01T11:33:43.7261Z","end":"2026-07-01T11:33:43.927938Z","steps":["trace[1357566256] 'agreement among raft nodes before linearized reading' (duration: 201.596271ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:44.088368Z","caller":"traceutil/trace.go:171","msg":"trace[1262017587] linearizableReadLoop","detail":"{readStateIndex:26503; appliedIndex:26502; }","duration":"160.680437ms","start":"2026-07-01T11:33:43.927661Z","end":"2026-07-01T11:33:44.088341Z","steps":["trace[1262017587] 'read index received' (duration: 152.584062ms)","trace[1262017587] 'applied index is now lower than readState.Index' (duration: 8.095245ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:33:44.08852Z","caller":"traceutil/trace.go:171","msg":"trace[1097732244] transaction","detail":"{read_only:false; response_revision:25653; number_of_response:1; }","duration":"182.656493ms","start":"2026-07-01T11:33:43.905853Z","end":"2026-07-01T11:33:44.088509Z","steps":["trace[1097732244] 'process raft request' (duration: 174.468225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:44.088707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.507014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-01T11:33:44.088796Z","caller":"traceutil/trace.go:171","msg":"trace[161939934] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25653; }","duration":"148.634958ms","start":"2026-07-01T11:33:43.940141Z","end":"2026-07-01T11:33:44.088776Z","steps":["trace[161939934] 'agreement among raft nodes before linearized reading' (duration: 148.416441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:44.088952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.741295ms","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-07-01T11:33:44.088998Z","caller":"traceutil/trace.go:171","msg":"trace[1752188188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25653; }","duration":"310.831228ms","start":"2026-07-01T11:33:43.778153Z","end":"2026-07-01T11:33:44.088984Z","steps":["trace[1752188188] 'agreement among raft nodes before linearized reading' (duration: 310.684943ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:44.089031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:43.778135Z","time spent":"310.88868ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-01T11:33:44.454815Z","caller":"traceutil/trace.go:171","msg":"trace[126468725] transaction","detail":"{read_only:false; response_revision:25655; number_of_response:1; }","duration":"324.871743ms","start":"2026-07-01T11:33:44.129917Z","end":"2026-07-01T11:33:44.454789Z","steps":["trace[126468725] 'process raft request' (duration: 283.118214ms)","trace[126468725] 'compare' (duration: 41.340207ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:33:44.454947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:44.129895Z","time spent":"324.982017ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:33:44.46665Z","caller":"traceutil/trace.go:171","msg":"trace[984894077] linearizableReadLoop","detail":"{readStateIndex:26506; appliedIndex:26504; }","duration":"307.536962ms","start":"2026-07-01T11:33:44.159093Z","end":"2026-07-01T11:33:44.46663Z","steps":["trace[984894077] 'read index received' (duration: 254.107653ms)","trace[984894077] 'applied index is now lower than readState.Index' (duration: 53.428239ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:33:44.466824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.712648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:33:44.466816Z","caller":"traceutil/trace.go:171","msg":"trace[1045685896] transaction","detail":"{read_only:false; response_revision:25656; number_of_response:1; }","duration":"336.078328ms","start":"2026-07-01T11:33:44.130705Z","end":"2026-07-01T11:33:44.466783Z","steps":["trace[1045685896] 'process raft request' (duration: 335.815011ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:44.466893Z","caller":"traceutil/trace.go:171","msg":"trace[119284965] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:25656; }","duration":"307.829021ms","start":"2026-07-01T11:33:44.159046Z","end":"2026-07-01T11:33:44.466875Z","steps":["trace[119284965] 'agreement among raft nodes before linearized reading' (duration: 307.690877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:44.466986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:44.130677Z","time spent":"336.222993ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:33:44.466939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:44.15903Z","time spent":"307.894902ms","remote":"127.0.0.1:36036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true "} {"level":"info","ts":"2026-07-01T11:33:44.819527Z","caller":"traceutil/trace.go:171","msg":"trace[1274788024] transaction","detail":"{read_only:false; response_revision:25657; number_of_response:1; }","duration":"374.612145ms","start":"2026-07-01T11:33:44.444888Z","end":"2026-07-01T11:33:44.8195Z","steps":["trace[1274788024] 'process raft request' (duration: 353.099512ms)","trace[1274788024] 'compare' (duration: 21.115612ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:33:44.819656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:44.444865Z","time spent":"374.729209ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:33:44.819824Z","caller":"traceutil/trace.go:171","msg":"trace[44218629] transaction","detail":"{read_only:false; response_revision:25658; number_of_response:1; }","duration":"374.77414ms","start":"2026-07-01T11:33:44.445033Z","end":"2026-07-01T11:33:44.819807Z","steps":["trace[44218629] 'process raft request' (duration: 374.342998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:44.819868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:44.445023Z","time spent":"374.820622ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:33:44.820054Z","caller":"traceutil/trace.go:171","msg":"trace[99963954] linearizableReadLoop","detail":"{readStateIndex:26510; appliedIndex:26506; }","duration":"353.330029ms","start":"2026-07-01T11:33:44.466716Z","end":"2026-07-01T11:33:44.820046Z","steps":["trace[99963954] 'read index received' (duration: 331.28119ms)","trace[99963954] 'applied index is now lower than readState.Index' (duration: 22.048199ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:33:44.820026Z","caller":"traceutil/trace.go:171","msg":"trace[926676126] transaction","detail":"{read_only:false; response_revision:25659; number_of_response:1; }","duration":"374.274107ms","start":"2026-07-01T11:33:44.445725Z","end":"2026-07-01T11:33:44.819999Z","steps":["trace[926676126] 'process raft request' (duration: 374.103012ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:44.820214Z","caller":"traceutil/trace.go:171","msg":"trace[1689409762] transaction","detail":"{read_only:false; response_revision:25660; number_of_response:1; }","duration":"372.452553ms","start":"2026-07-01T11:33:44.447743Z","end":"2026-07-01T11:33:44.820196Z","steps":["trace[1689409762] 'process raft request' (duration: 372.217556ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:44.820225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.292255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-01T11:33:44.820226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.339858ms","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":"warn","ts":"2026-07-01T11:33:44.820296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:44.44772Z","time spent":"372.533075ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:33:44.820311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.984412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-01T11:33:44.8203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.057518ms","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-07-01T11:33:44.820327Z","caller":"traceutil/trace.go:171","msg":"trace[165213588] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:25660; }","duration":"511.488002ms","start":"2026-07-01T11:33:44.308819Z","end":"2026-07-01T11:33:44.820307Z","steps":["trace[165213588] 'agreement among raft nodes before linearized reading' (duration: 511.330688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:44.820228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:44.445697Z","time spent":"374.393699ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:33:44.82039Z","caller":"traceutil/trace.go:171","msg":"trace[1081973360] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25660; }","duration":"356.208752ms","start":"2026-07-01T11:33:44.464155Z","end":"2026-07-01T11:33:44.820364Z","steps":["trace[1081973360] 'agreement among raft nodes before linearized reading' (duration: 355.921564ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:44.820395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:44.308793Z","time spent":"511.586955ms","remote":"127.0.0.1:49566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":32,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-07-01T11:33:44.820319Z","caller":"traceutil/trace.go:171","msg":"trace[374353297] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:25660; }","duration":"551.425509ms","start":"2026-07-01T11:33:44.268865Z","end":"2026-07-01T11:33:44.820291Z","steps":["trace[374353297] 'agreement among raft nodes before linearized reading' (duration: 551.280515ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:44.820341Z","caller":"traceutil/trace.go:171","msg":"trace[1949473167] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25660; }","duration":"653.016563ms","start":"2026-07-01T11:33:44.167317Z","end":"2026-07-01T11:33:44.820334Z","steps":["trace[1949473167] 'agreement among raft nodes before linearized reading' (duration: 652.942591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:44.820437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:44.464139Z","time spent":"356.288794ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-01T11:33:44.820467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:44.268847Z","time spent":"551.607545ms","remote":"127.0.0.1:41856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":2,"response size":32,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true "} {"level":"warn","ts":"2026-07-01T11:33:44.820475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:44.167301Z","time spent":"653.162307ms","remote":"127.0.0.1:35278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":30,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-07-01T11:33:45.124559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.888188ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:33:45.124696Z","caller":"traceutil/trace.go:171","msg":"trace[838172806] transaction","detail":"{read_only:false; response_revision:25663; number_of_response:1; }","duration":"300.631873ms","start":"2026-07-01T11:33:44.824052Z","end":"2026-07-01T11:33:45.124684Z","steps":["trace[838172806] 'process raft request' (duration: 300.610552ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:45.124742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:44.824031Z","time spent":"300.693794ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:33:45.124853Z","caller":"traceutil/trace.go:171","msg":"trace[307943071] transaction","detail":"{read_only:false; response_revision:25662; number_of_response:1; }","duration":"349.397584ms","start":"2026-07-01T11:33:44.77545Z","end":"2026-07-01T11:33:45.124848Z","steps":["trace[307943071] 'process raft request' (duration: 349.174568ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:45.124885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:44.775443Z","time spent":"349.428386ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:33:45.12486Z","caller":"traceutil/trace.go:171","msg":"trace[227322135] transaction","detail":"{read_only:false; response_revision:25661; number_of_response:1; }","duration":"349.702724ms","start":"2026-07-01T11:33:44.775132Z","end":"2026-07-01T11:33:45.124835Z","steps":["trace[227322135] 'process raft request' (duration: 145.469366ms)","trace[227322135] 'compare' (duration: 203.498366ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:33:45.12498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:44.77511Z","time spent":"349.810366ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:33:45.124904Z","caller":"traceutil/trace.go:171","msg":"trace[1086829133] linearizableReadLoop","detail":"{readStateIndex:26512; appliedIndex:26510; }","duration":"304.636248ms","start":"2026-07-01T11:33:44.820071Z","end":"2026-07-01T11:33:45.124874Z","steps":["trace[1086829133] 'read index received' (duration: 100.392839ms)","trace[1086829133] 'applied index is now lower than readState.Index' (duration: 204.239779ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:33:45.125274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.080373ms","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-07-01T11:33:45.125367Z","caller":"traceutil/trace.go:171","msg":"trace[1547219241] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25663; }","duration":"351.26399ms","start":"2026-07-01T11:33:44.77408Z","end":"2026-07-01T11:33:45.125344Z","steps":["trace[1547219241] 'agreement among raft nodes before linearized reading' (duration: 351.011962ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:45.125435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:44.774066Z","time spent":"351.353602ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-01T11:33:45.125277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.615866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:33:45.125535Z","caller":"traceutil/trace.go:171","msg":"trace[1182503627] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:25663; }","duration":"560.928024ms","start":"2026-07-01T11:33:44.564566Z","end":"2026-07-01T11:33:45.125494Z","steps":["trace[1182503627] 'agreement among raft nodes before linearized reading' (duration: 560.612205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:45.125607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.642181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-01T11:33:45.125622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:44.56454Z","time spent":"561.058888ms","remote":"127.0.0.1:49462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-01T11:33:45.125656Z","caller":"traceutil/trace.go:171","msg":"trace[447914592] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25663; }","duration":"301.725043ms","start":"2026-07-01T11:33:44.823918Z","end":"2026-07-01T11:33:45.125643Z","steps":["trace[447914592] 'agreement among raft nodes before linearized reading' (duration: 301.641661ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:45.125703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:44.823908Z","time spent":"301.786876ms","remote":"127.0.0.1:35022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-07-01T11:33:45.126048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.591016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:33:45.126125Z","caller":"traceutil/trace.go:171","msg":"trace[2034588972] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:25663; }","duration":"304.740501ms","start":"2026-07-01T11:33:44.821372Z","end":"2026-07-01T11:33:45.126113Z","steps":["trace[2034588972] 'agreement among raft nodes before linearized reading' (duration: 304.142033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:45.126213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:44.821357Z","time spent":"304.843204ms","remote":"127.0.0.1:59738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":30,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"info","ts":"2026-07-01T11:33:45.489652Z","caller":"traceutil/trace.go:171","msg":"trace[1270413258] linearizableReadLoop","detail":"{readStateIndex:26515; appliedIndex:26513; }","duration":"350.516197ms","start":"2026-07-01T11:33:45.139108Z","end":"2026-07-01T11:33:45.489624Z","steps":["trace[1270413258] 'read index received' (duration: 46.444505ms)","trace[1270413258] 'applied index is now lower than readState.Index' (duration: 304.070092ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:33:45.489739Z","caller":"traceutil/trace.go:171","msg":"trace[2116246090] transaction","detail":"{read_only:false; response_revision:25665; number_of_response:1; }","duration":"359.287082ms","start":"2026-07-01T11:33:45.130407Z","end":"2026-07-01T11:33:45.489694Z","steps":["trace[2116246090] 'process raft request' (duration: 292.98324ms)","trace[2116246090] 'compare' (duration: 66.057915ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:33:45.489915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:45.130395Z","time spent":"359.423005ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:33:45.489973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.843886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-07-01T11:33:45.490051Z","caller":"traceutil/trace.go:171","msg":"trace[1317911623] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25665; }","duration":"350.969929ms","start":"2026-07-01T11:33:45.139062Z","end":"2026-07-01T11:33:45.490032Z","steps":["trace[1317911623] 'agreement among raft nodes before linearized reading' (duration: 350.696662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:45.490092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:45.139046Z","time spent":"351.037882ms","remote":"127.0.0.1:59848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5676,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-01T11:33:45.968858Z","caller":"traceutil/trace.go:171","msg":"trace[429650763] transaction","detail":"{read_only:false; response_revision:25666; number_of_response:1; }","duration":"388.739975ms","start":"2026-07-01T11:33:45.580092Z","end":"2026-07-01T11:33:45.968832Z","steps":["trace[429650763] 'process raft request' (duration: 388.599921ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:45.969009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:45.580035Z","time spent":"388.89821ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:33:46.522361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.008171ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-07-01T11:33:46.523092Z","caller":"traceutil/trace.go:171","msg":"trace[1517619198] transaction","detail":"{read_only:false; response_revision:25667; number_of_response:1; }","duration":"615.862207ms","start":"2026-07-01T11:33:45.907212Z","end":"2026-07-01T11:33:46.523075Z","steps":["trace[1517619198] 'process raft request' (duration: 615.237949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:46.523177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:45.907187Z","time spent":"615.935268ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:33:46.974497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.341989ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:33:46.974704Z","caller":"traceutil/trace.go:171","msg":"trace[806541671] linearizableReadLoop","detail":"{readStateIndex:26519; appliedIndex:26516; }","duration":"865.277494ms","start":"2026-07-01T11:33:46.109407Z","end":"2026-07-01T11:33:46.974685Z","steps":["trace[806541671] 'read index received' (duration: 48.692µs)","trace[806541671] 'applied index is now lower than readState.Index' (duration: 865.227102ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:33:46.974783Z","caller":"traceutil/trace.go:171","msg":"trace[2077968239] transaction","detail":"{read_only:false; response_revision:25668; number_of_response:1; }","duration":"1.041273573s","start":"2026-07-01T11:33:45.93344Z","end":"2026-07-01T11:33:46.974714Z","steps":["trace[2077968239] 'process raft request' (duration: 589.626866ms)","trace[2077968239] 'compare' (duration: 450.836864ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:33:46.974919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.478109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:33:46.974978Z","caller":"traceutil/trace.go:171","msg":"trace[124657308] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:25668; }","duration":"865.614973ms","start":"2026-07-01T11:33:46.10935Z","end":"2026-07-01T11:33:46.974965Z","steps":["trace[124657308] 'agreement among raft nodes before linearized reading' (duration: 865.483989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:46.97501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:45.933414Z","time spent":"1.041475329s","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:33:46.975049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:46.109329Z","time spent":"865.706066ms","remote":"127.0.0.1:49452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-01T11:33:46.978961Z","caller":"traceutil/trace.go:171","msg":"trace[610622548] transaction","detail":"{read_only:false; response_revision:25669; number_of_response:1; }","duration":"155.204938ms","start":"2026-07-01T11:33:46.823733Z","end":"2026-07-01T11:33:46.978938Z","steps":["trace[610622548] 'process raft request' (duration: 155.105914ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:47.297865Z","caller":"traceutil/trace.go:171","msg":"trace[1176836624] transaction","detail":"{read_only:false; response_revision:25673; number_of_response:1; }","duration":"167.799233ms","start":"2026-07-01T11:33:47.130034Z","end":"2026-07-01T11:33:47.297833Z","steps":["trace[1176836624] 'process raft request' (duration: 167.728191ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:47.297934Z","caller":"traceutil/trace.go:171","msg":"trace[2100897993] transaction","detail":"{read_only:false; response_revision:25672; number_of_response:1; }","duration":"472.348116ms","start":"2026-07-01T11:33:46.825552Z","end":"2026-07-01T11:33:47.2979Z","steps":["trace[2100897993] 'process raft request' (duration: 472.158591ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:47.297977Z","caller":"traceutil/trace.go:171","msg":"trace[1412253364] linearizableReadLoop","detail":"{readStateIndex:26523; appliedIndex:26519; }","duration":"323.126423ms","start":"2026-07-01T11:33:46.974803Z","end":"2026-07-01T11:33:47.297929Z","steps":["trace[1412253364] 'read index received' (duration: 4.09332ms)","trace[1412253364] 'applied index is now lower than readState.Index' (duration: 319.029403ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:33:47.298087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:46.825531Z","time spent":"472.482111ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:33:47.298111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.100514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:33:47.298155Z","caller":"traceutil/trace.go:171","msg":"trace[263336569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25673; }","duration":"811.292558ms","start":"2026-07-01T11:33:46.48685Z","end":"2026-07-01T11:33:47.298143Z","steps":["trace[263336569] 'agreement among raft nodes before linearized reading' (duration: 811.093853ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:47.298201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:46.48684Z","time spent":"811.341441ms","remote":"127.0.0.1:34832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-01T11:33:47.298243Z","caller":"traceutil/trace.go:171","msg":"trace[1827485460] transaction","detail":"{read_only:false; response_revision:25671; number_of_response:1; }","duration":"473.259994ms","start":"2026-07-01T11:33:46.824969Z","end":"2026-07-01T11:33:47.298229Z","steps":["trace[1827485460] 'process raft request' (duration: 472.641985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:47.298301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:46.82496Z","time spent":"473.306835ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:33:47.298384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.016023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418324"} {"level":"info","ts":"2026-07-01T11:33:47.298429Z","caller":"traceutil/trace.go:171","msg":"trace[340276543] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:25673; }","duration":"908.124976ms","start":"2026-07-01T11:33:46.390291Z","end":"2026-07-01T11:33:47.298416Z","steps":["trace[340276543] 'agreement among raft nodes before linearized reading' (duration: 907.819827ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:47.29842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.33152ms","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":"warn","ts":"2026-07-01T11:33:47.298463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:46.390274Z","time spent":"908.181427ms","remote":"127.0.0.1:41804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":418348,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"info","ts":"2026-07-01T11:33:47.298471Z","caller":"traceutil/trace.go:171","msg":"trace[1684445394] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25673; }","duration":"705.397021ms","start":"2026-07-01T11:33:46.593059Z","end":"2026-07-01T11:33:47.298456Z","steps":["trace[1684445394] 'agreement among raft nodes before linearized reading' (duration: 705.302519ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:47.298505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:46.593002Z","time spent":"705.494214ms","remote":"127.0.0.1:35830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true "} {"level":"warn","ts":"2026-07-01T11:33:47.29859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.664984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-01T11:33:47.298594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.877618ms","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-07-01T11:33:47.298658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.417256ms","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-07-01T11:33:47.298692Z","caller":"traceutil/trace.go:171","msg":"trace[856439176] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25673; }","duration":"828.472878ms","start":"2026-07-01T11:33:46.47021Z","end":"2026-07-01T11:33:47.298683Z","steps":["trace[856439176] 'agreement among raft nodes before linearized reading' (duration: 828.330194ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:47.298686Z","caller":"traceutil/trace.go:171","msg":"trace[603111741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25673; }","duration":"840.022582ms","start":"2026-07-01T11:33:46.458642Z","end":"2026-07-01T11:33:47.298664Z","steps":["trace[603111741] 'agreement among raft nodes before linearized reading' (duration: 839.332232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:47.298707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.188166279s","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-07-01T11:33:47.298719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:46.4702Z","time spent":"828.512759ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-01T11:33:47.298735Z","caller":"traceutil/trace.go:171","msg":"trace[1452553509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25673; }","duration":"1.188212301s","start":"2026-07-01T11:33:46.110516Z","end":"2026-07-01T11:33:47.298728Z","steps":["trace[1452553509] 'agreement among raft nodes before linearized reading' (duration: 1.188133909s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:47.298393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.461372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-01T11:33:47.298771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:46.458625Z","time spent":"840.107974ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-01T11:33:47.298786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:46.110506Z","time spent":"1.188273103s","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-01T11:33:47.298798Z","caller":"traceutil/trace.go:171","msg":"trace[299175420] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:25673; }","duration":"598.866174ms","start":"2026-07-01T11:33:46.699919Z","end":"2026-07-01T11:33:47.298786Z","steps":["trace[299175420] 'agreement among raft nodes before linearized reading' (duration: 598.435331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:47.29884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:46.699872Z","time spent":"598.952486ms","remote":"127.0.0.1:35856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true "} {"level":"info","ts":"2026-07-01T11:33:47.298633Z","caller":"traceutil/trace.go:171","msg":"trace[867321204] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:25673; }","duration":"172.751776ms","start":"2026-07-01T11:33:47.12587Z","end":"2026-07-01T11:33:47.298622Z","steps":["trace[867321204] 'agreement among raft nodes before linearized reading' (duration: 172.682434ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:47.298908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.846504ms","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-07-01T11:33:47.298937Z","caller":"traceutil/trace.go:171","msg":"trace[1715933253] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25673; }","duration":"108.915496ms","start":"2026-07-01T11:33:47.190013Z","end":"2026-07-01T11:33:47.298928Z","steps":["trace[1715933253] 'agreement among raft nodes before linearized reading' (duration: 108.836024ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:47.299164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.917758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-07-01T11:33:47.299153Z","caller":"traceutil/trace.go:171","msg":"trace[1635502753] transaction","detail":"{read_only:false; response_revision:25670; number_of_response:1; }","duration":"473.864891ms","start":"2026-07-01T11:33:46.824029Z","end":"2026-07-01T11:33:47.297894Z","steps":["trace[1635502753] 'process raft request' (duration: 443.284155ms)","trace[1635502753] 'compare' (duration: 30.059531ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:33:47.299208Z","caller":"traceutil/trace.go:171","msg":"trace[1736389403] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:25673; }","duration":"155.988931ms","start":"2026-07-01T11:33:47.143208Z","end":"2026-07-01T11:33:47.299197Z","steps":["trace[1736389403] 'agreement among raft nodes before linearized reading' (duration: 155.890188ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:47.299249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.302427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-07-01T11:33:47.29928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:46.824022Z","time spent":"475.19015ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:33:47.299328Z","caller":"traceutil/trace.go:171","msg":"trace[1135495930] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25673; }","duration":"169.43683ms","start":"2026-07-01T11:33:47.12988Z","end":"2026-07-01T11:33:47.299317Z","steps":["trace[1135495930] 'agreement among raft nodes before linearized reading' (duration: 169.308436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:47.667355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.45378ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:33:47.667442Z","caller":"traceutil/trace.go:171","msg":"trace[1692962897] transaction","detail":"{read_only:false; response_revision:25674; number_of_response:1; }","duration":"364.455951ms","start":"2026-07-01T11:33:47.302971Z","end":"2026-07-01T11:33:47.667427Z","steps":["trace[1692962897] 'process raft request' (duration: 174.863867ms)","trace[1692962897] 'compare' (duration: 189.289725ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:33:47.667489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:47.30295Z","time spent":"364.515253ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:33:48.030375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.971862ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:33:48.030657Z","caller":"traceutil/trace.go:171","msg":"trace[854653539] linearizableReadLoop","detail":"{readStateIndex:26530; appliedIndex:26525; }","duration":"540.816101ms","start":"2026-07-01T11:33:47.489828Z","end":"2026-07-01T11:33:48.030644Z","steps":["trace[854653539] 'read index received' (duration: 298.590842ms)","trace[854653539] 'applied index is now lower than readState.Index' (duration: 242.224159ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:33:48.03066Z","caller":"traceutil/trace.go:171","msg":"trace[1163331835] transaction","detail":"{read_only:false; response_revision:25675; number_of_response:1; }","duration":"727.653206ms","start":"2026-07-01T11:33:47.302971Z","end":"2026-07-01T11:33:48.030625Z","steps":["trace[1163331835] 'process raft request' (duration: 485.349054ms)","trace[1163331835] 'compare' (duration: 241.852049ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:33:48.03077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.940535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:33:48.030803Z","caller":"traceutil/trace.go:171","msg":"trace[989236144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25679; }","duration":"541.017668ms","start":"2026-07-01T11:33:47.489777Z","end":"2026-07-01T11:33:48.030795Z","steps":["trace[989236144] 'agreement among raft nodes before linearized reading' (duration: 540.917265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:48.030832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:47.489754Z","time spent":"541.071439ms","remote":"127.0.0.1:34832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T11:33:48.030854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:47.302952Z","time spent":"727.761059ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:33:48.031055Z","caller":"traceutil/trace.go:171","msg":"trace[491464329] transaction","detail":"{read_only:false; response_revision:25678; number_of_response:1; }","duration":"724.555107ms","start":"2026-07-01T11:33:47.306489Z","end":"2026-07-01T11:33:48.031044Z","steps":["trace[491464329] 'process raft request' (duration: 724.080113ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:48.031117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:47.30648Z","time spent":"724.605198ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:33:48.031167Z","caller":"traceutil/trace.go:171","msg":"trace[1595888471] transaction","detail":"{read_only:false; response_revision:25679; number_of_response:1; }","duration":"585.430175ms","start":"2026-07-01T11:33:47.445716Z","end":"2026-07-01T11:33:48.031146Z","steps":["trace[1595888471] 'process raft request' (duration: 584.884899ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:48.031232Z","caller":"traceutil/trace.go:171","msg":"trace[1259422771] transaction","detail":"{read_only:false; response_revision:25676; number_of_response:1; }","duration":"728.1144ms","start":"2026-07-01T11:33:47.303071Z","end":"2026-07-01T11:33:48.031185Z","steps":["trace[1259422771] 'process raft request' (duration: 727.40464ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:48.031316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:47.445687Z","time spent":"585.549848ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:33:48.031448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:47.303047Z","time spent":"728.288164ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:33:48.030973Z","caller":"traceutil/trace.go:171","msg":"trace[1596360045] transaction","detail":"{read_only:false; response_revision:25677; number_of_response:1; }","duration":"726.77532ms","start":"2026-07-01T11:33:47.304162Z","end":"2026-07-01T11:33:48.030937Z","steps":["trace[1596360045] 'process raft request' (duration: 726.373208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:48.031727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:47.304144Z","time spent":"727.543412ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:33:48.04882Z","caller":"traceutil/trace.go:171","msg":"trace[1681200020] transaction","detail":"{read_only:false; response_revision:25680; number_of_response:1; }","duration":"323.978748ms","start":"2026-07-01T11:33:47.724812Z","end":"2026-07-01T11:33:48.048791Z","steps":["trace[1681200020] 'process raft request' (duration: 323.628728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:48.049001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:47.724776Z","time spent":"324.128292ms","remote":"127.0.0.1:41804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418382,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:33:48.094054Z","caller":"traceutil/trace.go:171","msg":"trace[550364953] transaction","detail":"{read_only:false; response_revision:25681; number_of_response:1; }","duration":"119.481743ms","start":"2026-07-01T11:33:47.974539Z","end":"2026-07-01T11:33:48.09402Z","steps":["trace[550364953] 'process raft request' (duration: 119.301798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:48.433652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.570894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-messaging-topology-operator\" ","response":"range_response_count:1 size:1115"} {"level":"warn","ts":"2026-07-01T11:33:48.433702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.745342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:33:48.433751Z","caller":"traceutil/trace.go:171","msg":"trace[157734611] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-messaging-topology-operator; range_end:; response_count:1; response_revision:25681; }","duration":"178.729379ms","start":"2026-07-01T11:33:48.255001Z","end":"2026-07-01T11:33:48.43373Z","steps":["trace[157734611] 'range keys from in-memory index tree' (duration: 178.326347ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:48.433785Z","caller":"traceutil/trace.go:171","msg":"trace[337957526] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:25681; }","duration":"264.871506ms","start":"2026-07-01T11:33:48.16889Z","end":"2026-07-01T11:33:48.433762Z","steps":["trace[337957526] 'count revisions from in-memory index tree' (duration: 264.645019ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:48.884046Z","caller":"traceutil/trace.go:171","msg":"trace[2045740005] transaction","detail":"{read_only:false; response_revision:25682; number_of_response:1; }","duration":"355.896784ms","start":"2026-07-01T11:33:48.528124Z","end":"2026-07-01T11:33:48.884021Z","steps":["trace[2045740005] 'process raft request' (duration: 355.689818ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:48.884219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:48.528096Z","time spent":"356.018556ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:33:49.228681Z","caller":"traceutil/trace.go:171","msg":"trace[924580552] transaction","detail":"{read_only:false; response_revision:25683; number_of_response:1; }","duration":"247.721139ms","start":"2026-07-01T11:33:48.980937Z","end":"2026-07-01T11:33:49.228658Z","steps":["trace[924580552] 'process raft request' (duration: 203.897499ms)","trace[924580552] 'compare' (duration: 43.51431ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:33:49.228986Z","caller":"traceutil/trace.go:171","msg":"trace[1429004224] transaction","detail":"{read_only:false; response_revision:25684; number_of_response:1; }","duration":"244.565788ms","start":"2026-07-01T11:33:48.984386Z","end":"2026-07-01T11:33:49.228952Z","steps":["trace[1429004224] 'process raft request' (duration: 244.239208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:49.575076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.766062ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:33:49.575173Z","caller":"traceutil/trace.go:171","msg":"trace[988711151] transaction","detail":"{read_only:false; response_revision:25685; number_of_response:1; }","duration":"269.926801ms","start":"2026-07-01T11:33:49.30523Z","end":"2026-07-01T11:33:49.575157Z","steps":["trace[988711151] 'process raft request' (duration: 103.015005ms)","trace[988711151] 'compare' (duration: 166.604457ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:33:49.596252Z","caller":"traceutil/trace.go:171","msg":"trace[765311964] transaction","detail":"{read_only:false; response_revision:25687; number_of_response:1; }","duration":"290.336253ms","start":"2026-07-01T11:33:49.305886Z","end":"2026-07-01T11:33:49.596222Z","steps":["trace[765311964] 'process raft request' (duration: 290.20577ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:49.596281Z","caller":"traceutil/trace.go:171","msg":"trace[1930757835] linearizableReadLoop","detail":"{readStateIndex:26539; appliedIndex:26536; }","duration":"107.690971ms","start":"2026-07-01T11:33:49.488567Z","end":"2026-07-01T11:33:49.596258Z","steps":["trace[1930757835] 'read index received' (duration: 107.161136ms)","trace[1930757835] 'applied index is now lower than readState.Index' (duration: 529.095µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:33:49.5963Z","caller":"traceutil/trace.go:171","msg":"trace[458971948] transaction","detail":"{read_only:false; response_revision:25686; number_of_response:1; }","duration":"290.91797ms","start":"2026-07-01T11:33:49.305337Z","end":"2026-07-01T11:33:49.596255Z","steps":["trace[458971948] 'process raft request' (duration: 290.522159ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:49.596424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.851786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:33:49.596331Z","caller":"traceutil/trace.go:171","msg":"trace[472602967] transaction","detail":"{read_only:false; response_revision:25688; number_of_response:1; }","duration":"289.892661ms","start":"2026-07-01T11:33:49.306416Z","end":"2026-07-01T11:33:49.596309Z","steps":["trace[472602967] 'process raft request' (duration: 289.761187ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:49.596455Z","caller":"traceutil/trace.go:171","msg":"trace[853966874] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25688; }","duration":"107.895487ms","start":"2026-07-01T11:33:49.488552Z","end":"2026-07-01T11:33:49.596448Z","steps":["trace[853966874] 'agreement among raft nodes before linearized reading' (duration: 107.796404ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:49.916427Z","caller":"traceutil/trace.go:171","msg":"trace[1566566634] linearizableReadLoop","detail":"{readStateIndex:26540; appliedIndex:26539; }","duration":"127.483544ms","start":"2026-07-01T11:33:49.788923Z","end":"2026-07-01T11:33:49.916406Z","steps":["trace[1566566634] 'read index received' (duration: 93.739966ms)","trace[1566566634] 'applied index is now lower than readState.Index' (duration: 33.743098ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:33:49.916598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.611638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:33:49.916627Z","caller":"traceutil/trace.go:171","msg":"trace[556722918] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:25689; }","duration":"127.740362ms","start":"2026-07-01T11:33:49.788878Z","end":"2026-07-01T11:33:49.916619Z","steps":["trace[556722918] 'agreement among raft nodes before linearized reading' (duration: 127.607308ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:49.916599Z","caller":"traceutil/trace.go:171","msg":"trace[1957459844] transaction","detail":"{read_only:false; response_revision:25689; number_of_response:1; }","duration":"240.831479ms","start":"2026-07-01T11:33:49.675724Z","end":"2026-07-01T11:33:49.916555Z","steps":["trace[1957459844] 'process raft request' (duration: 206.994118ms)","trace[1957459844] 'compare' (duration: 33.611724ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:33:50.094219Z","caller":"traceutil/trace.go:171","msg":"trace[1842549632] transaction","detail":"{read_only:false; response_revision:25690; number_of_response:1; }","duration":"128.35294ms","start":"2026-07-01T11:33:49.965831Z","end":"2026-07-01T11:33:50.094183Z","steps":["trace[1842549632] 'process raft request' (duration: 107.440553ms)","trace[1842549632] 'compare' (duration: 20.648468ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:33:50.289071Z","caller":"traceutil/trace.go:171","msg":"trace[2012562267] transaction","detail":"{read_only:false; response_revision:25691; number_of_response:1; }","duration":"190.592763ms","start":"2026-07-01T11:33:50.098434Z","end":"2026-07-01T11:33:50.289027Z","steps":["trace[2012562267] 'process raft request' (duration: 141.44386ms)","trace[2012562267] 'compare' (duration: 48.938307ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:33:50.426424Z","caller":"traceutil/trace.go:171","msg":"trace[1719870604] transaction","detail":"{read_only:false; response_revision:25692; number_of_response:1; }","duration":"327.874471ms","start":"2026-07-01T11:33:50.098516Z","end":"2026-07-01T11:33:50.42639Z","steps":["trace[1719870604] 'process raft request' (duration: 326.885773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:50.426639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:50.098494Z","time spent":"328.019905ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:33:50.426834Z","caller":"traceutil/trace.go:171","msg":"trace[722381499] transaction","detail":"{read_only:false; response_revision:25693; number_of_response:1; }","duration":"327.262494ms","start":"2026-07-01T11:33:50.099534Z","end":"2026-07-01T11:33:50.426797Z","steps":["trace[722381499] 'process raft request' (duration: 326.808441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:50.426983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:50.099525Z","time spent":"327.381557ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:33:50.426939Z","caller":"traceutil/trace.go:171","msg":"trace[1988942531] transaction","detail":"{read_only:false; response_revision:25695; number_of_response:1; }","duration":"326.709707ms","start":"2026-07-01T11:33:50.10017Z","end":"2026-07-01T11:33:50.42688Z","steps":["trace[1988942531] 'process raft request' (duration: 326.610814ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:50.426982Z","caller":"traceutil/trace.go:171","msg":"trace[146205311] transaction","detail":"{read_only:false; response_revision:25694; number_of_response:1; }","duration":"327.444079ms","start":"2026-07-01T11:33:50.099502Z","end":"2026-07-01T11:33:50.426946Z","steps":["trace[146205311] 'process raft request' (duration: 327.035017ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:50.427134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:50.099487Z","time spent":"327.570042ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:33:50.427161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:50.100157Z","time spent":"326.879021ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:33:52.475182Z","caller":"traceutil/trace.go:171","msg":"trace[1409787108] linearizableReadLoop","detail":"{readStateIndex:26556; appliedIndex:26555; }","duration":"394.402828ms","start":"2026-07-01T11:33:52.080751Z","end":"2026-07-01T11:33:52.475154Z","steps":["trace[1409787108] 'read index received' (duration: 394.293085ms)","trace[1409787108] 'applied index is now lower than readState.Index' (duration: 108.843µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:33:52.475301Z","caller":"traceutil/trace.go:171","msg":"trace[309169876] transaction","detail":"{read_only:false; response_revision:25704; number_of_response:1; }","duration":"551.032988ms","start":"2026-07-01T11:33:51.924232Z","end":"2026-07-01T11:33:52.475265Z","steps":["trace[309169876] 'process raft request' (duration: 550.77022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:52.475431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:51.924214Z","time spent":"551.149052ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:33:52.475437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.665997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:33:52.475506Z","caller":"traceutil/trace.go:171","msg":"trace[1799430249] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:25704; }","duration":"394.803901ms","start":"2026-07-01T11:33:52.080683Z","end":"2026-07-01T11:33:52.475487Z","steps":["trace[1799430249] 'agreement among raft nodes before linearized reading' (duration: 394.609405ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:52.47557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:52.080655Z","time spent":"394.898844ms","remote":"127.0.0.1:49342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-07-01T11:33:52.520498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.232785ms","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-07-01T11:33:52.52059Z","caller":"traceutil/trace.go:171","msg":"trace[1656295160] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25705; }","duration":"227.373159ms","start":"2026-07-01T11:33:52.293195Z","end":"2026-07-01T11:33:52.520568Z","steps":["trace[1656295160] 'agreement among raft nodes before linearized reading' (duration: 227.136522ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:52.520692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.728182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:33:52.520776Z","caller":"traceutil/trace.go:171","msg":"trace[1105879284] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:25705; }","duration":"147.859565ms","start":"2026-07-01T11:33:52.372896Z","end":"2026-07-01T11:33:52.520755Z","steps":["trace[1105879284] 'agreement among raft nodes before linearized reading' (duration: 147.736992ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:58.76232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.527096ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:33:58.762459Z","caller":"traceutil/trace.go:171","msg":"trace[252980309] linearizableReadLoop","detail":"{readStateIndex:26603; appliedIndex:26602; }","duration":"180.778238ms","start":"2026-07-01T11:33:58.58165Z","end":"2026-07-01T11:33:58.762429Z","steps":["trace[252980309] 'read index received' (duration: 63.060728ms)","trace[252980309] 'applied index is now lower than readState.Index' (duration: 117.71582ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:33:58.76256Z","caller":"traceutil/trace.go:171","msg":"trace[519297392] transaction","detail":"{read_only:false; response_revision:25750; number_of_response:1; }","duration":"183.59683ms","start":"2026-07-01T11:33:58.578923Z","end":"2026-07-01T11:33:58.76252Z","steps":["trace[519297392] 'process raft request' (duration: 65.783657ms)","trace[519297392] 'compare' (duration: 117.309469ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:33:58.762642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.987974ms","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-07-01T11:33:58.762668Z","caller":"traceutil/trace.go:171","msg":"trace[1044127762] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25750; }","duration":"181.035416ms","start":"2026-07-01T11:33:58.581625Z","end":"2026-07-01T11:33:58.76266Z","steps":["trace[1044127762] 'agreement among raft nodes before linearized reading' (duration: 180.899192ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:58.763044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.286084ms","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-07-01T11:33:58.763151Z","caller":"traceutil/trace.go:171","msg":"trace[1721633452] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25750; }","duration":"181.480779ms","start":"2026-07-01T11:33:58.581647Z","end":"2026-07-01T11:33:58.763127Z","steps":["trace[1721633452] 'agreement among raft nodes before linearized reading' (duration: 181.216551ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:58.763334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.584919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-01T11:33:58.763418Z","caller":"traceutil/trace.go:171","msg":"trace[236480650] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25750; }","duration":"175.704471ms","start":"2026-07-01T11:33:58.587696Z","end":"2026-07-01T11:33:58.7634Z","steps":["trace[236480650] 'agreement among raft nodes before linearized reading' (duration: 175.420103ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:59.254789Z","caller":"traceutil/trace.go:171","msg":"trace[572859440] transaction","detail":"{read_only:false; response_revision:25752; number_of_response:1; }","duration":"487.226219ms","start":"2026-07-01T11:33:58.767536Z","end":"2026-07-01T11:33:59.254762Z","steps":["trace[572859440] 'process raft request' (duration: 486.917669ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:59.254859Z","caller":"traceutil/trace.go:171","msg":"trace[895826879] transaction","detail":"{read_only:false; response_revision:25753; number_of_response:1; }","duration":"486.833057ms","start":"2026-07-01T11:33:58.767885Z","end":"2026-07-01T11:33:59.254718Z","steps":["trace[895826879] 'process raft request' (duration: 486.738605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:59.25493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:58.767514Z","time spent":"487.348232ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:33:59.254966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:58.767862Z","time spent":"487.047904ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:33:59.443923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.282135ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:33:59.444124Z","caller":"traceutil/trace.go:171","msg":"trace[636629894] transaction","detail":"{read_only:false; response_revision:25754; number_of_response:1; }","duration":"526.96735ms","start":"2026-07-01T11:33:58.91711Z","end":"2026-07-01T11:33:59.444077Z","steps":["trace[636629894] 'process raft request' (duration: 376.456928ms)","trace[636629894] 'compare' (duration: 150.063559ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:33:59.444175Z","caller":"traceutil/trace.go:171","msg":"trace[2138450347] linearizableReadLoop","detail":"{readStateIndex:26607; appliedIndex:26604; }","duration":"434.280435ms","start":"2026-07-01T11:33:59.009856Z","end":"2026-07-01T11:33:59.444136Z","steps":["trace[2138450347] 'read index received' (duration: 244.696582ms)","trace[2138450347] 'applied index is now lower than readState.Index' (duration: 189.579353ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:33:59.444327Z","caller":"traceutil/trace.go:171","msg":"trace[1062632279] transaction","detail":"{read_only:false; response_revision:25756; number_of_response:1; }","duration":"159.981646ms","start":"2026-07-01T11:33:59.284331Z","end":"2026-07-01T11:33:59.444313Z","steps":["trace[1062632279] 'process raft request' (duration: 159.936015ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:59.444317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:58.917091Z","time spent":"527.131675ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:33:59.444397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.514291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:33:59.444432Z","caller":"traceutil/trace.go:171","msg":"trace[1334108710] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:25756; }","duration":"434.582243ms","start":"2026-07-01T11:33:59.009841Z","end":"2026-07-01T11:33:59.444423Z","steps":["trace[1334108710] 'agreement among raft nodes before linearized reading' (duration: 434.455689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:59.444456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:59.009819Z","time spent":"434.629335ms","remote":"127.0.0.1:49324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-01T11:33:59.444631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.331551ms","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-07-01T11:33:59.444657Z","caller":"traceutil/trace.go:171","msg":"trace[1241288928] 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:25756; }","duration":"361.389453ms","start":"2026-07-01T11:33:59.08326Z","end":"2026-07-01T11:33:59.44465Z","steps":["trace[1241288928] 'agreement among raft nodes before linearized reading' (duration: 361.351351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:59.444674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:59.083241Z","time spent":"361.428553ms","remote":"127.0.0.1:49552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-07-01T11:33:59.444691Z","caller":"traceutil/trace.go:171","msg":"trace[1762241408] transaction","detail":"{read_only:false; response_revision:25755; number_of_response:1; }","duration":"171.315714ms","start":"2026-07-01T11:33:59.273353Z","end":"2026-07-01T11:33:59.444669Z","steps":["trace[1762241408] 'process raft request' (duration: 170.684896ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:59.444806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.313118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T11:33:59.444831Z","caller":"traceutil/trace.go:171","msg":"trace[600649421] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:25756; }","duration":"246.37403ms","start":"2026-07-01T11:33:59.19845Z","end":"2026-07-01T11:33:59.444824Z","steps":["trace[600649421] 'agreement among raft nodes before linearized reading' (duration: 246.330678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:59.445012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.913549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-01T11:33:59.445038Z","caller":"traceutil/trace.go:171","msg":"trace[486943789] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25756; }","duration":"350.980451ms","start":"2026-07-01T11:33:59.094053Z","end":"2026-07-01T11:33:59.445033Z","steps":["trace[486943789] 'agreement among raft nodes before linearized reading' (duration: 350.881178ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:59.445054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:33:59.094035Z","time spent":"351.015603ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":556,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-07-01T11:33:59.641361Z","caller":"traceutil/trace.go:171","msg":"trace[1079755176] transaction","detail":"{read_only:false; response_revision:25757; number_of_response:1; }","duration":"190.245273ms","start":"2026-07-01T11:33:59.4511Z","end":"2026-07-01T11:33:59.641345Z","steps":["trace[1079755176] 'process raft request' (duration: 190.002985ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:59.64131Z","caller":"traceutil/trace.go:171","msg":"trace[2105488723] linearizableReadLoop","detail":"{readStateIndex:26610; appliedIndex:26609; }","duration":"153.407105ms","start":"2026-07-01T11:33:59.487878Z","end":"2026-07-01T11:33:59.641285Z","steps":["trace[2105488723] 'read index received' (duration: 153.280121ms)","trace[2105488723] 'applied index is now lower than readState.Index' (duration: 125.864µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:33:59.641566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.665843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:33:59.641861Z","caller":"traceutil/trace.go:171","msg":"trace[1519639207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25757; }","duration":"153.991493ms","start":"2026-07-01T11:33:59.487833Z","end":"2026-07-01T11:33:59.641824Z","steps":["trace[1519639207] 'agreement among raft nodes before linearized reading' (duration: 153.611502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:34:03.725013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.195329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c75c5eb5-d573-4a8a-91e6-0dc1a788bdd7\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-07-01T11:34:03.725116Z","caller":"traceutil/trace.go:171","msg":"trace[1729298863] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c75c5eb5-d573-4a8a-91e6-0dc1a788bdd7; range_end:; response_count:1; response_revision:25849; }","duration":"114.366433ms","start":"2026-07-01T11:34:03.610726Z","end":"2026-07-01T11:34:03.725093Z","steps":["trace[1729298863] 'range keys from in-memory index tree' (duration: 114.009793ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:34:03.849918Z","caller":"traceutil/trace.go:171","msg":"trace[775951925] transaction","detail":"{read_only:false; response_revision:25851; number_of_response:1; }","duration":"191.825459ms","start":"2026-07-01T11:34:03.658063Z","end":"2026-07-01T11:34:03.849889Z","steps":["trace[775951925] 'process raft request' (duration: 191.479909ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:34:03.850609Z","caller":"traceutil/trace.go:171","msg":"trace[66747121] transaction","detail":"{read_only:false; response_revision:25854; number_of_response:1; }","duration":"118.624667ms","start":"2026-07-01T11:34:03.731974Z","end":"2026-07-01T11:34:03.850599Z","steps":["trace[66747121] 'process raft request' (duration: 118.38319ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:34:03.850158Z","caller":"traceutil/trace.go:171","msg":"trace[1485827726] transaction","detail":"{read_only:false; response_revision:25852; number_of_response:1; }","duration":"191.047396ms","start":"2026-07-01T11:34:03.659099Z","end":"2026-07-01T11:34:03.850146Z","steps":["trace[1485827726] 'process raft request' (duration: 190.744278ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:34:03.850502Z","caller":"traceutil/trace.go:171","msg":"trace[172649393] transaction","detail":"{read_only:false; response_revision:25853; number_of_response:1; }","duration":"190.394597ms","start":"2026-07-01T11:34:03.660065Z","end":"2026-07-01T11:34:03.85046Z","steps":["trace[172649393] 'process raft request' (duration: 190.027807ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:34:03.850532Z","caller":"traceutil/trace.go:171","msg":"trace[939976201] linearizableReadLoop","detail":"{readStateIndex:26707; appliedIndex:26703; }","duration":"144.393194ms","start":"2026-07-01T11:34:03.706109Z","end":"2026-07-01T11:34:03.850502Z","steps":["trace[939976201] 'read index received' (duration: 26.630741ms)","trace[939976201] 'applied index is now lower than readState.Index' (duration: 117.761402ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:34:03.85079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.59094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:34:03.85111Z","caller":"traceutil/trace.go:171","msg":"trace[58512686] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:25854; }","duration":"145.025762ms","start":"2026-07-01T11:34:03.706064Z","end":"2026-07-01T11:34:03.85109Z","steps":["trace[58512686] 'agreement among raft nodes before linearized reading' (duration: 144.533768ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:34:04.667709Z","caller":"traceutil/trace.go:171","msg":"trace[1956872438] transaction","detail":"{read_only:false; response_revision:25855; number_of_response:1; }","duration":"132.291443ms","start":"2026-07-01T11:34:04.535201Z","end":"2026-07-01T11:34:04.667492Z","steps":["trace[1956872438] 'process raft request' (duration: 132.102378ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:34:04.685583Z","caller":"traceutil/trace.go:171","msg":"trace[1205663530] transaction","detail":"{read_only:false; response_revision:25856; number_of_response:1; }","duration":"122.50922ms","start":"2026-07-01T11:34:04.563046Z","end":"2026-07-01T11:34:04.685555Z","steps":["trace[1205663530] 'process raft request' (duration: 122.199832ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:34:35.258205Z","caller":"traceutil/trace.go:171","msg":"trace[38328042] linearizableReadLoop","detail":"{readStateIndex:26983; appliedIndex:26982; }","duration":"108.360321ms","start":"2026-07-01T11:34:35.149815Z","end":"2026-07-01T11:34:35.258175Z","steps":["trace[38328042] 'read index received' (duration: 108.085912ms)","trace[38328042] 'applied index is now lower than readState.Index' (duration: 273.369µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:34:35.258288Z","caller":"traceutil/trace.go:171","msg":"trace[1674357558] transaction","detail":"{read_only:false; response_revision:26123; number_of_response:1; }","duration":"119.806621ms","start":"2026-07-01T11:34:35.138435Z","end":"2026-07-01T11:34:35.258242Z","steps":["trace[1674357558] 'process raft request' (duration: 119.571104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:34:35.258357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.513895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:34:35.258401Z","caller":"traceutil/trace.go:171","msg":"trace[667070987] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:26123; }","duration":"108.617378ms","start":"2026-07-01T11:34:35.149771Z","end":"2026-07-01T11:34:35.258388Z","steps":["trace[667070987] 'agreement among raft nodes before linearized reading' (duration: 108.520975ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:34:35.755157Z","caller":"traceutil/trace.go:171","msg":"trace[632118428] transaction","detail":"{read_only:false; response_revision:26126; number_of_response:1; }","duration":"187.840003ms","start":"2026-07-01T11:34:35.567286Z","end":"2026-07-01T11:34:35.755126Z","steps":["trace[632118428] 'process raft request' (duration: 136.009411ms)","trace[632118428] 'compare' (duration: 51.693728ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:34:36.048766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.758148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:34:36.049018Z","caller":"traceutil/trace.go:171","msg":"trace[69365616] transaction","detail":"{read_only:false; response_revision:26127; number_of_response:1; }","duration":"480.787962ms","start":"2026-07-01T11:34:35.568198Z","end":"2026-07-01T11:34:36.048986Z","steps":["trace[69365616] 'process raft request' (duration: 328.675095ms)","trace[69365616] 'compare' (duration: 151.432137ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:34:36.049047Z","caller":"traceutil/trace.go:171","msg":"trace[1182453832] transaction","detail":"{read_only:false; response_revision:26128; number_of_response:1; }","duration":"480.795202ms","start":"2026-07-01T11:34:35.568232Z","end":"2026-07-01T11:34:36.049027Z","steps":["trace[1182453832] 'process raft request' (duration: 480.643998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:34:36.049137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:34:35.568213Z","time spent":"480.886345ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:34:36.049206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:34:35.568175Z","time spent":"480.921336ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:34:36.128281Z","caller":"traceutil/trace.go:171","msg":"trace[1963836403] transaction","detail":"{read_only:false; response_revision:26130; number_of_response:1; }","duration":"146.251187ms","start":"2026-07-01T11:34:35.981996Z","end":"2026-07-01T11:34:36.128247Z","steps":["trace[1963836403] 'process raft request' (duration: 146.158045ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:34:36.128345Z","caller":"traceutil/trace.go:171","msg":"trace[1410015284] transaction","detail":"{read_only:false; response_revision:26129; number_of_response:1; }","duration":"300.109456ms","start":"2026-07-01T11:34:35.828187Z","end":"2026-07-01T11:34:36.128297Z","steps":["trace[1410015284] 'process raft request' (duration: 299.685194ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:34:36.128541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:34:35.828159Z","time spent":"300.269231ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:34:36.294372Z","caller":"traceutil/trace.go:171","msg":"trace[366237727] linearizableReadLoop","detail":"{readStateIndex:26993; appliedIndex:26991; }","duration":"146.871646ms","start":"2026-07-01T11:34:36.147483Z","end":"2026-07-01T11:34:36.294354Z","steps":["trace[366237727] 'read index received' (duration: 115.440885ms)","trace[366237727] 'applied index is now lower than readState.Index' (duration: 31.429781ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:34:36.294418Z","caller":"traceutil/trace.go:171","msg":"trace[1857011628] transaction","detail":"{read_only:false; response_revision:26132; number_of_response:1; }","duration":"310.241779ms","start":"2026-07-01T11:34:35.984137Z","end":"2026-07-01T11:34:36.294378Z","steps":["trace[1857011628] 'process raft request' (duration: 310.119976ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:34:36.294376Z","caller":"traceutil/trace.go:171","msg":"trace[841328159] transaction","detail":"{read_only:false; response_revision:26131; number_of_response:1; }","duration":"310.845407ms","start":"2026-07-01T11:34:35.983493Z","end":"2026-07-01T11:34:36.294339Z","steps":["trace[841328159] 'process raft request' (duration: 279.390066ms)","trace[841328159] 'compare' (duration: 31.129342ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:34:36.294604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:34:35.98413Z","time spent":"310.387763ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:34:36.294634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:34:35.983482Z","time spent":"311.061475ms","remote":"127.0.0.1:35098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:34:36.294616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.101123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T11:34:36.294724Z","caller":"traceutil/trace.go:171","msg":"trace[2008798842] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:26132; }","duration":"147.259157ms","start":"2026-07-01T11:34:36.147439Z","end":"2026-07-01T11:34:36.294698Z","steps":["trace[2008798842] 'agreement among raft nodes before linearized reading' (duration: 147.002599ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:34:36.294869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.345189ms","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-07-01T11:34:36.29493Z","caller":"traceutil/trace.go:171","msg":"trace[1917021209] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:26132; }","duration":"128.428111ms","start":"2026-07-01T11:34:36.166476Z","end":"2026-07-01T11:34:36.294904Z","steps":["trace[1917021209] 'agreement among raft nodes before linearized reading' (duration: 128.348449ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:34:37.062803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.4124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:34:37.062958Z","caller":"traceutil/trace.go:171","msg":"trace[469146228] linearizableReadLoop","detail":"{readStateIndex:26996; appliedIndex:26995; }","duration":"169.754889ms","start":"2026-07-01T11:34:36.893179Z","end":"2026-07-01T11:34:37.062934Z","steps":["trace[469146228] 'read index received' (duration: 38.985019ms)","trace[469146228] 'applied index is now lower than readState.Index' (duration: 130.76801ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:34:37.062967Z","caller":"traceutil/trace.go:171","msg":"trace[655404304] transaction","detail":"{read_only:false; response_revision:26135; number_of_response:1; }","duration":"218.116241ms","start":"2026-07-01T11:34:36.84482Z","end":"2026-07-01T11:34:37.062937Z","steps":["trace[655404304] 'process raft request' (duration: 87.434364ms)","trace[655404304] 'compare' (duration: 130.155972ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:34:37.063119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.928065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T11:34:37.063205Z","caller":"traceutil/trace.go:171","msg":"trace[1713883797] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:26135; }","duration":"170.012927ms","start":"2026-07-01T11:34:36.893172Z","end":"2026-07-01T11:34:37.063185Z","steps":["trace[1713883797] 'agreement among raft nodes before linearized reading' (duration: 169.876273ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:34:37.592103Z","caller":"traceutil/trace.go:171","msg":"trace[775724118] linearizableReadLoop","detail":"{readStateIndex:26999; appliedIndex:26998; }","duration":"104.013594ms","start":"2026-07-01T11:34:37.488068Z","end":"2026-07-01T11:34:37.592082Z","steps":["trace[775724118] 'read index received' (duration: 103.819128ms)","trace[775724118] 'applied index is now lower than readState.Index' (duration: 193.606µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:34:37.592363Z","caller":"traceutil/trace.go:171","msg":"trace[2096516899] transaction","detail":"{read_only:false; response_revision:26138; number_of_response:1; }","duration":"145.344861ms","start":"2026-07-01T11:34:37.447007Z","end":"2026-07-01T11:34:37.592352Z","steps":["trace[2096516899] 'process raft request' (duration: 144.93653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:34:37.592526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.450507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:34:37.592556Z","caller":"traceutil/trace.go:171","msg":"trace[1381830437] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26138; }","duration":"104.533959ms","start":"2026-07-01T11:34:37.488014Z","end":"2026-07-01T11:34:37.592548Z","steps":["trace[1381830437] 'agreement among raft nodes before linearized reading' (duration: 104.458547ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:34:38.948038Z","caller":"traceutil/trace.go:171","msg":"trace[1555669546] transaction","detail":"{read_only:false; response_revision:26148; number_of_response:1; }","duration":"100.572825ms","start":"2026-07-01T11:34:38.84742Z","end":"2026-07-01T11:34:38.947993Z","steps":["trace[1555669546] 'process raft request' (duration: 100.363699ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:34:39.440768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.153427ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:34:39.440988Z","caller":"traceutil/trace.go:171","msg":"trace[74860839] transaction","detail":"{read_only:false; response_revision:26150; number_of_response:1; }","duration":"263.377292ms","start":"2026-07-01T11:34:39.177584Z","end":"2026-07-01T11:34:39.440961Z","steps":["trace[74860839] 'process raft request' (duration: 157.950937ms)","trace[74860839] 'compare' (duration: 104.370744ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:34:39.441182Z","caller":"traceutil/trace.go:171","msg":"trace[2009118942] transaction","detail":"{read_only:false; response_revision:26151; number_of_response:1; }","duration":"263.251549ms","start":"2026-07-01T11:34:39.177845Z","end":"2026-07-01T11:34:39.441097Z","steps":["trace[2009118942] 'process raft request' (duration: 263.025902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:34:39.89703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.359307ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:34:39.89734Z","caller":"traceutil/trace.go:171","msg":"trace[651905006] transaction","detail":"{read_only:false; response_revision:26153; number_of_response:1; }","duration":"244.868396ms","start":"2026-07-01T11:34:39.652447Z","end":"2026-07-01T11:34:39.897315Z","steps":["trace[651905006] 'process raft request' (duration: 105.138747ms)","trace[651905006] 'compare' (duration: 139.10849ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:34:39.994569Z","caller":"traceutil/trace.go:171","msg":"trace[827209392] transaction","detail":"{read_only:false; response_revision:26154; number_of_response:1; }","duration":"140.838991ms","start":"2026-07-01T11:34:39.853677Z","end":"2026-07-01T11:34:39.994516Z","steps":["trace[827209392] 'process raft request' (duration: 140.703827ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:34:40.252838Z","caller":"traceutil/trace.go:171","msg":"trace[1222879657] transaction","detail":"{read_only:false; response_revision:26156; number_of_response:1; }","duration":"172.606621ms","start":"2026-07-01T11:34:40.080204Z","end":"2026-07-01T11:34:40.252811Z","steps":["trace[1222879657] 'process raft request' (duration: 115.468395ms)","trace[1222879657] 'compare' (duration: 56.972751ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:34:40.500047Z","caller":"traceutil/trace.go:171","msg":"trace[1551028252] transaction","detail":"{read_only:false; response_revision:26159; number_of_response:1; }","duration":"133.954942ms","start":"2026-07-01T11:34:40.366063Z","end":"2026-07-01T11:34:40.500018Z","steps":["trace[1551028252] 'process raft request' (duration: 106.861827ms)","trace[1551028252] 'compare' (duration: 26.956081ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:34:40.502124Z","caller":"traceutil/trace.go:171","msg":"trace[689740310] transaction","detail":"{read_only:false; response_revision:26160; number_of_response:1; }","duration":"127.32479ms","start":"2026-07-01T11:34:40.374769Z","end":"2026-07-01T11:34:40.502093Z","steps":["trace[689740310] 'process raft request' (duration: 127.066502ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:34:41.365738Z","caller":"traceutil/trace.go:171","msg":"trace[108501295] transaction","detail":"{read_only:false; response_revision:26163; number_of_response:1; }","duration":"125.787365ms","start":"2026-07-01T11:34:41.239921Z","end":"2026-07-01T11:34:41.365708Z","steps":["trace[108501295] 'process raft request' (duration: 125.591519ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:34:51.889666Z","caller":"traceutil/trace.go:171","msg":"trace[1774308025] transaction","detail":"{read_only:false; response_revision:26252; number_of_response:1; }","duration":"120.268695ms","start":"2026-07-01T11:34:51.769353Z","end":"2026-07-01T11:34:51.889622Z","steps":["trace[1774308025] 'process raft request' (duration: 119.935936ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:34:57.742096Z","caller":"traceutil/trace.go:171","msg":"trace[1637977847] transaction","detail":"{read_only:false; response_revision:26293; number_of_response:1; }","duration":"215.104874ms","start":"2026-07-01T11:34:57.52695Z","end":"2026-07-01T11:34:57.742055Z","steps":["trace[1637977847] 'process raft request' (duration: 145.124546ms)","trace[1637977847] 'compare' (duration: 69.723699ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:34:57.7421Z","caller":"traceutil/trace.go:171","msg":"trace[1042252961] transaction","detail":"{read_only:false; response_revision:26294; number_of_response:1; }","duration":"214.97256ms","start":"2026-07-01T11:34:57.527105Z","end":"2026-07-01T11:34:57.742077Z","steps":["trace[1042252961] 'process raft request' (duration: 214.903888ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:35:02.487828Z","caller":"traceutil/trace.go:171","msg":"trace[38678248] transaction","detail":"{read_only:false; response_revision:26345; number_of_response:1; }","duration":"157.621318ms","start":"2026-07-01T11:35:02.330172Z","end":"2026-07-01T11:35:02.487794Z","steps":["trace[38678248] 'process raft request' (duration: 102.054298ms)","trace[38678248] 'compare' (duration: 55.278831ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:35:02.564284Z","caller":"traceutil/trace.go:171","msg":"trace[746926247] transaction","detail":"{read_only:false; response_revision:26346; number_of_response:1; }","duration":"231.370405ms","start":"2026-07-01T11:35:02.332867Z","end":"2026-07-01T11:35:02.564237Z","steps":["trace[746926247] 'process raft request' (duration: 231.036215ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:35:02.564482Z","caller":"traceutil/trace.go:171","msg":"trace[287988169] transaction","detail":"{read_only:false; response_revision:26347; number_of_response:1; }","duration":"230.021895ms","start":"2026-07-01T11:35:02.334427Z","end":"2026-07-01T11:35:02.564449Z","steps":["trace[287988169] 'process raft request' (duration: 229.626044ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:35:02.564668Z","caller":"traceutil/trace.go:171","msg":"trace[222473385] linearizableReadLoop","detail":"{readStateIndex:27214; appliedIndex:27211; }","duration":"133.725815ms","start":"2026-07-01T11:35:02.430683Z","end":"2026-07-01T11:35:02.564408Z","steps":["trace[222473385] 'read index received' (duration: 1.554555ms)","trace[222473385] 'applied index is now lower than readState.Index' (duration: 132.17028ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:35:02.564762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.058185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:35:02.564865Z","caller":"traceutil/trace.go:171","msg":"trace[351846238] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:26347; }","duration":"134.208899ms","start":"2026-07-01T11:35:02.430633Z","end":"2026-07-01T11:35:02.564842Z","steps":["trace[351846238] 'agreement among raft nodes before linearized reading' (duration: 134.053505ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:35:02.687518Z","caller":"traceutil/trace.go:171","msg":"trace[1579024426] transaction","detail":"{read_only:false; response_revision:26348; number_of_response:1; }","duration":"107.695691ms","start":"2026-07-01T11:35:02.579639Z","end":"2026-07-01T11:35:02.687335Z","steps":["trace[1579024426] 'process raft request' (duration: 73.229893ms)","trace[1579024426] 'compare' (duration: 34.354865ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:35:02.748963Z","caller":"traceutil/trace.go:171","msg":"trace[1268164156] transaction","detail":"{read_only:false; response_revision:26349; number_of_response:1; }","duration":"164.661551ms","start":"2026-07-01T11:35:02.584273Z","end":"2026-07-01T11:35:02.748934Z","steps":["trace[1268164156] 'process raft request' (duration: 164.506737ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:38:07.157342Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25263} {"level":"info","ts":"2026-07-01T11:38:07.3363Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25263,"took":"174.755922ms","hash":4079172758,"current-db-size-bytes":58564608,"current-db-size":"59 MB","current-db-size-in-use-bytes":38715392,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-07-01T11:38:07.33639Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4079172758,"revision":25263,"compact-revision":21985} {"level":"info","ts":"2026-07-01T11:39:43.175916Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d7dbc9f5893cc1f6","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-01T11:39:43.180174Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-01T11:39:43.180254Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-01T11:43:07.16406Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28229} {"level":"info","ts":"2026-07-01T11:43:07.301493Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28229,"took":"133.200828ms","hash":1629813521,"current-db-size-bytes":58564608,"current-db-size":"59 MB","current-db-size-in-use-bytes":32403456,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-01T11:43:07.301566Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1629813521,"revision":28229,"compact-revision":25263} {"level":"info","ts":"2026-07-01T11:48:07.171012Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30594} {"level":"info","ts":"2026-07-01T11:48:07.30686Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30594,"took":"131.56543ms","hash":1220085096,"current-db-size-bytes":58564608,"current-db-size":"59 MB","current-db-size-in-use-bytes":30547968,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-01T11:48:07.306956Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1220085096,"revision":30594,"compact-revision":28229} {"level":"warn","ts":"2026-07-01T11:48:21.331298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.833997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:48:21.33139Z","caller":"traceutil/trace.go:171","msg":"trace[1904489525] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:32949; }","duration":"276.976281ms","start":"2026-07-01T11:48:21.054393Z","end":"2026-07-01T11:48:21.33137Z","steps":["trace[1904489525] 'count revisions from in-memory index tree' (duration: 276.755915ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:48:21.331631Z","caller":"traceutil/trace.go:171","msg":"trace[2039927641] transaction","detail":"{read_only:false; response_revision:32950; number_of_response:1; }","duration":"276.864997ms","start":"2026-07-01T11:48:21.054737Z","end":"2026-07-01T11:48:21.331602Z","steps":["trace[2039927641] 'process raft request' (duration: 273.570992ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:53:07.180105Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32846} {"level":"info","ts":"2026-07-01T11:53:07.325781Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32846,"took":"140.962742ms","hash":3765150569,"current-db-size-bytes":59609088,"current-db-size":"60 MB","current-db-size-in-use-bytes":31092736,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-01T11:53:07.325871Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3765150569,"revision":32846,"compact-revision":30594} {"level":"info","ts":"2026-07-01T11:54:56.051214Z","caller":"traceutil/trace.go:171","msg":"trace[499397543] linearizableReadLoop","detail":"{readStateIndex:37075; appliedIndex:37073; }","duration":"176.351088ms","start":"2026-07-01T11:54:55.874842Z","end":"2026-07-01T11:54:56.051193Z","steps":["trace[499397543] 'read index received' (duration: 176.099181ms)","trace[499397543] 'applied index is now lower than readState.Index' (duration: 251.177µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:54:56.05134Z","caller":"traceutil/trace.go:171","msg":"trace[908734966] transaction","detail":"{read_only:false; response_revision:35940; number_of_response:1; }","duration":"184.467263ms","start":"2026-07-01T11:54:55.86686Z","end":"2026-07-01T11:54:56.051327Z","steps":["trace[908734966] 'process raft request' (duration: 184.139474ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:54:56.0515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.643286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-01T11:54:56.051528Z","caller":"traceutil/trace.go:171","msg":"trace[740853675] transaction","detail":"{read_only:false; response_revision:35941; number_of_response:1; }","duration":"184.555516ms","start":"2026-07-01T11:54:55.866912Z","end":"2026-07-01T11:54:56.051468Z","steps":["trace[740853675] 'process raft request' (duration: 184.241297ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:54:56.051565Z","caller":"traceutil/trace.go:171","msg":"trace[132423193] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:35941; }","duration":"176.730869ms","start":"2026-07-01T11:54:55.874816Z","end":"2026-07-01T11:54:56.051547Z","steps":["trace[132423193] 'agreement among raft nodes before linearized reading' (duration: 176.575975ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:58:07.187008Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35140} {"level":"info","ts":"2026-07-01T11:58:07.331249Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35140,"took":"140.999193ms","hash":3020739095,"current-db-size-bytes":59609088,"current-db-size":"60 MB","current-db-size-in-use-bytes":32346112,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-01T11:58:07.331323Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3020739095,"revision":35140,"compact-revision":32846} {"level":"info","ts":"2026-07-01T12:00:55.96395Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d7dbc9f5893cc1f6","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-01T12:00:55.968646Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-01T12:00:55.968828Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-01T12:03:07.198186Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37378} {"level":"info","ts":"2026-07-01T12:03:07.330587Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37378,"took":"128.70999ms","hash":2787487359,"current-db-size-bytes":61296640,"current-db-size":"61 MB","current-db-size-in-use-bytes":32997376,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-01T12:03:07.33065Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2787487359,"revision":37378,"compact-revision":35140}