{"level":"warn","ts":"2026-04-04T10:15:15.874741Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-04T10:15:15.874821Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.69: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.69:2380","--initial-cluster=instance=https://199.204.45.69:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.69:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.69:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-04T10:15:15.874879Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-04T10:15:15.874892Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.69:2380"]} {"level":"info","ts":"2026-04-04T10:15:15.874908Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-04T10:15:15.875454Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"]} {"level":"info","ts":"2026-04-04T10:15:15.875521Z","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.69:2380"],"listen-peer-urls":["https://199.204.45.69:2380"],"advertise-client-urls":["https://199.204.45.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.69:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-04T10:15:15.880172Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.642657ms"} {"level":"info","ts":"2026-04-04T10:15:15.89079Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fe334179879fd0aa","cluster-id":"d79a8cccc23ced73"} {"level":"info","ts":"2026-04-04T10:15:15.890991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=()"} {"level":"info","ts":"2026-04-04T10:15:15.891067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became follower at term 0"} {"level":"info","ts":"2026-04-04T10:15:15.891157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fe334179879fd0aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-04T10:15:15.891224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became follower at term 1"} {"level":"info","ts":"2026-04-04T10:15:15.891328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=(18317056099668185258)"} {"level":"warn","ts":"2026-04-04T10:15:15.898127Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-04T10:15:15.902542Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-04T10:15:15.903963Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-04T10:15:15.908057Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fe334179879fd0aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-04T10:15:15.908282Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fe334179879fd0aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-04T10:15:15.908457Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-04T10:15:15.908641Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-04T10:15:15.908721Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-04T10:15:15.909374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=(18317056099668185258)"} {"level":"info","ts":"2026-04-04T10:15:15.909549Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d79a8cccc23ced73","local-member-id":"fe334179879fd0aa","added-peer-id":"fe334179879fd0aa","added-peer-peer-urls":["https://199.204.45.69:2380"]} {"level":"info","ts":"2026-04-04T10:15:15.912912Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-04T10:15:15.913054Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.69:2380"} {"level":"info","ts":"2026-04-04T10:15:15.913111Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.69:2380"} {"level":"info","ts":"2026-04-04T10:15:15.914034Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fe334179879fd0aa","initial-advertise-peer-urls":["https://199.204.45.69:2380"],"listen-peer-urls":["https://199.204.45.69:2380"],"advertise-client-urls":["https://199.204.45.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-04T10:15:15.9141Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-04T10:15:16.692566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa is starting a new election at term 1"} {"level":"info","ts":"2026-04-04T10:15:16.692639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became pre-candidate at term 1"} {"level":"info","ts":"2026-04-04T10:15:16.692712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa received MsgPreVoteResp from fe334179879fd0aa at term 1"} {"level":"info","ts":"2026-04-04T10:15:16.692758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became candidate at term 2"} {"level":"info","ts":"2026-04-04T10:15:16.692782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa received MsgVoteResp from fe334179879fd0aa at term 2"} {"level":"info","ts":"2026-04-04T10:15:16.692804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became leader at term 2"} {"level":"info","ts":"2026-04-04T10:15:16.692828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fe334179879fd0aa elected leader fe334179879fd0aa at term 2"} {"level":"info","ts":"2026-04-04T10:15:16.69423Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-04T10:15:16.695315Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fe334179879fd0aa","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.69:2379]}","request-path":"/0/members/fe334179879fd0aa/attributes","cluster-id":"d79a8cccc23ced73","publish-timeout":"7s"} {"level":"info","ts":"2026-04-04T10:15:16.695383Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-04T10:15:16.695418Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-04T10:15:16.695677Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-04T10:15:16.695711Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-04T10:15:16.6957Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d79a8cccc23ced73","local-member-id":"fe334179879fd0aa","cluster-version":"3.5"} {"level":"info","ts":"2026-04-04T10:15:16.695997Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-04T10:15:16.696049Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-04T10:15:16.698956Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-04T10:15:16.704402Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.69:2379"} {"level":"warn","ts":"2026-04-04T10:15:40.983435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.886605ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-04T10:15:40.983697Z","caller":"traceutil/trace.go:171","msg":"trace[105509024] linearizableReadLoop","detail":"{readStateIndex:477; appliedIndex:475; }","duration":"140.439387ms","start":"2026-04-04T10:15:40.843234Z","end":"2026-04-04T10:15:40.983673Z","steps":["trace[105509024] 'read index received' (duration: 32.670953ms)","trace[105509024] 'applied index is now lower than readState.Index' (duration: 107.766854ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:15:40.983816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.58348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-04T10:15:40.983853Z","caller":"traceutil/trace.go:171","msg":"trace[1400790555] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:460; }","duration":"140.677472ms","start":"2026-04-04T10:15:40.843164Z","end":"2026-04-04T10:15:40.983841Z","steps":["trace[1400790555] 'agreement among raft nodes before linearized reading' (duration: 140.59112ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:15:40.984171Z","caller":"traceutil/trace.go:171","msg":"trace[1642137012] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"354.982515ms","start":"2026-04-04T10:15:40.629174Z","end":"2026-04-04T10:15:40.984157Z","steps":["trace[1642137012] 'process raft request' (duration: 246.70788ms)","trace[1642137012] 'compare' (duration: 105.709261ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:15:40.984252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:15:40.629159Z","time spent":"355.055047ms","remote":"127.0.0.1:52898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-04T10:15:40.984535Z","caller":"traceutil/trace.go:171","msg":"trace[368957776] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"355.328203ms","start":"2026-04-04T10:15:40.629195Z","end":"2026-04-04T10:15:40.984523Z","steps":["trace[368957776] 'process raft request' (duration: 354.396783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:15:40.984619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:15:40.629179Z","time spent":"355.403335ms","remote":"127.0.0.1:52898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-04T10:15:54.226937Z","caller":"traceutil/trace.go:171","msg":"trace[1288525486] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"112.658281ms","start":"2026-04-04T10:15:54.114253Z","end":"2026-04-04T10:15:54.226911Z","steps":["trace[1288525486] 'read index received' (duration: 111.848283ms)","trace[1288525486] 'applied index is now lower than readState.Index' (duration: 809.118µs)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:15:54.227075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.799584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qp9js\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-04T10:15:54.227107Z","caller":"traceutil/trace.go:171","msg":"trace[1593710839] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qp9js; range_end:; response_count:1; response_revision:613; }","duration":"112.885576ms","start":"2026-04-04T10:15:54.114211Z","end":"2026-04-04T10:15:54.227096Z","steps":["trace[1593710839] 'agreement among raft nodes before linearized reading' (duration: 112.803904ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:15:54.227117Z","caller":"traceutil/trace.go:171","msg":"trace[533991591] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"381.024692ms","start":"2026-04-04T10:15:53.846041Z","end":"2026-04-04T10:15:54.227066Z","steps":["trace[533991591] 'process raft request' (duration: 380.362358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:15:54.227374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:15:53.846015Z","time spent":"381.219106ms","remote":"127.0.0.1:52712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036353,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-04T10:15:54.775365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.325506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-04T10:15:54.775496Z","caller":"traceutil/trace.go:171","msg":"trace[1017609540] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"536.64709ms","start":"2026-04-04T10:15:54.238829Z","end":"2026-04-04T10:15:54.775476Z","steps":["trace[1017609540] 'process raft request' (duration: 50.128449ms)","trace[1017609540] 'compare' (duration: 486.212224ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:15:54.775558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:15:54.23879Z","time spent":"536.738202ms","remote":"127.0.0.1:52828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-04T10:15:54.777627Z","caller":"traceutil/trace.go:171","msg":"trace[802309471] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"295.128181ms","start":"2026-04-04T10:15:54.482479Z","end":"2026-04-04T10:15:54.777607Z","steps":["trace[802309471] 'read index received' (duration: 295.015739ms)","trace[802309471] 'applied index is now lower than readState.Index' (duration: 111.662µs)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:15:54.777689Z","caller":"traceutil/trace.go:171","msg":"trace[729670597] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"493.140432ms","start":"2026-04-04T10:15:54.284517Z","end":"2026-04-04T10:15:54.777657Z","steps":["trace[729670597] 'process raft request' (duration: 493.014079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:15:54.77781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.342326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-04T10:15:54.777857Z","caller":"traceutil/trace.go:171","msg":"trace[932713639] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:615; }","duration":"295.461218ms","start":"2026-04-04T10:15:54.482386Z","end":"2026-04-04T10:15:54.777847Z","steps":["trace[932713639] 'agreement among raft nodes before linearized reading' (duration: 295.287844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:15:54.777879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:15:54.284488Z","time spent":"493.320256ms","remote":"127.0.0.1:52984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-04T10:15:56.437857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.170825ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-04T10:15:56.438041Z","caller":"traceutil/trace.go:171","msg":"trace[1317116787] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"172.217401ms","start":"2026-04-04T10:15:56.265788Z","end":"2026-04-04T10:15:56.438006Z","steps":["trace[1317116787] 'process raft request' (duration: 41.836901ms)","trace[1317116787] 'compare' (duration: 130.032723ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:15:56.438207Z","caller":"traceutil/trace.go:171","msg":"trace[1052328898] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"170.078766ms","start":"2026-04-04T10:15:56.268093Z","end":"2026-04-04T10:15:56.438171Z","steps":["trace[1052328898] 'process raft request' (duration: 169.865462ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:15:56.438261Z","caller":"traceutil/trace.go:171","msg":"trace[176855365] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"157.102529ms","start":"2026-04-04T10:15:56.281144Z","end":"2026-04-04T10:15:56.438247Z","steps":["trace[176855365] 'process raft request' (duration: 156.950066ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:15:56.438299Z","caller":"traceutil/trace.go:171","msg":"trace[2043023711] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"169.142466ms","start":"2026-04-04T10:15:56.269142Z","end":"2026-04-04T10:15:56.438285Z","steps":["trace[2043023711] 'process raft request' (duration: 168.85766ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:16:39.495522Z","caller":"traceutil/trace.go:171","msg":"trace[1612066022] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"129.95306ms","start":"2026-04-04T10:16:39.365543Z","end":"2026-04-04T10:16:39.495496Z","steps":["trace[1612066022] 'process raft request' (duration: 129.783806ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:16:39.505448Z","caller":"traceutil/trace.go:171","msg":"trace[161397828] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"138.36108ms","start":"2026-04-04T10:16:39.367059Z","end":"2026-04-04T10:16:39.50542Z","steps":["trace[161397828] 'process raft request' (duration: 138.111235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:17:27.900539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.44123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-04T10:17:27.900605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.840413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9078424d-c3fa-4cfb-972b-dd6b461cd98d\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-04T10:17:27.900638Z","caller":"traceutil/trace.go:171","msg":"trace[545009992] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1925; }","duration":"100.570344ms","start":"2026-04-04T10:17:27.800048Z","end":"2026-04-04T10:17:27.900618Z","steps":["trace[545009992] 'range keys from in-memory index tree' (duration: 100.278828ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:17:27.900682Z","caller":"traceutil/trace.go:171","msg":"trace[161127544] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9078424d-c3fa-4cfb-972b-dd6b461cd98d; range_end:; response_count:1; response_revision:1925; }","duration":"293.968466ms","start":"2026-04-04T10:17:27.606695Z","end":"2026-04-04T10:17:27.900663Z","steps":["trace[161127544] 'range keys from in-memory index tree' (duration: 293.671319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:17:27.900824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.89876ms","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":"warn","ts":"2026-04-04T10:17:27.900819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.620795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-04T10:17:27.900886Z","caller":"traceutil/trace.go:171","msg":"trace[2095537554] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1925; }","duration":"276.002422ms","start":"2026-04-04T10:17:27.624868Z","end":"2026-04-04T10:17:27.90087Z","steps":["trace[2095537554] 'range keys from in-memory index tree' (duration: 275.754206ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:17:27.900917Z","caller":"traceutil/trace.go:171","msg":"trace[1638941080] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1925; }","duration":"177.749328ms","start":"2026-04-04T10:17:27.723133Z","end":"2026-04-04T10:17:27.900882Z","steps":["trace[1638941080] 'count revisions from in-memory index tree' (duration: 177.519833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:17:27.900809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.133274ms","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-04-04T10:17:27.901035Z","caller":"traceutil/trace.go:171","msg":"trace[1536511195] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1925; }","duration":"222.40237ms","start":"2026-04-04T10:17:27.678614Z","end":"2026-04-04T10:17:27.901017Z","steps":["trace[1536511195] 'range keys from in-memory index tree' (duration: 221.95182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:17:27.901283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.272716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-04T10:17:27.901331Z","caller":"traceutil/trace.go:171","msg":"trace[1692524281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1925; }","duration":"106.352647ms","start":"2026-04-04T10:17:27.794968Z","end":"2026-04-04T10:17:27.901321Z","steps":["trace[1692524281] 'range keys from in-memory index tree' (duration: 105.298193ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:17:28.075413Z","caller":"traceutil/trace.go:171","msg":"trace[214239429] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"170.524285ms","start":"2026-04-04T10:17:27.904839Z","end":"2026-04-04T10:17:28.075364Z","steps":["trace[214239429] 'process raft request' (duration: 170.273269ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:17:28.141827Z","caller":"traceutil/trace.go:171","msg":"trace[1233876810] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"232.993266ms","start":"2026-04-04T10:17:27.908807Z","end":"2026-04-04T10:17:28.141801Z","steps":["trace[1233876810] 'process raft request' (duration: 232.874044ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:17:28.141848Z","caller":"traceutil/trace.go:171","msg":"trace[604769326] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"235.192102ms","start":"2026-04-04T10:17:27.90662Z","end":"2026-04-04T10:17:28.141813Z","steps":["trace[604769326] 'process raft request' (duration: 205.235284ms)","trace[604769326] 'compare' (duration: 29.56223ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:17:28.536266Z","caller":"traceutil/trace.go:171","msg":"trace[948776373] linearizableReadLoop","detail":"{readStateIndex:2004; appliedIndex:2002; }","duration":"137.430059ms","start":"2026-04-04T10:17:28.398811Z","end":"2026-04-04T10:17:28.536241Z","steps":["trace[948776373] 'read index received' (duration: 116.34953ms)","trace[948776373] 'applied index is now lower than readState.Index' (duration: 21.079449ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:17:28.53645Z","caller":"traceutil/trace.go:171","msg":"trace[1513930456] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"304.180243ms","start":"2026-04-04T10:17:28.232245Z","end":"2026-04-04T10:17:28.536426Z","steps":["trace[1513930456] 'process raft request' (duration: 303.900757ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:17:28.536488Z","caller":"traceutil/trace.go:171","msg":"trace[2114292072] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"378.584999ms","start":"2026-04-04T10:17:28.157877Z","end":"2026-04-04T10:17:28.536462Z","steps":["trace[2114292072] 'process raft request' (duration: 357.311546ms)","trace[2114292072] 'compare' (duration: 20.797703ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:17:28.536592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:17:28.232209Z","time spent":"304.307305ms","remote":"127.0.0.1:52898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-04T10:17:28.536611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:17:28.157855Z","time spent":"378.6891ms","remote":"127.0.0.1:52828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10969,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-04T10:17:28.536694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.803477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-04T10:17:28.537159Z","caller":"traceutil/trace.go:171","msg":"trace[345341843] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1930; }","duration":"138.437781ms","start":"2026-04-04T10:17:28.398703Z","end":"2026-04-04T10:17:28.537141Z","steps":["trace[345341843] 'agreement among raft nodes before linearized reading' (duration: 137.792886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:17:28.537083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.31886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9078424d-c3fa-4cfb-972b-dd6b461cd98d\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-04-04T10:17:28.537569Z","caller":"traceutil/trace.go:171","msg":"trace[793742056] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9078424d-c3fa-4cfb-972b-dd6b461cd98d; range_end:; response_count:1; response_revision:1930; }","duration":"125.705769ms","start":"2026-04-04T10:17:28.411726Z","end":"2026-04-04T10:17:28.537432Z","steps":["trace[793742056] 'agreement among raft nodes before linearized reading' (duration: 125.201737ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:17:36.261733Z","caller":"traceutil/trace.go:171","msg":"trace[301185427] transaction","detail":"{read_only:false; response_revision:2006; number_of_response:1; }","duration":"147.401442ms","start":"2026-04-04T10:17:36.114301Z","end":"2026-04-04T10:17:36.261702Z","steps":["trace[301185427] 'process raft request' (duration: 147.154307ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:18:18.622539Z","caller":"traceutil/trace.go:171","msg":"trace[202188300] transaction","detail":"{read_only:false; response_revision:2253; number_of_response:1; }","duration":"144.520413ms","start":"2026-04-04T10:18:18.477969Z","end":"2026-04-04T10:18:18.622489Z","steps":["trace[202188300] 'process raft request' (duration: 144.200776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:18:19.381284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.570249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-04T10:18:19.381405Z","caller":"traceutil/trace.go:171","msg":"trace[1621471788] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2257; }","duration":"264.754913ms","start":"2026-04-04T10:18:19.116625Z","end":"2026-04-04T10:18:19.38138Z","steps":["trace[1621471788] 'range keys from in-memory index tree' (duration: 264.262403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:18:19.381684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.53431ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-04T10:18:19.381833Z","caller":"traceutil/trace.go:171","msg":"trace[981671565] linearizableReadLoop","detail":"{readStateIndex:2343; appliedIndex:2342; }","duration":"111.842752ms","start":"2026-04-04T10:18:19.269963Z","end":"2026-04-04T10:18:19.381806Z","steps":["trace[981671565] 'read index received' (duration: 47.181µs)","trace[981671565] 'applied index is now lower than readState.Index' (duration: 111.793851ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:18:19.381902Z","caller":"traceutil/trace.go:171","msg":"trace[431709053] transaction","detail":"{read_only:false; response_revision:2258; number_of_response:1; }","duration":"199.416413ms","start":"2026-04-04T10:18:19.182418Z","end":"2026-04-04T10:18:19.381834Z","steps":["trace[431709053] 'process raft request' (duration: 21.623837ms)","trace[431709053] 'compare' (duration: 177.400697ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:18:19.382064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.101818ms","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-04-04T10:18:19.382114Z","caller":"traceutil/trace.go:171","msg":"trace[2013099525] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2258; }","duration":"112.194761ms","start":"2026-04-04T10:18:19.269906Z","end":"2026-04-04T10:18:19.382101Z","steps":["trace[2013099525] 'agreement among raft nodes before linearized reading' (duration: 111.974816ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:18:19.597866Z","caller":"traceutil/trace.go:171","msg":"trace[463729442] transaction","detail":"{read_only:false; response_revision:2259; number_of_response:1; }","duration":"210.966528ms","start":"2026-04-04T10:18:19.386868Z","end":"2026-04-04T10:18:19.597835Z","steps":["trace[463729442] 'process raft request' (duration: 210.753013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:19:44.036798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.369917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-04T10:19:44.036909Z","caller":"traceutil/trace.go:171","msg":"trace[984506551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2908; }","duration":"190.5217ms","start":"2026-04-04T10:19:43.846369Z","end":"2026-04-04T10:19:44.036891Z","steps":["trace[984506551] 'range keys from in-memory index tree' (duration: 190.305466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:19:44.037196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.844071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-04T10:19:44.037375Z","caller":"traceutil/trace.go:171","msg":"trace[1398062140] transaction","detail":"{read_only:false; response_revision:2909; number_of_response:1; }","duration":"138.59196ms","start":"2026-04-04T10:19:43.898762Z","end":"2026-04-04T10:19:44.037354Z","steps":["trace[1398062140] 'process raft request' (duration: 10.475713ms)","trace[1398062140] 'compare' (duration: 127.456653ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:19:44.238363Z","caller":"traceutil/trace.go:171","msg":"trace[847075386] linearizableReadLoop","detail":"{readStateIndex:3021; appliedIndex:3020; }","duration":"193.487464ms","start":"2026-04-04T10:19:44.04482Z","end":"2026-04-04T10:19:44.238307Z","steps":["trace[847075386] 'read index received' (duration: 133.93218ms)","trace[847075386] 'applied index is now lower than readState.Index' (duration: 59.553304ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:19:44.238562Z","caller":"traceutil/trace.go:171","msg":"trace[827490188] transaction","detail":"{read_only:false; response_revision:2910; number_of_response:1; }","duration":"196.033798ms","start":"2026-04-04T10:19:44.042502Z","end":"2026-04-04T10:19:44.238536Z","steps":["trace[827490188] 'process raft request' (duration: 136.26795ms)","trace[827490188] 'compare' (duration: 59.268617ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:19:44.238902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.055877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1315"} {"level":"info","ts":"2026-04-04T10:19:44.238981Z","caller":"traceutil/trace.go:171","msg":"trace[1448608843] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2910; }","duration":"194.20906ms","start":"2026-04-04T10:19:44.044751Z","end":"2026-04-04T10:19:44.23896Z","steps":["trace[1448608843] 'agreement among raft nodes before linearized reading' (duration: 193.802621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:19:44.238984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.241627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-04T10:19:44.239062Z","caller":"traceutil/trace.go:171","msg":"trace[129646593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2910; }","duration":"183.368939ms","start":"2026-04-04T10:19:44.055668Z","end":"2026-04-04T10:19:44.239037Z","steps":["trace[129646593] 'agreement among raft nodes before linearized reading' (duration: 183.157255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:19:44.239254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.57636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-04T10:19:44.239373Z","caller":"traceutil/trace.go:171","msg":"trace[1470063430] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2910; }","duration":"163.788484ms","start":"2026-04-04T10:19:44.075556Z","end":"2026-04-04T10:19:44.239344Z","steps":["trace[1470063430] 'agreement among raft nodes before linearized reading' (duration: 163.436536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:22:26.080475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.701122ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-04T10:22:26.080712Z","caller":"traceutil/trace.go:171","msg":"trace[916743413] linearizableReadLoop","detail":"{readStateIndex:4115; appliedIndex:4112; }","duration":"236.781562ms","start":"2026-04-04T10:22:25.843909Z","end":"2026-04-04T10:22:26.080691Z","steps":["trace[916743413] 'read index received' (duration: 36.259029ms)","trace[916743413] 'applied index is now lower than readState.Index' (duration: 200.521253ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:22:26.080814Z","caller":"traceutil/trace.go:171","msg":"trace[700194018] transaction","detail":"{read_only:false; response_revision:3965; number_of_response:1; }","duration":"287.582439ms","start":"2026-04-04T10:22:25.793182Z","end":"2026-04-04T10:22:26.080764Z","steps":["trace[700194018] 'process raft request' (duration: 287.435316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:22:26.080977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.033587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-04T10:22:26.080907Z","caller":"traceutil/trace.go:171","msg":"trace[874283330] transaction","detail":"{read_only:false; response_revision:3964; number_of_response:1; }","duration":"291.280869ms","start":"2026-04-04T10:22:25.789505Z","end":"2026-04-04T10:22:26.080785Z","steps":["trace[874283330] 'process raft request' (duration: 144.146678ms)","trace[874283330] 'compare' (duration: 146.522938ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:22:26.081009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.639563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-04T10:22:26.08103Z","caller":"traceutil/trace.go:171","msg":"trace[1815002338] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3965; }","duration":"237.1709ms","start":"2026-04-04T10:22:25.843847Z","end":"2026-04-04T10:22:26.081018Z","steps":["trace[1815002338] 'agreement among raft nodes before linearized reading' (duration: 236.924265ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:22:26.081066Z","caller":"traceutil/trace.go:171","msg":"trace[2027168468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3965; }","duration":"231.783676ms","start":"2026-04-04T10:22:25.849267Z","end":"2026-04-04T10:22:26.081051Z","steps":["trace[2027168468] 'agreement among raft nodes before linearized reading' (duration: 231.577462ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:23:40.665736Z","caller":"traceutil/trace.go:171","msg":"trace[1623567103] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"118.346383ms","start":"2026-04-04T10:23:40.547363Z","end":"2026-04-04T10:23:40.665709Z","steps":["trace[1623567103] 'process raft request' (duration: 118.282411ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:23:40.666166Z","caller":"traceutil/trace.go:171","msg":"trace[840531828] transaction","detail":"{read_only:false; response_revision:4750; number_of_response:1; }","duration":"119.009026ms","start":"2026-04-04T10:23:40.547119Z","end":"2026-04-04T10:23:40.666128Z","steps":["trace[840531828] 'process raft request' (duration: 30.427116ms)","trace[840531828] 'compare' (duration: 87.980558ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:23:40.691903Z","caller":"traceutil/trace.go:171","msg":"trace[1094395169] linearizableReadLoop","detail":"{readStateIndex:4934; appliedIndex:4930; }","duration":"139.91118ms","start":"2026-04-04T10:23:40.55197Z","end":"2026-04-04T10:23:40.691882Z","steps":["trace[1094395169] 'read index received' (duration: 25.592333ms)","trace[1094395169] 'applied index is now lower than readState.Index' (duration: 114.318157ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:23:40.691994Z","caller":"traceutil/trace.go:171","msg":"trace[902993599] transaction","detail":"{read_only:false; response_revision:4753; number_of_response:1; }","duration":"140.717996ms","start":"2026-04-04T10:23:40.551266Z","end":"2026-04-04T10:23:40.691984Z","steps":["trace[902993599] 'process raft request' (duration: 140.559962ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:23:40.691962Z","caller":"traceutil/trace.go:171","msg":"trace[243889220] transaction","detail":"{read_only:false; response_revision:4752; number_of_response:1; }","duration":"142.650557ms","start":"2026-04-04T10:23:40.549266Z","end":"2026-04-04T10:23:40.691917Z","steps":["trace[243889220] 'process raft request' (duration: 142.447763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:23:40.692135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.144344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2643"} {"level":"info","ts":"2026-04-04T10:23:40.692195Z","caller":"traceutil/trace.go:171","msg":"trace[2008490376] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4753; }","duration":"140.244436ms","start":"2026-04-04T10:23:40.551937Z","end":"2026-04-04T10:23:40.692182Z","steps":["trace[2008490376] 'agreement among raft nodes before linearized reading' (duration: 140.050832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:23:40.699238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.31499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-envoy-gateway-system-atmosphere-e7b904d3\" ","response":"range_response_count:1 size:1030"} {"level":"info","ts":"2026-04-04T10:23:40.699345Z","caller":"traceutil/trace.go:171","msg":"trace[1766804996] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-envoy-gateway-system-atmosphere-e7b904d3; range_end:; response_count:1; response_revision:4754; }","duration":"142.430953ms","start":"2026-04-04T10:23:40.556883Z","end":"2026-04-04T10:23:40.699314Z","steps":["trace[1766804996] 'agreement among raft nodes before linearized reading' (duration: 142.187457ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:23:44.786305Z","caller":"traceutil/trace.go:171","msg":"trace[248474333] transaction","detail":"{read_only:false; response_revision:4882; number_of_response:1; }","duration":"103.830635ms","start":"2026-04-04T10:23:44.682457Z","end":"2026-04-04T10:23:44.786287Z","steps":["trace[248474333] 'process raft request' (duration: 46.523078ms)","trace[248474333] 'compare' (duration: 56.869316ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:23:44.786407Z","caller":"traceutil/trace.go:171","msg":"trace[1866714387] linearizableReadLoop","detail":"{readStateIndex:5067; appliedIndex:5066; }","duration":"103.611829ms","start":"2026-04-04T10:23:44.682788Z","end":"2026-04-04T10:23:44.7864Z","steps":["trace[1866714387] 'read index received' (duration: 46.17162ms)","trace[1866714387] 'applied index is now lower than readState.Index' (duration: 57.439689ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:23:44.78656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.757442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-dd7pf-tls\" ","response":"range_response_count:1 size:2517"} {"level":"info","ts":"2026-04-04T10:23:44.786585Z","caller":"traceutil/trace.go:171","msg":"trace[1911078463] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-dd7pf-tls; range_end:; response_count:1; response_revision:4883; }","duration":"103.804443ms","start":"2026-04-04T10:23:44.682774Z","end":"2026-04-04T10:23:44.786578Z","steps":["trace[1911078463] 'agreement among raft nodes before linearized reading' (duration: 103.715181ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:23:59.87335Z","caller":"traceutil/trace.go:171","msg":"trace[967487521] transaction","detail":"{read_only:false; response_revision:5211; number_of_response:1; }","duration":"136.403115ms","start":"2026-04-04T10:23:59.736921Z","end":"2026-04-04T10:23:59.873324Z","steps":["trace[967487521] 'process raft request' (duration: 136.1674ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:24:00.184918Z","caller":"traceutil/trace.go:171","msg":"trace[986670431] linearizableReadLoop","detail":"{readStateIndex:5406; appliedIndex:5405; }","duration":"311.263304ms","start":"2026-04-04T10:23:59.873604Z","end":"2026-04-04T10:24:00.184868Z","steps":["trace[986670431] 'read index received' (duration: 306.022843ms)","trace[986670431] 'applied index is now lower than readState.Index' (duration: 5.237011ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:24:00.184957Z","caller":"traceutil/trace.go:171","msg":"trace[1314696144] transaction","detail":"{read_only:false; response_revision:5212; number_of_response:1; }","duration":"319.588882ms","start":"2026-04-04T10:23:59.865345Z","end":"2026-04-04T10:24:00.184934Z","steps":["trace[1314696144] 'process raft request' (duration: 314.339931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:24:00.185412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:23:59.865317Z","time spent":"320.025161ms","remote":"127.0.0.1:52898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-04T10:24:00.185149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.92607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"warn","ts":"2026-04-04T10:24:00.185601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.331939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-dd7pf.18a320544d33dfd8\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-04-04T10:24:00.185616Z","caller":"traceutil/trace.go:171","msg":"trace[27026256] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:5212; }","duration":"321.435591ms","start":"2026-04-04T10:23:59.864158Z","end":"2026-04-04T10:24:00.185593Z","steps":["trace[27026256] 'agreement among raft nodes before linearized reading' (duration: 320.814607ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:24:00.18565Z","caller":"traceutil/trace.go:171","msg":"trace[1164271823] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-dd7pf.18a320544d33dfd8; range_end:; response_count:1; response_revision:5212; }","duration":"174.429622ms","start":"2026-04-04T10:24:00.011207Z","end":"2026-04-04T10:24:00.185637Z","steps":["trace[1164271823] 'agreement among raft nodes before linearized reading' (duration: 174.271568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:24:00.185664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.405143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:17162"} {"level":"info","ts":"2026-04-04T10:24:00.185708Z","caller":"traceutil/trace.go:171","msg":"trace[1522101646] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5212; }","duration":"307.485015ms","start":"2026-04-04T10:23:59.878209Z","end":"2026-04-04T10:24:00.185694Z","steps":["trace[1522101646] 'agreement among raft nodes before linearized reading' (duration: 307.379193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:24:00.185706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:23:59.864134Z","time spent":"321.548633ms","remote":"127.0.0.1:52844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":918,"request content":"key:\"/registry/serviceaccounts/monitoring/vector\" "} {"level":"warn","ts":"2026-04-04T10:24:00.185736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.309693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:23 size:768836"} {"level":"warn","ts":"2026-04-04T10:24:00.185727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.681644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-04T10:24:00.185888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.63896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-ddc4f282-547b-4a5b-9784-201d1e68afc7\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-04-04T10:24:00.185918Z","caller":"traceutil/trace.go:171","msg":"trace[291961150] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:5212; }","duration":"312.003561ms","start":"2026-04-04T10:23:59.873893Z","end":"2026-04-04T10:24:00.185897Z","steps":["trace[291961150] 'agreement among raft nodes before linearized reading' (duration: 311.563142ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:24:00.185935Z","caller":"traceutil/trace.go:171","msg":"trace[1839103872] range","detail":"{range_begin:/registry/persistentvolumes/pvc-ddc4f282-547b-4a5b-9784-201d1e68afc7; range_end:; response_count:1; response_revision:5212; }","duration":"308.69762ms","start":"2026-04-04T10:23:59.877215Z","end":"2026-04-04T10:24:00.185913Z","steps":["trace[1839103872] 'agreement among raft nodes before linearized reading' (duration: 308.608678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:24:00.185977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:23:59.877197Z","time spent":"308.771161ms","remote":"127.0.0.1:52772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1459,"request content":"key:\"/registry/persistentvolumes/pvc-ddc4f282-547b-4a5b-9784-201d1e68afc7\" "} {"level":"warn","ts":"2026-04-04T10:24:00.186006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.032702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.69\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-04T10:24:00.186076Z","caller":"traceutil/trace.go:171","msg":"trace[395633791] range","detail":"{range_begin:/registry/masterleases/199.204.45.69; range_end:; response_count:1; response_revision:5212; }","duration":"198.134684ms","start":"2026-04-04T10:23:59.987931Z","end":"2026-04-04T10:24:00.186065Z","steps":["trace[395633791] 'agreement among raft nodes before linearized reading' (duration: 197.579343ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:24:00.185786Z","caller":"traceutil/trace.go:171","msg":"trace[1317177921] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:23; response_revision:5212; }","duration":"259.392494ms","start":"2026-04-04T10:23:59.926378Z","end":"2026-04-04T10:24:00.185771Z","steps":["trace[1317177921] 'agreement among raft nodes before linearized reading' (duration: 259.089528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:24:00.186051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:23:59.873844Z","time spent":"312.187435ms","remote":"127.0.0.1:52968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":27,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-04T10:24:00.185743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:23:59.878191Z","time spent":"307.543397ms","remote":"127.0.0.1:52828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":17186,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-04-04T10:24:00.185824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.785107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-af84730f-c516-4486-af46-12e585a93c63\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-04-04T10:24:00.186555Z","caller":"traceutil/trace.go:171","msg":"trace[1329376663] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-af84730f-c516-4486-af46-12e585a93c63; range_end:; response_count:1; response_revision:5212; }","duration":"304.529882ms","start":"2026-04-04T10:23:59.882012Z","end":"2026-04-04T10:24:00.186542Z","steps":["trace[1329376663] 'agreement among raft nodes before linearized reading' (duration: 303.749386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:24:00.186598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:23:59.882002Z","time spent":"304.584523ms","remote":"127.0.0.1:52828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3624,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-af84730f-c516-4486-af46-12e585a93c63\" "} {"level":"info","ts":"2026-04-04T10:25:09.18633Z","caller":"traceutil/trace.go:171","msg":"trace[943873736] transaction","detail":"{read_only:false; response_revision:6007; number_of_response:1; }","duration":"102.114783ms","start":"2026-04-04T10:25:09.084184Z","end":"2026-04-04T10:25:09.186299Z","steps":["trace[943873736] 'process raft request' (duration: 25.125012ms)","trace[943873736] 'compare' (duration: 76.850588ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:25:16.719425Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3090} {"level":"info","ts":"2026-04-04T10:25:16.800172Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3090,"took":"79.591366ms","hash":3843342396,"current-db-size-bytes":59236352,"current-db-size":"59 MB","current-db-size-in-use-bytes":31588352,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-04T10:25:16.800243Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3843342396,"revision":3090,"compact-revision":-1} {"level":"info","ts":"2026-04-04T10:30:16.726297Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6069} {"level":"info","ts":"2026-04-04T10:30:16.831974Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6069,"took":"102.80875ms","hash":3216107256,"current-db-size-bytes":59236352,"current-db-size":"59 MB","current-db-size-in-use-bytes":21819392,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-04T10:30:16.832086Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3216107256,"revision":6069,"compact-revision":3090} {"level":"warn","ts":"2026-04-04T10:30:25.839271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.221157ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-04T10:30:25.83954Z","caller":"traceutil/trace.go:171","msg":"trace[480633573] linearizableReadLoop","detail":"{readStateIndex:8639; appliedIndex:8637; }","duration":"122.339125ms","start":"2026-04-04T10:30:25.717181Z","end":"2026-04-04T10:30:25.83952Z","steps":["trace[480633573] 'read index received' (duration: 101.739618ms)","trace[480633573] 'applied index is now lower than readState.Index' (duration: 20.598057ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:30:25.839663Z","caller":"traceutil/trace.go:171","msg":"trace[212420367] transaction","detail":"{read_only:false; response_revision:8347; number_of_response:1; }","duration":"128.270831ms","start":"2026-04-04T10:30:25.711381Z","end":"2026-04-04T10:30:25.839652Z","steps":["trace[212420367] 'process raft request' (duration: 127.995075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:30:25.839894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.693342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-04T10:30:25.839921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.970026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-04T10:30:25.839963Z","caller":"traceutil/trace.go:171","msg":"trace[2121398486] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8347; }","duration":"122.807945ms","start":"2026-04-04T10:30:25.717139Z","end":"2026-04-04T10:30:25.839947Z","steps":["trace[2121398486] 'agreement among raft nodes before linearized reading' (duration: 122.59866ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:30:25.840004Z","caller":"traceutil/trace.go:171","msg":"trace[51935333] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8347; }","duration":"121.095348ms","start":"2026-04-04T10:30:25.718889Z","end":"2026-04-04T10:30:25.839984Z","steps":["trace[51935333] 'agreement among raft nodes before linearized reading' (duration: 120.877023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:30:25.840043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.790454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-04T10:30:25.840163Z","caller":"traceutil/trace.go:171","msg":"trace[1353793134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8347; }","duration":"122.952918ms","start":"2026-04-04T10:30:25.717182Z","end":"2026-04-04T10:30:25.840135Z","steps":["trace[1353793134] 'agreement among raft nodes before linearized reading' (duration: 122.57521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:30:38.774006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.399005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-04T10:30:38.774125Z","caller":"traceutil/trace.go:171","msg":"trace[111517531] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:8416; }","duration":"195.594459ms","start":"2026-04-04T10:30:38.578511Z","end":"2026-04-04T10:30:38.774105Z","steps":["trace[111517531] 'count revisions from in-memory index tree' (duration: 195.269072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:30:38.774075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.786515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-04T10:30:38.774232Z","caller":"traceutil/trace.go:171","msg":"trace[820742050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8416; }","duration":"132.99108ms","start":"2026-04-04T10:30:38.641212Z","end":"2026-04-04T10:30:38.774203Z","steps":["trace[820742050] 'range keys from in-memory index tree' (duration: 132.54948ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:30:53.063296Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000225e.wal"} {"level":"info","ts":"2026-04-04T10:31:15.994427Z","caller":"traceutil/trace.go:171","msg":"trace[1359079512] linearizableReadLoop","detail":"{readStateIndex:9002; appliedIndex:9001; }","duration":"243.773839ms","start":"2026-04-04T10:31:15.750624Z","end":"2026-04-04T10:31:15.994397Z","steps":["trace[1359079512] 'read index received' (duration: 243.489372ms)","trace[1359079512] 'applied index is now lower than readState.Index' (duration: 283.567µs)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:31:15.99455Z","caller":"traceutil/trace.go:171","msg":"trace[1816544328] transaction","detail":"{read_only:false; response_revision:8696; number_of_response:1; }","duration":"265.47522ms","start":"2026-04-04T10:31:15.729035Z","end":"2026-04-04T10:31:15.99451Z","steps":["trace[1816544328] 'process raft request' (duration: 265.186984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:31:15.994678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.671157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-04T10:31:15.994696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.047805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-04T10:31:15.994757Z","caller":"traceutil/trace.go:171","msg":"trace[1905172766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8696; }","duration":"151.807219ms","start":"2026-04-04T10:31:15.842935Z","end":"2026-04-04T10:31:15.994742Z","steps":["trace[1905172766] 'agreement among raft nodes before linearized reading' (duration: 151.659396ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:31:15.994782Z","caller":"traceutil/trace.go:171","msg":"trace[182966656] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8696; }","duration":"244.190168ms","start":"2026-04-04T10:31:15.750575Z","end":"2026-04-04T10:31:15.994765Z","steps":["trace[182966656] 'agreement among raft nodes before linearized reading' (duration: 243.948643ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:31:28.842256Z","caller":"traceutil/trace.go:171","msg":"trace[1970389916] transaction","detail":"{read_only:false; response_revision:8783; number_of_response:1; }","duration":"226.197757ms","start":"2026-04-04T10:31:28.616025Z","end":"2026-04-04T10:31:28.842222Z","steps":["trace[1970389916] 'process raft request' (duration: 225.930752ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:31:28.842714Z","caller":"traceutil/trace.go:171","msg":"trace[1967067806] linearizableReadLoop","detail":"{readStateIndex:9092; appliedIndex:9092; }","duration":"200.076823ms","start":"2026-04-04T10:31:28.642596Z","end":"2026-04-04T10:31:28.842673Z","steps":["trace[1967067806] 'read index received' (duration: 200.058822ms)","trace[1967067806] 'applied index is now lower than readState.Index' (duration: 14.751µs)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:31:28.843022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.387729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-r8lcf\" ","response":"range_response_count:1 size:4644"} {"level":"info","ts":"2026-04-04T10:31:28.843068Z","caller":"traceutil/trace.go:171","msg":"trace[1516059287] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-r8lcf; range_end:; response_count:1; response_revision:8783; }","duration":"200.500752ms","start":"2026-04-04T10:31:28.642551Z","end":"2026-04-04T10:31:28.843052Z","steps":["trace[1516059287] 'agreement among raft nodes before linearized reading' (duration: 200.249246ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:31:28.941115Z","caller":"traceutil/trace.go:171","msg":"trace[1078380635] transaction","detail":"{read_only:false; response_revision:8784; number_of_response:1; }","duration":"298.382217ms","start":"2026-04-04T10:31:28.642687Z","end":"2026-04-04T10:31:28.941069Z","steps":["trace[1078380635] 'process raft request' (duration: 297.890857ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:31:28.984112Z","caller":"traceutil/trace.go:171","msg":"trace[1318844986] transaction","detail":"{read_only:false; response_revision:8785; number_of_response:1; }","duration":"134.428611ms","start":"2026-04-04T10:31:28.849637Z","end":"2026-04-04T10:31:28.984065Z","steps":["trace[1318844986] 'process raft request' (duration: 134.144255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:31:29.739941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.192621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-04-04T10:31:29.740048Z","caller":"traceutil/trace.go:171","msg":"trace[1151560169] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:8791; }","duration":"188.351404ms","start":"2026-04-04T10:31:29.551673Z","end":"2026-04-04T10:31:29.740025Z","steps":["trace[1151560169] 'range keys from in-memory index tree' (duration: 187.969176ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:31:30.432415Z","caller":"traceutil/trace.go:171","msg":"trace[1181929675] transaction","detail":"{read_only:false; response_revision:8796; number_of_response:1; }","duration":"113.614878ms","start":"2026-04-04T10:31:30.318765Z","end":"2026-04-04T10:31:30.43238Z","steps":["trace[1181929675] 'process raft request' (duration: 62.647048ms)","trace[1181929675] 'compare' (duration: 50.670644ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:32:30.781562Z","caller":"traceutil/trace.go:171","msg":"trace[656095843] transaction","detail":"{read_only:false; response_revision:9452; number_of_response:1; }","duration":"105.713665ms","start":"2026-04-04T10:32:30.675813Z","end":"2026-04-04T10:32:30.781527Z","steps":["trace[656095843] 'process raft request' (duration: 105.377538ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:32:43.940435Z","caller":"traceutil/trace.go:171","msg":"trace[1378647291] linearizableReadLoop","detail":"{readStateIndex:9859; appliedIndex:9858; }","duration":"151.626699ms","start":"2026-04-04T10:32:43.788777Z","end":"2026-04-04T10:32:43.940404Z","steps":["trace[1378647291] 'read index received' (duration: 151.413864ms)","trace[1378647291] 'applied index is now lower than readState.Index' (duration: 211.645µs)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:32:43.940517Z","caller":"traceutil/trace.go:171","msg":"trace[703184503] transaction","detail":"{read_only:false; response_revision:9529; number_of_response:1; }","duration":"404.252442ms","start":"2026-04-04T10:32:43.53622Z","end":"2026-04-04T10:32:43.940473Z","steps":["trace[703184503] 'process raft request' (duration: 404.004826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:32:43.940771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:32:43.536191Z","time spent":"404.450116ms","remote":"127.0.0.1:52898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-04T10:32:43.94083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.070569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-04T10:32:43.940923Z","caller":"traceutil/trace.go:171","msg":"trace[712508951] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9529; }","duration":"152.247123ms","start":"2026-04-04T10:32:43.788654Z","end":"2026-04-04T10:32:43.940901Z","steps":["trace[712508951] 'agreement among raft nodes before linearized reading' (duration: 151.902006ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:32:59.724323Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fe334179879fd0aa","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-04T10:32:59.730561Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-04T10:32:59.730673Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-04T10:35:16.736037Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8171} {"level":"info","ts":"2026-04-04T10:35:16.802731Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8171,"took":"64.080311ms","hash":4201417993,"current-db-size-bytes":59236352,"current-db-size":"59 MB","current-db-size-in-use-bytes":28241920,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-04T10:35:16.802795Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4201417993,"revision":8171,"compact-revision":6069} {"level":"info","ts":"2026-04-04T10:38:23.931444Z","caller":"traceutil/trace.go:171","msg":"trace[1047039158] transaction","detail":"{read_only:false; response_revision:11970; number_of_response:1; }","duration":"113.114852ms","start":"2026-04-04T10:38:23.818273Z","end":"2026-04-04T10:38:23.931388Z","steps":["trace[1047039158] 'process raft request' (duration: 62.294773ms)","trace[1047039158] 'compare' (duration: 50.621074ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:38:24.243007Z","caller":"traceutil/trace.go:171","msg":"trace[291911130] transaction","detail":"{read_only:false; response_revision:11972; number_of_response:1; }","duration":"149.921764ms","start":"2026-04-04T10:38:24.093059Z","end":"2026-04-04T10:38:24.24298Z","steps":["trace[291911130] 'process raft request' (duration: 149.849802ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:38:24.243198Z","caller":"traceutil/trace.go:171","msg":"trace[1600220561] transaction","detail":"{read_only:false; response_revision:11971; number_of_response:1; }","duration":"286.493632ms","start":"2026-04-04T10:38:23.956666Z","end":"2026-04-04T10:38:24.24316Z","steps":["trace[1600220561] 'process raft request' (duration: 242.280473ms)","trace[1600220561] 'compare' (duration: 43.825201ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:38:24.24321Z","caller":"traceutil/trace.go:171","msg":"trace[1438287299] transaction","detail":"{read_only:false; response_revision:11973; number_of_response:1; }","duration":"144.846086ms","start":"2026-04-04T10:38:24.098327Z","end":"2026-04-04T10:38:24.243173Z","steps":["trace[1438287299] 'process raft request' (duration: 144.620301ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:40:16.743449Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10537} {"level":"info","ts":"2026-04-04T10:40:16.847966Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10537,"took":"100.36416ms","hash":1564640650,"current-db-size-bytes":59236352,"current-db-size":"59 MB","current-db-size-in-use-bytes":26112000,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-04T10:40:16.848055Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1564640650,"revision":10537,"compact-revision":8171} {"level":"info","ts":"2026-04-04T10:45:16.752064Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12727} {"level":"info","ts":"2026-04-04T10:45:16.836533Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12727,"took":"80.671834ms","hash":223319922,"current-db-size-bytes":59236352,"current-db-size":"59 MB","current-db-size-in-use-bytes":27381760,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-04T10:45:16.836599Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":223319922,"revision":12727,"compact-revision":10537} {"level":"info","ts":"2026-04-04T10:45:34.141009Z","caller":"traceutil/trace.go:171","msg":"trace[1854865887] transaction","detail":"{read_only:false; response_revision:14995; number_of_response:1; }","duration":"237.745429ms","start":"2026-04-04T10:45:33.903238Z","end":"2026-04-04T10:45:34.140984Z","steps":["trace[1854865887] 'process raft request' (duration: 237.602666ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:45:34.278325Z","caller":"traceutil/trace.go:171","msg":"trace[1526755151] linearizableReadLoop","detail":"{readStateIndex:15514; appliedIndex:15512; }","duration":"260.212406ms","start":"2026-04-04T10:45:34.018085Z","end":"2026-04-04T10:45:34.278297Z","steps":["trace[1526755151] 'read index received' (duration: 123.456722ms)","trace[1526755151] 'applied index is now lower than readState.Index' (duration: 136.754314ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:45:34.278374Z","caller":"traceutil/trace.go:171","msg":"trace[42071200] transaction","detail":"{read_only:false; response_revision:14996; number_of_response:1; }","duration":"374.887122ms","start":"2026-04-04T10:45:33.903451Z","end":"2026-04-04T10:45:34.278338Z","steps":["trace[42071200] 'process raft request' (duration: 277.739629ms)","trace[42071200] 'compare' (duration: 96.755515ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:45:34.278478Z","caller":"traceutil/trace.go:171","msg":"trace[1493681470] transaction","detail":"{read_only:false; response_revision:14997; number_of_response:1; }","duration":"374.987104ms","start":"2026-04-04T10:45:33.903479Z","end":"2026-04-04T10:45:34.278466Z","steps":["trace[1493681470] 'process raft request' (duration: 374.717148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:45:34.278517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:45:33.903446Z","time spent":"374.999414ms","remote":"127.0.0.1:52898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-04T10:45:34.278597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:45:33.903468Z","time spent":"375.042805ms","remote":"127.0.0.1:52898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-04T10:45:34.278662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.586825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-04T10:45:34.278576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.119099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-04T10:45:34.278723Z","caller":"traceutil/trace.go:171","msg":"trace[1715518996] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:14997; }","duration":"260.633246ms","start":"2026-04-04T10:45:34.018066Z","end":"2026-04-04T10:45:34.278699Z","steps":["trace[1715518996] 'agreement among raft nodes before linearized reading' (duration: 260.570854ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:45:34.278772Z","caller":"traceutil/trace.go:171","msg":"trace[122912026] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:14997; }","duration":"232.337504ms","start":"2026-04-04T10:45:34.046419Z","end":"2026-04-04T10:45:34.278757Z","steps":["trace[122912026] 'agreement among raft nodes before linearized reading' (duration: 232.068088ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:46:50.543906Z","caller":"traceutil/trace.go:171","msg":"trace[1337123053] transaction","detail":"{read_only:false; response_revision:15752; number_of_response:1; }","duration":"131.528613ms","start":"2026-04-04T10:46:50.412342Z","end":"2026-04-04T10:46:50.543871Z","steps":["trace[1337123053] 'process raft request' (duration: 131.233687ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:50:16.772942Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14726} {"level":"info","ts":"2026-04-04T10:50:16.851041Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14726,"took":"75.744049ms","hash":1314031721,"current-db-size-bytes":59236352,"current-db-size":"59 MB","current-db-size-in-use-bytes":34140160,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-04T10:50:16.85111Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1314031721,"revision":14726,"compact-revision":12727} {"level":"warn","ts":"2026-04-04T10:53:07.422053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.755391ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-04T10:53:07.422257Z","caller":"traceutil/trace.go:171","msg":"trace[235134175] transaction","detail":"{read_only:false; response_revision:18496; number_of_response:1; }","duration":"531.502961ms","start":"2026-04-04T10:53:06.890715Z","end":"2026-04-04T10:53:07.422218Z","steps":["trace[235134175] 'process raft request' (duration: 357.442193ms)","trace[235134175] 'compare' (duration: 173.538127ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:53:07.423238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:53:06.890702Z","time spent":"532.417689ms","remote":"127.0.0.1:52898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-04T10:53:07.422348Z","caller":"traceutil/trace.go:171","msg":"trace[1730518091] transaction","detail":"{read_only:false; response_revision:18497; number_of_response:1; }","duration":"110.345385ms","start":"2026-04-04T10:53:07.311979Z","end":"2026-04-04T10:53:07.422324Z","steps":["trace[1730518091] 'process raft request' (duration: 110.254353ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:53:07.422419Z","caller":"traceutil/trace.go:171","msg":"trace[72562423] linearizableReadLoop","detail":"{readStateIndex:19126; appliedIndex:19123; }","duration":"474.02387ms","start":"2026-04-04T10:53:06.948382Z","end":"2026-04-04T10:53:07.422405Z","steps":["trace[72562423] 'read index received' (duration: 19.445144ms)","trace[72562423] 'applied index is now lower than readState.Index' (duration: 454.576866ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:53:07.422649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.280594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-2xwbt\" ","response":"range_response_count:1 size:22017"} {"level":"info","ts":"2026-04-04T10:53:07.42459Z","caller":"traceutil/trace.go:171","msg":"trace[1724249269] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-2xwbt; range_end:; response_count:1; response_revision:18497; }","duration":"476.275068ms","start":"2026-04-04T10:53:06.948288Z","end":"2026-04-04T10:53:07.424563Z","steps":["trace[1724249269] 'agreement among raft nodes before linearized reading' (duration: 474.153852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:53:07.424787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:53:06.948256Z","time spent":"476.505552ms","remote":"127.0.0.1:52828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22041,"request content":"key:\"/registry/pods/openstack/nova-compute-default-2xwbt\" "} {"level":"warn","ts":"2026-04-04T10:53:07.425009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.116005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-04T10:53:07.425125Z","caller":"traceutil/trace.go:171","msg":"trace[1697902959] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:18497; }","duration":"452.313539ms","start":"2026-04-04T10:53:06.972787Z","end":"2026-04-04T10:53:07.425101Z","steps":["trace[1697902959] 'agreement among raft nodes before linearized reading' (duration: 452.081064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:53:07.425187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:53:06.972764Z","time spent":"452.41086ms","remote":"127.0.0.1:53072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":32,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-04-04T10:54:30.000571Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fe334179879fd0aa","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-04T10:54:30.008866Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-04T10:54:30.009023Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-04T10:55:16.779204Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17384} {"level":"info","ts":"2026-04-04T10:55:16.885052Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17384,"took":"101.825773ms","hash":649645094,"current-db-size-bytes":59236352,"current-db-size":"59 MB","current-db-size-in-use-bytes":34533376,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-04T10:55:16.885134Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":649645094,"revision":17384,"compact-revision":14726} {"level":"info","ts":"2026-04-04T10:59:13.604015Z","caller":"traceutil/trace.go:171","msg":"trace[1649174679] linearizableReadLoop","detail":"{readStateIndex:22015; appliedIndex:22014; }","duration":"124.275391ms","start":"2026-04-04T10:59:13.47971Z","end":"2026-04-04T10:59:13.603985Z","steps":["trace[1649174679] 'read index received' (duration: 81.89779ms)","trace[1649174679] 'applied index is now lower than readState.Index' (duration: 42.376541ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:59:13.604308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.585367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-04T10:59:13.604421Z","caller":"traceutil/trace.go:171","msg":"trace[1033068457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21292; }","duration":"124.754251ms","start":"2026-04-04T10:59:13.479652Z","end":"2026-04-04T10:59:13.604406Z","steps":["trace[1033068457] 'agreement among raft nodes before linearized reading' (duration: 124.496825ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:59:13.604258Z","caller":"traceutil/trace.go:171","msg":"trace[686287012] transaction","detail":"{read_only:false; response_revision:21292; number_of_response:1; }","duration":"161.329367ms","start":"2026-04-04T10:59:13.442825Z","end":"2026-04-04T10:59:13.604154Z","steps":["trace[686287012] 'process raft request' (duration: 118.771383ms)","trace[686287012] 'compare' (duration: 42.251258ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:59:13.901989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.138101ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-04T10:59:13.902264Z","caller":"traceutil/trace.go:171","msg":"trace[346490539] transaction","detail":"{read_only:false; response_revision:21293; number_of_response:1; }","duration":"390.357765ms","start":"2026-04-04T10:59:13.511863Z","end":"2026-04-04T10:59:13.90222Z","steps":["trace[346490539] 'process raft request' (duration: 254.808334ms)","trace[346490539] 'compare' (duration: 134.898366ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:59:13.902692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:59:13.51184Z","time spent":"390.723672ms","remote":"127.0.0.1:52898","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-04-04T10:59:13.902699Z","caller":"traceutil/trace.go:171","msg":"trace[750813979] transaction","detail":"{read_only:false; response_revision:21295; number_of_response:1; }","duration":"354.060674ms","start":"2026-04-04T10:59:13.548616Z","end":"2026-04-04T10:59:13.902677Z","steps":["trace[750813979] 'process raft request' (duration: 353.974742ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:59:13.902732Z","caller":"traceutil/trace.go:171","msg":"trace[1338009890] transaction","detail":"{read_only:false; response_revision:21294; number_of_response:1; }","duration":"375.06066ms","start":"2026-04-04T10:59:13.527626Z","end":"2026-04-04T10:59:13.902687Z","steps":["trace[1338009890] 'process raft request' (duration: 374.492798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:59:13.90279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:59:13.548607Z","time spent":"354.144615ms","remote":"127.0.0.1:52898","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-04-04T10:59:13.902939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:59:13.527608Z","time spent":"375.227313ms","remote":"127.0.0.1:52898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-04T10:59:13.903093Z","caller":"traceutil/trace.go:171","msg":"trace[1307617371] transaction","detail":"{read_only:false; response_revision:21296; number_of_response:1; }","duration":"347.635577ms","start":"2026-04-04T10:59:13.555382Z","end":"2026-04-04T10:59:13.903018Z","steps":["trace[1307617371] 'process raft request' (duration: 347.254589ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:59:13.903193Z","caller":"traceutil/trace.go:171","msg":"trace[38892350] transaction","detail":"{read_only:false; response_revision:21297; number_of_response:1; }","duration":"293.703091ms","start":"2026-04-04T10:59:13.609457Z","end":"2026-04-04T10:59:13.90316Z","steps":["trace[38892350] 'process raft request' (duration: 293.541687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:59:13.903363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:59:13.555367Z","time spent":"347.886992ms","remote":"127.0.0.1:52898","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-04-04T10:59:13.903196Z","caller":"traceutil/trace.go:171","msg":"trace[970441548] linearizableReadLoop","detail":"{readStateIndex:22019; appliedIndex:22015; }","duration":"299.064225ms","start":"2026-04-04T10:59:13.604109Z","end":"2026-04-04T10:59:13.903174Z","steps":["trace[970441548] 'read index received' (duration: 162.717498ms)","trace[970441548] 'applied index is now lower than readState.Index' (duration: 136.345317ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:59:13.903387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.218406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-04T10:59:13.903402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.904157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-04-04T10:59:13.903441Z","caller":"traceutil/trace.go:171","msg":"trace[1874055478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21297; }","duration":"418.329908ms","start":"2026-04-04T10:59:13.4851Z","end":"2026-04-04T10:59:13.90343Z","steps":["trace[1874055478] 'agreement among raft nodes before linearized reading' (duration: 418.128754ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:59:13.90346Z","caller":"traceutil/trace.go:171","msg":"trace[1150940213] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21297; }","duration":"304.00519ms","start":"2026-04-04T10:59:13.599438Z","end":"2026-04-04T10:59:13.903443Z","steps":["trace[1150940213] 'agreement among raft nodes before linearized reading' (duration: 303.821986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:59:13.903474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:59:13.485071Z","time spent":"418.396299ms","remote":"127.0.0.1:52898","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":"warn","ts":"2026-04-04T10:59:13.90346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.562579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-04T10:59:13.903513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:59:13.599409Z","time spent":"304.094942ms","remote":"127.0.0.1:52898","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":"info","ts":"2026-04-04T10:59:13.903543Z","caller":"traceutil/trace.go:171","msg":"trace[1810529383] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:21297; }","duration":"383.693632ms","start":"2026-04-04T10:59:13.519827Z","end":"2026-04-04T10:59:13.903521Z","steps":["trace[1810529383] 'agreement among raft nodes before linearized reading' (duration: 383.478548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:59:13.903632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:59:13.519811Z","time spent":"383.768134ms","remote":"127.0.0.1:53056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":32,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-04T10:59:13.903648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.666949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-04T10:59:13.90375Z","caller":"traceutil/trace.go:171","msg":"trace[976219261] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:21297; }","duration":"212.858823ms","start":"2026-04-04T10:59:13.69087Z","end":"2026-04-04T10:59:13.903729Z","steps":["trace[976219261] 'agreement among raft nodes before linearized reading' (duration: 212.649488ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:59:15.171502Z","caller":"traceutil/trace.go:171","msg":"trace[1280534747] linearizableReadLoop","detail":"{readStateIndex:22023; appliedIndex:22022; }","duration":"281.183975ms","start":"2026-04-04T10:59:14.890287Z","end":"2026-04-04T10:59:15.171471Z","steps":["trace[1280534747] 'read index received' (duration: 280.821088ms)","trace[1280534747] 'applied index is now lower than readState.Index' (duration: 361.707µs)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:59:15.17171Z","caller":"traceutil/trace.go:171","msg":"trace[1439725723] transaction","detail":"{read_only:false; response_revision:21300; number_of_response:1; }","duration":"321.829588ms","start":"2026-04-04T10:59:14.849848Z","end":"2026-04-04T10:59:15.171677Z","steps":["trace[1439725723] 'process raft request' (duration: 321.371328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:59:15.171744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.43816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-04-04T10:59:15.171788Z","caller":"traceutil/trace.go:171","msg":"trace[2030473881] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21300; }","duration":"281.556123ms","start":"2026-04-04T10:59:14.890219Z","end":"2026-04-04T10:59:15.171775Z","steps":["trace[2030473881] 'agreement among raft nodes before linearized reading' (duration: 281.377269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:59:15.171878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:59:14.849813Z","time spent":"321.969321ms","remote":"127.0.0.1:52898","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-04-04T10:59:15.323619Z","caller":"traceutil/trace.go:171","msg":"trace[724387141] transaction","detail":"{read_only:false; response_revision:21301; number_of_response:1; }","duration":"147.154357ms","start":"2026-04-04T10:59:15.176434Z","end":"2026-04-04T10:59:15.323588Z","steps":["trace[724387141] 'process raft request' (duration: 145.580913ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:00:16.785831Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19707} {"level":"info","ts":"2026-04-04T11:00:16.913885Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19707,"took":"123.088016ms","hash":1491881696,"current-db-size-bytes":59236352,"current-db-size":"59 MB","current-db-size-in-use-bytes":29700096,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-04T11:00:16.91397Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1491881696,"revision":19707,"compact-revision":17384} {"level":"info","ts":"2026-04-04T11:05:16.794369Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21961} {"level":"info","ts":"2026-04-04T11:05:16.908048Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21961,"took":"110.425095ms","hash":2262385580,"current-db-size-bytes":59236352,"current-db-size":"59 MB","current-db-size-in-use-bytes":42754048,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-04-04T11:05:16.908386Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2262385580,"revision":21961,"compact-revision":19707} {"level":"warn","ts":"2026-04-04T11:07:04.429773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.598798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-04T11:07:04.429921Z","caller":"traceutil/trace.go:171","msg":"trace[792960967] transaction","detail":"{read_only:false; response_revision:26098; number_of_response:1; }","duration":"189.738961ms","start":"2026-04-04T11:07:04.240159Z","end":"2026-04-04T11:07:04.429898Z","steps":["trace[792960967] 'process raft request' (duration: 55.916898ms)","trace[792960967] 'compare' (duration: 133.455966ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T11:07:04.830094Z","caller":"traceutil/trace.go:171","msg":"trace[1893133602] transaction","detail":"{read_only:false; response_revision:26104; number_of_response:1; }","duration":"222.813315ms","start":"2026-04-04T11:07:04.607251Z","end":"2026-04-04T11:07:04.830064Z","steps":["trace[1893133602] 'process raft request' (duration: 222.59536ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:04.830353Z","caller":"traceutil/trace.go:171","msg":"trace[1892942375] linearizableReadLoop","detail":"{readStateIndex:26958; appliedIndex:26955; }","duration":"222.630829ms","start":"2026-04-04T11:07:04.607712Z","end":"2026-04-04T11:07:04.830343Z","steps":["trace[1892942375] 'read index received' (duration: 31.962329ms)","trace[1892942375] 'applied index is now lower than readState.Index' (duration: 190.6674ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T11:07:04.830527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.798393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/container-infra-registry.18a322b0238cd2b6\" ","response":"range_response_count:1 size:722"} {"level":"info","ts":"2026-04-04T11:07:04.830561Z","caller":"traceutil/trace.go:171","msg":"trace[154199196] range","detail":"{range_begin:/registry/events/openstack/container-infra-registry.18a322b0238cd2b6; range_end:; response_count:1; response_revision:26105; }","duration":"222.858405ms","start":"2026-04-04T11:07:04.607691Z","end":"2026-04-04T11:07:04.830549Z","steps":["trace[154199196] 'agreement among raft nodes before linearized reading' (duration: 222.686611ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:04.830714Z","caller":"traceutil/trace.go:171","msg":"trace[898027977] transaction","detail":"{read_only:false; response_revision:26105; number_of_response:1; }","duration":"219.703557ms","start":"2026-04-04T11:07:04.610969Z","end":"2026-04-04T11:07:04.830672Z","steps":["trace[898027977] 'process raft request' (duration: 219.048944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:04.983269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.210082ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-04T11:07:04.983495Z","caller":"traceutil/trace.go:171","msg":"trace[1564558889] transaction","detail":"{read_only:false; response_revision:26106; number_of_response:1; }","duration":"149.380385ms","start":"2026-04-04T11:07:04.834055Z","end":"2026-04-04T11:07:04.983436Z","steps":["trace[1564558889] 'process raft request' (duration: 47.843686ms)","trace[1564558889] 'compare' (duration: 100.780692ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T11:07:05.657008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.03858ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-04-04T11:07:05.657419Z","caller":"traceutil/trace.go:171","msg":"trace[1167432622] linearizableReadLoop","detail":"{readStateIndex:26962; appliedIndex:26960; }","duration":"194.657304ms","start":"2026-04-04T11:07:05.462738Z","end":"2026-04-04T11:07:05.657395Z","steps":["trace[1167432622] 'read index received' (duration: 184.368877ms)","trace[1167432622] 'applied index is now lower than readState.Index' (duration: 10.286537ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T11:07:05.657607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.826489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-04T11:07:05.657657Z","caller":"traceutil/trace.go:171","msg":"trace[273068730] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:26107; }","duration":"194.956241ms","start":"2026-04-04T11:07:05.462681Z","end":"2026-04-04T11:07:05.657638Z","steps":["trace[273068730] 'agreement among raft nodes before linearized reading' (duration: 194.786898ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:05.658012Z","caller":"traceutil/trace.go:171","msg":"trace[292120081] transaction","detail":"{read_only:false; response_revision:26107; number_of_response:1; }","duration":"258.853919ms","start":"2026-04-04T11:07:05.399102Z","end":"2026-04-04T11:07:05.657956Z","steps":["trace[292120081] 'process raft request' (duration: 258.067583ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:10.783956Z","caller":"traceutil/trace.go:171","msg":"trace[237940095] transaction","detail":"{read_only:false; response_revision:26148; number_of_response:1; }","duration":"110.767344ms","start":"2026-04-04T11:07:10.673152Z","end":"2026-04-04T11:07:10.783919Z","steps":["trace[237940095] 'process raft request' (duration: 110.513058ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:10.787517Z","caller":"traceutil/trace.go:171","msg":"trace[1179092957] transaction","detail":"{read_only:false; response_revision:26149; number_of_response:1; }","duration":"114.053534ms","start":"2026-04-04T11:07:10.67343Z","end":"2026-04-04T11:07:10.787483Z","steps":["trace[1179092957] 'process raft request' (duration: 113.970631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:16.308099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.601038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-04T11:07:16.308216Z","caller":"traceutil/trace.go:171","msg":"trace[966016774] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:26188; }","duration":"237.807713ms","start":"2026-04-04T11:07:16.070379Z","end":"2026-04-04T11:07:16.308187Z","steps":["trace[966016774] 'count revisions from in-memory index tree' (duration: 237.392524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:16.308439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.891743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-04T11:07:16.308602Z","caller":"traceutil/trace.go:171","msg":"trace[475963020] transaction","detail":"{read_only:false; response_revision:26189; number_of_response:1; }","duration":"168.925659ms","start":"2026-04-04T11:07:16.139641Z","end":"2026-04-04T11:07:16.308567Z","steps":["trace[475963020] 'process raft request' (duration: 35.778811ms)","trace[475963020] 'compare' (duration: 132.688278ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T11:07:16.592424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.793648ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-04T11:07:16.592583Z","caller":"traceutil/trace.go:171","msg":"trace[505816724] transaction","detail":"{read_only:false; response_revision:26190; number_of_response:1; }","duration":"249.48575ms","start":"2026-04-04T11:07:16.34307Z","end":"2026-04-04T11:07:16.592556Z","steps":["trace[505816724] 'process raft request' (duration: 103.429887ms)","trace[505816724] 'compare' (duration: 145.464501ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T11:07:16.672035Z","caller":"traceutil/trace.go:171","msg":"trace[540071406] transaction","detail":"{read_only:false; response_revision:26191; number_of_response:1; }","duration":"189.749012ms","start":"2026-04-04T11:07:16.48224Z","end":"2026-04-04T11:07:16.671989Z","steps":["trace[540071406] 'process raft request' (duration: 189.519777ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:16.672132Z","caller":"traceutil/trace.go:171","msg":"trace[535012544] linearizableReadLoop","detail":"{readStateIndex:27048; appliedIndex:27047; }","duration":"145.217766ms","start":"2026-04-04T11:07:16.526871Z","end":"2026-04-04T11:07:16.672089Z","steps":["trace[535012544] 'read index received' (duration: 144.821617ms)","trace[535012544] 'applied index is now lower than readState.Index' (duration: 393.439µs)"],"step_count":2} {"level":"warn","ts":"2026-04-04T11:07:16.672323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.416129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-04T11:07:16.672378Z","caller":"traceutil/trace.go:171","msg":"trace[1596177704] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:26191; }","duration":"145.550422ms","start":"2026-04-04T11:07:16.526811Z","end":"2026-04-04T11:07:16.672362Z","steps":["trace[1596177704] 'agreement among raft nodes before linearized reading' (duration: 145.414189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:21.660238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.460054ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-04T11:07:21.660375Z","caller":"traceutil/trace.go:171","msg":"trace[159761299] linearizableReadLoop","detail":"{readStateIndex:27091; appliedIndex:27090; }","duration":"304.950979ms","start":"2026-04-04T11:07:21.355404Z","end":"2026-04-04T11:07:21.660355Z","steps":["trace[159761299] 'read index received' (duration: 44.284761ms)","trace[159761299] 'applied index is now lower than readState.Index' (duration: 260.664508ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T11:07:21.660693Z","caller":"traceutil/trace.go:171","msg":"trace[1660559593] transaction","detail":"{read_only:false; response_revision:26233; number_of_response:1; }","duration":"345.47137ms","start":"2026-04-04T11:07:21.315207Z","end":"2026-04-04T11:07:21.660678Z","steps":["trace[1660559593] 'process raft request' (duration: 84.443774ms)","trace[1660559593] 'compare' (duration: 260.161258ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T11:07:21.660774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:21.315179Z","time spent":"345.557402ms","remote":"127.0.0.1:52898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-04T11:07:21.661042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.94258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-04T11:07:21.661182Z","caller":"traceutil/trace.go:171","msg":"trace[807196418] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:26233; }","duration":"258.133115ms","start":"2026-04-04T11:07:21.403014Z","end":"2026-04-04T11:07:21.661147Z","steps":["trace[807196418] 'agreement among raft nodes before linearized reading' (duration: 257.812758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:21.661058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.640403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-04T11:07:21.661306Z","caller":"traceutil/trace.go:171","msg":"trace[1171212105] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:26233; }","duration":"305.9334ms","start":"2026-04-04T11:07:21.35535Z","end":"2026-04-04T11:07:21.661284Z","steps":["trace[1171212105] 'agreement among raft nodes before linearized reading' (duration: 305.443449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:21.661372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:21.355324Z","time spent":"306.027762ms","remote":"127.0.0.1:35212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true "} {"level":"warn","ts":"2026-04-04T11:07:21.661062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.460287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-04-04T11:07:21.661529Z","caller":"traceutil/trace.go:171","msg":"trace[878031109] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:26233; }","duration":"271.988799ms","start":"2026-04-04T11:07:21.389514Z","end":"2026-04-04T11:07:21.661503Z","steps":["trace[878031109] 'agreement among raft nodes before linearized reading' (duration: 271.285844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:21.954699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.560565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-04T11:07:21.954826Z","caller":"traceutil/trace.go:171","msg":"trace[425921112] transaction","detail":"{read_only:false; response_revision:26234; number_of_response:1; }","duration":"239.911608ms","start":"2026-04-04T11:07:21.714887Z","end":"2026-04-04T11:07:21.954798Z","steps":["trace[425921112] 'process raft request' (duration: 124.143329ms)","trace[425921112] 'compare' (duration: 115.418792ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T11:07:24.837358Z","caller":"traceutil/trace.go:171","msg":"trace[257307557] transaction","detail":"{read_only:false; response_revision:26257; number_of_response:1; }","duration":"133.876845ms","start":"2026-04-04T11:07:24.703429Z","end":"2026-04-04T11:07:24.837305Z","steps":["trace[257307557] 'process raft request' (duration: 133.66501ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:26.111498Z","caller":"traceutil/trace.go:171","msg":"trace[74191848] transaction","detail":"{read_only:false; response_revision:26262; number_of_response:1; }","duration":"143.944159ms","start":"2026-04-04T11:07:25.967522Z","end":"2026-04-04T11:07:26.111466Z","steps":["trace[74191848] 'process raft request' (duration: 143.677914ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:26.11572Z","caller":"traceutil/trace.go:171","msg":"trace[688752366] transaction","detail":"{read_only:false; response_revision:26264; number_of_response:1; }","duration":"134.815915ms","start":"2026-04-04T11:07:25.98087Z","end":"2026-04-04T11:07:26.115686Z","steps":["trace[688752366] 'process raft request' (duration: 134.713712ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:26.115785Z","caller":"traceutil/trace.go:171","msg":"trace[751894629] transaction","detail":"{read_only:false; response_revision:26263; number_of_response:1; }","duration":"147.222137ms","start":"2026-04-04T11:07:25.968496Z","end":"2026-04-04T11:07:26.115718Z","steps":["trace[751894629] 'process raft request' (duration: 146.86142ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:26.116465Z","caller":"traceutil/trace.go:171","msg":"trace[215819929] transaction","detail":"{read_only:false; response_revision:26265; number_of_response:1; }","duration":"134.458186ms","start":"2026-04-04T11:07:25.981992Z","end":"2026-04-04T11:07:26.11645Z","steps":["trace[215819929] 'process raft request' (duration: 133.639329ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:26.117366Z","caller":"traceutil/trace.go:171","msg":"trace[2068910690] transaction","detail":"{read_only:false; response_revision:26266; number_of_response:1; }","duration":"134.980107ms","start":"2026-04-04T11:07:25.982372Z","end":"2026-04-04T11:07:26.117352Z","steps":["trace[2068910690] 'process raft request' (duration: 133.989956ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:26.118248Z","caller":"traceutil/trace.go:171","msg":"trace[264342968] transaction","detail":"{read_only:false; response_revision:26267; number_of_response:1; }","duration":"126.980448ms","start":"2026-04-04T11:07:25.99125Z","end":"2026-04-04T11:07:26.11823Z","steps":["trace[264342968] 'process raft request' (duration: 126.026048ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:27.013225Z","caller":"traceutil/trace.go:171","msg":"trace[823624455] linearizableReadLoop","detail":"{readStateIndex:27129; appliedIndex:27128; }","duration":"187.652316ms","start":"2026-04-04T11:07:26.825546Z","end":"2026-04-04T11:07:27.013198Z","steps":["trace[823624455] 'read index received' (duration: 109.23141ms)","trace[823624455] 'applied index is now lower than readState.Index' (duration: 78.419896ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T11:07:27.013304Z","caller":"traceutil/trace.go:171","msg":"trace[1956598673] transaction","detail":"{read_only:false; response_revision:26270; number_of_response:1; }","duration":"346.464801ms","start":"2026-04-04T11:07:26.666769Z","end":"2026-04-04T11:07:27.013234Z","steps":["trace[1956598673] 'process raft request' (duration: 268.019284ms)","trace[1956598673] 'compare' (duration: 78.166991ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T11:07:27.013433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.865262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-04T11:07:27.013485Z","caller":"traceutil/trace.go:171","msg":"trace[943861991] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:26270; }","duration":"187.995014ms","start":"2026-04-04T11:07:26.82548Z","end":"2026-04-04T11:07:27.013475Z","steps":["trace[943861991] 'agreement among raft nodes before linearized reading' (duration: 187.81229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:27.013687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:26.666738Z","time spent":"346.674436ms","remote":"127.0.0.1:52898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-04T11:07:27.327975Z","caller":"traceutil/trace.go:171","msg":"trace[1847908515] transaction","detail":"{read_only:false; response_revision:26271; number_of_response:1; }","duration":"483.84914ms","start":"2026-04-04T11:07:26.844092Z","end":"2026-04-04T11:07:27.327942Z","steps":["trace[1847908515] 'process raft request' (duration: 475.534804ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:27.328125Z","caller":"traceutil/trace.go:171","msg":"trace[122375408] linearizableReadLoop","detail":"{readStateIndex:27131; appliedIndex:27129; }","duration":"314.784228ms","start":"2026-04-04T11:07:27.013304Z","end":"2026-04-04T11:07:27.328088Z","steps":["trace[122375408] 'read index received' (duration: 306.261156ms)","trace[122375408] 'applied index is now lower than readState.Index' (duration: 8.520441ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T11:07:27.328173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:26.844059Z","time spent":"484.000904ms","remote":"127.0.0.1:52898","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-04-04T11:07:27.328147Z","caller":"traceutil/trace.go:171","msg":"trace[11713437] transaction","detail":"{read_only:false; response_revision:26272; number_of_response:1; }","duration":"401.769586ms","start":"2026-04-04T11:07:26.926331Z","end":"2026-04-04T11:07:27.3281Z","steps":["trace[11713437] 'process raft request' (duration: 401.465969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:27.328421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.944495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-04T11:07:27.32853Z","caller":"traceutil/trace.go:171","msg":"trace[1557135799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26272; }","duration":"454.130748ms","start":"2026-04-04T11:07:26.874379Z","end":"2026-04-04T11:07:27.328509Z","steps":["trace[1557135799] 'agreement among raft nodes before linearized reading' (duration: 453.787041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:27.328472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.186551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-04T11:07:27.328663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:26.926301Z","time spent":"401.93777ms","remote":"127.0.0.1:52898","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-04-04T11:07:27.328697Z","caller":"traceutil/trace.go:171","msg":"trace[1416842085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26272; }","duration":"455.460247ms","start":"2026-04-04T11:07:26.873209Z","end":"2026-04-04T11:07:27.328669Z","steps":["trace[1416842085] 'agreement among raft nodes before linearized reading' (duration: 454.978327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:27.328427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.574208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-04-04T11:07:27.328927Z","caller":"traceutil/trace.go:171","msg":"trace[1743469619] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26272; }","duration":"383.139459ms","start":"2026-04-04T11:07:26.945757Z","end":"2026-04-04T11:07:27.328897Z","steps":["trace[1743469619] 'agreement among raft nodes before linearized reading' (duration: 382.360863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:27.328938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:26.873188Z","time spent":"455.705011ms","remote":"127.0.0.1:52898","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":"warn","ts":"2026-04-04T11:07:27.329009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:26.945741Z","time spent":"383.249152ms","remote":"127.0.0.1:52898","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-04-04T11:07:27.328593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:26.874352Z","time spent":"454.227941ms","remote":"127.0.0.1:52898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-04T11:07:27.627811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.070639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-04T11:07:27.627946Z","caller":"traceutil/trace.go:171","msg":"trace[109906953] linearizableReadLoop","detail":"{readStateIndex:27134; appliedIndex:27131; }","duration":"273.91999ms","start":"2026-04-04T11:07:27.354007Z","end":"2026-04-04T11:07:27.627927Z","steps":["trace[109906953] 'read index received' (duration: 18.429082ms)","trace[109906953] 'applied index is now lower than readState.Index' (duration: 255.489638ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T11:07:27.62803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.026232ms","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-04-04T11:07:27.628061Z","caller":"traceutil/trace.go:171","msg":"trace[421482014] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:26275; }","duration":"274.090704ms","start":"2026-04-04T11:07:27.353957Z","end":"2026-04-04T11:07:27.628047Z","steps":["trace[421482014] 'agreement among raft nodes before linearized reading' (duration: 274.021202ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:27.628068Z","caller":"traceutil/trace.go:171","msg":"trace[1096897716] transaction","detail":"{read_only:false; response_revision:26275; number_of_response:1; }","duration":"294.427946ms","start":"2026-04-04T11:07:27.333568Z","end":"2026-04-04T11:07:27.627996Z","steps":["trace[1096897716] 'process raft request' (duration: 152.054291ms)","trace[1096897716] 'compare' (duration: 141.542287ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T11:07:28.494841Z","caller":"traceutil/trace.go:171","msg":"trace[1723667029] linearizableReadLoop","detail":"{readStateIndex:27140; appliedIndex:27134; }","duration":"367.125221ms","start":"2026-04-04T11:07:28.12769Z","end":"2026-04-04T11:07:28.494815Z","steps":["trace[1723667029] 'read index received' (duration: 33.648425ms)","trace[1723667029] 'applied index is now lower than readState.Index' (duration: 333.475746ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T11:07:28.495184Z","caller":"traceutil/trace.go:171","msg":"trace[1985098269] transaction","detail":"{read_only:false; response_revision:26277; number_of_response:1; }","duration":"373.091448ms","start":"2026-04-04T11:07:28.122079Z","end":"2026-04-04T11:07:28.495171Z","steps":["trace[1985098269] 'process raft request' (duration: 372.329421ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:28.495314Z","caller":"traceutil/trace.go:171","msg":"trace[242494078] transaction","detail":"{read_only:false; response_revision:26281; number_of_response:1; }","duration":"370.619435ms","start":"2026-04-04T11:07:28.124648Z","end":"2026-04-04T11:07:28.495268Z","steps":["trace[242494078] 'process raft request' (duration: 370.121774ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:28.495355Z","caller":"traceutil/trace.go:171","msg":"trace[389185768] transaction","detail":"{read_only:false; response_revision:26279; number_of_response:1; }","duration":"372.232949ms","start":"2026-04-04T11:07:28.123092Z","end":"2026-04-04T11:07:28.495325Z","steps":["trace[389185768] 'process raft request' (duration: 371.590035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:28.495362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:28.122038Z","time spent":"373.202351ms","remote":"127.0.0.1:52898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-04T11:07:28.495426Z","caller":"traceutil/trace.go:171","msg":"trace[1351790026] transaction","detail":"{read_only:false; response_revision:26280; number_of_response:1; }","duration":"371.217468ms","start":"2026-04-04T11:07:28.124185Z","end":"2026-04-04T11:07:28.495403Z","steps":["trace[1351790026] 'process raft request' (duration: 370.546253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:28.495468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:28.123075Z","time spent":"372.336471ms","remote":"127.0.0.1:52898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-04T11:07:28.495496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:28.124635Z","time spent":"370.757769ms","remote":"127.0.0.1:52898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-04T11:07:28.4955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:28.124175Z","time spent":"371.287139ms","remote":"127.0.0.1:52898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-04T11:07:28.495592Z","caller":"traceutil/trace.go:171","msg":"trace[1415099419] transaction","detail":"{read_only:false; response_revision:26278; number_of_response:1; }","duration":"373.397305ms","start":"2026-04-04T11:07:28.12218Z","end":"2026-04-04T11:07:28.495577Z","steps":["trace[1415099419] 'process raft request' (duration: 372.446894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:28.495665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:28.122122Z","time spent":"373.502277ms","remote":"127.0.0.1:52898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-04T11:07:28.49568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.985649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-04T11:07:28.495732Z","caller":"traceutil/trace.go:171","msg":"trace[1310855917] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26281; }","duration":"368.072222ms","start":"2026-04-04T11:07:28.127646Z","end":"2026-04-04T11:07:28.495718Z","steps":["trace[1310855917] 'agreement among raft nodes before linearized reading' (duration: 367.890938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:28.495773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:28.127631Z","time spent":"368.131692ms","remote":"127.0.0.1:52898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-04T11:07:28.495896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.172811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-04T11:07:28.495921Z","caller":"traceutil/trace.go:171","msg":"trace[1374805519] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26281; }","duration":"254.235183ms","start":"2026-04-04T11:07:28.241679Z","end":"2026-04-04T11:07:28.495914Z","steps":["trace[1374805519] 'agreement among raft nodes before linearized reading' (duration: 253.979107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:28.842708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.259621ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-04T11:07:28.842833Z","caller":"traceutil/trace.go:171","msg":"trace[1592658879] linearizableReadLoop","detail":"{readStateIndex:27141; appliedIndex:27140; }","duration":"341.071697ms","start":"2026-04-04T11:07:28.501741Z","end":"2026-04-04T11:07:28.842812Z","steps":["trace[1592658879] 'read index received' (duration: 71.523049ms)","trace[1592658879] 'applied index is now lower than readState.Index' (duration: 269.546988ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T11:07:28.842938Z","caller":"traceutil/trace.go:171","msg":"trace[354548007] transaction","detail":"{read_only:false; response_revision:26282; number_of_response:1; }","duration":"341.65786ms","start":"2026-04-04T11:07:28.501267Z","end":"2026-04-04T11:07:28.842925Z","steps":["trace[354548007] 'process raft request' (duration: 72.082292ms)","trace[354548007] 'compare' (duration: 269.054977ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T11:07:28.843025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:28.501238Z","time spent":"341.735321ms","remote":"127.0.0.1:52898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-04T11:07:28.843199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.233799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-04T11:07:28.843298Z","caller":"traceutil/trace.go:171","msg":"trace[2062753858] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:26282; }","duration":"204.396793ms","start":"2026-04-04T11:07:28.638876Z","end":"2026-04-04T11:07:28.843273Z","steps":["trace[2062753858] 'agreement among raft nodes before linearized reading' (duration: 204.218929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:28.843849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.982886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18589"} {"level":"warn","ts":"2026-04-04T11:07:28.843951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.052681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-04T11:07:28.844025Z","caller":"traceutil/trace.go:171","msg":"trace[58933953] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26282; }","duration":"342.251552ms","start":"2026-04-04T11:07:28.501725Z","end":"2026-04-04T11:07:28.843977Z","steps":["trace[58933953] 'agreement among raft nodes before linearized reading' (duration: 341.494356ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:28.844043Z","caller":"traceutil/trace.go:171","msg":"trace[438463240] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26282; }","duration":"112.201694ms","start":"2026-04-04T11:07:28.731817Z","end":"2026-04-04T11:07:28.844018Z","steps":["trace[438463240] 'agreement among raft nodes before linearized reading' (duration: 111.711264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:28.844126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:28.501718Z","time spent":"342.398045ms","remote":"127.0.0.1:52814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18613,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-04T11:07:29.542707Z","caller":"traceutil/trace.go:171","msg":"trace[437941741] transaction","detail":"{read_only:false; response_revision:26284; number_of_response:1; }","duration":"463.184751ms","start":"2026-04-04T11:07:29.079497Z","end":"2026-04-04T11:07:29.542682Z","steps":["trace[437941741] 'process raft request' (duration: 462.987316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:29.543096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:29.079462Z","time spent":"463.334075ms","remote":"127.0.0.1:52696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-04T11:07:29.561788Z","caller":"traceutil/trace.go:171","msg":"trace[1596119792] linearizableReadLoop","detail":"{readStateIndex:27145; appliedIndex:27142; }","duration":"184.672224ms","start":"2026-04-04T11:07:29.377088Z","end":"2026-04-04T11:07:29.56176Z","steps":["trace[1596119792] 'read index received' (duration: 165.441915ms)","trace[1596119792] 'applied index is now lower than readState.Index' (duration: 19.228599ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T11:07:29.561852Z","caller":"traceutil/trace.go:171","msg":"trace[866215102] transaction","detail":"{read_only:false; response_revision:26285; number_of_response:1; }","duration":"227.539065ms","start":"2026-04-04T11:07:29.334265Z","end":"2026-04-04T11:07:29.561804Z","steps":["trace[866215102] 'process raft request' (duration: 227.251029ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:29.561873Z","caller":"traceutil/trace.go:171","msg":"trace[80564041] transaction","detail":"{read_only:false; response_revision:26286; number_of_response:1; }","duration":"227.074544ms","start":"2026-04-04T11:07:29.334755Z","end":"2026-04-04T11:07:29.561829Z","steps":["trace[80564041] 'process raft request' (duration: 226.945662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:29.562098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.00005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-04-04T11:07:29.5622Z","caller":"traceutil/trace.go:171","msg":"trace[2001328554] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26286; }","duration":"185.142754ms","start":"2026-04-04T11:07:29.377035Z","end":"2026-04-04T11:07:29.562177Z","steps":["trace[2001328554] 'agreement among raft nodes before linearized reading' (duration: 184.902949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:29.562296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.389547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-04T11:07:29.562339Z","caller":"traceutil/trace.go:171","msg":"trace[579027410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26286; }","duration":"184.509131ms","start":"2026-04-04T11:07:29.377817Z","end":"2026-04-04T11:07:29.562326Z","steps":["trace[579027410] 'agreement among raft nodes before linearized reading' (duration: 184.280365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:29.562411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.296089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-04T11:07:29.562541Z","caller":"traceutil/trace.go:171","msg":"trace[214391373] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:26286; }","duration":"122.519574ms","start":"2026-04-04T11:07:29.439987Z","end":"2026-04-04T11:07:29.562506Z","steps":["trace[214391373] 'agreement among raft nodes before linearized reading' (duration: 122.238668ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:29.756499Z","caller":"traceutil/trace.go:171","msg":"trace[383148727] transaction","detail":"{read_only:false; response_revision:26287; number_of_response:1; }","duration":"191.015779ms","start":"2026-04-04T11:07:29.565423Z","end":"2026-04-04T11:07:29.756439Z","steps":["trace[383148727] 'process raft request' (duration: 154.36242ms)","trace[383148727] 'compare' (duration: 36.065147ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T11:07:30.110783Z","caller":"traceutil/trace.go:171","msg":"trace[425794648] linearizableReadLoop","detail":"{readStateIndex:27148; appliedIndex:27145; }","duration":"542.100887ms","start":"2026-04-04T11:07:29.568653Z","end":"2026-04-04T11:07:30.110754Z","steps":["trace[425794648] 'read index received' (duration: 151.163962ms)","trace[425794648] 'applied index is now lower than readState.Index' (duration: 390.935095ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T11:07:30.11097Z","caller":"traceutil/trace.go:171","msg":"trace[1908025482] transaction","detail":"{read_only:false; response_revision:26289; number_of_response:1; }","duration":"542.873853ms","start":"2026-04-04T11:07:29.568015Z","end":"2026-04-04T11:07:30.110889Z","steps":["trace[1908025482] 'process raft request' (duration: 542.597977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:30.110965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.296141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-04T11:07:30.111029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.866073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-04T11:07:30.111078Z","caller":"traceutil/trace.go:171","msg":"trace[337191955] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26289; }","duration":"270.012017ms","start":"2026-04-04T11:07:29.841054Z","end":"2026-04-04T11:07:30.111066Z","steps":["trace[337191955] 'agreement among raft nodes before linearized reading' (duration: 269.890864ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:30.111084Z","caller":"traceutil/trace.go:171","msg":"trace[985831040] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26289; }","duration":"542.474475ms","start":"2026-04-04T11:07:29.568585Z","end":"2026-04-04T11:07:30.111059Z","steps":["trace[985831040] 'agreement among raft nodes before linearized reading' (duration: 542.301711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:30.111132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:29.568559Z","time spent":"542.563737ms","remote":"127.0.0.1:52840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-04-04T11:07:30.111168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.986064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-04T11:07:30.111251Z","caller":"traceutil/trace.go:171","msg":"trace[219792374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26289; }","duration":"477.149448ms","start":"2026-04-04T11:07:29.634089Z","end":"2026-04-04T11:07:30.111238Z","steps":["trace[219792374] 'agreement among raft nodes before linearized reading' (duration: 476.860692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:30.1113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:29.634069Z","time spent":"477.22319ms","remote":"127.0.0.1:52898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-04T11:07:30.111199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:29.567995Z","time spent":"543.073898ms","remote":"127.0.0.1:52898","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-04-04T11:07:30.110882Z","caller":"traceutil/trace.go:171","msg":"trace[534654499] transaction","detail":"{read_only:false; response_revision:26288; number_of_response:1; }","duration":"543.572929ms","start":"2026-04-04T11:07:29.567269Z","end":"2026-04-04T11:07:30.110842Z","steps":["trace[534654499] 'process raft request' (duration: 470.721632ms)","trace[534654499] 'compare' (duration: 72.309056ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T11:07:30.11181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:29.567222Z","time spent":"544.53432ms","remote":"127.0.0.1:52898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-04T11:07:30.261967Z","caller":"traceutil/trace.go:171","msg":"trace[1930258648] transaction","detail":"{read_only:false; response_revision:26290; number_of_response:1; }","duration":"144.569251ms","start":"2026-04-04T11:07:30.117326Z","end":"2026-04-04T11:07:30.261896Z","steps":["trace[1930258648] 'process raft request' (duration: 143.188042ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:30.262066Z","caller":"traceutil/trace.go:171","msg":"trace[830999670] linearizableReadLoop","detail":"{readStateIndex:27149; appliedIndex:27148; }","duration":"144.584351ms","start":"2026-04-04T11:07:30.117453Z","end":"2026-04-04T11:07:30.262037Z","steps":["trace[830999670] 'read index received' (duration: 143.076319ms)","trace[830999670] 'applied index is now lower than readState.Index' (duration: 1.506242ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T11:07:30.262298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.814357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-04T11:07:30.262349Z","caller":"traceutil/trace.go:171","msg":"trace[1231144995] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26291; }","duration":"144.902488ms","start":"2026-04-04T11:07:30.11743Z","end":"2026-04-04T11:07:30.262332Z","steps":["trace[1231144995] 'agreement among raft nodes before linearized reading' (duration: 144.798526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:30.262811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.037467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.69\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-04-04T11:07:30.262753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.009913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/auth-system/keycloak\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-04-04T11:07:30.262845Z","caller":"traceutil/trace.go:171","msg":"trace[1974134965] range","detail":"{range_begin:/registry/masterleases/199.204.45.69; range_end:; response_count:1; response_revision:26291; }","duration":"117.093598ms","start":"2026-04-04T11:07:30.145744Z","end":"2026-04-04T11:07:30.262837Z","steps":["trace[1974134965] 'agreement among raft nodes before linearized reading' (duration: 116.951465ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:30.262884Z","caller":"traceutil/trace.go:171","msg":"trace[1542891438] range","detail":"{range_begin:/registry/serviceaccounts/auth-system/keycloak; range_end:; response_count:1; response_revision:26291; }","duration":"130.213447ms","start":"2026-04-04T11:07:30.132643Z","end":"2026-04-04T11:07:30.262857Z","steps":["trace[1542891438] 'agreement among raft nodes before linearized reading' (duration: 129.904661ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:30.840289Z","caller":"traceutil/trace.go:171","msg":"trace[106587718] transaction","detail":"{read_only:false; response_revision:26294; number_of_response:1; }","duration":"336.102441ms","start":"2026-04-04T11:07:30.504158Z","end":"2026-04-04T11:07:30.840261Z","steps":["trace[106587718] 'process raft request' (duration: 335.820715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:30.84049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:30.504127Z","time spent":"336.257595ms","remote":"127.0.0.1:52898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-04T11:07:30.840712Z","caller":"traceutil/trace.go:171","msg":"trace[1423512880] transaction","detail":"{read_only:false; response_revision:26295; number_of_response:1; }","duration":"336.307615ms","start":"2026-04-04T11:07:30.504354Z","end":"2026-04-04T11:07:30.840661Z","steps":["trace[1423512880] 'process raft request' (duration: 335.848896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:30.840989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:30.504348Z","time spent":"336.510609ms","remote":"127.0.0.1:52898","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-04-04T11:07:30.841084Z","caller":"traceutil/trace.go:171","msg":"trace[1330272188] transaction","detail":"{read_only:false; response_revision:26296; number_of_response:1; }","duration":"336.608412ms","start":"2026-04-04T11:07:30.504422Z","end":"2026-04-04T11:07:30.84103Z","steps":["trace[1330272188] 'process raft request' (duration: 336.092291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:30.841161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:30.504415Z","time spent":"336.709414ms","remote":"127.0.0.1:52898","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-04-04T11:07:30.841177Z","caller":"traceutil/trace.go:171","msg":"trace[1033675508] transaction","detail":"{read_only:false; response_revision:26297; number_of_response:1; }","duration":"335.978219ms","start":"2026-04-04T11:07:30.505053Z","end":"2026-04-04T11:07:30.841031Z","steps":["trace[1033675508] 'process raft request' (duration: 335.744694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:30.841477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:30.505027Z","time spent":"336.303015ms","remote":"127.0.0.1:52898","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-04-04T11:07:30.938041Z","caller":"traceutil/trace.go:171","msg":"trace[712260120] transaction","detail":"{read_only:false; response_revision:26298; number_of_response:1; }","duration":"138.709428ms","start":"2026-04-04T11:07:30.799277Z","end":"2026-04-04T11:07:30.937987Z","steps":["trace[712260120] 'process raft request' (duration: 116.682799ms)","trace[712260120] 'compare' (duration: 21.240111ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T11:07:31.836792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.206409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-04-04T11:07:31.836887Z","caller":"traceutil/trace.go:171","msg":"trace[564221569] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:26301; }","duration":"300.360521ms","start":"2026-04-04T11:07:31.536499Z","end":"2026-04-04T11:07:31.83686Z","steps":["trace[564221569] 'count revisions from in-memory index tree' (duration: 300.053475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:31.836895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.694938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-04T11:07:31.836979Z","caller":"traceutil/trace.go:171","msg":"trace[2011325185] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:26301; }","duration":"275.831301ms","start":"2026-04-04T11:07:31.561124Z","end":"2026-04-04T11:07:31.836956Z","steps":["trace[2011325185] 'count revisions from in-memory index tree' (duration: 275.580535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:31.837042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.819787ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"warn","ts":"2026-04-04T11:07:31.836929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:31.536478Z","time spent":"300.438504ms","remote":"127.0.0.1:52724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":141,"response size":33,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-04-04T11:07:31.837266Z","caller":"traceutil/trace.go:171","msg":"trace[149030095] transaction","detail":"{read_only:false; response_revision:26303; number_of_response:1; }","duration":"268.112597ms","start":"2026-04-04T11:07:31.569132Z","end":"2026-04-04T11:07:31.837244Z","steps":["trace[149030095] 'process raft request' (duration: 268.048336ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:31.837408Z","caller":"traceutil/trace.go:171","msg":"trace[1529811577] transaction","detail":"{read_only:false; response_revision:26302; number_of_response:1; }","duration":"269.109568ms","start":"2026-04-04T11:07:31.568279Z","end":"2026-04-04T11:07:31.837389Z","steps":["trace[1529811577] 'process raft request' (duration: 99.835821ms)","trace[1529811577] 'compare' (duration: 168.678934ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T11:07:32.303194Z","caller":"traceutil/trace.go:171","msg":"trace[145930842] linearizableReadLoop","detail":"{readStateIndex:27164; appliedIndex:27163; }","duration":"257.146064ms","start":"2026-04-04T11:07:32.046015Z","end":"2026-04-04T11:07:32.303161Z","steps":["trace[145930842] 'read index received' (duration: 256.93945ms)","trace[145930842] 'applied index is now lower than readState.Index' (duration: 204.794µs)"],"step_count":2} {"level":"info","ts":"2026-04-04T11:07:32.303473Z","caller":"traceutil/trace.go:171","msg":"trace[1408198167] transaction","detail":"{read_only:false; response_revision:26304; number_of_response:1; }","duration":"283.968163ms","start":"2026-04-04T11:07:32.019452Z","end":"2026-04-04T11:07:32.30342Z","steps":["trace[1408198167] 'process raft request' (duration: 283.537514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:32.303574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.512181ms","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":"info","ts":"2026-04-04T11:07:32.303964Z","caller":"traceutil/trace.go:171","msg":"trace[427992843] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:26304; }","duration":"257.973731ms","start":"2026-04-04T11:07:32.045965Z","end":"2026-04-04T11:07:32.303939Z","steps":["trace[427992843] 'agreement among raft nodes before linearized reading' (duration: 257.469381ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:32.512219Z","caller":"traceutil/trace.go:171","msg":"trace[904401934] linearizableReadLoop","detail":"{readStateIndex:27165; appliedIndex:27164; }","duration":"208.44498ms","start":"2026-04-04T11:07:32.303742Z","end":"2026-04-04T11:07:32.512187Z","steps":["trace[904401934] 'read index received' (duration: 205.695471ms)","trace[904401934] 'applied index is now lower than readState.Index' (duration: 2.748279ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T11:07:32.512456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.369873ms","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-04-04T11:07:32.512417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.114978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-04T11:07:32.512489Z","caller":"traceutil/trace.go:171","msg":"trace[354316322] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26305; }","duration":"396.487205ms","start":"2026-04-04T11:07:32.11599Z","end":"2026-04-04T11:07:32.512477Z","steps":["trace[354316322] 'agreement among raft nodes before linearized reading' (duration: 396.300361ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:32.51247Z","caller":"traceutil/trace.go:171","msg":"trace[220084821] transaction","detail":"{read_only:false; response_revision:26305; number_of_response:1; }","duration":"244.419554ms","start":"2026-04-04T11:07:32.26802Z","end":"2026-04-04T11:07:32.51244Z","steps":["trace[220084821] 'process raft request' (duration: 241.42814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:32.512524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:32.115955Z","time spent":"396.557456ms","remote":"127.0.0.1:52898","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":"info","ts":"2026-04-04T11:07:32.512515Z","caller":"traceutil/trace.go:171","msg":"trace[706021568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26305; }","duration":"244.269791ms","start":"2026-04-04T11:07:32.268221Z","end":"2026-04-04T11:07:32.512491Z","steps":["trace[706021568] 'agreement among raft nodes before linearized reading' (duration: 244.005226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:32.513286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.8928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-04T11:07:32.517861Z","caller":"traceutil/trace.go:171","msg":"trace[545996672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26305; }","duration":"399.53543ms","start":"2026-04-04T11:07:32.118306Z","end":"2026-04-04T11:07:32.517841Z","steps":["trace[545996672] 'agreement among raft nodes before linearized reading' (duration: 394.090164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:32.518078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:32.118282Z","time spent":"399.777114ms","remote":"127.0.0.1:52898","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-04-04T11:07:45.518549Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a91.wal"} {"level":"info","ts":"2026-04-04T11:07:47.145155Z","caller":"traceutil/trace.go:171","msg":"trace[12130755] linearizableReadLoop","detail":"{readStateIndex:27338; appliedIndex:27337; }","duration":"222.276152ms","start":"2026-04-04T11:07:46.922836Z","end":"2026-04-04T11:07:47.145113Z","steps":["trace[12130755] 'read index received' (duration: 221.923815ms)","trace[12130755] 'applied index is now lower than readState.Index' (duration: 350.917µs)"],"step_count":2} {"level":"info","ts":"2026-04-04T11:07:47.145339Z","caller":"traceutil/trace.go:171","msg":"trace[1871399363] transaction","detail":"{read_only:false; response_revision:26474; number_of_response:1; }","duration":"245.999007ms","start":"2026-04-04T11:07:46.899283Z","end":"2026-04-04T11:07:47.145282Z","steps":["trace[1871399363] 'process raft request' (duration: 245.531217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:47.145515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.647661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c39608e9-b91e-4539-9dd2-1dff316cf3a5\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-04-04T11:07:47.145566Z","caller":"traceutil/trace.go:171","msg":"trace[989360903] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c39608e9-b91e-4539-9dd2-1dff316cf3a5; range_end:; response_count:1; response_revision:26474; }","duration":"222.764883ms","start":"2026-04-04T11:07:46.922788Z","end":"2026-04-04T11:07:47.145553Z","steps":["trace[989360903] 'agreement among raft nodes before linearized reading' (duration: 222.541558ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:47.291328Z","caller":"traceutil/trace.go:171","msg":"trace[1468133380] transaction","detail":"{read_only:false; response_revision:26475; number_of_response:1; }","duration":"365.017895ms","start":"2026-04-04T11:07:46.926274Z","end":"2026-04-04T11:07:47.291292Z","steps":["trace[1468133380] 'process raft request' (duration: 364.735889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:47.291549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:46.926257Z","time spent":"365.186779ms","remote":"127.0.0.1:52898","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-04-04T11:07:47.291602Z","caller":"traceutil/trace.go:171","msg":"trace[784451818] transaction","detail":"{read_only:false; response_revision:26476; number_of_response:1; }","duration":"364.28547ms","start":"2026-04-04T11:07:46.927296Z","end":"2026-04-04T11:07:47.291582Z","steps":["trace[784451818] 'process raft request' (duration: 363.932403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:47.291715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:46.927287Z","time spent":"364.377642ms","remote":"127.0.0.1:52898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-04T11:07:47.292012Z","caller":"traceutil/trace.go:171","msg":"trace[1300128706] linearizableReadLoop","detail":"{readStateIndex:27341; appliedIndex:27338; }","duration":"146.684376ms","start":"2026-04-04T11:07:47.145288Z","end":"2026-04-04T11:07:47.291972Z","steps":["trace[1300128706] 'read index received' (duration: 145.640324ms)","trace[1300128706] 'applied index is now lower than readState.Index' (duration: 1.041482ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T11:07:47.292057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.894437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-04T11:07:47.292126Z","caller":"traceutil/trace.go:171","msg":"trace[1624298642] transaction","detail":"{read_only:false; response_revision:26477; number_of_response:1; }","duration":"346.496073ms","start":"2026-04-04T11:07:46.945614Z","end":"2026-04-04T11:07:47.29211Z","steps":["trace[1624298642] 'process raft request' (duration: 345.894479ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:47.292179Z","caller":"traceutil/trace.go:171","msg":"trace[1764263996] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:26477; }","duration":"184.067951ms","start":"2026-04-04T11:07:47.108092Z","end":"2026-04-04T11:07:47.29216Z","steps":["trace[1764263996] 'agreement among raft nodes before linearized reading' (duration: 183.904387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:47.292178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.045005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-04-04T11:07:47.292259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.383297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-04-04T11:07:47.292293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.543571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-04T11:07:47.292304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.426687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-04T11:07:47.292386Z","caller":"traceutil/trace.go:171","msg":"trace[603488105] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:26477; }","duration":"176.675683ms","start":"2026-04-04T11:07:47.115688Z","end":"2026-04-04T11:07:47.292363Z","steps":["trace[603488105] 'agreement among raft nodes before linearized reading' (duration: 176.50544ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:47.292426Z","caller":"traceutil/trace.go:171","msg":"trace[444516817] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:26477; }","duration":"359.669792ms","start":"2026-04-04T11:07:46.932731Z","end":"2026-04-04T11:07:47.292401Z","steps":["trace[444516817] 'agreement among raft nodes before linearized reading' (duration: 359.370336ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:47.292276Z","caller":"traceutil/trace.go:171","msg":"trace[399838200] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26477; }","duration":"227.194988ms","start":"2026-04-04T11:07:47.065059Z","end":"2026-04-04T11:07:47.292254Z","steps":["trace[399838200] 'agreement among raft nodes before linearized reading' (duration: 226.947412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:47.292493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:46.93269Z","time spent":"359.791525ms","remote":"127.0.0.1:34652","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":"info","ts":"2026-04-04T11:07:47.292349Z","caller":"traceutil/trace.go:171","msg":"trace[758755949] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26477; }","duration":"231.527059ms","start":"2026-04-04T11:07:47.060795Z","end":"2026-04-04T11:07:47.292322Z","steps":["trace[758755949] 'agreement among raft nodes before linearized reading' (duration: 231.268554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:47.292214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:46.945582Z","time spent":"346.586894ms","remote":"127.0.0.1:52898","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-04-04T11:07:47.49633Z","caller":"traceutil/trace.go:171","msg":"trace[1232260528] transaction","detail":"{read_only:false; response_revision:26478; number_of_response:1; }","duration":"343.256593ms","start":"2026-04-04T11:07:47.153018Z","end":"2026-04-04T11:07:47.496274Z","steps":["trace[1232260528] 'process raft request' (duration: 291.663127ms)","trace[1232260528] 'compare' (duration: 51.32368ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T11:07:47.496521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:47.152969Z","time spent":"343.451227ms","remote":"127.0.0.1:52828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3745,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-04T11:07:47.496563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.867569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-04T11:07:47.49669Z","caller":"traceutil/trace.go:171","msg":"trace[1929348900] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26478; }","duration":"234.089424ms","start":"2026-04-04T11:07:47.262576Z","end":"2026-04-04T11:07:47.496666Z","steps":["trace[1929348900] 'agreement among raft nodes before linearized reading' (duration: 233.835608ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:47.496786Z","caller":"traceutil/trace.go:171","msg":"trace[820921789] linearizableReadLoop","detail":"{readStateIndex:27342; appliedIndex:27341; }","duration":"204.095536ms","start":"2026-04-04T11:07:47.292103Z","end":"2026-04-04T11:07:47.496199Z","steps":["trace[820921789] 'read index received' (duration: 152.590001ms)","trace[820921789] 'applied index is now lower than readState.Index' (duration: 51.504135ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T11:07:47.752013Z","caller":"traceutil/trace.go:171","msg":"trace[484462782] transaction","detail":"{read_only:false; response_revision:26479; number_of_response:1; }","duration":"454.945217ms","start":"2026-04-04T11:07:47.297034Z","end":"2026-04-04T11:07:47.751979Z","steps":["trace[484462782] 'process raft request' (duration: 454.688321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:47.752188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:47.297015Z","time spent":"455.087999ms","remote":"127.0.0.1:52898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-04T11:07:47.752312Z","caller":"traceutil/trace.go:171","msg":"trace[1365761134] transaction","detail":"{read_only:false; response_revision:26480; number_of_response:1; }","duration":"454.096068ms","start":"2026-04-04T11:07:47.298196Z","end":"2026-04-04T11:07:47.752292Z","steps":["trace[1365761134] 'process raft request' (duration: 453.732331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:47.752411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:47.298188Z","time spent":"454.170669ms","remote":"127.0.0.1:52898","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-04-04T11:07:47.752567Z","caller":"traceutil/trace.go:171","msg":"trace[848342882] transaction","detail":"{read_only:false; response_revision:26481; number_of_response:1; }","duration":"428.531506ms","start":"2026-04-04T11:07:47.324023Z","end":"2026-04-04T11:07:47.752554Z","steps":["trace[848342882] 'process raft request' (duration: 428.196049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:47.752667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:47.323993Z","time spent":"428.605446ms","remote":"127.0.0.1:52898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-04T11:07:48.099266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.339515ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-04T11:07:48.099583Z","caller":"traceutil/trace.go:171","msg":"trace[1828250274] transaction","detail":"{read_only:false; response_revision:26483; number_of_response:1; }","duration":"194.538193ms","start":"2026-04-04T11:07:47.905015Z","end":"2026-04-04T11:07:48.099553Z","steps":["trace[1828250274] 'process raft request' (duration: 194.399521ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:48.099671Z","caller":"traceutil/trace.go:171","msg":"trace[161320242] transaction","detail":"{read_only:false; response_revision:26482; number_of_response:1; }","duration":"197.836364ms","start":"2026-04-04T11:07:47.90174Z","end":"2026-04-04T11:07:48.099577Z","steps":["trace[161320242] 'process raft request' (duration: 23.07739ms)","trace[161320242] 'compare' (duration: 173.906565ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T11:07:48.709434Z","caller":"traceutil/trace.go:171","msg":"trace[699835928] transaction","detail":"{read_only:false; response_revision:26485; number_of_response:1; }","duration":"139.933313ms","start":"2026-04-04T11:07:48.569473Z","end":"2026-04-04T11:07:48.709406Z","steps":["trace[699835928] 'process raft request' (duration: 139.800071ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:48.709666Z","caller":"traceutil/trace.go:171","msg":"trace[1952759452] linearizableReadLoop","detail":"{readStateIndex:27349; appliedIndex:27349; }","duration":"115.28055ms","start":"2026-04-04T11:07:48.594349Z","end":"2026-04-04T11:07:48.709629Z","steps":["trace[1952759452] 'read index received' (duration: 115.2723ms)","trace[1952759452] 'applied index is now lower than readState.Index' (duration: 6.62µs)"],"step_count":2} {"level":"warn","ts":"2026-04-04T11:07:48.709927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.569587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-04-04T11:07:48.709975Z","caller":"traceutil/trace.go:171","msg":"trace[115234009] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26485; }","duration":"115.633288ms","start":"2026-04-04T11:07:48.594329Z","end":"2026-04-04T11:07:48.709962Z","steps":["trace[115234009] 'agreement among raft nodes before linearized reading' (duration: 115.442174ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:48.954901Z","caller":"traceutil/trace.go:171","msg":"trace[1282804738] linearizableReadLoop","detail":"{readStateIndex:27350; appliedIndex:27349; }","duration":"245.127098ms","start":"2026-04-04T11:07:48.709747Z","end":"2026-04-04T11:07:48.954874Z","steps":["trace[1282804738] 'read index received' (duration: 210.388301ms)","trace[1282804738] 'applied index is now lower than readState.Index' (duration: 34.737727ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T11:07:48.954996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.878075ms","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-04-04T11:07:48.955032Z","caller":"traceutil/trace.go:171","msg":"trace[982191722] transaction","detail":"{read_only:false; response_revision:26486; number_of_response:1; }","duration":"357.266721ms","start":"2026-04-04T11:07:48.597679Z","end":"2026-04-04T11:07:48.954945Z","steps":["trace[982191722] 'process raft request' (duration: 322.37478ms)","trace[982191722] 'compare' (duration: 34.697307ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T11:07:48.955097Z","caller":"traceutil/trace.go:171","msg":"trace[1209373414] 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:26486; }","duration":"286.999477ms","start":"2026-04-04T11:07:48.668072Z","end":"2026-04-04T11:07:48.955072Z","steps":["trace[1209373414] 'agreement among raft nodes before linearized reading' (duration: 286.814913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:48.955327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.441153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-04-04T11:07:48.9557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:48.597659Z","time spent":"357.921994ms","remote":"127.0.0.1:52898","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-04-04T11:07:48.955521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.094336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-04T11:07:48.955777Z","caller":"traceutil/trace.go:171","msg":"trace[607165189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26486; }","duration":"356.975414ms","start":"2026-04-04T11:07:48.598753Z","end":"2026-04-04T11:07:48.955729Z","steps":["trace[607165189] 'agreement among raft nodes before linearized reading' (duration: 356.234519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:48.955896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:48.59874Z","time spent":"357.114448ms","remote":"127.0.0.1:52898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-04T11:07:48.95609Z","caller":"traceutil/trace.go:171","msg":"trace[1532828885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26486; }","duration":"356.76295ms","start":"2026-04-04T11:07:48.599299Z","end":"2026-04-04T11:07:48.956062Z","steps":["trace[1532828885] 'agreement among raft nodes before linearized reading' (duration: 355.617406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:48.956302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:48.599275Z","time spent":"356.972125ms","remote":"127.0.0.1:52898","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-04-04T11:07:48.96873Z","caller":"traceutil/trace.go:171","msg":"trace[1963215802] transaction","detail":"{read_only:false; response_revision:26487; number_of_response:1; }","duration":"255.058649ms","start":"2026-04-04T11:07:48.713648Z","end":"2026-04-04T11:07:48.968707Z","steps":["trace[1963215802] 'process raft request' (duration: 254.844375ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:48.969098Z","caller":"traceutil/trace.go:171","msg":"trace[658713734] transaction","detail":"{read_only:false; response_revision:26488; number_of_response:1; }","duration":"138.260288ms","start":"2026-04-04T11:07:48.830809Z","end":"2026-04-04T11:07:48.96907Z","steps":["trace[658713734] 'process raft request' (duration: 137.834569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:48.96924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.181591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-04T11:07:48.969346Z","caller":"traceutil/trace.go:171","msg":"trace[1342571155] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:26488; }","duration":"182.293513ms","start":"2026-04-04T11:07:48.787035Z","end":"2026-04-04T11:07:48.969328Z","steps":["trace[1342571155] 'agreement among raft nodes before linearized reading' (duration: 182.077879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:48.969683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.228193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-04T11:07:48.969741Z","caller":"traceutil/trace.go:171","msg":"trace[1134555568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26488; }","duration":"128.323166ms","start":"2026-04-04T11:07:48.841403Z","end":"2026-04-04T11:07:48.969726Z","steps":["trace[1134555568] 'agreement among raft nodes before linearized reading' (duration: 127.59305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:48.969812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.734738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c39608e9-b91e-4539-9dd2-1dff316cf3a5\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-04-04T11:07:48.969902Z","caller":"traceutil/trace.go:171","msg":"trace[965226858] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c39608e9-b91e-4539-9dd2-1dff316cf3a5; range_end:; response_count:1; response_revision:26488; }","duration":"211.856341ms","start":"2026-04-04T11:07:48.758024Z","end":"2026-04-04T11:07:48.96988Z","steps":["trace[965226858] 'agreement among raft nodes before linearized reading' (duration: 210.968302ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:49.257711Z","caller":"traceutil/trace.go:171","msg":"trace[804226048] transaction","detail":"{read_only:false; response_revision:26491; number_of_response:1; }","duration":"104.323677ms","start":"2026-04-04T11:07:49.153349Z","end":"2026-04-04T11:07:49.257673Z","steps":["trace[804226048] 'process raft request' (duration: 102.215652ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:49.629824Z","caller":"traceutil/trace.go:171","msg":"trace[1739253181] linearizableReadLoop","detail":"{readStateIndex:27356; appliedIndex:27355; }","duration":"332.505435ms","start":"2026-04-04T11:07:49.29728Z","end":"2026-04-04T11:07:49.629786Z","steps":["trace[1739253181] 'read index received' (duration: 331.926333ms)","trace[1739253181] 'applied index is now lower than readState.Index' (duration: 577.972µs)"],"step_count":2} {"level":"info","ts":"2026-04-04T11:07:49.629944Z","caller":"traceutil/trace.go:171","msg":"trace[445433567] transaction","detail":"{read_only:false; response_revision:26492; number_of_response:1; }","duration":"332.72109ms","start":"2026-04-04T11:07:49.297128Z","end":"2026-04-04T11:07:49.629849Z","steps":["trace[445433567] 'process raft request' (duration: 331.989234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:49.630065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.767351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-04T11:07:49.630096Z","caller":"traceutil/trace.go:171","msg":"trace[1334761123] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26492; }","duration":"332.857083ms","start":"2026-04-04T11:07:49.297229Z","end":"2026-04-04T11:07:49.630086Z","steps":["trace[1334761123] 'agreement among raft nodes before linearized reading' (duration: 332.686379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:49.630122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:49.297215Z","time spent":"332.900903ms","remote":"127.0.0.1:52828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-04T11:07:49.630175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:49.297095Z","time spent":"332.948635ms","remote":"127.0.0.1:52898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-04T11:07:49.631283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.281419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-04T11:07:49.631271Z","caller":"traceutil/trace.go:171","msg":"trace[1851943062] transaction","detail":"{read_only:false; response_revision:26493; number_of_response:1; }","duration":"333.462226ms","start":"2026-04-04T11:07:49.297768Z","end":"2026-04-04T11:07:49.631231Z","steps":["trace[1851943062] 'process raft request' (duration: 333.261321ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:49.631359Z","caller":"traceutil/trace.go:171","msg":"trace[1343328098] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:26494; }","duration":"146.398022ms","start":"2026-04-04T11:07:49.484939Z","end":"2026-04-04T11:07:49.631337Z","steps":["trace[1343328098] 'agreement among raft nodes before linearized reading' (duration: 146.243787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:49.63149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:49.297757Z","time spent":"333.608959ms","remote":"127.0.0.1:52898","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-04-04T11:07:49.631442Z","caller":"traceutil/trace.go:171","msg":"trace[575600051] transaction","detail":"{read_only:false; response_revision:26494; number_of_response:1; }","duration":"331.763799ms","start":"2026-04-04T11:07:49.299645Z","end":"2026-04-04T11:07:49.631409Z","steps":["trace[575600051] 'process raft request' (duration: 331.486202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:49.631672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:49.299631Z","time spent":"331.935883ms","remote":"127.0.0.1:52898","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-04-04T11:07:56.909989Z","caller":"traceutil/trace.go:171","msg":"trace[675111948] transaction","detail":"{read_only:false; response_revision:26570; number_of_response:1; }","duration":"166.002377ms","start":"2026-04-04T11:07:56.743961Z","end":"2026-04-04T11:07:56.909963Z","steps":["trace[675111948] 'process raft request' (duration: 165.466285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:57.173079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.629449ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-04T11:07:57.173188Z","caller":"traceutil/trace.go:171","msg":"trace[956854550] linearizableReadLoop","detail":"{readStateIndex:27437; appliedIndex:27436; }","duration":"164.958635ms","start":"2026-04-04T11:07:57.008213Z","end":"2026-04-04T11:07:57.173172Z","steps":["trace[956854550] 'read index received' (duration: 23.05501ms)","trace[956854550] 'applied index is now lower than readState.Index' (duration: 141.902085ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T11:07:57.173272Z","caller":"traceutil/trace.go:171","msg":"trace[1757013764] transaction","detail":"{read_only:false; response_revision:26571; number_of_response:1; }","duration":"188.097907ms","start":"2026-04-04T11:07:56.985143Z","end":"2026-04-04T11:07:57.173241Z","steps":["trace[1757013764] 'process raft request' (duration: 46.229772ms)","trace[1757013764] 'compare' (duration: 141.297003ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T11:07:57.173347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.137539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-04-04T11:07:57.173376Z","caller":"traceutil/trace.go:171","msg":"trace[28780413] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26571; }","duration":"165.18733ms","start":"2026-04-04T11:07:57.008178Z","end":"2026-04-04T11:07:57.173366Z","steps":["trace[28780413] 'agreement among raft nodes before linearized reading' (duration: 165.074318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:57.173543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.266966ms","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-04-04T11:07:57.173561Z","caller":"traceutil/trace.go:171","msg":"trace[911472557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26571; }","duration":"144.342087ms","start":"2026-04-04T11:07:57.029214Z","end":"2026-04-04T11:07:57.173556Z","steps":["trace[911472557] 'agreement among raft nodes before linearized reading' (duration: 144.276296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:57.173768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.530802ms","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-04-04T11:07:57.17382Z","caller":"traceutil/trace.go:171","msg":"trace[891350937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26571; }","duration":"145.609343ms","start":"2026-04-04T11:07:57.0282Z","end":"2026-04-04T11:07:57.173809Z","steps":["trace[891350937] 'agreement among raft nodes before linearized reading' (duration: 145.448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:57.530269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.541725ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-04T11:07:57.530532Z","caller":"traceutil/trace.go:171","msg":"trace[1434807962] transaction","detail":"{read_only:false; response_revision:26573; number_of_response:1; }","duration":"351.858365ms","start":"2026-04-04T11:07:57.178656Z","end":"2026-04-04T11:07:57.530514Z","steps":["trace[1434807962] 'process raft request' (duration: 351.726612ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:57.530535Z","caller":"traceutil/trace.go:171","msg":"trace[1981551975] transaction","detail":"{read_only:false; response_revision:26572; number_of_response:1; }","duration":"352.586161ms","start":"2026-04-04T11:07:57.177899Z","end":"2026-04-04T11:07:57.530485Z","steps":["trace[1981551975] 'process raft request' (duration: 132.729111ms)","trace[1981551975] 'compare' (duration: 218.570273ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T11:07:57.530597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:57.17864Z","time spent":"351.926567ms","remote":"127.0.0.1:52898","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-04-04T11:07:57.530702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:57.177881Z","time spent":"352.734325ms","remote":"127.0.0.1:52898","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-04-04T11:07:57.530887Z","caller":"traceutil/trace.go:171","msg":"trace[1373095461] transaction","detail":"{read_only:false; response_revision:26575; number_of_response:1; }","duration":"348.092185ms","start":"2026-04-04T11:07:57.182779Z","end":"2026-04-04T11:07:57.530872Z","steps":["trace[1373095461] 'process raft request' (duration: 347.895811ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:07:57.530879Z","caller":"traceutil/trace.go:171","msg":"trace[1104059591] linearizableReadLoop","detail":"{readStateIndex:27441; appliedIndex:27437; }","duration":"347.570825ms","start":"2026-04-04T11:07:57.183278Z","end":"2026-04-04T11:07:57.530849Z","steps":["trace[1104059591] 'read index received' (duration: 127.447019ms)","trace[1104059591] 'applied index is now lower than readState.Index' (duration: 220.122846ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T11:07:57.530983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:57.182772Z","time spent":"348.158977ms","remote":"127.0.0.1:52828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8666,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-04T11:07:57.530988Z","caller":"traceutil/trace.go:171","msg":"trace[47389052] transaction","detail":"{read_only:false; response_revision:26574; number_of_response:1; }","duration":"352.308844ms","start":"2026-04-04T11:07:57.178657Z","end":"2026-04-04T11:07:57.530965Z","steps":["trace[47389052] 'process raft request' (duration: 351.814034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:57.53106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:57.178634Z","time spent":"352.389066ms","remote":"127.0.0.1:52898","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-04-04T11:07:57.531197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.862852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-04-04T11:07:57.531245Z","caller":"traceutil/trace.go:171","msg":"trace[558322208] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26575; }","duration":"347.955024ms","start":"2026-04-04T11:07:57.183272Z","end":"2026-04-04T11:07:57.531227Z","steps":["trace[558322208] 'agreement among raft nodes before linearized reading' (duration: 347.725648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:07:57.531285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:07:57.183265Z","time spent":"348.010795ms","remote":"127.0.0.1:52786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1884,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" "} {"level":"info","ts":"2026-04-04T11:07:57.589864Z","caller":"traceutil/trace.go:171","msg":"trace[1632987295] transaction","detail":"{read_only:false; response_revision:26576; number_of_response:1; }","duration":"238.227912ms","start":"2026-04-04T11:07:57.351603Z","end":"2026-04-04T11:07:57.589831Z","steps":["trace[1632987295] 'process raft request' (duration: 237.742902ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:08:01.313281Z","caller":"traceutil/trace.go:171","msg":"trace[36321564] linearizableReadLoop","detail":"{readStateIndex:27477; appliedIndex:27476; }","duration":"100.034685ms","start":"2026-04-04T11:08:01.213214Z","end":"2026-04-04T11:08:01.313248Z","steps":["trace[36321564] 'read index received' (duration: 99.740399ms)","trace[36321564] 'applied index is now lower than readState.Index' (duration: 293.276µs)"],"step_count":2} {"level":"warn","ts":"2026-04-04T11:08:01.313745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.443704ms","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-04-04T11:08:01.313965Z","caller":"traceutil/trace.go:171","msg":"trace[1805038716] 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:26610; }","duration":"100.691839ms","start":"2026-04-04T11:08:01.213182Z","end":"2026-04-04T11:08:01.313874Z","steps":["trace[1805038716] 'agreement among raft nodes before linearized reading' (duration: 100.186389ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:08:01.314338Z","caller":"traceutil/trace.go:171","msg":"trace[965239878] transaction","detail":"{read_only:false; response_revision:26610; number_of_response:1; }","duration":"109.049857ms","start":"2026-04-04T11:08:01.205252Z","end":"2026-04-04T11:08:01.314302Z","steps":["trace[965239878] 'process raft request' (duration: 107.766171ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:08:04.015469Z","caller":"traceutil/trace.go:171","msg":"trace[1320034200] transaction","detail":"{read_only:false; response_revision:26637; number_of_response:1; }","duration":"173.15667ms","start":"2026-04-04T11:08:03.842286Z","end":"2026-04-04T11:08:04.015443Z","steps":["trace[1320034200] 'process raft request' (duration: 172.991986ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:08:06.302643Z","caller":"traceutil/trace.go:171","msg":"trace[1558390598] transaction","detail":"{read_only:false; response_revision:26654; number_of_response:1; }","duration":"138.293518ms","start":"2026-04-04T11:08:06.164316Z","end":"2026-04-04T11:08:06.302609Z","steps":["trace[1558390598] 'process raft request' (duration: 125.835833ms)","trace[1558390598] 'compare' (duration: 12.352133ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T11:08:10.217009Z","caller":"traceutil/trace.go:171","msg":"trace[632395025] linearizableReadLoop","detail":"{readStateIndex:27549; appliedIndex:27548; }","duration":"112.94346ms","start":"2026-04-04T11:08:10.104038Z","end":"2026-04-04T11:08:10.216981Z","steps":["trace[632395025] 'read index received' (duration: 112.563262ms)","trace[632395025] 'applied index is now lower than readState.Index' (duration: 379.118µs)"],"step_count":2} {"level":"warn","ts":"2026-04-04T11:08:10.217279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.214405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-04-04T11:08:10.217328Z","caller":"traceutil/trace.go:171","msg":"trace[180958274] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:26681; }","duration":"113.299117ms","start":"2026-04-04T11:08:10.104013Z","end":"2026-04-04T11:08:10.217312Z","steps":["trace[180958274] 'agreement among raft nodes before linearized reading' (duration: 113.110613ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:08:10.217396Z","caller":"traceutil/trace.go:171","msg":"trace[1716382123] transaction","detail":"{read_only:false; response_revision:26681; number_of_response:1; }","duration":"159.760064ms","start":"2026-04-04T11:08:10.057477Z","end":"2026-04-04T11:08:10.217237Z","steps":["trace[1716382123] 'process raft request' (duration: 159.287644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:08:10.489183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.514068ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"info","ts":"2026-04-04T11:08:10.489453Z","caller":"traceutil/trace.go:171","msg":"trace[773866998] transaction","detail":"{read_only:false; response_revision:26683; number_of_response:1; }","duration":"173.266532ms","start":"2026-04-04T11:08:10.316171Z","end":"2026-04-04T11:08:10.489438Z","steps":["trace[773866998] 'process raft request' (duration: 173.234111ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:08:10.489467Z","caller":"traceutil/trace.go:171","msg":"trace[2126207197] linearizableReadLoop","detail":"{readStateIndex:27550; appliedIndex:27549; }","duration":"268.567246ms","start":"2026-04-04T11:08:10.220856Z","end":"2026-04-04T11:08:10.489424Z","steps":["trace[2126207197] 'read index received' (duration: 133.808233ms)","trace[2126207197] 'applied index is now lower than readState.Index' (duration: 134.754173ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T11:08:10.48963Z","caller":"traceutil/trace.go:171","msg":"trace[953631214] transaction","detail":"{read_only:false; response_revision:26682; number_of_response:1; }","duration":"231.820555ms","start":"2026-04-04T11:08:10.257802Z","end":"2026-04-04T11:08:10.489623Z","steps":["trace[953631214] 'process raft request' (duration: 231.488018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:08:10.489901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.362571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-04T11:08:10.489973Z","caller":"traceutil/trace.go:171","msg":"trace[987838464] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26683; }","duration":"267.457002ms","start":"2026-04-04T11:08:10.2225Z","end":"2026-04-04T11:08:10.489957Z","steps":["trace[987838464] 'agreement among raft nodes before linearized reading' (duration: 267.243768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:08:10.490168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.259412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-34a03fdd-b76d-468e-b342-8da52975972d\" ","response":"range_response_count:1 size:1323"} {"level":"info","ts":"2026-04-04T11:08:10.49026Z","caller":"traceutil/trace.go:171","msg":"trace[275856452] range","detail":"{range_begin:/registry/persistentvolumes/pvc-34a03fdd-b76d-468e-b342-8da52975972d; range_end:; response_count:1; response_revision:26683; }","duration":"269.406465ms","start":"2026-04-04T11:08:10.220829Z","end":"2026-04-04T11:08:10.490235Z","steps":["trace[275856452] 'agreement among raft nodes before linearized reading' (duration: 269.031686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:08:10.490242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.272328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-04T11:08:10.490436Z","caller":"traceutil/trace.go:171","msg":"trace[676298909] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26683; }","duration":"106.583124ms","start":"2026-04-04T11:08:10.3838Z","end":"2026-04-04T11:08:10.490383Z","steps":["trace[676298909] 'agreement among raft nodes before linearized reading' (duration: 105.989021ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:08:13.172655Z","caller":"traceutil/trace.go:171","msg":"trace[925914913] transaction","detail":"{read_only:false; response_revision:26700; number_of_response:1; }","duration":"125.588529ms","start":"2026-04-04T11:08:13.047028Z","end":"2026-04-04T11:08:13.172617Z","steps":["trace[925914913] 'process raft request' (duration: 46.360366ms)","trace[925914913] 'compare' (duration: 79.047059ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T11:08:18.241252Z","caller":"traceutil/trace.go:171","msg":"trace[2046227582] linearizableReadLoop","detail":"{readStateIndex:27624; appliedIndex:27623; }","duration":"117.080427ms","start":"2026-04-04T11:08:18.124136Z","end":"2026-04-04T11:08:18.241216Z","steps":["trace[2046227582] 'read index received' (duration: 116.815261ms)","trace[2046227582] 'applied index is now lower than readState.Index' (duration: 263.675µs)"],"step_count":2} {"level":"warn","ts":"2026-04-04T11:08:18.241573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.416604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-04T11:08:18.241636Z","caller":"traceutil/trace.go:171","msg":"trace[51204346] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26754; }","duration":"117.544368ms","start":"2026-04-04T11:08:18.124074Z","end":"2026-04-04T11:08:18.241618Z","steps":["trace[51204346] 'agreement among raft nodes before linearized reading' (duration: 117.283181ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:08:18.242003Z","caller":"traceutil/trace.go:171","msg":"trace[680377776] transaction","detail":"{read_only:false; response_revision:26754; number_of_response:1; }","duration":"128.92949ms","start":"2026-04-04T11:08:18.113048Z","end":"2026-04-04T11:08:18.241978Z","steps":["trace[680377776] 'process raft request' (duration: 127.986689ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:08:18.374347Z","caller":"traceutil/trace.go:171","msg":"trace[136794586] linearizableReadLoop","detail":"{readStateIndex:27625; appliedIndex:27624; }","duration":"125.799993ms","start":"2026-04-04T11:08:18.248518Z","end":"2026-04-04T11:08:18.374318Z","steps":["trace[136794586] 'read index received' (duration: 102.982888ms)","trace[136794586] 'applied index is now lower than readState.Index' (duration: 22.815585ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T11:08:18.374422Z","caller":"traceutil/trace.go:171","msg":"trace[1209853016] transaction","detail":"{read_only:false; response_revision:26755; number_of_response:1; }","duration":"128.213694ms","start":"2026-04-04T11:08:18.246165Z","end":"2026-04-04T11:08:18.374379Z","steps":["trace[1209853016] 'process raft request' (duration: 105.325188ms)","trace[1209853016] 'compare' (duration: 22.705993ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T11:08:18.374542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.017707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-04T11:08:18.37459Z","caller":"traceutil/trace.go:171","msg":"trace[1097449475] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26755; }","duration":"126.16321ms","start":"2026-04-04T11:08:18.248411Z","end":"2026-04-04T11:08:18.374575Z","steps":["trace[1097449475] 'agreement among raft nodes before linearized reading' (duration: 125.999817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:08:18.631343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.451793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-04T11:08:18.631471Z","caller":"traceutil/trace.go:171","msg":"trace[429243995] transaction","detail":"{read_only:false; response_revision:26756; number_of_response:1; }","duration":"252.804372ms","start":"2026-04-04T11:08:18.378643Z","end":"2026-04-04T11:08:18.631448Z","steps":["trace[429243995] 'process raft request' (duration: 113.144465ms)","trace[429243995] 'compare' (duration: 139.29513ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T11:08:18.848693Z","caller":"traceutil/trace.go:171","msg":"trace[2024700231] linearizableReadLoop","detail":"{readStateIndex:27628; appliedIndex:27626; }","duration":"251.119135ms","start":"2026-04-04T11:08:18.597536Z","end":"2026-04-04T11:08:18.848655Z","steps":["trace[2024700231] 'read index received' (duration: 42.83138ms)","trace[2024700231] 'applied index is now lower than readState.Index' (duration: 208.285915ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T11:08:18.848866Z","caller":"traceutil/trace.go:171","msg":"trace[2006864775] transaction","detail":"{read_only:false; response_revision:26758; number_of_response:1; }","duration":"255.142341ms","start":"2026-04-04T11:08:18.593708Z","end":"2026-04-04T11:08:18.84885Z","steps":["trace[2006864775] 'process raft request' (duration: 173.916645ms)","trace[2006864775] 'compare' (duration: 80.806047ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T11:08:18.848976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.413211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-04T11:08:18.849085Z","caller":"traceutil/trace.go:171","msg":"trace[17018767] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:26758; }","duration":"251.598195ms","start":"2026-04-04T11:08:18.597468Z","end":"2026-04-04T11:08:18.849066Z","steps":["trace[17018767] 'agreement among raft nodes before linearized reading' (duration: 251.438901ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:08:21.305583Z","caller":"traceutil/trace.go:171","msg":"trace[7487693] linearizableReadLoop","detail":"{readStateIndex:27645; appliedIndex:27644; }","duration":"101.313513ms","start":"2026-04-04T11:08:21.204244Z","end":"2026-04-04T11:08:21.305558Z","steps":["trace[7487693] 'read index received' (duration: 101.18004ms)","trace[7487693] 'applied index is now lower than readState.Index' (duration: 132.363µs)"],"step_count":2} {"level":"warn","ts":"2026-04-04T11:08:21.305701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.434335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-04T11:08:21.30573Z","caller":"traceutil/trace.go:171","msg":"trace[1513275850] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:26774; }","duration":"101.535237ms","start":"2026-04-04T11:08:21.204184Z","end":"2026-04-04T11:08:21.30572Z","steps":["trace[1513275850] 'agreement among raft nodes before linearized reading' (duration: 101.466496ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:08:21.30574Z","caller":"traceutil/trace.go:171","msg":"trace[392680944] transaction","detail":"{read_only:false; response_revision:26774; number_of_response:1; }","duration":"103.482809ms","start":"2026-04-04T11:08:21.202118Z","end":"2026-04-04T11:08:21.305601Z","steps":["trace[392680944] 'process raft request' (duration: 103.292935ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:08:21.519933Z","caller":"traceutil/trace.go:171","msg":"trace[400013115] transaction","detail":"{read_only:false; response_revision:26775; number_of_response:1; }","duration":"128.173243ms","start":"2026-04-04T11:08:21.391724Z","end":"2026-04-04T11:08:21.519897Z","steps":["trace[400013115] 'process raft request' (duration: 28.684949ms)","trace[400013115] 'compare' (duration: 99.249969ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T11:08:23.952983Z","caller":"traceutil/trace.go:171","msg":"trace[1861534179] transaction","detail":"{read_only:false; response_revision:26793; number_of_response:1; }","duration":"198.096698ms","start":"2026-04-04T11:08:23.754855Z","end":"2026-04-04T11:08:23.952952Z","steps":["trace[1861534179] 'process raft request' (duration: 197.902384ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:08:24.083413Z","caller":"traceutil/trace.go:171","msg":"trace[331042599] transaction","detail":"{read_only:false; response_revision:26794; number_of_response:1; }","duration":"327.915638ms","start":"2026-04-04T11:08:23.755465Z","end":"2026-04-04T11:08:24.08338Z","steps":["trace[331042599] 'process raft request' (duration: 327.55249ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:08:24.08341Z","caller":"traceutil/trace.go:171","msg":"trace[726142577] transaction","detail":"{read_only:false; response_revision:26795; number_of_response:1; }","duration":"327.284033ms","start":"2026-04-04T11:08:23.75609Z","end":"2026-04-04T11:08:24.083374Z","steps":["trace[726142577] 'process raft request' (duration: 327.157491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:08:24.08358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:08:23.755447Z","time spent":"328.04427ms","remote":"127.0.0.1:52898","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-04-04T11:08:24.083622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:08:23.756073Z","time spent":"327.442697ms","remote":"127.0.0.1:52898","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-04-04T11:08:24.083767Z","caller":"traceutil/trace.go:171","msg":"trace[64962616] transaction","detail":"{read_only:false; response_revision:26796; number_of_response:1; }","duration":"310.295082ms","start":"2026-04-04T11:08:23.773412Z","end":"2026-04-04T11:08:24.083707Z","steps":["trace[64962616] 'process raft request' (duration: 309.903484ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:08:24.083806Z","caller":"traceutil/trace.go:171","msg":"trace[587370537] transaction","detail":"{read_only:false; response_revision:26798; number_of_response:1; }","duration":"292.72116ms","start":"2026-04-04T11:08:23.791065Z","end":"2026-04-04T11:08:24.083786Z","steps":["trace[587370537] 'process raft request' (duration: 292.668189ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:08:24.083792Z","caller":"traceutil/trace.go:171","msg":"trace[484171303] transaction","detail":"{read_only:false; response_revision:26797; number_of_response:1; }","duration":"293.296182ms","start":"2026-04-04T11:08:23.790478Z","end":"2026-04-04T11:08:24.083774Z","steps":["trace[484171303] 'process raft request' (duration: 293.158329ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T11:08:24.083788Z","caller":"traceutil/trace.go:171","msg":"trace[318616138] linearizableReadLoop","detail":"{readStateIndex:27669; appliedIndex:27663; }","duration":"258.354889ms","start":"2026-04-04T11:08:23.825409Z","end":"2026-04-04T11:08:24.083764Z","steps":["trace[318616138] 'read index received' (duration: 127.371905ms)","trace[318616138] 'applied index is now lower than readState.Index' (duration: 130.981984ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T11:08:24.08389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.456601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-04T11:08:24.08393Z","caller":"traceutil/trace.go:171","msg":"trace[2040363973] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:26798; }","duration":"258.558563ms","start":"2026-04-04T11:08:23.82536Z","end":"2026-04-04T11:08:24.083919Z","steps":["trace[2040363973] 'agreement among raft nodes before linearized reading' (duration: 258.474561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T11:08:24.083956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T11:08:23.773396Z","time spent":"310.462207ms","remote":"127.0.0.1:52898","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-04-04T11:10:16.802572Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25087} {"level":"info","ts":"2026-04-04T11:10:16.944971Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25087,"took":"136.43687ms","hash":3091917716,"current-db-size-bytes":60928000,"current-db-size":"61 MB","current-db-size-in-use-bytes":36929536,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-04-04T11:10:16.945038Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3091917716,"revision":25087,"compact-revision":21961} {"level":"info","ts":"2026-04-04T11:12:15.161572Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fe334179879fd0aa","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-04T11:12:15.167192Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-04T11:12:15.167371Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-04-04T11:15:16.811884Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27937} {"level":"info","ts":"2026-04-04T11:15:16.95581Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27937,"took":"138.100955ms","hash":2290505863,"current-db-size-bytes":60928000,"current-db-size":"61 MB","current-db-size-in-use-bytes":34398208,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-04T11:15:16.955901Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2290505863,"revision":27937,"compact-revision":25087} {"level":"info","ts":"2026-04-04T11:20:16.818378Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30573} {"level":"info","ts":"2026-04-04T11:20:16.96254Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30573,"took":"139.063403ms","hash":183898791,"current-db-size-bytes":60928000,"current-db-size":"61 MB","current-db-size-in-use-bytes":30756864,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-04T11:20:16.962626Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":183898791,"revision":30573,"compact-revision":27937} {"level":"info","ts":"2026-04-04T11:25:16.823883Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32830} {"level":"info","ts":"2026-04-04T11:25:16.983088Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32830,"took":"153.223345ms","hash":3709500781,"current-db-size-bytes":61972480,"current-db-size":"62 MB","current-db-size-in-use-bytes":30384128,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-04T11:25:16.983152Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3709500781,"revision":32830,"compact-revision":30573} {"level":"info","ts":"2026-04-04T11:30:16.834913Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35065} {"level":"info","ts":"2026-04-04T11:30:16.964084Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35065,"took":"122.779327ms","hash":2165345143,"current-db-size-bytes":61972480,"current-db-size":"62 MB","current-db-size-in-use-bytes":31457280,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-04T11:30:16.964164Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2165345143,"revision":35065,"compact-revision":32830} {"level":"info","ts":"2026-04-04T11:33:26.135201Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fe334179879fd0aa","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-04T11:33:26.140796Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-04-04T11:33:26.140955Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-04-04T11:35:16.845883Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37361} {"level":"info","ts":"2026-04-04T11:35:16.973966Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37361,"took":"123.105563ms","hash":3202707488,"current-db-size-bytes":61972480,"current-db-size":"62 MB","current-db-size-in-use-bytes":32608256,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-04T11:35:16.974055Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3202707488,"revision":37361,"compact-revision":35065} {"level":"info","ts":"2026-04-04T11:40:16.852915Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39646} {"level":"info","ts":"2026-04-04T11:40:16.982373Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39646,"took":"123.704369ms","hash":3114612081,"current-db-size-bytes":62394368,"current-db-size":"62 MB","current-db-size-in-use-bytes":31240192,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-04T11:40:16.982449Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3114612081,"revision":39646,"compact-revision":37361}