{"level":"warn","ts":"2026-04-28T13:51:54.014536Z","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-28T13:51:54.014621Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.222: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.222:2380","--initial-cluster=instance=https://199.204.45.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.222: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-28T13:51:54.01469Z","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-28T13:51:54.014715Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-04-28T13:51:54.014734Z","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-28T13:51:54.01535Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"]} {"level":"info","ts":"2026-04-28T13:51:54.015428Z","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.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.222: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-28T13:51:54.019662Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.016309ms"} {"level":"info","ts":"2026-04-28T13:51:54.027568Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"380f6760faea5f9e","cluster-id":"b87e8aca83a17eab"} {"level":"info","ts":"2026-04-28T13:51:54.027712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T13:51:54.027744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 0"} {"level":"info","ts":"2026-04-28T13:51:54.027763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 380f6760faea5f9e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T13:51:54.027777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 1"} {"level":"info","ts":"2026-04-28T13:51:54.027835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"warn","ts":"2026-04-28T13:51:54.035215Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T13:51:54.037255Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T13:51:54.03972Z","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-28T13:51:54.041999Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"380f6760faea5f9e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T13:51:54.042314Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"380f6760faea5f9e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T13:51:54.042626Z","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-28T13:51:54.042684Z","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-28T13:51:54.042703Z","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-28T13:51:54.04354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"info","ts":"2026-04-28T13:51:54.043731Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","added-peer-id":"380f6760faea5f9e","added-peer-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-04-28T13:51:54.052131Z","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-28T13:51:54.052272Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-04-28T13:51:54.052356Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-04-28T13:51:54.05243Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"380f6760faea5f9e","initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T13:51:54.052483Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T13:51:54.128148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T13:51:54.12823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T13:51:54.128284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgPreVoteResp from 380f6760faea5f9e at term 1"} {"level":"info","ts":"2026-04-28T13:51:54.128306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became candidate at term 2"} {"level":"info","ts":"2026-04-28T13:51:54.128317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgVoteResp from 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-04-28T13:51:54.128359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became leader at term 2"} {"level":"info","ts":"2026-04-28T13:51:54.128374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 380f6760faea5f9e elected leader 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-04-28T13:51:54.129539Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T13:51:54.130495Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T13:51:54.130502Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"380f6760faea5f9e","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.222:2379]}","request-path":"/0/members/380f6760faea5f9e/attributes","cluster-id":"b87e8aca83a17eab","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T13:51:54.130583Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T13:51:54.13096Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T13:51:54.130949Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T13:51:54.131088Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T13:51:54.13117Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T13:51:54.131249Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T13:51:54.134161Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T13:51:54.134668Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.222:2379"} {"level":"warn","ts":"2026-04-28T13:52:32.646404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.958129ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:52:32.646584Z","caller":"traceutil/trace.go:171","msg":"trace[1868636806] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"115.234404ms","start":"2026-04-28T13:52:32.531325Z","end":"2026-04-28T13:52:32.646559Z","steps":["trace[1868636806] 'read index received' (duration: 7.695956ms)","trace[1868636806] 'applied index is now lower than readState.Index' (duration: 107.535588ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:52:32.646779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.439179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wr7qp\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-28T13:52:32.646826Z","caller":"traceutil/trace.go:171","msg":"trace[756173043] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wr7qp; range_end:; response_count:1; response_revision:587; }","duration":"115.544802ms","start":"2026-04-28T13:52:32.531268Z","end":"2026-04-28T13:52:32.646813Z","steps":["trace[756173043] 'agreement among raft nodes before linearized reading' (duration: 115.369228ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:52:32.646895Z","caller":"traceutil/trace.go:171","msg":"trace[1280332123] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"201.707682ms","start":"2026-04-28T13:52:32.44487Z","end":"2026-04-28T13:52:32.646578Z","steps":["trace[1280332123] 'process raft request' (duration: 94.247785ms)","trace[1280332123] 'compare' (duration: 105.776465ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:52:32.906902Z","caller":"traceutil/trace.go:171","msg":"trace[1576257560] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"243.167768ms","start":"2026-04-28T13:52:32.663687Z","end":"2026-04-28T13:52:32.906855Z","steps":["trace[1576257560] 'process raft request' (duration: 165.649728ms)","trace[1576257560] 'compare' (duration: 77.358666ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:52:33.391632Z","caller":"traceutil/trace.go:171","msg":"trace[281906435] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"329.814018ms","start":"2026-04-28T13:52:33.061789Z","end":"2026-04-28T13:52:33.391603Z","steps":["trace[281906435] 'process raft request' (duration: 329.157972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:52:33.391971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:52:33.061748Z","time spent":"329.940922ms","remote":"127.0.0.1:50344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:52:33.424509Z","caller":"traceutil/trace.go:171","msg":"trace[945633943] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:611; }","duration":"179.089333ms","start":"2026-04-28T13:52:33.245307Z","end":"2026-04-28T13:52:33.424397Z","steps":["trace[945633943] 'read index received' (duration: 145.364855ms)","trace[945633943] 'applied index is now lower than readState.Index' (duration: 33.722928ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:52:33.424909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.575835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-28T13:52:33.424967Z","caller":"traceutil/trace.go:171","msg":"trace[1720346039] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:589; }","duration":"179.713589ms","start":"2026-04-28T13:52:33.245239Z","end":"2026-04-28T13:52:33.424952Z","steps":["trace[1720346039] 'agreement among raft nodes before linearized reading' (duration: 179.419991ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:52:47.656974Z","caller":"traceutil/trace.go:171","msg":"trace[2028745280] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"153.707457ms","start":"2026-04-28T13:52:47.503208Z","end":"2026-04-28T13:52:47.656916Z","steps":["trace[2028745280] 'process raft request' (duration: 153.306548ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:52:47.824908Z","caller":"traceutil/trace.go:171","msg":"trace[399098612] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"158.075444ms","start":"2026-04-28T13:52:47.666813Z","end":"2026-04-28T13:52:47.824888Z","steps":["trace[399098612] 'process raft request' (duration: 157.592033ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:52:47.825024Z","caller":"traceutil/trace.go:171","msg":"trace[306131409] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"162.628514ms","start":"2026-04-28T13:52:47.662368Z","end":"2026-04-28T13:52:47.824997Z","steps":["trace[306131409] 'process raft request' (duration: 161.992319ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:52:47.825348Z","caller":"traceutil/trace.go:171","msg":"trace[2061097221] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"162.85413ms","start":"2026-04-28T13:52:47.662095Z","end":"2026-04-28T13:52:47.824949Z","steps":["trace[2061097221] 'process raft request' (duration: 162.084581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:53:27.177885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.974103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:53:27.178007Z","caller":"traceutil/trace.go:171","msg":"trace[712620479] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1190; }","duration":"204.168386ms","start":"2026-04-28T13:53:26.973814Z","end":"2026-04-28T13:53:27.177982Z","steps":["trace[712620479] 'count revisions from in-memory index tree' (duration: 203.847989ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:53:27.178449Z","caller":"traceutil/trace.go:171","msg":"trace[1102592954] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"194.201224ms","start":"2026-04-28T13:53:26.984213Z","end":"2026-04-28T13:53:27.178415Z","steps":["trace[1102592954] 'process raft request' (duration: 193.712522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:54:13.798238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.147118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-221369ed-4c13-42cc-ac05-6680e8df1222\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-28T13:54:13.798407Z","caller":"traceutil/trace.go:171","msg":"trace[824293315] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-221369ed-4c13-42cc-ac05-6680e8df1222; range_end:; response_count:1; response_revision:1866; }","duration":"153.373743ms","start":"2026-04-28T13:54:13.644994Z","end":"2026-04-28T13:54:13.798367Z","steps":["trace[824293315] 'range keys from in-memory index tree' (duration: 152.8137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:54:14.402195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.165538ms","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-28T13:54:14.402431Z","caller":"traceutil/trace.go:171","msg":"trace[207398678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1868; }","duration":"115.481806ms","start":"2026-04-28T13:54:14.286916Z","end":"2026-04-28T13:54:14.402398Z","steps":["trace[207398678] 'range keys from in-memory index tree' (duration: 114.925863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:54:14.402301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.090477ms","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-28T13:54:14.402704Z","caller":"traceutil/trace.go:171","msg":"trace[942551257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1868; }","duration":"115.555068ms","start":"2026-04-28T13:54:14.287108Z","end":"2026-04-28T13:54:14.402663Z","steps":["trace[942551257] 'range keys from in-memory index tree' (duration: 114.79112ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:03.437321Z","caller":"traceutil/trace.go:171","msg":"trace[814553276] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"108.5016ms","start":"2026-04-28T13:55:03.32876Z","end":"2026-04-28T13:55:03.437262Z","steps":["trace[814553276] 'process raft request' (duration: 54.174794ms)","trace[814553276] 'compare' (duration: 54.101921ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:55:03.646506Z","caller":"traceutil/trace.go:171","msg":"trace[890624982] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"113.834379ms","start":"2026-04-28T13:55:03.532645Z","end":"2026-04-28T13:55:03.64648Z","steps":["trace[890624982] 'process raft request' (duration: 113.629954ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:03.651069Z","caller":"traceutil/trace.go:171","msg":"trace[1268180231] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"110.275402ms","start":"2026-04-28T13:55:03.540764Z","end":"2026-04-28T13:55:03.651039Z","steps":["trace[1268180231] 'process raft request' (duration: 110.015916ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:03.651111Z","caller":"traceutil/trace.go:171","msg":"trace[793176233] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"109.696378ms","start":"2026-04-28T13:55:03.541381Z","end":"2026-04-28T13:55:03.651078Z","steps":["trace[793176233] 'process raft request' (duration: 109.549975ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:08.90539Z","caller":"traceutil/trace.go:171","msg":"trace[843595825] transaction","detail":"{read_only:false; response_revision:2213; number_of_response:1; }","duration":"141.665744ms","start":"2026-04-28T13:55:08.76366Z","end":"2026-04-28T13:55:08.905326Z","steps":["trace[843595825] 'process raft request' (duration: 141.292965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:09.599603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.57408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:55:09.599763Z","caller":"traceutil/trace.go:171","msg":"trace[2087021345] linearizableReadLoop","detail":"{readStateIndex:2302; appliedIndex:2301; }","duration":"252.344576ms","start":"2026-04-28T13:55:09.347394Z","end":"2026-04-28T13:55:09.599739Z","steps":["trace[2087021345] 'read index received' (duration: 127.465659ms)","trace[2087021345] 'applied index is now lower than readState.Index' (duration: 124.873607ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:55:09.599841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.446349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:55:09.599885Z","caller":"traceutil/trace.go:171","msg":"trace[797906240] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2216; }","duration":"252.50643ms","start":"2026-04-28T13:55:09.347365Z","end":"2026-04-28T13:55:09.599871Z","steps":["trace[797906240] 'agreement among raft nodes before linearized reading' (duration: 252.434838ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:09.599943Z","caller":"traceutil/trace.go:171","msg":"trace[1357503042] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"258.021504ms","start":"2026-04-28T13:55:09.34189Z","end":"2026-04-28T13:55:09.599912Z","steps":["trace[1357503042] 'process raft request' (duration: 132.999433ms)","trace[1357503042] 'compare' (duration: 124.407456ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:55:09.775942Z","caller":"traceutil/trace.go:171","msg":"trace[1554141592] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"107.759772ms","start":"2026-04-28T13:55:09.668147Z","end":"2026-04-28T13:55:09.775906Z","steps":["trace[1554141592] 'process raft request' (duration: 73.584254ms)","trace[1554141592] 'compare' (duration: 34.013594ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:55:09.78066Z","caller":"traceutil/trace.go:171","msg":"trace[853871571] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"109.090284ms","start":"2026-04-28T13:55:09.671539Z","end":"2026-04-28T13:55:09.78063Z","steps":["trace[853871571] 'process raft request' (duration: 108.876339ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:09.780928Z","caller":"traceutil/trace.go:171","msg":"trace[1556939105] linearizableReadLoop","detail":"{readStateIndex:2305; appliedIndex:2302; }","duration":"106.906442ms","start":"2026-04-28T13:55:09.674001Z","end":"2026-04-28T13:55:09.780908Z","steps":["trace[1556939105] 'read index received' (duration: 67.841405ms)","trace[1556939105] 'applied index is now lower than readState.Index' (duration: 39.063617ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:55:09.780977Z","caller":"traceutil/trace.go:171","msg":"trace[1006228495] transaction","detail":"{read_only:false; response_revision:2219; number_of_response:1; }","duration":"109.232258ms","start":"2026-04-28T13:55:09.671713Z","end":"2026-04-28T13:55:09.780945Z","steps":["trace[1006228495] 'process raft request' (duration: 108.876588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:09.78109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.080777ms","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-28T13:55:09.781139Z","caller":"traceutil/trace.go:171","msg":"trace[1112990217] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2219; }","duration":"107.166658ms","start":"2026-04-28T13:55:09.67396Z","end":"2026-04-28T13:55:09.781127Z","steps":["trace[1112990217] 'agreement among raft nodes before linearized reading' (duration: 107.007994ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:27.671515Z","caller":"traceutil/trace.go:171","msg":"trace[1488228650] transaction","detail":"{read_only:false; response_revision:2831; number_of_response:1; }","duration":"277.944269ms","start":"2026-04-28T13:56:27.393542Z","end":"2026-04-28T13:56:27.671486Z","steps":["trace[1488228650] 'process raft request' (duration: 277.643802ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:59:02.402496Z","caller":"traceutil/trace.go:171","msg":"trace[1486349704] linearizableReadLoop","detail":"{readStateIndex:3995; appliedIndex:3994; }","duration":"111.393039ms","start":"2026-04-28T13:59:02.291082Z","end":"2026-04-28T13:59:02.402475Z","steps":["trace[1486349704] 'read index received' (duration: 111.196724ms)","trace[1486349704] 'applied index is now lower than readState.Index' (duration: 195.605µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:59:02.402618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.522852ms","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:5"} {"level":"info","ts":"2026-04-28T13:59:02.402641Z","caller":"traceutil/trace.go:171","msg":"trace[2031242581] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:3847; }","duration":"111.610414ms","start":"2026-04-28T13:59:02.291023Z","end":"2026-04-28T13:59:02.402634Z","steps":["trace[2031242581] 'agreement among raft nodes before linearized reading' (duration: 111.543123ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:59:02.402742Z","caller":"traceutil/trace.go:171","msg":"trace[1250705176] transaction","detail":"{read_only:false; response_revision:3847; number_of_response:1; }","duration":"141.172496ms","start":"2026-04-28T13:59:02.261548Z","end":"2026-04-28T13:59:02.40272Z","steps":["trace[1250705176] 'process raft request' (duration: 140.782687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:00:39.397605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.136681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-04-28T14:00:39.397691Z","caller":"traceutil/trace.go:171","msg":"trace[262261719] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:5181; }","duration":"133.268134ms","start":"2026-04-28T14:00:39.264406Z","end":"2026-04-28T14:00:39.397674Z","steps":["trace[262261719] 'range keys from in-memory index tree' (duration: 132.994137ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:00:42.680635Z","caller":"traceutil/trace.go:171","msg":"trace[344416391] transaction","detail":"{read_only:false; response_revision:5228; number_of_response:1; }","duration":"188.169335ms","start":"2026-04-28T14:00:42.492439Z","end":"2026-04-28T14:00:42.680609Z","steps":["trace[344416391] 'process raft request' (duration: 187.985691ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:00:42.820722Z","caller":"traceutil/trace.go:171","msg":"trace[1170066846] linearizableReadLoop","detail":"{readStateIndex:5429; appliedIndex:5427; }","duration":"130.2038ms","start":"2026-04-28T14:00:42.690501Z","end":"2026-04-28T14:00:42.820704Z","steps":["trace[1170066846] 'read index received' (duration: 92.435524ms)","trace[1170066846] 'applied index is now lower than readState.Index' (duration: 37.766996ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:00:42.820895Z","caller":"traceutil/trace.go:171","msg":"trace[435064316] transaction","detail":"{read_only:false; response_revision:5229; number_of_response:1; }","duration":"138.45245ms","start":"2026-04-28T14:00:42.682415Z","end":"2026-04-28T14:00:42.820867Z","steps":["trace[435064316] 'process raft request' (duration: 138.138503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:00:42.82096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.432526ms","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-28T14:00:42.820997Z","caller":"traceutil/trace.go:171","msg":"trace[2010796114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5230; }","duration":"130.520947ms","start":"2026-04-28T14:00:42.690466Z","end":"2026-04-28T14:00:42.820987Z","steps":["trace[2010796114] 'agreement among raft nodes before linearized reading' (duration: 130.313082ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:01:14.909729Z","caller":"traceutil/trace.go:171","msg":"trace[739628499] linearizableReadLoop","detail":"{readStateIndex:5856; appliedIndex:5855; }","duration":"473.045732ms","start":"2026-04-28T14:01:14.436664Z","end":"2026-04-28T14:01:14.90971Z","steps":["trace[739628499] 'read index received' (duration: 472.837456ms)","trace[739628499] 'applied index is now lower than readState.Index' (duration: 207.236µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:01:14.909936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.884186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T14:01:14.909978Z","caller":"traceutil/trace.go:171","msg":"trace[1557872259] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5648; }","duration":"455.969528ms","start":"2026-04-28T14:01:14.453997Z","end":"2026-04-28T14:01:14.909967Z","steps":["trace[1557872259] 'agreement among raft nodes before linearized reading' (duration: 455.836735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:14.909971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.260257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-04-28T14:01:14.910003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:14.453983Z","time spent":"456.013799ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T14:01:14.910011Z","caller":"traceutil/trace.go:171","msg":"trace[1996307538] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5648; }","duration":"473.407951ms","start":"2026-04-28T14:01:14.436593Z","end":"2026-04-28T14:01:14.910001Z","steps":["trace[1996307538] 'agreement among raft nodes before linearized reading' (duration: 473.221146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:14.910034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:14.436572Z","time spent":"473.455701ms","remote":"127.0.0.1:50428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-28T14:01:14.910296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.894845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T14:01:14.910402Z","caller":"traceutil/trace.go:171","msg":"trace[2049866494] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:5648; }","duration":"257.058298ms","start":"2026-04-28T14:01:14.653316Z","end":"2026-04-28T14:01:14.910375Z","steps":["trace[2049866494] 'agreement among raft nodes before linearized reading' (duration: 256.845173ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:01:14.910734Z","caller":"traceutil/trace.go:171","msg":"trace[978561185] transaction","detail":"{read_only:false; response_revision:5648; number_of_response:1; }","duration":"493.768514ms","start":"2026-04-28T14:01:14.416037Z","end":"2026-04-28T14:01:14.909805Z","steps":["trace[978561185] 'process raft request' (duration: 493.533008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:14.910948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:14.416015Z","time spent":"494.804319ms","remote":"127.0.0.1:50454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10667,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T14:01:15.036718Z","caller":"traceutil/trace.go:171","msg":"trace[1130467868] transaction","detail":"{read_only:false; response_revision:5650; number_of_response:1; }","duration":"119.690421ms","start":"2026-04-28T14:01:14.917Z","end":"2026-04-28T14:01:15.03669Z","steps":["trace[1130467868] 'process raft request' (duration: 113.939621ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:01:15.036779Z","caller":"traceutil/trace.go:171","msg":"trace[2094292925] transaction","detail":"{read_only:false; response_revision:5651; number_of_response:1; }","duration":"119.202309ms","start":"2026-04-28T14:01:14.91754Z","end":"2026-04-28T14:01:15.036742Z","steps":["trace[2094292925] 'process raft request' (duration: 119.071436ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:01:15.036982Z","caller":"traceutil/trace.go:171","msg":"trace[389722800] transaction","detail":"{read_only:false; response_revision:5652; number_of_response:1; }","duration":"118.664814ms","start":"2026-04-28T14:01:14.918306Z","end":"2026-04-28T14:01:15.036971Z","steps":["trace[389722800] 'process raft request' (duration: 118.350297ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:01:15.036985Z","caller":"traceutil/trace.go:171","msg":"trace[1786812449] transaction","detail":"{read_only:false; response_revision:5653; number_of_response:1; }","duration":"118.356448ms","start":"2026-04-28T14:01:14.918618Z","end":"2026-04-28T14:01:15.036974Z","steps":["trace[1786812449] 'process raft request' (duration: 118.272416ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:01:15.03709Z","caller":"traceutil/trace.go:171","msg":"trace[273381666] transaction","detail":"{read_only:false; response_revision:5654; number_of_response:1; }","duration":"111.966833ms","start":"2026-04-28T14:01:14.925098Z","end":"2026-04-28T14:01:15.037065Z","steps":["trace[273381666] 'process raft request' (duration: 111.83875ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:01:15.198198Z","caller":"traceutil/trace.go:171","msg":"trace[1862544963] transaction","detail":"{read_only:false; response_revision:5662; number_of_response:1; }","duration":"124.303032ms","start":"2026-04-28T14:01:15.073851Z","end":"2026-04-28T14:01:15.198154Z","steps":["trace[1862544963] 'process raft request' (duration: 82.355336ms)","trace[1862544963] 'compare' (duration: 41.72399ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:01:15.206061Z","caller":"traceutil/trace.go:171","msg":"trace[1749447798] transaction","detail":"{read_only:false; response_revision:5663; number_of_response:1; }","duration":"108.199011ms","start":"2026-04-28T14:01:15.097829Z","end":"2026-04-28T14:01:15.206028Z","steps":["trace[1749447798] 'process raft request' (duration: 107.963006ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:01:53.51263Z","caller":"traceutil/trace.go:171","msg":"trace[779449578] linearizableReadLoop","detail":"{readStateIndex:6233; appliedIndex:6232; }","duration":"131.460184ms","start":"2026-04-28T14:01:53.381134Z","end":"2026-04-28T14:01:53.512594Z","steps":["trace[779449578] 'read index received' (duration: 84.48268ms)","trace[779449578] 'applied index is now lower than readState.Index' (duration: 46.976384ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:01:53.512674Z","caller":"traceutil/trace.go:171","msg":"trace[649323546] transaction","detail":"{read_only:false; response_revision:6015; number_of_response:1; }","duration":"133.299829ms","start":"2026-04-28T14:01:53.379321Z","end":"2026-04-28T14:01:53.512621Z","steps":["trace[649323546] 'process raft request' (duration: 86.335205ms)","trace[649323546] 'compare' (duration: 46.772108ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:01:53.512927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.772831ms","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-28T14:01:53.512981Z","caller":"traceutil/trace.go:171","msg":"trace[591382022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6015; }","duration":"131.877304ms","start":"2026-04-28T14:01:53.381089Z","end":"2026-04-28T14:01:53.512966Z","steps":["trace[591382022] 'agreement among raft nodes before linearized reading' (duration: 131.645049ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:01:54.400942Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2982} {"level":"info","ts":"2026-04-28T14:01:54.465279Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2982,"took":"63.252698ms","hash":956033546,"current-db-size-bytes":57659392,"current-db-size":"58 MB","current-db-size-in-use-bytes":33222656,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-28T14:01:54.465332Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":956033546,"revision":2982,"compact-revision":-1} {"level":"info","ts":"2026-04-28T14:06:54.407114Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6020} {"level":"info","ts":"2026-04-28T14:06:54.502318Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6020,"took":"93.145974ms","hash":787469616,"current-db-size-bytes":57659392,"current-db-size":"58 MB","current-db-size-in-use-bytes":21831680,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-28T14:06:54.502371Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":787469616,"revision":6020,"compact-revision":2982} {"level":"info","ts":"2026-04-28T14:07:31.345448Z","caller":"traceutil/trace.go:171","msg":"trace[231049946] transaction","detail":"{read_only:false; response_revision:8473; number_of_response:1; }","duration":"141.800558ms","start":"2026-04-28T14:07:31.203621Z","end":"2026-04-28T14:07:31.345422Z","steps":["trace[231049946] 'process raft request' (duration: 132.128394ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:07:32.777708Z","caller":"traceutil/trace.go:171","msg":"trace[892625383] transaction","detail":"{read_only:false; response_revision:8479; number_of_response:1; }","duration":"301.166148ms","start":"2026-04-28T14:07:32.476493Z","end":"2026-04-28T14:07:32.77766Z","steps":["trace[892625383] 'process raft request' (duration: 286.975956ms)","trace[892625383] 'compare' (duration: 13.826203ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:07:32.777865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:07:32.476475Z","time spent":"301.312722ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T14:07:33.34332Z","caller":"traceutil/trace.go:171","msg":"trace[335641206] transaction","detail":"{read_only:false; response_revision:8481; number_of_response:1; }","duration":"104.180091ms","start":"2026-04-28T14:07:33.239113Z","end":"2026-04-28T14:07:33.343293Z","steps":["trace[335641206] 'process raft request' (duration: 104.012837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:07:33.644361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.19078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T14:07:33.644533Z","caller":"traceutil/trace.go:171","msg":"trace[679692488] linearizableReadLoop","detail":"{readStateIndex:8782; appliedIndex:8781; }","duration":"284.734443ms","start":"2026-04-28T14:07:33.359731Z","end":"2026-04-28T14:07:33.644466Z","steps":["trace[679692488] 'read index received' (duration: 41.253405ms)","trace[679692488] 'applied index is now lower than readState.Index' (duration: 243.479948ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:07:33.64461Z","caller":"traceutil/trace.go:171","msg":"trace[1966111393] transaction","detail":"{read_only:false; response_revision:8482; number_of_response:1; }","duration":"292.535531ms","start":"2026-04-28T14:07:33.352034Z","end":"2026-04-28T14:07:33.644569Z","steps":["trace[1966111393] 'process raft request' (duration: 49.038222ms)","trace[1966111393] 'compare' (duration: 243.058217ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:07:33.644742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.992029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T14:07:33.644836Z","caller":"traceutil/trace.go:171","msg":"trace[2107293671] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8482; }","duration":"285.123743ms","start":"2026-04-28T14:07:33.359697Z","end":"2026-04-28T14:07:33.644821Z","steps":["trace[2107293671] 'agreement among raft nodes before linearized reading' (duration: 284.917348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:07:33.64491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.539472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-28T14:07:33.645008Z","caller":"traceutil/trace.go:171","msg":"trace[189824383] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:8482; }","duration":"141.681966ms","start":"2026-04-28T14:07:33.503303Z","end":"2026-04-28T14:07:33.644985Z","steps":["trace[189824383] 'agreement among raft nodes before linearized reading' (duration: 141.359738ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:07:33.772425Z","caller":"traceutil/trace.go:171","msg":"trace[745447452] transaction","detail":"{read_only:false; response_revision:8484; number_of_response:1; }","duration":"120.33588ms","start":"2026-04-28T14:07:33.652064Z","end":"2026-04-28T14:07:33.7724Z","steps":["trace[745447452] 'process raft request' (duration: 120.279699ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:07:33.772463Z","caller":"traceutil/trace.go:171","msg":"trace[1464318452] transaction","detail":"{read_only:false; response_revision:8483; number_of_response:1; }","duration":"123.691611ms","start":"2026-04-28T14:07:33.648731Z","end":"2026-04-28T14:07:33.772422Z","steps":["trace[1464318452] 'process raft request' (duration: 95.472671ms)","trace[1464318452] 'compare' (duration: 27.984914ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:08:01.330693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.538794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T14:08:01.330775Z","caller":"traceutil/trace.go:171","msg":"trace[924954083] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8685; }","duration":"378.662107ms","start":"2026-04-28T14:08:00.952097Z","end":"2026-04-28T14:08:01.330759Z","steps":["trace[924954083] 'range keys from in-memory index tree' (duration: 378.395921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:08:01.330814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:08:00.952079Z","time spent":"378.725718ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T14:08:04.550152Z","caller":"traceutil/trace.go:171","msg":"trace[1117191737] transaction","detail":"{read_only:false; response_revision:8700; number_of_response:1; }","duration":"110.955025ms","start":"2026-04-28T14:08:04.439169Z","end":"2026-04-28T14:08:04.550124Z","steps":["trace[1117191737] 'process raft request' (duration: 110.695778ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:08:04.774381Z","caller":"traceutil/trace.go:171","msg":"trace[548241298] transaction","detail":"{read_only:false; response_revision:8701; number_of_response:1; }","duration":"215.417532ms","start":"2026-04-28T14:08:04.558937Z","end":"2026-04-28T14:08:04.774354Z","steps":["trace[548241298] 'process raft request' (duration: 215.222367ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:08:04.796718Z","caller":"traceutil/trace.go:171","msg":"trace[480582256] linearizableReadLoop","detail":"{readStateIndex:9011; appliedIndex:9009; }","duration":"158.476651ms","start":"2026-04-28T14:08:04.638218Z","end":"2026-04-28T14:08:04.796695Z","steps":["trace[480582256] 'read index received' (duration: 135.968398ms)","trace[480582256] 'applied index is now lower than readState.Index' (duration: 22.506903ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:08:04.79697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.726056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T14:08:04.796988Z","caller":"traceutil/trace.go:171","msg":"trace[1628927734] transaction","detail":"{read_only:false; response_revision:8702; number_of_response:1; }","duration":"160.020987ms","start":"2026-04-28T14:08:04.636758Z","end":"2026-04-28T14:08:04.796779Z","steps":["trace[1628927734] 'process raft request' (duration: 159.807251ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:08:04.797039Z","caller":"traceutil/trace.go:171","msg":"trace[830030988] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:8702; }","duration":"158.853019ms","start":"2026-04-28T14:08:04.63817Z","end":"2026-04-28T14:08:04.797023Z","steps":["trace[830030988] 'agreement among raft nodes before linearized reading' (duration: 158.637924ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:08:14.355826Z","caller":"traceutil/trace.go:171","msg":"trace[493802949] linearizableReadLoop","detail":"{readStateIndex:9111; appliedIndex:9110; }","duration":"232.844372ms","start":"2026-04-28T14:08:14.122956Z","end":"2026-04-28T14:08:14.3558Z","steps":["trace[493802949] 'read index received' (duration: 195.630045ms)","trace[493802949] 'applied index is now lower than readState.Index' (duration: 37.213237ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:08:14.355971Z","caller":"traceutil/trace.go:171","msg":"trace[1591952787] transaction","detail":"{read_only:false; response_revision:8797; number_of_response:1; }","duration":"323.479257ms","start":"2026-04-28T14:08:14.032452Z","end":"2026-04-28T14:08:14.355931Z","steps":["trace[1591952787] 'process raft request' (duration: 286.122947ms)","trace[1591952787] 'compare' (duration: 37.105194ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:08:14.356027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.037637ms","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:7"} {"level":"warn","ts":"2026-04-28T14:08:14.356149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:08:14.032432Z","time spent":"323.62298ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T14:08:14.356199Z","caller":"traceutil/trace.go:171","msg":"trace[1625987869] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8797; }","duration":"233.270013ms","start":"2026-04-28T14:08:14.122908Z","end":"2026-04-28T14:08:14.356178Z","steps":["trace[1625987869] 'agreement among raft nodes before linearized reading' (duration: 233.023247ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:08:29.636323Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000242a.wal"} {"level":"info","ts":"2026-04-28T14:09:32.376583Z","caller":"traceutil/trace.go:171","msg":"trace[1390956093] transaction","detail":"{read_only:false; response_revision:9558; number_of_response:1; }","duration":"136.699691ms","start":"2026-04-28T14:09:32.239842Z","end":"2026-04-28T14:09:32.376542Z","steps":["trace[1390956093] 'process raft request' (duration: 136.431705ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:09:44.433984Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"380f6760faea5f9e","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-28T14:09:44.439242Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-28T14:09:44.439325Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-28T14:11:54.414564Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8141} {"level":"info","ts":"2026-04-28T14:11:54.487828Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8141,"took":"71.142712ms","hash":129931796,"current-db-size-bytes":57659392,"current-db-size":"58 MB","current-db-size-in-use-bytes":28680192,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-28T14:11:54.487916Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":129931796,"revision":8141,"compact-revision":6020} {"level":"info","ts":"2026-04-28T14:13:25.589367Z","caller":"traceutil/trace.go:171","msg":"trace[1084026019] transaction","detail":"{read_only:false; response_revision:11451; number_of_response:1; }","duration":"101.657867ms","start":"2026-04-28T14:13:25.487674Z","end":"2026-04-28T14:13:25.589332Z","steps":["trace[1084026019] 'process raft request' (duration: 86.437051ms)","trace[1084026019] 'compare' (duration: 15.090153ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:13:25.80567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.854657ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T14:13:25.805752Z","caller":"traceutil/trace.go:171","msg":"trace[1115958889] linearizableReadLoop","detail":"{readStateIndex:11840; appliedIndex:11839; }","duration":"133.476973ms","start":"2026-04-28T14:13:25.67226Z","end":"2026-04-28T14:13:25.805737Z","steps":["trace[1115958889] 'read index received' (duration: 20.231816ms)","trace[1115958889] 'applied index is now lower than readState.Index' (duration: 113.244167ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:13:25.805916Z","caller":"traceutil/trace.go:171","msg":"trace[198884549] transaction","detail":"{read_only:false; response_revision:11452; number_of_response:1; }","duration":"145.81311ms","start":"2026-04-28T14:13:25.660091Z","end":"2026-04-28T14:13:25.805904Z","steps":["trace[198884549] 'process raft request' (duration: 32.647655ms)","trace[198884549] 'compare' (duration: 112.605021ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:13:25.806101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.844301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-28T14:13:25.806125Z","caller":"traceutil/trace.go:171","msg":"trace[809097258] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11452; }","duration":"133.895863ms","start":"2026-04-28T14:13:25.672222Z","end":"2026-04-28T14:13:25.806118Z","steps":["trace[809097258] 'agreement among raft nodes before linearized reading' (duration: 133.806771ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:13:26.970808Z","caller":"traceutil/trace.go:171","msg":"trace[1582278385] linearizableReadLoop","detail":"{readStateIndex:11847; appliedIndex:11846; }","duration":"199.509471ms","start":"2026-04-28T14:13:26.771275Z","end":"2026-04-28T14:13:26.970784Z","steps":["trace[1582278385] 'read index received' (duration: 199.293626ms)","trace[1582278385] 'applied index is now lower than readState.Index' (duration: 214.255µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:13:26.971034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.710698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-28T14:13:26.971089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.267981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T14:13:26.97113Z","caller":"traceutil/trace.go:171","msg":"trace[652849957] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:11458; }","duration":"199.855821ms","start":"2026-04-28T14:13:26.771252Z","end":"2026-04-28T14:13:26.971107Z","steps":["trace[652849957] 'agreement among raft nodes before linearized reading' (duration: 199.660586ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:13:26.971149Z","caller":"traceutil/trace.go:171","msg":"trace[1685097608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11458; }","duration":"156.378244ms","start":"2026-04-28T14:13:26.814754Z","end":"2026-04-28T14:13:26.971133Z","steps":["trace[1685097608] 'agreement among raft nodes before linearized reading' (duration: 156.268352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:13:26.971379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.785236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T14:13:26.971498Z","caller":"traceutil/trace.go:171","msg":"trace[373558134] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11458; }","duration":"145.01936ms","start":"2026-04-28T14:13:26.826458Z","end":"2026-04-28T14:13:26.971478Z","steps":["trace[373558134] 'agreement among raft nodes before linearized reading' (duration: 144.60158ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:13:27.241246Z","caller":"traceutil/trace.go:171","msg":"trace[1663042726] transaction","detail":"{read_only:false; response_revision:11459; number_of_response:1; }","duration":"268.771739ms","start":"2026-04-28T14:13:26.97243Z","end":"2026-04-28T14:13:27.241202Z","steps":["trace[1663042726] 'process raft request' (duration: 246.344449ms)","trace[1663042726] 'compare' (duration: 22.260876ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:13:27.254135Z","caller":"traceutil/trace.go:171","msg":"trace[1535907818] transaction","detail":"{read_only:false; response_revision:11460; number_of_response:1; }","duration":"278.849572ms","start":"2026-04-28T14:13:26.975257Z","end":"2026-04-28T14:13:27.254106Z","steps":["trace[1535907818] 'process raft request' (duration: 278.597436ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:13:27.254317Z","caller":"traceutil/trace.go:171","msg":"trace[1715768593] linearizableReadLoop","detail":"{readStateIndex:11849; appliedIndex:11847; }","duration":"206.867049ms","start":"2026-04-28T14:13:27.047407Z","end":"2026-04-28T14:13:27.254274Z","steps":["trace[1715768593] 'read index received' (duration: 171.379164ms)","trace[1715768593] 'applied index is now lower than readState.Index' (duration: 35.485545ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:13:27.254399Z","caller":"traceutil/trace.go:171","msg":"trace[1304154596] transaction","detail":"{read_only:false; response_revision:11461; number_of_response:1; }","duration":"131.964296ms","start":"2026-04-28T14:13:27.122408Z","end":"2026-04-28T14:13:27.254372Z","steps":["trace[1304154596] 'process raft request' (duration: 131.632808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:13:27.254576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.159356ms","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-28T14:13:27.254618Z","caller":"traceutil/trace.go:171","msg":"trace[1430853591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11461; }","duration":"207.287578ms","start":"2026-04-28T14:13:27.04732Z","end":"2026-04-28T14:13:27.254608Z","steps":["trace[1430853591] 'agreement among raft nodes before linearized reading' (duration: 207.105324ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:16:54.420826Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10628} {"level":"info","ts":"2026-04-28T14:16:54.49729Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10628,"took":"74.287045ms","hash":3728864379,"current-db-size-bytes":57659392,"current-db-size":"58 MB","current-db-size-in-use-bytes":27148288,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-28T14:16:54.497348Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3728864379,"revision":10628,"compact-revision":8141} {"level":"info","ts":"2026-04-28T14:19:53.789528Z","caller":"traceutil/trace.go:171","msg":"trace[1247680959] transaction","detail":"{read_only:false; response_revision:14049; number_of_response:1; }","duration":"121.434461ms","start":"2026-04-28T14:19:53.668074Z","end":"2026-04-28T14:19:53.789508Z","steps":["trace[1247680959] 'process raft request' (duration: 121.39313ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:19:53.789697Z","caller":"traceutil/trace.go:171","msg":"trace[57248481] transaction","detail":"{read_only:false; response_revision:14048; number_of_response:1; }","duration":"190.555303ms","start":"2026-04-28T14:19:53.599124Z","end":"2026-04-28T14:19:53.789679Z","steps":["trace[57248481] 'process raft request' (duration: 154.337437ms)","trace[57248481] 'compare' (duration: 35.901199ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:20:01.265136Z","caller":"traceutil/trace.go:171","msg":"trace[1499047069] transaction","detail":"{read_only:false; response_revision:14098; number_of_response:1; }","duration":"128.490171ms","start":"2026-04-28T14:20:01.136628Z","end":"2026-04-28T14:20:01.265118Z","steps":["trace[1499047069] 'process raft request' (duration: 128.231655ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:20:01.265163Z","caller":"traceutil/trace.go:171","msg":"trace[795132644] transaction","detail":"{read_only:false; response_revision:14097; number_of_response:1; }","duration":"132.205009ms","start":"2026-04-28T14:20:01.132917Z","end":"2026-04-28T14:20:01.265122Z","steps":["trace[795132644] 'process raft request' (duration: 91.545207ms)","trace[795132644] 'compare' (duration: 40.229392ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:20:02.29641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.603046ms","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-28T14:20:02.296504Z","caller":"traceutil/trace.go:171","msg":"trace[222070197] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14107; }","duration":"262.716978ms","start":"2026-04-28T14:20:02.033763Z","end":"2026-04-28T14:20:02.29648Z","steps":["trace[222070197] 'range keys from in-memory index tree' (duration: 262.399591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:20:02.296695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.804577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T14:20:02.296771Z","caller":"traceutil/trace.go:171","msg":"trace[527166408] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:14107; }","duration":"187.966961ms","start":"2026-04-28T14:20:02.108786Z","end":"2026-04-28T14:20:02.296753Z","steps":["trace[527166408] 'count revisions from in-memory index tree' (duration: 187.726286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:20:02.296822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.743431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-04-28T14:20:02.296894Z","caller":"traceutil/trace.go:171","msg":"trace[1783606126] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:14107; }","duration":"101.883254ms","start":"2026-04-28T14:20:02.194995Z","end":"2026-04-28T14:20:02.296879Z","steps":["trace[1783606126] 'range keys from in-memory index tree' (duration: 101.519626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:20:23.49306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.789807ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-28T14:20:23.493182Z","caller":"traceutil/trace.go:171","msg":"trace[555260939] linearizableReadLoop","detail":"{readStateIndex:14898; appliedIndex:14897; }","duration":"320.140107ms","start":"2026-04-28T14:20:23.173023Z","end":"2026-04-28T14:20:23.493163Z","steps":["trace[555260939] 'read index received' (duration: 207.284068ms)","trace[555260939] 'applied index is now lower than readState.Index' (duration: 112.854309ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:20:23.493418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.367972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-28T14:20:23.493456Z","caller":"traceutil/trace.go:171","msg":"trace[1250807327] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14403; }","duration":"320.484395ms","start":"2026-04-28T14:20:23.172961Z","end":"2026-04-28T14:20:23.493445Z","steps":["trace[1250807327] 'agreement among raft nodes before linearized reading' (duration: 320.27862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:20:23.493494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:20:23.172932Z","time spent":"320.550807ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T14:20:23.514689Z","caller":"traceutil/trace.go:171","msg":"trace[869478532] transaction","detail":"{read_only:false; response_revision:14404; number_of_response:1; }","duration":"168.882159ms","start":"2026-04-28T14:20:23.345783Z","end":"2026-04-28T14:20:23.514665Z","steps":["trace[869478532] 'process raft request' (duration: 168.660144ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:20:23.515023Z","caller":"traceutil/trace.go:171","msg":"trace[1589920683] transaction","detail":"{read_only:false; response_revision:14405; number_of_response:1; }","duration":"163.800147ms","start":"2026-04-28T14:20:23.351186Z","end":"2026-04-28T14:20:23.514986Z","steps":["trace[1589920683] 'process raft request' (duration: 163.429269ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:20:23.515097Z","caller":"traceutil/trace.go:171","msg":"trace[142016375] transaction","detail":"{read_only:false; response_revision:14406; number_of_response:1; }","duration":"154.436604ms","start":"2026-04-28T14:20:23.360639Z","end":"2026-04-28T14:20:23.515076Z","steps":["trace[142016375] 'process raft request' (duration: 154.319171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:20:23.516039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.367911ms","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-28T14:20:23.516101Z","caller":"traceutil/trace.go:171","msg":"trace[1392169844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14408; }","duration":"271.509654ms","start":"2026-04-28T14:20:23.244577Z","end":"2026-04-28T14:20:23.516087Z","steps":["trace[1392169844] 'agreement among raft nodes before linearized reading' (duration: 271.34271ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:20:23.516102Z","caller":"traceutil/trace.go:171","msg":"trace[64350437] transaction","detail":"{read_only:false; response_revision:14407; number_of_response:1; }","duration":"131.816343ms","start":"2026-04-28T14:20:23.384269Z","end":"2026-04-28T14:20:23.516086Z","steps":["trace[64350437] 'process raft request' (duration: 131.514925ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:21:27.836469Z","caller":"traceutil/trace.go:171","msg":"trace[412794595] transaction","detail":"{read_only:false; response_revision:15099; number_of_response:1; }","duration":"100.989616ms","start":"2026-04-28T14:21:27.735466Z","end":"2026-04-28T14:21:27.836455Z","steps":["trace[412794595] 'process raft request' (duration: 100.819502ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:21:27.836537Z","caller":"traceutil/trace.go:171","msg":"trace[1642720689] transaction","detail":"{read_only:false; response_revision:15102; number_of_response:1; }","duration":"100.254908ms","start":"2026-04-28T14:21:27.736265Z","end":"2026-04-28T14:21:27.83652Z","steps":["trace[1642720689] 'process raft request' (duration: 100.180606ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:21:27.836609Z","caller":"traceutil/trace.go:171","msg":"trace[615292054] transaction","detail":"{read_only:false; response_revision:15101; number_of_response:1; }","duration":"101.015386ms","start":"2026-04-28T14:21:27.735563Z","end":"2026-04-28T14:21:27.836578Z","steps":["trace[615292054] 'process raft request' (duration: 100.859563ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:21:27.837052Z","caller":"traceutil/trace.go:171","msg":"trace[653263988] transaction","detail":"{read_only:false; response_revision:15100; number_of_response:1; }","duration":"101.479077ms","start":"2026-04-28T14:21:27.735563Z","end":"2026-04-28T14:21:27.837042Z","steps":["trace[653263988] 'process raft request' (duration: 100.833382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:21:43.373351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.720533ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-28T14:21:43.373488Z","caller":"traceutil/trace.go:171","msg":"trace[1857323937] linearizableReadLoop","detail":"{readStateIndex:15702; appliedIndex:15701; }","duration":"167.059166ms","start":"2026-04-28T14:21:43.206403Z","end":"2026-04-28T14:21:43.373462Z","steps":["trace[1857323937] 'read index received' (duration: 47.208219ms)","trace[1857323937] 'applied index is now lower than readState.Index' (duration: 119.848907ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:21:43.373633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.217539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T14:21:43.37368Z","caller":"traceutil/trace.go:171","msg":"trace[1495394531] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15185; }","duration":"167.314482ms","start":"2026-04-28T14:21:43.206352Z","end":"2026-04-28T14:21:43.373666Z","steps":["trace[1495394531] 'agreement among raft nodes before linearized reading' (duration: 167.219649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:21:43.373912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.696946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-04-28T14:21:43.373903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.367193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-04-28T14:21:43.373942Z","caller":"traceutil/trace.go:171","msg":"trace[1194292745] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15185; }","duration":"166.737087ms","start":"2026-04-28T14:21:43.207197Z","end":"2026-04-28T14:21:43.373934Z","steps":["trace[1194292745] 'agreement among raft nodes before linearized reading' (duration: 166.678836ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:21:43.373996Z","caller":"traceutil/trace.go:171","msg":"trace[720239656] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15185; }","duration":"167.450125ms","start":"2026-04-28T14:21:43.206499Z","end":"2026-04-28T14:21:43.373949Z","steps":["trace[720239656] 'agreement among raft nodes before linearized reading' (duration: 167.22607ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:21:44.252851Z","caller":"traceutil/trace.go:171","msg":"trace[857931767] transaction","detail":"{read_only:false; response_revision:15187; number_of_response:1; }","duration":"332.444601ms","start":"2026-04-28T14:21:43.920345Z","end":"2026-04-28T14:21:44.25279Z","steps":["trace[857931767] 'process raft request' (duration: 244.547379ms)","trace[857931767] 'compare' (duration: 87.758799ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:21:44.253177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:21:43.920321Z","time spent":"332.606065ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T14:21:44.303536Z","caller":"traceutil/trace.go:171","msg":"trace[78049450] transaction","detail":"{read_only:false; response_revision:15188; number_of_response:1; }","duration":"382.401736ms","start":"2026-04-28T14:21:43.921105Z","end":"2026-04-28T14:21:44.303507Z","steps":["trace[78049450] 'process raft request' (duration: 382.14003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:21:44.303663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:21:43.921092Z","time spent":"382.505658ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T14:21:44.3041Z","caller":"traceutil/trace.go:171","msg":"trace[1850309626] transaction","detail":"{read_only:false; response_revision:15189; number_of_response:1; }","duration":"382.744984ms","start":"2026-04-28T14:21:43.921338Z","end":"2026-04-28T14:21:44.304083Z","steps":["trace[1850309626] 'process raft request' (duration: 382.036027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:21:44.304197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:21:43.921328Z","time spent":"382.829655ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T14:21:44.304464Z","caller":"traceutil/trace.go:171","msg":"trace[1826502488] transaction","detail":"{read_only:false; response_revision:15190; number_of_response:1; }","duration":"382.749834ms","start":"2026-04-28T14:21:43.921676Z","end":"2026-04-28T14:21:44.304426Z","steps":["trace[1826502488] 'process raft request' (duration: 382.341954ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:21:44.304501Z","caller":"traceutil/trace.go:171","msg":"trace[282716573] linearizableReadLoop","detail":"{readStateIndex:15707; appliedIndex:15703; }","duration":"378.974634ms","start":"2026-04-28T14:21:43.925501Z","end":"2026-04-28T14:21:44.304476Z","steps":["trace[282716573] 'read index received' (duration: 239.330655ms)","trace[282716573] 'applied index is now lower than readState.Index' (duration: 139.642579ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:21:44.304548Z","caller":"traceutil/trace.go:171","msg":"trace[1948744118] transaction","detail":"{read_only:false; response_revision:15191; number_of_response:1; }","duration":"264.890005ms","start":"2026-04-28T14:21:44.039644Z","end":"2026-04-28T14:21:44.304534Z","steps":["trace[1948744118] 'process raft request' (duration: 264.6903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:21:44.304647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:21:43.92165Z","time spent":"382.893027ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T14:21:44.304734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.217549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T14:21:44.30485Z","caller":"traceutil/trace.go:171","msg":"trace[1755577528] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15191; }","duration":"379.372344ms","start":"2026-04-28T14:21:43.925453Z","end":"2026-04-28T14:21:44.304825Z","steps":["trace[1755577528] 'agreement among raft nodes before linearized reading' (duration: 379.114517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:21:44.304866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.567214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T14:21:44.30491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:21:43.925435Z","time spent":"379.463556ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T14:21:44.304916Z","caller":"traceutil/trace.go:171","msg":"trace[14383486] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15191; }","duration":"258.698358ms","start":"2026-04-28T14:21:44.046205Z","end":"2026-04-28T14:21:44.304903Z","steps":["trace[14383486] 'agreement among raft nodes before linearized reading' (duration: 258.556924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:21:44.304786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.912642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T14:21:44.304945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.267216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-28T14:21:44.304908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.670554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-04-28T14:21:44.304944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.644797ms","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-28T14:21:44.304997Z","caller":"traceutil/trace.go:171","msg":"trace[941140159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15191; }","duration":"375.327807ms","start":"2026-04-28T14:21:43.929654Z","end":"2026-04-28T14:21:44.304981Z","steps":["trace[941140159] 'agreement among raft nodes before linearized reading' (duration: 375.197384ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:21:44.30502Z","caller":"traceutil/trace.go:171","msg":"trace[1910988249] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15191; }","duration":"143.742119ms","start":"2026-04-28T14:21:44.161252Z","end":"2026-04-28T14:21:44.304994Z","steps":["trace[1910988249] 'agreement among raft nodes before linearized reading' (duration: 143.587695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:21:44.304961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.100067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T14:21:44.30505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:21:43.929646Z","time spent":"375.392228ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T14:21:44.305046Z","caller":"traceutil/trace.go:171","msg":"trace[45412487] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15191; }","duration":"252.796607ms","start":"2026-04-28T14:21:44.052195Z","end":"2026-04-28T14:21:44.304992Z","steps":["trace[45412487] 'agreement among raft nodes before linearized reading' (duration: 252.415387ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:21:44.304991Z","caller":"traceutil/trace.go:171","msg":"trace[1227256387] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15191; }","duration":"259.134078ms","start":"2026-04-28T14:21:44.045836Z","end":"2026-04-28T14:21:44.30497Z","steps":["trace[1227256387] 'agreement among raft nodes before linearized reading' (duration: 258.855541ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:21:44.305115Z","caller":"traceutil/trace.go:171","msg":"trace[1170452426] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15191; }","duration":"259.304142ms","start":"2026-04-28T14:21:44.045783Z","end":"2026-04-28T14:21:44.305087Z","steps":["trace[1170452426] 'agreement among raft nodes before linearized reading' (duration: 259.049436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:21:44.30522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.792999ms","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-28T14:21:44.305287Z","caller":"traceutil/trace.go:171","msg":"trace[1108703979] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15191; }","duration":"135.898291ms","start":"2026-04-28T14:21:44.16937Z","end":"2026-04-28T14:21:44.305268Z","steps":["trace[1108703979] 'agreement among raft nodes before linearized reading' (duration: 135.356327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:21:44.305511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.89732ms","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-28T14:21:44.305566Z","caller":"traceutil/trace.go:171","msg":"trace[1111489876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15191; }","duration":"375.979042ms","start":"2026-04-28T14:21:43.929569Z","end":"2026-04-28T14:21:44.305548Z","steps":["trace[1111489876] 'agreement among raft nodes before linearized reading' (duration: 375.032229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:21:44.305607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:21:43.929557Z","time spent":"376.038313ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T14:21:54.425945Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12791} {"level":"info","ts":"2026-04-28T14:21:54.509324Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12791,"took":"80.66654ms","hash":3300426140,"current-db-size-bytes":57659392,"current-db-size":"58 MB","current-db-size-in-use-bytes":30486528,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-28T14:21:54.509471Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3300426140,"revision":12791,"compact-revision":10628} {"level":"info","ts":"2026-04-28T14:26:54.432237Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15242} {"level":"info","ts":"2026-04-28T14:26:54.525863Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15242,"took":"90.496363ms","hash":4082749163,"current-db-size-bytes":57659392,"current-db-size":"58 MB","current-db-size-in-use-bytes":31617024,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-28T14:26:54.525946Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4082749163,"revision":15242,"compact-revision":12791} {"level":"warn","ts":"2026-04-28T14:28:01.495268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.022703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T14:28:01.4954Z","caller":"traceutil/trace.go:171","msg":"trace[54287440] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:17921; }","duration":"120.202547ms","start":"2026-04-28T14:28:01.375177Z","end":"2026-04-28T14:28:01.49538Z","steps":["trace[54287440] 'range keys from in-memory index tree' (duration: 119.878019ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:28:01.732987Z","caller":"traceutil/trace.go:171","msg":"trace[1726829714] transaction","detail":"{read_only:false; response_revision:17925; number_of_response:1; }","duration":"167.041091ms","start":"2026-04-28T14:28:01.565918Z","end":"2026-04-28T14:28:01.732959Z","steps":["trace[1726829714] 'process raft request' (duration: 166.99978ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:28:01.733176Z","caller":"traceutil/trace.go:171","msg":"trace[11349883] transaction","detail":"{read_only:false; response_revision:17923; number_of_response:1; }","duration":"169.905801ms","start":"2026-04-28T14:28:01.563229Z","end":"2026-04-28T14:28:01.733135Z","steps":["trace[11349883] 'process raft request' (duration: 169.426519ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:28:01.733512Z","caller":"traceutil/trace.go:171","msg":"trace[1532438883] transaction","detail":"{read_only:false; response_revision:17924; number_of_response:1; }","duration":"169.529711ms","start":"2026-04-28T14:28:01.563956Z","end":"2026-04-28T14:28:01.733486Z","steps":["trace[1532438883] 'process raft request' (duration: 168.914666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:28:02.256658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.211548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-28T14:28:02.256722Z","caller":"traceutil/trace.go:171","msg":"trace[380794112] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17927; }","duration":"150.31446ms","start":"2026-04-28T14:28:02.106394Z","end":"2026-04-28T14:28:02.256708Z","steps":["trace[380794112] 'range keys from in-memory index tree' (duration: 150.074294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:28:02.256695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.736078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-pwb2c\" ","response":"range_response_count:1 size:20857"} {"level":"info","ts":"2026-04-28T14:28:02.256786Z","caller":"traceutil/trace.go:171","msg":"trace[2001337843] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-pwb2c; range_end:; response_count:1; response_revision:17927; }","duration":"207.865392ms","start":"2026-04-28T14:28:02.048897Z","end":"2026-04-28T14:28:02.256763Z","steps":["trace[2001337843] 'range keys from in-memory index tree' (duration: 207.580375ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:28:02.40743Z","caller":"traceutil/trace.go:171","msg":"trace[415884910] transaction","detail":"{read_only:false; response_revision:17929; number_of_response:1; }","duration":"146.247232ms","start":"2026-04-28T14:28:02.26116Z","end":"2026-04-28T14:28:02.407407Z","steps":["trace[415884910] 'process raft request' (duration: 146.205501ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:28:02.407519Z","caller":"traceutil/trace.go:171","msg":"trace[2133712077] transaction","detail":"{read_only:false; response_revision:17928; number_of_response:1; }","duration":"146.380085ms","start":"2026-04-28T14:28:02.261107Z","end":"2026-04-28T14:28:02.407487Z","steps":["trace[2133712077] 'process raft request' (duration: 146.095619ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:28:02.502949Z","caller":"traceutil/trace.go:171","msg":"trace[359487655] transaction","detail":"{read_only:false; response_revision:17931; number_of_response:1; }","duration":"241.534ms","start":"2026-04-28T14:28:02.261391Z","end":"2026-04-28T14:28:02.502925Z","steps":["trace[359487655] 'process raft request' (duration: 241.474069ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:28:02.50295Z","caller":"traceutil/trace.go:171","msg":"trace[2919139] transaction","detail":"{read_only:false; response_revision:17930; number_of_response:1; }","duration":"241.741965ms","start":"2026-04-28T14:28:02.261176Z","end":"2026-04-28T14:28:02.502918Z","steps":["trace[2919139] 'process raft request' (duration: 241.53329ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:28:02.503203Z","caller":"traceutil/trace.go:171","msg":"trace[1368340724] transaction","detail":"{read_only:false; response_revision:17932; number_of_response:1; }","duration":"241.222133ms","start":"2026-04-28T14:28:02.261966Z","end":"2026-04-28T14:28:02.503188Z","steps":["trace[1368340724] 'process raft request' (duration: 240.932077ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:28:02.503367Z","caller":"traceutil/trace.go:171","msg":"trace[1038582697] transaction","detail":"{read_only:false; response_revision:17933; number_of_response:1; }","duration":"231.895699ms","start":"2026-04-28T14:28:02.271453Z","end":"2026-04-28T14:28:02.503348Z","steps":["trace[1038582697] 'process raft request' (duration: 231.658444ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:30:43.553724Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"380f6760faea5f9e","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-28T14:30:43.55883Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-28T14:30:43.55897Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-28T14:31:54.438167Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17467} {"level":"info","ts":"2026-04-28T14:31:54.554078Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17467,"took":"112.410719ms","hash":2796785413,"current-db-size-bytes":57659392,"current-db-size":"58 MB","current-db-size-in-use-bytes":33624064,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-28T14:31:54.554153Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2796785413,"revision":17467,"compact-revision":15242} {"level":"info","ts":"2026-04-28T14:33:39.957478Z","caller":"traceutil/trace.go:171","msg":"trace[1376862964] linearizableReadLoop","detail":"{readStateIndex:21261; appliedIndex:21260; }","duration":"142.444568ms","start":"2026-04-28T14:33:39.815014Z","end":"2026-04-28T14:33:39.957459Z","steps":["trace[1376862964] 'read index received' (duration: 142.270394ms)","trace[1376862964] 'applied index is now lower than readState.Index' (duration: 173.144µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:33:39.958118Z","caller":"traceutil/trace.go:171","msg":"trace[2002938500] transaction","detail":"{read_only:false; response_revision:20565; number_of_response:1; }","duration":"306.422771ms","start":"2026-04-28T14:33:39.651681Z","end":"2026-04-28T14:33:39.958103Z","steps":["trace[2002938500] 'process raft request' (duration: 305.666424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:33:39.958224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:33:39.651662Z","time spent":"306.498023ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T14:33:39.958482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.463073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T14:33:39.958546Z","caller":"traceutil/trace.go:171","msg":"trace[640065116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20565; }","duration":"143.584457ms","start":"2026-04-28T14:33:39.814949Z","end":"2026-04-28T14:33:39.958533Z","steps":["trace[640065116] 'agreement among raft nodes before linearized reading' (duration: 143.495224ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:33:40.441779Z","caller":"traceutil/trace.go:171","msg":"trace[2011700097] transaction","detail":"{read_only:false; response_revision:20570; number_of_response:1; }","duration":"193.165401ms","start":"2026-04-28T14:33:40.24859Z","end":"2026-04-28T14:33:40.441755Z","steps":["trace[2011700097] 'process raft request' (duration: 123.052164ms)","trace[2011700097] 'compare' (duration: 69.862421ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:33:40.968048Z","caller":"traceutil/trace.go:171","msg":"trace[1979115477] transaction","detail":"{read_only:false; response_revision:20571; number_of_response:1; }","duration":"222.518435ms","start":"2026-04-28T14:33:40.745499Z","end":"2026-04-28T14:33:40.968017Z","steps":["trace[1979115477] 'process raft request' (duration: 222.33591ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:33:41.078535Z","caller":"traceutil/trace.go:171","msg":"trace[1447998338] transaction","detail":"{read_only:false; response_revision:20572; number_of_response:1; }","duration":"330.903187ms","start":"2026-04-28T14:33:40.747585Z","end":"2026-04-28T14:33:41.078488Z","steps":["trace[1447998338] 'process raft request' (duration: 309.140767ms)","trace[1447998338] 'compare' (duration: 21.627167ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:33:41.078576Z","caller":"traceutil/trace.go:171","msg":"trace[1651582456] linearizableReadLoop","detail":"{readStateIndex:21268; appliedIndex:21266; }","duration":"316.344429ms","start":"2026-04-28T14:33:40.762188Z","end":"2026-04-28T14:33:41.078532Z","steps":["trace[1651582456] 'read index received' (duration: 205.60789ms)","trace[1651582456] 'applied index is now lower than readState.Index' (duration: 110.733659ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:33:41.078689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:33:40.747575Z","time spent":"331.02909ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T14:33:41.079011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.789651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-28T14:33:41.07913Z","caller":"traceutil/trace.go:171","msg":"trace[67982035] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:20575; }","duration":"316.899793ms","start":"2026-04-28T14:33:40.76216Z","end":"2026-04-28T14:33:41.07906Z","steps":["trace[67982035] 'agreement among raft nodes before linearized reading' (duration: 316.522153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:33:41.079228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:33:40.762151Z","time spent":"317.057746ms","remote":"127.0.0.1:50752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":34,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-04-28T14:36:54.444689Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19823} {"level":"info","ts":"2026-04-28T14:36:54.551613Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19823,"took":"102.977205ms","hash":312101364,"current-db-size-bytes":57659392,"current-db-size":"58 MB","current-db-size-in-use-bytes":30978048,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-28T14:36:54.552062Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":312101364,"revision":19823,"compact-revision":17467} {"level":"info","ts":"2026-04-28T14:37:30.972475Z","caller":"traceutil/trace.go:171","msg":"trace[1609787242] transaction","detail":"{read_only:false; response_revision:22999; number_of_response:1; }","duration":"124.258446ms","start":"2026-04-28T14:37:30.848193Z","end":"2026-04-28T14:37:30.972452Z","steps":["trace[1609787242] 'process raft request' (duration: 124.128683ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:37:31.300334Z","caller":"traceutil/trace.go:171","msg":"trace[1128485061] transaction","detail":"{read_only:false; response_revision:23000; number_of_response:1; }","duration":"267.144926ms","start":"2026-04-28T14:37:31.03316Z","end":"2026-04-28T14:37:31.300305Z","steps":["trace[1128485061] 'process raft request' (duration: 183.839237ms)","trace[1128485061] 'compare' (duration: 83.022592ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:37:31.305932Z","caller":"traceutil/trace.go:171","msg":"trace[585223001] transaction","detail":"{read_only:false; response_revision:23001; number_of_response:1; }","duration":"174.72798ms","start":"2026-04-28T14:37:31.131185Z","end":"2026-04-28T14:37:31.305913Z","steps":["trace[585223001] 'process raft request' (duration: 173.845539ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:54.402734Z","caller":"traceutil/trace.go:171","msg":"trace[1129749462] transaction","detail":"{read_only:false; response_revision:25523; number_of_response:1; }","duration":"103.195645ms","start":"2026-04-28T14:41:54.299516Z","end":"2026-04-28T14:41:54.402711Z","steps":["trace[1129749462] 'process raft request' (duration: 103.02277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:41:54.683416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.461514ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T14:41:54.683742Z","caller":"traceutil/trace.go:171","msg":"trace[2010994233] linearizableReadLoop","detail":"{readStateIndex:26357; appliedIndex:26356; }","duration":"198.56494ms","start":"2026-04-28T14:41:54.485157Z","end":"2026-04-28T14:41:54.683721Z","steps":["trace[2010994233] 'read index received' (duration: 83.692986ms)","trace[2010994233] 'applied index is now lower than readState.Index' (duration: 114.869174ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:41:54.683863Z","caller":"traceutil/trace.go:171","msg":"trace[1398403074] transaction","detail":"{read_only:false; response_revision:25524; number_of_response:1; }","duration":"237.555604ms","start":"2026-04-28T14:41:54.44629Z","end":"2026-04-28T14:41:54.683846Z","steps":["trace[1398403074] 'process raft request' (duration: 122.573468ms)","trace[1398403074] 'compare' (duration: 114.244458ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:41:54.684177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.960019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T14:41:54.685112Z","caller":"traceutil/trace.go:171","msg":"trace[888732683] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:25524; }","duration":"199.973944ms","start":"2026-04-28T14:41:54.485105Z","end":"2026-04-28T14:41:54.685079Z","steps":["trace[888732683] 'agreement among raft nodes before linearized reading' (duration: 198.844826ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:54.940249Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22190} {"level":"warn","ts":"2026-04-28T14:41:54.941251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.035514ms","expected-duration":"100ms","prefix":"","request":"header: compaction: ","response":"size:6"} {"level":"info","ts":"2026-04-28T14:41:54.941403Z","caller":"traceutil/trace.go:171","msg":"trace[710777559] compact","detail":"{revision:22190; response_revision:25525; }","duration":"256.030366ms","start":"2026-04-28T14:41:54.685359Z","end":"2026-04-28T14:41:54.941389Z","steps":["trace[710777559] 'check and update compact revision' (duration: 239.923462ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:54.941613Z","caller":"traceutil/trace.go:171","msg":"trace[421973771] linearizableReadLoop","detail":"{readStateIndex:26360; appliedIndex:26358; }","duration":"240.35213ms","start":"2026-04-28T14:41:54.701248Z","end":"2026-04-28T14:41:54.9416Z","steps":["trace[421973771] 'read index received' (duration: 177.292539ms)","trace[421973771] 'applied index is now lower than readState.Index' (duration: 63.058911ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:41:54.941701Z","caller":"traceutil/trace.go:171","msg":"trace[248683271] transaction","detail":"{read_only:false; response_revision:25526; number_of_response:1; }","duration":"247.771709ms","start":"2026-04-28T14:41:54.693896Z","end":"2026-04-28T14:41:54.941667Z","steps":["trace[248683271] 'process raft request' (duration: 247.475102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:41:54.941867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.580505ms","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-28T14:41:54.941936Z","caller":"traceutil/trace.go:171","msg":"trace[1266572823] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25526; }","duration":"240.706379ms","start":"2026-04-28T14:41:54.701214Z","end":"2026-04-28T14:41:54.94192Z","steps":["trace[1266572823] 'agreement among raft nodes before linearized reading' (duration: 240.442492ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:55.077498Z","caller":"traceutil/trace.go:171","msg":"trace[1284783887] transaction","detail":"{read_only:false; response_revision:25527; number_of_response:1; }","duration":"241.416997ms","start":"2026-04-28T14:41:54.836059Z","end":"2026-04-28T14:41:55.077476Z","steps":["trace[1284783887] 'process raft request' (duration: 240.954816ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:55.077847Z","caller":"traceutil/trace.go:171","msg":"trace[343466182] transaction","detail":"{read_only:false; response_revision:25528; number_of_response:1; }","duration":"237.210387ms","start":"2026-04-28T14:41:54.840609Z","end":"2026-04-28T14:41:55.07782Z","steps":["trace[343466182] 'process raft request' (duration: 236.772716ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:55.078219Z","caller":"traceutil/trace.go:171","msg":"trace[1211649600] transaction","detail":"{read_only:false; response_revision:25529; number_of_response:1; }","duration":"237.492643ms","start":"2026-04-28T14:41:54.840705Z","end":"2026-04-28T14:41:55.078198Z","steps":["trace[1211649600] 'process raft request' (duration: 237.030032ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:55.078547Z","caller":"traceutil/trace.go:171","msg":"trace[2079095984] linearizableReadLoop","detail":"{readStateIndex:26365; appliedIndex:26361; }","duration":"136.892472ms","start":"2026-04-28T14:41:54.94164Z","end":"2026-04-28T14:41:55.078533Z","steps":["trace[2079095984] 'read index received' (duration: 135.783065ms)","trace[2079095984] 'applied index is now lower than readState.Index' (duration: 1.108507ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:41:55.07866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.37005ms","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-28T14:41:55.078674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.392377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T14:41:55.078691Z","caller":"traceutil/trace.go:171","msg":"trace[1390961245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25531; }","duration":"174.452821ms","start":"2026-04-28T14:41:54.904231Z","end":"2026-04-28T14:41:55.078684Z","steps":["trace[1390961245] 'agreement among raft nodes before linearized reading' (duration: 174.336188ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:55.078672Z","caller":"traceutil/trace.go:171","msg":"trace[1982715525] transaction","detail":"{read_only:false; response_revision:25530; number_of_response:1; }","duration":"237.690887ms","start":"2026-04-28T14:41:54.840951Z","end":"2026-04-28T14:41:55.078642Z","steps":["trace[1982715525] 'process raft request' (duration: 237.188055ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:55.078738Z","caller":"traceutil/trace.go:171","msg":"trace[927199546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25531; }","duration":"264.489929ms","start":"2026-04-28T14:41:54.814207Z","end":"2026-04-28T14:41:55.078697Z","steps":["trace[927199546] 'agreement among raft nodes before linearized reading' (duration: 264.384897ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:55.078816Z","caller":"traceutil/trace.go:171","msg":"trace[1309409011] transaction","detail":"{read_only:false; response_revision:25531; number_of_response:1; }","duration":"229.591533ms","start":"2026-04-28T14:41:54.849215Z","end":"2026-04-28T14:41:55.078807Z","steps":["trace[1309409011] 'process raft request' (duration: 229.206394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:41:55.078894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.370202ms","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-28T14:41:55.078923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.309887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-28T14:41:55.078988Z","caller":"traceutil/trace.go:171","msg":"trace[1165507560] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25531; }","duration":"189.383109ms","start":"2026-04-28T14:41:54.889587Z","end":"2026-04-28T14:41:55.07897Z","steps":["trace[1165507560] 'agreement among raft nodes before linearized reading' (duration: 189.228375ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:55.079004Z","caller":"traceutil/trace.go:171","msg":"trace[1530415262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25531; }","duration":"167.517405ms","start":"2026-04-28T14:41:54.911439Z","end":"2026-04-28T14:41:55.078956Z","steps":["trace[1530415262] 'agreement among raft nodes before linearized reading' (duration: 167.104185ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:55.220789Z","caller":"traceutil/trace.go:171","msg":"trace[712104443] transaction","detail":"{read_only:false; response_revision:25532; number_of_response:1; }","duration":"273.951677ms","start":"2026-04-28T14:41:54.946825Z","end":"2026-04-28T14:41:55.220776Z","steps":["trace[712104443] 'process raft request' (duration: 273.62139ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:55.220736Z","caller":"traceutil/trace.go:171","msg":"trace[506547935] linearizableReadLoop","detail":"{readStateIndex:26366; appliedIndex:26365; }","duration":"140.845766ms","start":"2026-04-28T14:41:55.079873Z","end":"2026-04-28T14:41:55.220719Z","steps":["trace[506547935] 'read index received' (duration: 140.488148ms)","trace[506547935] 'applied index is now lower than readState.Index' (duration: 356.978µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:41:55.221193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.291226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T14:41:55.221342Z","caller":"traceutil/trace.go:171","msg":"trace[1526383004] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25532; }","duration":"141.435639ms","start":"2026-04-28T14:41:55.079847Z","end":"2026-04-28T14:41:55.221282Z","steps":["trace[1526383004] 'agreement among raft nodes before linearized reading' (duration: 141.089441ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:55.363102Z","caller":"traceutil/trace.go:171","msg":"trace[910157292] transaction","detail":"{read_only:false; response_revision:25533; number_of_response:1; }","duration":"280.321719ms","start":"2026-04-28T14:41:55.082734Z","end":"2026-04-28T14:41:55.363056Z","steps":["trace[910157292] 'process raft request' (duration: 279.783116ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:55.363462Z","caller":"traceutil/trace.go:171","msg":"trace[674578179] transaction","detail":"{read_only:false; response_revision:25534; number_of_response:1; }","duration":"280.428682ms","start":"2026-04-28T14:41:55.083014Z","end":"2026-04-28T14:41:55.363442Z","steps":["trace[674578179] 'process raft request' (duration: 279.980212ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:55.36351Z","caller":"traceutil/trace.go:171","msg":"trace[1993517635] transaction","detail":"{read_only:false; response_revision:25536; number_of_response:1; }","duration":"205.854305ms","start":"2026-04-28T14:41:55.157624Z","end":"2026-04-28T14:41:55.363479Z","steps":["trace[1993517635] 'process raft request' (duration: 205.772913ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:55.363666Z","caller":"traceutil/trace.go:171","msg":"trace[111051907] transaction","detail":"{read_only:false; response_revision:25535; number_of_response:1; }","duration":"278.731561ms","start":"2026-04-28T14:41:55.084899Z","end":"2026-04-28T14:41:55.36363Z","steps":["trace[111051907] 'process raft request' (duration: 278.381952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:41:55.801526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.580353ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T14:41:55.801731Z","caller":"traceutil/trace.go:171","msg":"trace[82349716] transaction","detail":"{read_only:false; response_revision:25539; number_of_response:1; }","duration":"335.258255ms","start":"2026-04-28T14:41:55.466461Z","end":"2026-04-28T14:41:55.801719Z","steps":["trace[82349716] 'process raft request' (duration: 335.232364ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:55.801726Z","caller":"traceutil/trace.go:171","msg":"trace[476876919] transaction","detail":"{read_only:false; response_revision:25537; number_of_response:1; }","duration":"578.832294ms","start":"2026-04-28T14:41:55.222835Z","end":"2026-04-28T14:41:55.801667Z","steps":["trace[476876919] 'process raft request' (duration: 165.029536ms)","trace[476876919] 'compare' (duration: 146.696426ms)","trace[476876919] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/capi-kubeadm-control-plane-system/kubeadm-control-plane-manager-leader-election-capi; req_size:623; } (duration: 266.18046ms)"],"step_count":3} {"level":"info","ts":"2026-04-28T14:41:55.801793Z","caller":"traceutil/trace.go:171","msg":"trace[1681778288] linearizableReadLoop","detail":"{readStateIndex:26371; appliedIndex:26366; }","duration":"536.592542ms","start":"2026-04-28T14:41:55.265195Z","end":"2026-04-28T14:41:55.801788Z","steps":["trace[1681778288] 'read index received' (duration: 97.333504ms)","trace[1681778288] 'applied index is now lower than readState.Index' (duration: 439.258548ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:41:55.801841Z","caller":"traceutil/trace.go:171","msg":"trace[1177226526] transaction","detail":"{read_only:false; response_revision:25538; number_of_response:1; }","duration":"357.950979ms","start":"2026-04-28T14:41:55.443884Z","end":"2026-04-28T14:41:55.801835Z","steps":["trace[1177226526] 'process raft request' (duration: 357.718653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:41:55.801865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:41:55.443858Z","time spent":"357.99135ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T14:41:55.801909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:41:55.222817Z","time spent":"579.001717ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T14:41:55.801771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:41:55.466434Z","time spent":"335.316677ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T14:41:55.801992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.015361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18417"} {"level":"info","ts":"2026-04-28T14:41:55.802037Z","caller":"traceutil/trace.go:171","msg":"trace[296538509] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:25539; }","duration":"431.094753ms","start":"2026-04-28T14:41:55.370932Z","end":"2026-04-28T14:41:55.802026Z","steps":["trace[296538509] 'agreement among raft nodes before linearized reading' (duration: 430.952399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:41:55.802082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:41:55.370919Z","time spent":"431.155674ms","remote":"127.0.0.1:50444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":18440,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" "} {"level":"warn","ts":"2026-04-28T14:41:55.802089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.89218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T14:41:55.802117Z","caller":"traceutil/trace.go:171","msg":"trace[785219905] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25539; }","duration":"536.952331ms","start":"2026-04-28T14:41:55.265156Z","end":"2026-04-28T14:41:55.802108Z","steps":["trace[785219905] 'agreement among raft nodes before linearized reading' (duration: 536.88369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:41:55.802138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:41:55.265138Z","time spent":"536.994552ms","remote":"127.0.0.1:59258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"warn","ts":"2026-04-28T14:41:56.217825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.70788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-28T14:41:56.217852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.384582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-28T14:41:56.2179Z","caller":"traceutil/trace.go:171","msg":"trace[872375310] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:25539; }","duration":"177.831053ms","start":"2026-04-28T14:41:56.040053Z","end":"2026-04-28T14:41:56.217885Z","steps":["trace[872375310] 'count revisions from in-memory index tree' (duration: 177.566175ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:56.217951Z","caller":"traceutil/trace.go:171","msg":"trace[343779935] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:25539; }","duration":"129.490353ms","start":"2026-04-28T14:41:56.088414Z","end":"2026-04-28T14:41:56.217904Z","steps":["trace[343779935] 'count revisions from in-memory index tree' (duration: 129.294789ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:56.456293Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22190,"took":"1.510902336s","hash":256963759,"current-db-size-bytes":57659392,"current-db-size":"58 MB","current-db-size-in-use-bytes":43413504,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-04-28T14:41:56.456363Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":256963759,"revision":22190,"compact-revision":19823} {"level":"warn","ts":"2026-04-28T14:41:56.456573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.718021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-04-28T14:41:56.456645Z","caller":"traceutil/trace.go:171","msg":"trace[425609761] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25539; }","duration":"147.853224ms","start":"2026-04-28T14:41:56.308771Z","end":"2026-04-28T14:41:56.456625Z","steps":["trace[425609761] 'range keys from in-memory index tree' (duration: 147.518077ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:56.678259Z","caller":"traceutil/trace.go:171","msg":"trace[517612670] transaction","detail":"{read_only:false; response_revision:25541; number_of_response:1; }","duration":"217.283228ms","start":"2026-04-28T14:41:56.460954Z","end":"2026-04-28T14:41:56.678237Z","steps":["trace[517612670] 'process raft request' (duration: 136.176834ms)","trace[517612670] 'compare' (duration: 81.002432ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:41:56.98138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.562232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T14:41:56.981445Z","caller":"traceutil/trace.go:171","msg":"trace[1500722363] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25542; }","duration":"166.679015ms","start":"2026-04-28T14:41:56.81475Z","end":"2026-04-28T14:41:56.981429Z","steps":["trace[1500722363] 'range keys from in-memory index tree' (duration: 166.47811ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:57.249323Z","caller":"traceutil/trace.go:171","msg":"trace[1612798983] linearizableReadLoop","detail":"{readStateIndex:26378; appliedIndex:26377; }","duration":"197.829412ms","start":"2026-04-28T14:41:57.051469Z","end":"2026-04-28T14:41:57.249298Z","steps":["trace[1612798983] 'read index received' (duration: 197.636447ms)","trace[1612798983] 'applied index is now lower than readState.Index' (duration: 191.955µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:41:57.249353Z","caller":"traceutil/trace.go:171","msg":"trace[973717948] transaction","detail":"{read_only:false; response_revision:25544; number_of_response:1; }","duration":"208.996069ms","start":"2026-04-28T14:41:57.040326Z","end":"2026-04-28T14:41:57.249322Z","steps":["trace[973717948] 'process raft request' (duration: 208.786434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:41:57.249545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.045127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/securitygroups/\" range_end:\"/registry/openstack.k-orc.cloud/securitygroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T14:41:57.249695Z","caller":"traceutil/trace.go:171","msg":"trace[984633417] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/securitygroups/; range_end:/registry/openstack.k-orc.cloud/securitygroups0; response_count:0; response_revision:25544; }","duration":"198.240892ms","start":"2026-04-28T14:41:57.051428Z","end":"2026-04-28T14:41:57.249669Z","steps":["trace[984633417] 'agreement among raft nodes before linearized reading' (duration: 197.973045ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:57.412719Z","caller":"traceutil/trace.go:171","msg":"trace[1113190983] transaction","detail":"{read_only:false; response_revision:25546; number_of_response:1; }","duration":"328.790921ms","start":"2026-04-28T14:41:57.083911Z","end":"2026-04-28T14:41:57.412702Z","steps":["trace[1113190983] 'process raft request' (duration: 328.73015ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:57.412723Z","caller":"traceutil/trace.go:171","msg":"trace[1879563161] transaction","detail":"{read_only:false; response_revision:25545; number_of_response:1; }","duration":"329.515638ms","start":"2026-04-28T14:41:57.083183Z","end":"2026-04-28T14:41:57.412699Z","steps":["trace[1879563161] 'process raft request' (duration: 329.329014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:41:57.412856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:41:57.083905Z","time spent":"328.872303ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T14:41:57.412868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:41:57.08317Z","time spent":"329.61268ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T14:41:57.413035Z","caller":"traceutil/trace.go:171","msg":"trace[286269305] transaction","detail":"{read_only:false; response_revision:25547; number_of_response:1; }","duration":"329.017537ms","start":"2026-04-28T14:41:57.083995Z","end":"2026-04-28T14:41:57.413012Z","steps":["trace[286269305] 'process raft request' (duration: 328.679468ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:57.413119Z","caller":"traceutil/trace.go:171","msg":"trace[1693712712] transaction","detail":"{read_only:false; response_revision:25548; number_of_response:1; }","duration":"329.074079ms","start":"2026-04-28T14:41:57.084031Z","end":"2026-04-28T14:41:57.413105Z","steps":["trace[1693712712] 'process raft request' (duration: 328.866844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:41:57.413167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:41:57.083967Z","time spent":"329.133929ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T14:41:57.413175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:41:57.084023Z","time spent":"329.12375ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T14:41:57.413174Z","caller":"traceutil/trace.go:171","msg":"trace[23584956] linearizableReadLoop","detail":"{readStateIndex:26383; appliedIndex:26378; }","duration":"163.717165ms","start":"2026-04-28T14:41:57.249393Z","end":"2026-04-28T14:41:57.41311Z","steps":["trace[23584956] 'read index received' (duration: 163.113869ms)","trace[23584956] 'applied index is now lower than readState.Index' (duration: 602.346µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:41:57.413271Z","caller":"traceutil/trace.go:171","msg":"trace[674585767] transaction","detail":"{read_only:false; response_revision:25549; number_of_response:1; }","duration":"329.195191ms","start":"2026-04-28T14:41:57.084068Z","end":"2026-04-28T14:41:57.413263Z","steps":["trace[674585767] 'process raft request' (duration: 328.946015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:41:57.413309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:41:57.083854Z","time spent":"329.433277ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T14:41:57.413418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.023149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T14:41:57.413467Z","caller":"traceutil/trace.go:171","msg":"trace[481229737] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:25549; }","duration":"337.116351ms","start":"2026-04-28T14:41:57.076337Z","end":"2026-04-28T14:41:57.413453Z","steps":["trace[481229737] 'agreement among raft nodes before linearized reading' (duration: 336.992318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:41:57.413475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.397657ms","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-28T14:41:57.413611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:41:57.076299Z","time spent":"337.259434ms","remote":"127.0.0.1:52092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-28T14:41:57.413684Z","caller":"traceutil/trace.go:171","msg":"trace[339718166] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25549; }","duration":"188.562121ms","start":"2026-04-28T14:41:57.22501Z","end":"2026-04-28T14:41:57.413572Z","steps":["trace[339718166] 'agreement among raft nodes before linearized reading' (duration: 188.311355ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:57.592995Z","caller":"traceutil/trace.go:171","msg":"trace[1698624645] transaction","detail":"{read_only:false; response_revision:25551; number_of_response:1; }","duration":"174.630596ms","start":"2026-04-28T14:41:57.418325Z","end":"2026-04-28T14:41:57.592956Z","steps":["trace[1698624645] 'process raft request' (duration: 146.84695ms)","trace[1698624645] 'compare' (duration: 27.328756ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:41:57.593039Z","caller":"traceutil/trace.go:171","msg":"trace[1949491381] transaction","detail":"{read_only:false; response_revision:25552; number_of_response:1; }","duration":"174.321648ms","start":"2026-04-28T14:41:57.418697Z","end":"2026-04-28T14:41:57.593019Z","steps":["trace[1949491381] 'process raft request' (duration: 174.150764ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:57.593014Z","caller":"traceutil/trace.go:171","msg":"trace[697750966] transaction","detail":"{read_only:false; response_revision:25553; number_of_response:1; }","duration":"173.715734ms","start":"2026-04-28T14:41:57.419273Z","end":"2026-04-28T14:41:57.592989Z","steps":["trace[697750966] 'process raft request' (duration: 173.665453ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:57.937148Z","caller":"traceutil/trace.go:171","msg":"trace[376417281] linearizableReadLoop","detail":"{readStateIndex:26388; appliedIndex:26387; }","duration":"122.119587ms","start":"2026-04-28T14:41:57.815004Z","end":"2026-04-28T14:41:57.937123Z","steps":["trace[376417281] 'read index received' (duration: 121.998524ms)","trace[376417281] 'applied index is now lower than readState.Index' (duration: 119.853µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:41:57.937221Z","caller":"traceutil/trace.go:171","msg":"trace[1077123526] transaction","detail":"{read_only:false; response_revision:25554; number_of_response:1; }","duration":"129.790441ms","start":"2026-04-28T14:41:57.807385Z","end":"2026-04-28T14:41:57.937175Z","steps":["trace[1077123526] 'process raft request' (duration: 129.570236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:41:57.937288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.269151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T14:41:57.937349Z","caller":"traceutil/trace.go:171","msg":"trace[93914302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25554; }","duration":"122.381333ms","start":"2026-04-28T14:41:57.814953Z","end":"2026-04-28T14:41:57.937334Z","steps":["trace[93914302] 'agreement among raft nodes before linearized reading' (duration: 122.280081ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:58.089767Z","caller":"traceutil/trace.go:171","msg":"trace[1061994875] transaction","detail":"{read_only:false; response_revision:25555; number_of_response:1; }","duration":"117.63332ms","start":"2026-04-28T14:41:57.972103Z","end":"2026-04-28T14:41:58.089736Z","steps":["trace[1061994875] 'process raft request' (duration: 115.293863ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:58.859544Z","caller":"traceutil/trace.go:171","msg":"trace[1217850630] transaction","detail":"{read_only:false; response_revision:25558; number_of_response:1; }","duration":"130.473648ms","start":"2026-04-28T14:41:58.729045Z","end":"2026-04-28T14:41:58.859518Z","steps":["trace[1217850630] 'process raft request' (duration: 130.15402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:41:58.998613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.934705ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T14:41:58.998708Z","caller":"traceutil/trace.go:171","msg":"trace[1633735249] linearizableReadLoop","detail":"{readStateIndex:26394; appliedIndex:26392; }","duration":"182.752191ms","start":"2026-04-28T14:41:58.815938Z","end":"2026-04-28T14:41:58.99869Z","steps":["trace[1633735249] 'read index received' (duration: 43.449152ms)","trace[1633735249] 'applied index is now lower than readState.Index' (duration: 139.301399ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:41:58.998776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.842794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T14:41:58.998811Z","caller":"traceutil/trace.go:171","msg":"trace[1022998671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25559; }","duration":"182.903435ms","start":"2026-04-28T14:41:58.815899Z","end":"2026-04-28T14:41:58.998802Z","steps":["trace[1022998671] 'agreement among raft nodes before linearized reading' (duration: 182.838013ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:41:58.998783Z","caller":"traceutil/trace.go:171","msg":"trace[1110810670] transaction","detail":"{read_only:false; response_revision:25559; number_of_response:1; }","duration":"241.299394ms","start":"2026-04-28T14:41:58.757451Z","end":"2026-04-28T14:41:58.99875Z","steps":["trace[1110810670] 'process raft request' (duration: 126.138663ms)","trace[1110810670] 'compare' (duration: 114.730659ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:42:08.275445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.320196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0f9e497c-b951-4b74-9b9c-22066708ce89\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-04-28T14:42:08.275527Z","caller":"traceutil/trace.go:171","msg":"trace[53501351] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0f9e497c-b951-4b74-9b9c-22066708ce89; range_end:; response_count:1; response_revision:25684; }","duration":"201.48699ms","start":"2026-04-28T14:42:08.074018Z","end":"2026-04-28T14:42:08.275505Z","steps":["trace[53501351] 'range keys from in-memory index tree' (duration: 201.07794ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:08.990107Z","caller":"traceutil/trace.go:171","msg":"trace[1944089301] transaction","detail":"{read_only:false; response_revision:25686; number_of_response:1; }","duration":"284.053398ms","start":"2026-04-28T14:42:08.706022Z","end":"2026-04-28T14:42:08.990076Z","steps":["trace[1944089301] 'process raft request' (duration: 283.877744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:09.234682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.183755ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T14:42:09.2348Z","caller":"traceutil/trace.go:171","msg":"trace[191397551] linearizableReadLoop","detail":"{readStateIndex:26524; appliedIndex:26523; }","duration":"422.308583ms","start":"2026-04-28T14:42:08.812476Z","end":"2026-04-28T14:42:09.234784Z","steps":["trace[191397551] 'read index received' (duration: 178.463529ms)","trace[191397551] 'applied index is now lower than readState.Index' (duration: 243.843814ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:42:09.234872Z","caller":"traceutil/trace.go:171","msg":"trace[483342992] transaction","detail":"{read_only:false; response_revision:25687; number_of_response:1; }","duration":"487.971317ms","start":"2026-04-28T14:42:08.746887Z","end":"2026-04-28T14:42:09.234858Z","steps":["trace[483342992] 'process raft request' (duration: 356.535266ms)","trace[483342992] 'compare' (duration: 131.074622ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:42:09.234932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:42:08.746859Z","time spent":"488.044609ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T14:42:09.234919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.425736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T14:42:09.23499Z","caller":"traceutil/trace.go:171","msg":"trace[388550327] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25687; }","duration":"422.536949ms","start":"2026-04-28T14:42:08.812433Z","end":"2026-04-28T14:42:09.23497Z","steps":["trace[388550327] 'agreement among raft nodes before linearized reading' (duration: 422.427776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:09.235026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:42:08.812414Z","time spent":"422.60337ms","remote":"127.0.0.1:50268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T14:42:09.236384Z","caller":"traceutil/trace.go:171","msg":"trace[501943809] transaction","detail":"{read_only:false; response_revision:25688; number_of_response:1; }","duration":"196.099211ms","start":"2026-04-28T14:42:09.040268Z","end":"2026-04-28T14:42:09.236368Z","steps":["trace[501943809] 'process raft request' (duration: 195.987888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:09.237447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.54318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T14:42:09.237514Z","caller":"traceutil/trace.go:171","msg":"trace[1327491781] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:25689; }","duration":"125.649122ms","start":"2026-04-28T14:42:09.111847Z","end":"2026-04-28T14:42:09.237496Z","steps":["trace[1327491781] 'agreement among raft nodes before linearized reading' (duration: 125.527519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:09.237546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.492101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-04-28T14:42:09.237627Z","caller":"traceutil/trace.go:171","msg":"trace[946041784] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25689; }","duration":"181.583203ms","start":"2026-04-28T14:42:09.056025Z","end":"2026-04-28T14:42:09.237609Z","steps":["trace[946041784] 'agreement among raft nodes before linearized reading' (duration: 181.388828ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:09.539892Z","caller":"traceutil/trace.go:171","msg":"trace[193691065] linearizableReadLoop","detail":"{readStateIndex:26527; appliedIndex:26526; }","duration":"295.697117ms","start":"2026-04-28T14:42:09.244155Z","end":"2026-04-28T14:42:09.539852Z","steps":["trace[193691065] 'read index received' (duration: 290.36139ms)","trace[193691065] 'applied index is now lower than readState.Index' (duration: 5.333787ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:42:09.540079Z","caller":"traceutil/trace.go:171","msg":"trace[1147699304] transaction","detail":"{read_only:false; response_revision:25690; number_of_response:1; }","duration":"298.226469ms","start":"2026-04-28T14:42:09.241821Z","end":"2026-04-28T14:42:09.540048Z","steps":["trace[1147699304] 'process raft request' (duration: 292.677246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:09.540278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.095308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-04-28T14:42:09.54032Z","caller":"traceutil/trace.go:171","msg":"trace[1392177165] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:25690; }","duration":"296.189309ms","start":"2026-04-28T14:42:09.244116Z","end":"2026-04-28T14:42:09.540305Z","steps":["trace[1392177165] 'agreement among raft nodes before linearized reading' (duration: 295.924173ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:09.784244Z","caller":"traceutil/trace.go:171","msg":"trace[1920480975] transaction","detail":"{read_only:false; response_revision:25691; number_of_response:1; }","duration":"319.060388ms","start":"2026-04-28T14:42:09.465153Z","end":"2026-04-28T14:42:09.784213Z","steps":["trace[1920480975] 'process raft request' (duration: 318.786331ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:09.784348Z","caller":"traceutil/trace.go:171","msg":"trace[509949967] linearizableReadLoop","detail":"{readStateIndex:26532; appliedIndex:26527; }","duration":"244.334397ms","start":"2026-04-28T14:42:09.539998Z","end":"2026-04-28T14:42:09.784332Z","steps":["trace[509949967] 'read index received' (duration: 243.853745ms)","trace[509949967] 'applied index is now lower than readState.Index' (duration: 479.842µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:42:09.784402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:42:09.465121Z","time spent":"319.196771ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T14:42:09.784444Z","caller":"traceutil/trace.go:171","msg":"trace[620272526] transaction","detail":"{read_only:false; response_revision:25695; number_of_response:1; }","duration":"313.946315ms","start":"2026-04-28T14:42:09.470451Z","end":"2026-04-28T14:42:09.784398Z","steps":["trace[620272526] 'process raft request' (duration: 313.831182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:09.784531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.22725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T14:42:09.78451Z","caller":"traceutil/trace.go:171","msg":"trace[310799834] transaction","detail":"{read_only:false; response_revision:25694; number_of_response:1; }","duration":"314.087018ms","start":"2026-04-28T14:42:09.470392Z","end":"2026-04-28T14:42:09.784479Z","steps":["trace[310799834] 'process raft request' (duration: 313.786381ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:09.784578Z","caller":"traceutil/trace.go:171","msg":"trace[1814424223] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:25695; }","duration":"447.325592ms","start":"2026-04-28T14:42:09.33724Z","end":"2026-04-28T14:42:09.784566Z","steps":["trace[1814424223] 'agreement among raft nodes before linearized reading' (duration: 447.186949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:09.784613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:42:09.337218Z","time spent":"447.386574ms","remote":"127.0.0.1:49050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true "} {"level":"warn","ts":"2026-04-28T14:42:09.78463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:42:09.470421Z","time spent":"314.107929ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T14:42:09.784657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:42:09.470383Z","time spent":"314.19846ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T14:42:09.78466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.929437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"info","ts":"2026-04-28T14:42:09.784689Z","caller":"traceutil/trace.go:171","msg":"trace[1718269167] transaction","detail":"{read_only:false; response_revision:25692; number_of_response:1; }","duration":"315.019521ms","start":"2026-04-28T14:42:09.469637Z","end":"2026-04-28T14:42:09.784656Z","steps":["trace[1718269167] 'process raft request' (duration: 314.463798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:09.784688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.018343ms","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-28T14:42:09.784731Z","caller":"traceutil/trace.go:171","msg":"trace[2019763948] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:25695; }","duration":"239.033139ms","start":"2026-04-28T14:42:09.545677Z","end":"2026-04-28T14:42:09.78471Z","steps":["trace[2019763948] 'agreement among raft nodes before linearized reading' (duration: 238.831935ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:09.784772Z","caller":"traceutil/trace.go:171","msg":"trace[2000269165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25695; }","duration":"119.113615ms","start":"2026-04-28T14:42:09.665639Z","end":"2026-04-28T14:42:09.784752Z","steps":["trace[2000269165] 'agreement among raft nodes before linearized reading' (duration: 118.904111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:09.78478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.326658ms","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-28T14:42:09.784824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.466058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-28T14:42:09.784838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:42:09.469624Z","time spent":"315.121513ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T14:42:09.784688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.935051ms","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-28T14:42:09.784873Z","caller":"traceutil/trace.go:171","msg":"trace[1763747432] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25695; }","duration":"136.459291ms","start":"2026-04-28T14:42:09.648398Z","end":"2026-04-28T14:42:09.784857Z","steps":["trace[1763747432] 'agreement among raft nodes before linearized reading' (duration: 136.224315ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:09.784874Z","caller":"traceutil/trace.go:171","msg":"trace[1691010368] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:25695; }","duration":"279.579081ms","start":"2026-04-28T14:42:09.505282Z","end":"2026-04-28T14:42:09.784861Z","steps":["trace[1691010368] 'agreement among raft nodes before linearized reading' (duration: 279.12532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:09.784875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.288649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0f9e497c-b951-4b74-9b9c-22066708ce89\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-04-28T14:42:09.784926Z","caller":"traceutil/trace.go:171","msg":"trace[1150161858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25695; }","duration":"119.195127ms","start":"2026-04-28T14:42:09.665707Z","end":"2026-04-28T14:42:09.784902Z","steps":["trace[1150161858] 'agreement among raft nodes before linearized reading' (duration: 118.808828ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:09.784932Z","caller":"traceutil/trace.go:171","msg":"trace[1183380234] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0f9e497c-b951-4b74-9b9c-22066708ce89; range_end:; response_count:1; response_revision:25695; }","duration":"502.395291ms","start":"2026-04-28T14:42:09.282522Z","end":"2026-04-28T14:42:09.784917Z","steps":["trace[1183380234] 'agreement among raft nodes before linearized reading' (duration: 502.141345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:09.784967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:42:09.282491Z","time spent":"502.467913ms","remote":"127.0.0.1:50454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3784,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0f9e497c-b951-4b74-9b9c-22066708ce89\" "} {"level":"info","ts":"2026-04-28T14:42:09.785117Z","caller":"traceutil/trace.go:171","msg":"trace[564457704] transaction","detail":"{read_only:false; response_revision:25693; number_of_response:1; }","duration":"315.238247ms","start":"2026-04-28T14:42:09.469854Z","end":"2026-04-28T14:42:09.785092Z","steps":["trace[564457704] 'process raft request' (duration: 314.293004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:09.785224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:42:09.469816Z","time spent":"315.353329ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T14:42:09.785531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.055149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-28T14:42:09.785626Z","caller":"traceutil/trace.go:171","msg":"trace[1165534259] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25695; }","duration":"246.153781ms","start":"2026-04-28T14:42:09.539457Z","end":"2026-04-28T14:42:09.785611Z","steps":["trace[1165534259] 'agreement among raft nodes before linearized reading' (duration: 244.988873ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:10.097189Z","caller":"traceutil/trace.go:171","msg":"trace[1033515686] transaction","detail":"{read_only:false; response_revision:25698; number_of_response:1; }","duration":"306.701621ms","start":"2026-04-28T14:42:09.790469Z","end":"2026-04-28T14:42:10.09717Z","steps":["trace[1033515686] 'process raft request' (duration: 306.621379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:10.097389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:42:09.790465Z","time spent":"306.798663ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T14:42:10.097391Z","caller":"traceutil/trace.go:171","msg":"trace[1205373455] transaction","detail":"{read_only:false; response_revision:25699; number_of_response:1; }","duration":"306.316922ms","start":"2026-04-28T14:42:09.791016Z","end":"2026-04-28T14:42:10.097333Z","steps":["trace[1205373455] 'process raft request' (duration: 306.124157ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:10.097192Z","caller":"traceutil/trace.go:171","msg":"trace[486057270] transaction","detail":"{read_only:false; response_revision:25697; number_of_response:1; }","duration":"306.737591ms","start":"2026-04-28T14:42:09.790417Z","end":"2026-04-28T14:42:10.097154Z","steps":["trace[486057270] 'process raft request' (duration: 261.411145ms)","trace[486057270] 'compare' (duration: 45.147822ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:42:10.097467Z","caller":"traceutil/trace.go:171","msg":"trace[1203744095] transaction","detail":"{read_only:false; response_revision:25700; number_of_response:1; }","duration":"113.626394ms","start":"2026-04-28T14:42:09.98381Z","end":"2026-04-28T14:42:10.097436Z","steps":["trace[1203744095] 'process raft request' (duration: 113.473469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:10.097571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:42:09.790999Z","time spent":"306.464937ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T14:42:10.097575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:42:09.7904Z","time spent":"307.118072ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T14:42:10.09757Z","caller":"traceutil/trace.go:171","msg":"trace[1108312023] linearizableReadLoop","detail":"{readStateIndex:26535; appliedIndex:26532; }","duration":"306.972369ms","start":"2026-04-28T14:42:09.790575Z","end":"2026-04-28T14:42:10.097548Z","steps":["trace[1108312023] 'read index received' (duration: 56.031173ms)","trace[1108312023] 'applied index is now lower than readState.Index' (duration: 250.938786ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:42:10.097745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.151423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18aa8c5b6330ed26\" ","response":"range_response_count:1 size:742"} {"level":"info","ts":"2026-04-28T14:42:10.097806Z","caller":"traceutil/trace.go:171","msg":"trace[666748994] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18aa8c5b6330ed26; range_end:; response_count:1; response_revision:25700; }","duration":"307.234994ms","start":"2026-04-28T14:42:09.790556Z","end":"2026-04-28T14:42:10.097791Z","steps":["trace[666748994] 'agreement among raft nodes before linearized reading' (duration: 307.05273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:10.097846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.772848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-28T14:42:10.097849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:42:09.790551Z","time spent":"307.286536ms","remote":"127.0.0.1:50326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":765,"request content":"key:\"/registry/events/openstack/rabbitmq-manila.18aa8c5b6330ed26\" "} {"level":"info","ts":"2026-04-28T14:42:10.097892Z","caller":"traceutil/trace.go:171","msg":"trace[28859937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25700; }","duration":"282.84601ms","start":"2026-04-28T14:42:09.815035Z","end":"2026-04-28T14:42:10.097881Z","steps":["trace[28859937] 'agreement among raft nodes before linearized reading' (duration: 282.766028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:10.098017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.854315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1444"} {"level":"info","ts":"2026-04-28T14:42:10.098068Z","caller":"traceutil/trace.go:171","msg":"trace[451500632] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25700; }","duration":"306.934448ms","start":"2026-04-28T14:42:09.791122Z","end":"2026-04-28T14:42:10.098056Z","steps":["trace[451500632] 'agreement among raft nodes before linearized reading' (duration: 306.767774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:10.098109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:42:09.791113Z","time spent":"306.987119ms","remote":"127.0.0.1:50466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":1467,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-04-28T14:42:10.296973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.282174ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T14:42:10.297082Z","caller":"traceutil/trace.go:171","msg":"trace[822083761] linearizableReadLoop","detail":"{readStateIndex:26538; appliedIndex:26537; }","duration":"194.235957ms","start":"2026-04-28T14:42:10.10283Z","end":"2026-04-28T14:42:10.297066Z","steps":["trace[822083761] 'read index received' (duration: 93.745598ms)","trace[822083761] 'applied index is now lower than readState.Index' (duration: 100.489219ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:42:10.297265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.431721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-04-28T14:42:10.297361Z","caller":"traceutil/trace.go:171","msg":"trace[555063808] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25701; }","duration":"194.527823ms","start":"2026-04-28T14:42:10.102819Z","end":"2026-04-28T14:42:10.297347Z","steps":["trace[555063808] 'agreement among raft nodes before linearized reading' (duration: 194.291408ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:10.297466Z","caller":"traceutil/trace.go:171","msg":"trace[523239327] transaction","detail":"{read_only:false; response_revision:25701; number_of_response:1; }","duration":"196.91108ms","start":"2026-04-28T14:42:10.100505Z","end":"2026-04-28T14:42:10.297416Z","steps":["trace[523239327] 'process raft request' (duration: 96.091963ms)","trace[523239327] 'compare' (duration: 99.940926ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:42:10.499212Z","caller":"traceutil/trace.go:171","msg":"trace[1344626962] transaction","detail":"{read_only:false; response_revision:25703; number_of_response:1; }","duration":"145.405025ms","start":"2026-04-28T14:42:10.353772Z","end":"2026-04-28T14:42:10.499177Z","steps":["trace[1344626962] 'process raft request' (duration: 145.133939ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:10.789932Z","caller":"traceutil/trace.go:171","msg":"trace[2064009783] transaction","detail":"{read_only:false; response_revision:25704; number_of_response:1; }","duration":"252.156624ms","start":"2026-04-28T14:42:10.53773Z","end":"2026-04-28T14:42:10.789887Z","steps":["trace[2064009783] 'process raft request' (duration: 205.866844ms)","trace[2064009783] 'compare' (duration: 45.898401ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:42:11.596656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.792877ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T14:42:11.596915Z","caller":"traceutil/trace.go:171","msg":"trace[855243173] transaction","detail":"{read_only:false; response_revision:25706; number_of_response:1; }","duration":"767.937838ms","start":"2026-04-28T14:42:10.828906Z","end":"2026-04-28T14:42:11.596844Z","steps":["trace[855243173] 'process raft request' (duration: 273.868864ms)","trace[855243173] 'compare' (duration: 493.657364ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:42:11.597072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:42:10.828876Z","time spent":"768.112342ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T14:42:11.597126Z","caller":"traceutil/trace.go:171","msg":"trace[1251373567] linearizableReadLoop","detail":"{readStateIndex:26543; appliedIndex:26542; }","duration":"428.998243ms","start":"2026-04-28T14:42:11.168108Z","end":"2026-04-28T14:42:11.597107Z","steps":["trace[1251373567] 'read index received' (duration: 51.111µs)","trace[1251373567] 'applied index is now lower than readState.Index' (duration: 428.944832ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:42:11.597117Z","caller":"traceutil/trace.go:171","msg":"trace[76467795] transaction","detail":"{read_only:false; response_revision:25707; number_of_response:1; }","duration":"350.268497ms","start":"2026-04-28T14:42:11.246824Z","end":"2026-04-28T14:42:11.597093Z","steps":["trace[76467795] 'process raft request' (duration: 349.962459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:11.597281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.167176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T14:42:11.597321Z","caller":"traceutil/trace.go:171","msg":"trace[1316403949] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25707; }","duration":"429.252909ms","start":"2026-04-28T14:42:11.168057Z","end":"2026-04-28T14:42:11.597309Z","steps":["trace[1316403949] 'agreement among raft nodes before linearized reading' (duration: 429.156956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:11.597355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:42:11.168024Z","time spent":"429.32188ms","remote":"127.0.0.1:59100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":29,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true "} {"level":"warn","ts":"2026-04-28T14:42:11.597387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:42:11.246799Z","time spent":"350.45531ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T14:42:11.597505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.634425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-28T14:42:11.597544Z","caller":"traceutil/trace.go:171","msg":"trace[1873119649] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:25707; }","duration":"234.719347ms","start":"2026-04-28T14:42:11.362814Z","end":"2026-04-28T14:42:11.597533Z","steps":["trace[1873119649] 'agreement among raft nodes before linearized reading' (duration: 234.580324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:16.857758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.361517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-28T14:42:16.857905Z","caller":"traceutil/trace.go:171","msg":"trace[757311188] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:25770; }","duration":"103.561982ms","start":"2026-04-28T14:42:16.754321Z","end":"2026-04-28T14:42:16.857883Z","steps":["trace[757311188] 'count revisions from in-memory index tree' (duration: 103.256845ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:16.912975Z","caller":"traceutil/trace.go:171","msg":"trace[20348476] transaction","detail":"{read_only:false; response_revision:25771; number_of_response:1; }","duration":"103.785077ms","start":"2026-04-28T14:42:16.809165Z","end":"2026-04-28T14:42:16.91295Z","steps":["trace[20348476] 'process raft request' (duration: 103.013029ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:35.102401Z","caller":"traceutil/trace.go:171","msg":"trace[431906693] transaction","detail":"{read_only:false; response_revision:25921; number_of_response:1; }","duration":"118.529661ms","start":"2026-04-28T14:42:34.983852Z","end":"2026-04-28T14:42:35.102382Z","steps":["trace[431906693] 'process raft request' (duration: 118.09703ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:35.724217Z","caller":"traceutil/trace.go:171","msg":"trace[1896899914] linearizableReadLoop","detail":"{readStateIndex:26764; appliedIndex:26763; }","duration":"133.613242ms","start":"2026-04-28T14:42:35.590582Z","end":"2026-04-28T14:42:35.724195Z","steps":["trace[1896899914] 'read index received' (duration: 133.563841ms)","trace[1896899914] 'applied index is now lower than readState.Index' (duration: 48.571µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:42:35.724448Z","caller":"traceutil/trace.go:171","msg":"trace[798427506] transaction","detail":"{read_only:false; response_revision:25922; number_of_response:1; }","duration":"144.77346ms","start":"2026-04-28T14:42:35.579665Z","end":"2026-04-28T14:42:35.724438Z","steps":["trace[798427506] 'process raft request' (duration: 144.40185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:35.724658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.063824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T14:42:35.724686Z","caller":"traceutil/trace.go:171","msg":"trace[1690853525] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:25922; }","duration":"134.136706ms","start":"2026-04-28T14:42:35.59054Z","end":"2026-04-28T14:42:35.724677Z","steps":["trace[1690853525] 'agreement among raft nodes before linearized reading' (duration: 134.068964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:35.979278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.893725ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T14:42:35.979381Z","caller":"traceutil/trace.go:171","msg":"trace[2036141954] linearizableReadLoop","detail":"{readStateIndex:26765; appliedIndex:26764; }","duration":"255.093576ms","start":"2026-04-28T14:42:35.724271Z","end":"2026-04-28T14:42:35.979365Z","steps":["trace[2036141954] 'read index received' (duration: 132.047416ms)","trace[2036141954] 'applied index is now lower than readState.Index' (duration: 123.04496ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:42:35.9794Z","caller":"traceutil/trace.go:171","msg":"trace[978910284] transaction","detail":"{read_only:false; response_revision:25923; number_of_response:1; }","duration":"301.646081ms","start":"2026-04-28T14:42:35.677726Z","end":"2026-04-28T14:42:35.979372Z","steps":["trace[978910284] 'process raft request' (duration: 178.576981ms)","trace[978910284] 'compare' (duration: 122.105796ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:42:35.979454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.387564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-28T14:42:35.979486Z","caller":"traceutil/trace.go:171","msg":"trace[818736589] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:25923; }","duration":"341.443514ms","start":"2026-04-28T14:42:35.63803Z","end":"2026-04-28T14:42:35.979474Z","steps":["trace[818736589] 'agreement among raft nodes before linearized reading' (duration: 341.381323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:35.979533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:42:35.638015Z","time spent":"341.508157ms","remote":"127.0.0.1:50754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":8,"response size":31,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-28T14:42:35.979533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:42:35.6777Z","time spent":"301.766494ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T14:42:35.979655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.33233ms","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-28T14:42:35.979769Z","caller":"traceutil/trace.go:171","msg":"trace[1965422571] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25923; }","duration":"275.434923ms","start":"2026-04-28T14:42:35.70429Z","end":"2026-04-28T14:42:35.979725Z","steps":["trace[1965422571] 'agreement among raft nodes before linearized reading' (duration: 275.163687ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:36.223236Z","caller":"traceutil/trace.go:171","msg":"trace[991876536] transaction","detail":"{read_only:false; response_revision:25924; number_of_response:1; }","duration":"491.82319ms","start":"2026-04-28T14:42:35.731392Z","end":"2026-04-28T14:42:36.223215Z","steps":["trace[991876536] 'process raft request' (duration: 491.670306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:36.223372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:42:35.731375Z","time spent":"491.926852ms","remote":"127.0.0.1:56282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3290,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T14:42:36.492034Z","caller":"traceutil/trace.go:171","msg":"trace[399419465] linearizableReadLoop","detail":"{readStateIndex:26772; appliedIndex:26765; }","duration":"503.312895ms","start":"2026-04-28T14:42:35.988698Z","end":"2026-04-28T14:42:36.492011Z","steps":["trace[399419465] 'read index received' (duration: 234.348347ms)","trace[399419465] 'applied index is now lower than readState.Index' (duration: 268.963728ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:42:36.492051Z","caller":"traceutil/trace.go:171","msg":"trace[1646275531] transaction","detail":"{read_only:false; response_revision:25926; number_of_response:1; }","duration":"610.860933ms","start":"2026-04-28T14:42:35.881146Z","end":"2026-04-28T14:42:36.492007Z","steps":["trace[1646275531] 'process raft request' (duration: 610.660228ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:36.492159Z","caller":"traceutil/trace.go:171","msg":"trace[1201270391] transaction","detail":"{read_only:false; response_revision:25927; number_of_response:1; }","duration":"609.411718ms","start":"2026-04-28T14:42:35.882736Z","end":"2026-04-28T14:42:36.492147Z","steps":["trace[1201270391] 'process raft request' (duration: 609.1146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:36.492219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:42:35.881128Z","time spent":"611.002966ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T14:42:36.492334Z","caller":"traceutil/trace.go:171","msg":"trace[503829786] transaction","detail":"{read_only:false; response_revision:25931; number_of_response:1; }","duration":"306.155079ms","start":"2026-04-28T14:42:36.186163Z","end":"2026-04-28T14:42:36.492318Z","steps":["trace[503829786] 'process raft request' (duration: 305.81335ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:36.492352Z","caller":"traceutil/trace.go:171","msg":"trace[1036045944] transaction","detail":"{read_only:false; response_revision:25929; number_of_response:1; }","duration":"607.622144ms","start":"2026-04-28T14:42:35.884716Z","end":"2026-04-28T14:42:36.492338Z","steps":["trace[1036045944] 'process raft request' (duration: 607.195583ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:36.492365Z","caller":"traceutil/trace.go:171","msg":"trace[1951926390] transaction","detail":"{read_only:false; response_revision:25925; number_of_response:1; }","duration":"619.860079ms","start":"2026-04-28T14:42:35.872495Z","end":"2026-04-28T14:42:36.492355Z","steps":["trace[1951926390] 'process raft request' (duration: 619.173232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:36.492401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:42:36.186145Z","time spent":"306.225001ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T14:42:36.492419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:42:35.884703Z","time spent":"607.684185ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T14:42:36.492442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:42:35.872466Z","time spent":"619.929571ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T14:42:36.492503Z","caller":"traceutil/trace.go:171","msg":"trace[2023572543] transaction","detail":"{read_only:false; response_revision:25930; number_of_response:1; }","duration":"509.37798ms","start":"2026-04-28T14:42:35.983118Z","end":"2026-04-28T14:42:36.492496Z","steps":["trace[2023572543] 'process raft request' (duration: 508.828916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:36.492563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:42:35.983097Z","time spent":"509.425261ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T14:42:36.49261Z","caller":"traceutil/trace.go:171","msg":"trace[1429912295] transaction","detail":"{read_only:false; response_revision:25928; number_of_response:1; }","duration":"608.935397ms","start":"2026-04-28T14:42:35.883665Z","end":"2026-04-28T14:42:36.4926Z","steps":["trace[1429912295] 'process raft request' (duration: 608.217699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:36.492676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:42:35.883649Z","time spent":"608.996758ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T14:42:36.49274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.917726ms","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-28T14:42:36.492839Z","caller":"traceutil/trace.go:171","msg":"trace[1330725347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25931; }","duration":"274.058519ms","start":"2026-04-28T14:42:36.218762Z","end":"2026-04-28T14:42:36.492821Z","steps":["trace[1330725347] 'agreement among raft nodes before linearized reading' (duration: 273.841854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:36.492848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.157374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-04-28T14:42:36.492914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.878961ms","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-28T14:42:36.492937Z","caller":"traceutil/trace.go:171","msg":"trace[1732264564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25931; }","duration":"272.946472ms","start":"2026-04-28T14:42:36.219984Z","end":"2026-04-28T14:42:36.49293Z","steps":["trace[1732264564] 'agreement among raft nodes before linearized reading' (duration: 272.893851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:36.492288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:42:35.882711Z","time spent":"609.48378ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T14:42:36.492953Z","caller":"traceutil/trace.go:171","msg":"trace[695135918] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25931; }","duration":"504.275607ms","start":"2026-04-28T14:42:35.988647Z","end":"2026-04-28T14:42:36.492922Z","steps":["trace[695135918] 'agreement among raft nodes before linearized reading' (duration: 504.103053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:36.492983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.573313ms","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-28T14:42:36.493038Z","caller":"traceutil/trace.go:171","msg":"trace[1573940004] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25931; }","duration":"272.647335ms","start":"2026-04-28T14:42:36.22038Z","end":"2026-04-28T14:42:36.493027Z","steps":["trace[1573940004] 'agreement among raft nodes before linearized reading' (duration: 272.47206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:36.493053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:42:35.98863Z","time spent":"504.393179ms","remote":"127.0.0.1:50518","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-28T14:42:36.493031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.20464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"info","ts":"2026-04-28T14:42:36.493181Z","caller":"traceutil/trace.go:171","msg":"trace[1124500831] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25931; }","duration":"261.329093ms","start":"2026-04-28T14:42:36.23181Z","end":"2026-04-28T14:42:36.493139Z","steps":["trace[1124500831] 'agreement among raft nodes before linearized reading' (duration: 260.845932ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:36.700755Z","caller":"traceutil/trace.go:171","msg":"trace[729454211] transaction","detail":"{read_only:false; response_revision:25933; number_of_response:1; }","duration":"203.229503ms","start":"2026-04-28T14:42:36.497508Z","end":"2026-04-28T14:42:36.700738Z","steps":["trace[729454211] 'process raft request' (duration: 202.842243ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:36.700943Z","caller":"traceutil/trace.go:171","msg":"trace[870156619] linearizableReadLoop","detail":"{readStateIndex:26777; appliedIndex:26774; }","duration":"136.991244ms","start":"2026-04-28T14:42:36.563945Z","end":"2026-04-28T14:42:36.700936Z","steps":["trace[870156619] 'read index received' (duration: 136.565164ms)","trace[870156619] 'applied index is now lower than readState.Index' (duration: 425.53µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:42:36.701004Z","caller":"traceutil/trace.go:171","msg":"trace[185969040] transaction","detail":"{read_only:false; response_revision:25935; number_of_response:1; }","duration":"203.098468ms","start":"2026-04-28T14:42:36.497901Z","end":"2026-04-28T14:42:36.700999Z","steps":["trace[185969040] 'process raft request' (duration: 202.934054ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:36.700918Z","caller":"traceutil/trace.go:171","msg":"trace[1161704553] transaction","detail":"{read_only:false; response_revision:25934; number_of_response:1; }","duration":"202.988425ms","start":"2026-04-28T14:42:36.497901Z","end":"2026-04-28T14:42:36.700889Z","steps":["trace[1161704553] 'process raft request' (duration: 202.78423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:36.701217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.2651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"info","ts":"2026-04-28T14:42:36.701271Z","caller":"traceutil/trace.go:171","msg":"trace[577918125] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:25935; }","duration":"137.369633ms","start":"2026-04-28T14:42:36.563888Z","end":"2026-04-28T14:42:36.701258Z","steps":["trace[577918125] 'agreement among raft nodes before linearized reading' (duration: 137.200609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:36.974459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.306513ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T14:42:36.974584Z","caller":"traceutil/trace.go:171","msg":"trace[1822082206] linearizableReadLoop","detail":"{readStateIndex:26778; appliedIndex:26777; }","duration":"273.581519ms","start":"2026-04-28T14:42:36.700985Z","end":"2026-04-28T14:42:36.974566Z","steps":["trace[1822082206] 'read index received' (duration: 151.048061ms)","trace[1822082206] 'applied index is now lower than readState.Index' (duration: 122.532008ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:42:36.974655Z","caller":"traceutil/trace.go:171","msg":"trace[1220245906] transaction","detail":"{read_only:false; response_revision:25936; number_of_response:1; }","duration":"293.406743ms","start":"2026-04-28T14:42:36.681199Z","end":"2026-04-28T14:42:36.974606Z","steps":["trace[1220245906] 'process raft request' (duration: 170.857366ms)","trace[1220245906] 'compare' (duration: 122.126637ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:42:36.974734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.883928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-28T14:42:36.974821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.162244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1444"} {"level":"info","ts":"2026-04-28T14:42:36.974837Z","caller":"traceutil/trace.go:171","msg":"trace[418339860] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:25936; }","duration":"408.02106ms","start":"2026-04-28T14:42:36.566794Z","end":"2026-04-28T14:42:36.974815Z","steps":["trace[418339860] 'agreement among raft nodes before linearized reading' (duration: 407.854456ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:36.97486Z","caller":"traceutil/trace.go:171","msg":"trace[179082733] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25936; }","duration":"267.223326ms","start":"2026-04-28T14:42:36.707626Z","end":"2026-04-28T14:42:36.97485Z","steps":["trace[179082733] 'agreement among raft nodes before linearized reading' (duration: 267.043282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:36.974885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:42:36.56678Z","time spent":"408.094333ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true "} {"level":"warn","ts":"2026-04-28T14:42:36.974994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.170604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T14:42:36.975063Z","caller":"traceutil/trace.go:171","msg":"trace[657969852] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25936; }","duration":"161.290647ms","start":"2026-04-28T14:42:36.813756Z","end":"2026-04-28T14:42:36.975047Z","steps":["trace[657969852] 'agreement among raft nodes before linearized reading' (duration: 161.177614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:38.015072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.802052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T14:42:38.015215Z","caller":"traceutil/trace.go:171","msg":"trace[945245449] transaction","detail":"{read_only:false; response_revision:25938; number_of_response:1; }","duration":"189.035041ms","start":"2026-04-28T14:42:37.826153Z","end":"2026-04-28T14:42:38.015188Z","steps":["trace[945245449] 'process raft request' (duration: 68.993223ms)","trace[945245449] 'compare' (duration: 119.247549ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:42:38.252834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.585489ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T14:42:38.252945Z","caller":"traceutil/trace.go:171","msg":"trace[1709572797] linearizableReadLoop","detail":"{readStateIndex:26782; appliedIndex:26781; }","duration":"262.83893ms","start":"2026-04-28T14:42:37.99009Z","end":"2026-04-28T14:42:38.252928Z","steps":["trace[1709572797] 'read index received' (duration: 155.063567ms)","trace[1709572797] 'applied index is now lower than readState.Index' (duration: 107.773763ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:42:38.253175Z","caller":"traceutil/trace.go:171","msg":"trace[1807344366] transaction","detail":"{read_only:false; response_revision:25939; number_of_response:1; }","duration":"267.86371ms","start":"2026-04-28T14:42:37.985277Z","end":"2026-04-28T14:42:38.253141Z","steps":["trace[1807344366] 'process raft request' (duration: 159.841371ms)","trace[1807344366] 'compare' (duration: 107.457046ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:42:38.253215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.108537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-28T14:42:38.253266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.12683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-04-28T14:42:38.253296Z","caller":"traceutil/trace.go:171","msg":"trace[1345650743] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:25939; }","duration":"263.24052ms","start":"2026-04-28T14:42:37.990036Z","end":"2026-04-28T14:42:38.253277Z","steps":["trace[1345650743] 'agreement among raft nodes before linearized reading' (duration: 263.099596ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:38.253342Z","caller":"traceutil/trace.go:171","msg":"trace[1894415172] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25939; }","duration":"236.235662ms","start":"2026-04-28T14:42:38.01709Z","end":"2026-04-28T14:42:38.253325Z","steps":["trace[1894415172] 'agreement among raft nodes before linearized reading' (duration: 236.023077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:38.605703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.729124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-28T14:42:38.605742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.21141ms","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-28T14:42:38.605794Z","caller":"traceutil/trace.go:171","msg":"trace[1694444037] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25939; }","duration":"107.310382ms","start":"2026-04-28T14:42:38.498471Z","end":"2026-04-28T14:42:38.605781Z","steps":["trace[1694444037] 'range keys from in-memory index tree' (duration: 107.066486ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:38.605795Z","caller":"traceutil/trace.go:171","msg":"trace[1600836817] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:25939; }","duration":"226.835387ms","start":"2026-04-28T14:42:38.378935Z","end":"2026-04-28T14:42:38.605771Z","steps":["trace[1600836817] 'count revisions from in-memory index tree' (duration: 226.632613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:38.605705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.427638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-04-28T14:42:38.605854Z","caller":"traceutil/trace.go:171","msg":"trace[1297195220] transaction","detail":"{read_only:false; response_revision:25940; number_of_response:1; }","duration":"107.276801ms","start":"2026-04-28T14:42:38.498555Z","end":"2026-04-28T14:42:38.605832Z","steps":["trace[1297195220] 'process raft request' (duration: 27.16195ms)","trace[1297195220] 'compare' (duration: 79.958548ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:42:38.605898Z","caller":"traceutil/trace.go:171","msg":"trace[200030735] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25939; }","duration":"244.673484ms","start":"2026-04-28T14:42:38.361203Z","end":"2026-04-28T14:42:38.605877Z","steps":["trace[200030735] 'range keys from in-memory index tree' (duration: 244.221234ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:38.644527Z","caller":"traceutil/trace.go:171","msg":"trace[1641140693] transaction","detail":"{read_only:false; response_revision:25941; number_of_response:1; }","duration":"145.910928ms","start":"2026-04-28T14:42:38.498597Z","end":"2026-04-28T14:42:38.644508Z","steps":["trace[1641140693] 'process raft request' (duration: 145.779855ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:38.644716Z","caller":"traceutil/trace.go:171","msg":"trace[2136761595] transaction","detail":"{read_only:false; response_revision:25942; number_of_response:1; }","duration":"146.099131ms","start":"2026-04-28T14:42:38.498612Z","end":"2026-04-28T14:42:38.644711Z","steps":["trace[2136761595] 'process raft request' (duration: 145.871296ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:38.644791Z","caller":"traceutil/trace.go:171","msg":"trace[1053392323] transaction","detail":"{read_only:false; response_revision:25943; number_of_response:1; }","duration":"146.159713ms","start":"2026-04-28T14:42:38.498618Z","end":"2026-04-28T14:42:38.644778Z","steps":["trace[1053392323] 'process raft request' (duration: 146.0228ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:38.644863Z","caller":"traceutil/trace.go:171","msg":"trace[1935055545] transaction","detail":"{read_only:false; response_revision:25944; number_of_response:1; }","duration":"146.194174ms","start":"2026-04-28T14:42:38.498653Z","end":"2026-04-28T14:42:38.644847Z","steps":["trace[1935055545] 'process raft request' (duration: 146.05825ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:38.644854Z","caller":"traceutil/trace.go:171","msg":"trace[1212651147] transaction","detail":"{read_only:false; response_revision:25945; number_of_response:1; }","duration":"145.58814ms","start":"2026-04-28T14:42:38.499232Z","end":"2026-04-28T14:42:38.644821Z","steps":["trace[1212651147] 'process raft request' (duration: 145.496978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:39.070587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.587529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-04-28T14:42:39.070643Z","caller":"traceutil/trace.go:171","msg":"trace[85900964] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25950; }","duration":"175.667421ms","start":"2026-04-28T14:42:38.894963Z","end":"2026-04-28T14:42:39.07063Z","steps":["trace[85900964] 'range keys from in-memory index tree' (duration: 175.480347ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:44.865969Z","caller":"traceutil/trace.go:171","msg":"trace[943284973] linearizableReadLoop","detail":"{readStateIndex:26834; appliedIndex:26829; }","duration":"154.935684ms","start":"2026-04-28T14:42:44.711012Z","end":"2026-04-28T14:42:44.865948Z","steps":["trace[943284973] 'read index received' (duration: 148.720705ms)","trace[943284973] 'applied index is now lower than readState.Index' (duration: 6.214249ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:42:44.86618Z","caller":"traceutil/trace.go:171","msg":"trace[155820133] transaction","detail":"{read_only:false; response_revision:25986; number_of_response:1; }","duration":"199.525782ms","start":"2026-04-28T14:42:44.666644Z","end":"2026-04-28T14:42:44.86617Z","steps":["trace[155820133] 'process raft request' (duration: 193.14776ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:44.866457Z","caller":"traceutil/trace.go:171","msg":"trace[550916323] transaction","detail":"{read_only:false; response_revision:25989; number_of_response:1; }","duration":"196.397617ms","start":"2026-04-28T14:42:44.670033Z","end":"2026-04-28T14:42:44.866431Z","steps":["trace[550916323] 'process raft request' (duration: 195.856835ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:44.866465Z","caller":"traceutil/trace.go:171","msg":"trace[1221484078] transaction","detail":"{read_only:false; response_revision:25987; number_of_response:1; }","duration":"198.16639ms","start":"2026-04-28T14:42:44.668259Z","end":"2026-04-28T14:42:44.866426Z","steps":["trace[1221484078] 'process raft request' (duration: 197.574756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:44.866515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.532122ms","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-28T14:42:44.866503Z","caller":"traceutil/trace.go:171","msg":"trace[1374101197] transaction","detail":"{read_only:false; response_revision:25990; number_of_response:1; }","duration":"196.256784ms","start":"2026-04-28T14:42:44.670207Z","end":"2026-04-28T14:42:44.866464Z","steps":["trace[1374101197] 'process raft request' (duration: 195.708721ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:44.866549Z","caller":"traceutil/trace.go:171","msg":"trace[640528691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25990; }","duration":"156.622564ms","start":"2026-04-28T14:42:44.709917Z","end":"2026-04-28T14:42:44.86654Z","steps":["trace[640528691] 'agreement among raft nodes before linearized reading' (duration: 156.46935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:44.866757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.726837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-28T14:42:44.867161Z","caller":"traceutil/trace.go:171","msg":"trace[1587330757] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:25990; }","duration":"127.203938ms","start":"2026-04-28T14:42:44.739943Z","end":"2026-04-28T14:42:44.867147Z","steps":["trace[1587330757] 'agreement among raft nodes before linearized reading' (duration: 126.673916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:44.866775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.814916ms","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-28T14:42:44.86734Z","caller":"traceutil/trace.go:171","msg":"trace[2093502434] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25990; }","duration":"169.4154ms","start":"2026-04-28T14:42:44.697894Z","end":"2026-04-28T14:42:44.86731Z","steps":["trace[2093502434] 'agreement among raft nodes before linearized reading' (duration: 168.669693ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:44.866829Z","caller":"traceutil/trace.go:171","msg":"trace[1191783054] transaction","detail":"{read_only:false; response_revision:25988; number_of_response:1; }","duration":"196.539061ms","start":"2026-04-28T14:42:44.669915Z","end":"2026-04-28T14:42:44.866454Z","steps":["trace[1191783054] 'process raft request' (duration: 195.949007ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:45.04988Z","caller":"traceutil/trace.go:171","msg":"trace[1126797590] transaction","detail":"{read_only:false; response_revision:25991; number_of_response:1; }","duration":"178.762344ms","start":"2026-04-28T14:42:44.87107Z","end":"2026-04-28T14:42:45.049833Z","steps":["trace[1126797590] 'process raft request' (duration: 154.324029ms)","trace[1126797590] 'compare' (duration: 24.092527ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:42:45.053076Z","caller":"traceutil/trace.go:171","msg":"trace[796245219] transaction","detail":"{read_only:false; response_revision:25992; number_of_response:1; }","duration":"181.941171ms","start":"2026-04-28T14:42:44.871109Z","end":"2026-04-28T14:42:45.05305Z","steps":["trace[796245219] 'process raft request' (duration: 181.795927ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:42:45.053264Z","caller":"traceutil/trace.go:171","msg":"trace[1242416787] linearizableReadLoop","detail":"{readStateIndex:26837; appliedIndex:26834; }","duration":"154.047112ms","start":"2026-04-28T14:42:44.899185Z","end":"2026-04-28T14:42:45.053232Z","steps":["trace[1242416787] 'read index received' (duration: 126.214225ms)","trace[1242416787] 'applied index is now lower than readState.Index' (duration: 27.830507ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:42:45.053317Z","caller":"traceutil/trace.go:171","msg":"trace[1986122030] transaction","detail":"{read_only:false; response_revision:25993; number_of_response:1; }","duration":"181.904931ms","start":"2026-04-28T14:42:44.871384Z","end":"2026-04-28T14:42:45.053289Z","steps":["trace[1986122030] 'process raft request' (duration: 181.618444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:45.053503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.998596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-28T14:42:45.053534Z","caller":"traceutil/trace.go:171","msg":"trace[77563819] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25993; }","duration":"150.094188ms","start":"2026-04-28T14:42:44.90343Z","end":"2026-04-28T14:42:45.053524Z","steps":["trace[77563819] 'agreement among raft nodes before linearized reading' (duration: 149.930234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:42:45.053515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.319308ms","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-28T14:42:45.053578Z","caller":"traceutil/trace.go:171","msg":"trace[1175840641] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25993; }","duration":"154.422721ms","start":"2026-04-28T14:42:44.89914Z","end":"2026-04-28T14:42:45.053563Z","steps":["trace[1175840641] 'agreement among raft nodes before linearized reading' (duration: 154.201956ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:43:00.80305Z","caller":"traceutil/trace.go:171","msg":"trace[1178011931] linearizableReadLoop","detail":"{readStateIndex:26950; appliedIndex:26949; }","duration":"200.838834ms","start":"2026-04-28T14:43:00.602192Z","end":"2026-04-28T14:43:00.80303Z","steps":["trace[1178011931] 'read index received' (duration: 200.829464ms)","trace[1178011931] 'applied index is now lower than readState.Index' (duration: 7.94µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:43:00.803056Z","caller":"traceutil/trace.go:171","msg":"trace[1455927948] transaction","detail":"{read_only:false; response_revision:26102; number_of_response:1; }","duration":"333.462203ms","start":"2026-04-28T14:43:00.469574Z","end":"2026-04-28T14:43:00.803036Z","steps":["trace[1455927948] 'process raft request' (duration: 333.35336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:43:00.803155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.943117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T14:43:00.80319Z","caller":"traceutil/trace.go:171","msg":"trace[1407574013] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:26102; }","duration":"201.032048ms","start":"2026-04-28T14:43:00.60215Z","end":"2026-04-28T14:43:00.803182Z","steps":["trace[1407574013] 'agreement among raft nodes before linearized reading' (duration: 200.959557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:43:00.803258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:43:00.469546Z","time spent":"333.614307ms","remote":"127.0.0.1:50518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T14:43:00.85992Z","caller":"traceutil/trace.go:171","msg":"trace[688558524] transaction","detail":"{read_only:false; response_revision:26104; number_of_response:1; }","duration":"101.845821ms","start":"2026-04-28T14:43:00.758057Z","end":"2026-04-28T14:43:00.859903Z","steps":["trace[688558524] 'process raft request' (duration: 101.620485ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:43:00.859927Z","caller":"traceutil/trace.go:171","msg":"trace[411284941] transaction","detail":"{read_only:false; response_revision:26103; number_of_response:1; }","duration":"125.074479ms","start":"2026-04-28T14:43:00.734828Z","end":"2026-04-28T14:43:00.859902Z","steps":["trace[411284941] 'process raft request' (duration: 93.552433ms)","trace[411284941] 'compare' (duration: 31.166467ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:43:07.063978Z","caller":"traceutil/trace.go:171","msg":"trace[1424476080] transaction","detail":"{read_only:false; response_revision:26158; number_of_response:1; }","duration":"112.300582ms","start":"2026-04-28T14:43:06.951649Z","end":"2026-04-28T14:43:07.063949Z","steps":["trace[1424476080] 'process raft request' (duration: 40.331726ms)","trace[1424476080] 'compare' (duration: 71.634578ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:43:07.114208Z","caller":"traceutil/trace.go:171","msg":"trace[443891874] transaction","detail":"{read_only:false; response_revision:26159; number_of_response:1; }","duration":"161.633395ms","start":"2026-04-28T14:43:06.952545Z","end":"2026-04-28T14:43:07.114178Z","steps":["trace[443891874] 'process raft request' (duration: 161.43063ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:43:07.114453Z","caller":"traceutil/trace.go:171","msg":"trace[1596614766] transaction","detail":"{read_only:false; response_revision:26160; number_of_response:1; }","duration":"161.741217ms","start":"2026-04-28T14:43:06.95269Z","end":"2026-04-28T14:43:07.114431Z","steps":["trace[1596614766] 'process raft request' (duration: 161.418869ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:43:07.114546Z","caller":"traceutil/trace.go:171","msg":"trace[1768274546] transaction","detail":"{read_only:false; response_revision:26161; number_of_response:1; }","duration":"160.548348ms","start":"2026-04-28T14:43:06.953965Z","end":"2026-04-28T14:43:07.114513Z","steps":["trace[1768274546] 'process raft request' (duration: 160.348274ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:43:07.114785Z","caller":"traceutil/trace.go:171","msg":"trace[1803273155] transaction","detail":"{read_only:false; response_revision:26162; number_of_response:1; }","duration":"159.058522ms","start":"2026-04-28T14:43:06.955711Z","end":"2026-04-28T14:43:07.114769Z","steps":["trace[1803273155] 'process raft request' (duration: 158.725915ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:43:07.281535Z","caller":"traceutil/trace.go:171","msg":"trace[1722062717] linearizableReadLoop","detail":"{readStateIndex:27013; appliedIndex:27012; }","duration":"122.460715ms","start":"2026-04-28T14:43:07.159047Z","end":"2026-04-28T14:43:07.281508Z","steps":["trace[1722062717] 'read index received' (duration: 122.221379ms)","trace[1722062717] 'applied index is now lower than readState.Index' (duration: 235.216µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:43:07.281612Z","caller":"traceutil/trace.go:171","msg":"trace[1389714853] transaction","detail":"{read_only:false; response_revision:26164; number_of_response:1; }","duration":"122.880455ms","start":"2026-04-28T14:43:07.158709Z","end":"2026-04-28T14:43:07.281589Z","steps":["trace[1389714853] 'process raft request' (duration: 122.492256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:43:07.281789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.718251ms","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-28T14:43:07.281906Z","caller":"traceutil/trace.go:171","msg":"trace[1637371327] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26164; }","duration":"122.824743ms","start":"2026-04-28T14:43:07.159024Z","end":"2026-04-28T14:43:07.281848Z","steps":["trace[1637371327] 'agreement among raft nodes before linearized reading' (duration: 122.587427ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:43:07.315266Z","caller":"traceutil/trace.go:171","msg":"trace[2003904106] transaction","detail":"{read_only:false; response_revision:26165; number_of_response:1; }","duration":"155.565128ms","start":"2026-04-28T14:43:07.159684Z","end":"2026-04-28T14:43:07.315249Z","steps":["trace[2003904106] 'process raft request' (duration: 155.432585ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:43:07.315477Z","caller":"traceutil/trace.go:171","msg":"trace[1186073890] transaction","detail":"{read_only:false; response_revision:26166; number_of_response:1; }","duration":"155.735782ms","start":"2026-04-28T14:43:07.159731Z","end":"2026-04-28T14:43:07.315467Z","steps":["trace[1186073890] 'process raft request' (duration: 155.481976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:43:07.315503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.380661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T14:43:07.31557Z","caller":"traceutil/trace.go:171","msg":"trace[1071849613] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:26166; }","duration":"144.476923ms","start":"2026-04-28T14:43:07.171073Z","end":"2026-04-28T14:43:07.31555Z","steps":["trace[1071849613] 'agreement among raft nodes before linearized reading' (duration: 144.36406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:43:07.315593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.874686ms","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-28T14:43:07.315685Z","caller":"traceutil/trace.go:171","msg":"trace[541005421] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26166; }","duration":"156.00064ms","start":"2026-04-28T14:43:07.159646Z","end":"2026-04-28T14:43:07.315647Z","steps":["trace[541005421] 'agreement among raft nodes before linearized reading' (duration: 155.747863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:43:07.315804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.753824ms","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-28T14:43:07.315858Z","caller":"traceutil/trace.go:171","msg":"trace[899795218] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26166; }","duration":"155.829745ms","start":"2026-04-28T14:43:07.160012Z","end":"2026-04-28T14:43:07.315841Z","steps":["trace[899795218] 'agreement among raft nodes before linearized reading' (duration: 155.573739ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:43:07.47724Z","caller":"traceutil/trace.go:171","msg":"trace[1084238754] linearizableReadLoop","detail":"{readStateIndex:27018; appliedIndex:27017; }","duration":"155.581339ms","start":"2026-04-28T14:43:07.321626Z","end":"2026-04-28T14:43:07.477208Z","steps":["trace[1084238754] 'read index received' (duration: 66.441722ms)","trace[1084238754] 'applied index is now lower than readState.Index' (duration: 89.138037ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:43:07.477388Z","caller":"traceutil/trace.go:171","msg":"trace[1687732820] transaction","detail":"{read_only:false; response_revision:26169; number_of_response:1; }","duration":"157.566097ms","start":"2026-04-28T14:43:07.319807Z","end":"2026-04-28T14:43:07.477373Z","steps":["trace[1687732820] 'process raft request' (duration: 68.300087ms)","trace[1687732820] 'compare' (duration: 88.930882ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:43:07.477495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.851855ms","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-28T14:43:07.477549Z","caller":"traceutil/trace.go:171","msg":"trace[1565926504] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26169; }","duration":"155.969918ms","start":"2026-04-28T14:43:07.321568Z","end":"2026-04-28T14:43:07.477538Z","steps":["trace[1565926504] 'agreement among raft nodes before linearized reading' (duration: 155.870975ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:43:07.968525Z","caller":"traceutil/trace.go:171","msg":"trace[1143795515] transaction","detail":"{read_only:false; response_revision:26170; number_of_response:1; }","duration":"139.467854ms","start":"2026-04-28T14:43:07.829033Z","end":"2026-04-28T14:43:07.968501Z","steps":["trace[1143795515] 'process raft request' (duration: 106.219077ms)","trace[1143795515] 'compare' (duration: 33.065802ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:43:27.210216Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006ab9.wal"} {"level":"info","ts":"2026-04-28T14:43:51.35194Z","caller":"traceutil/trace.go:171","msg":"trace[1213735383] transaction","detail":"{read_only:false; response_revision:26661; number_of_response:1; }","duration":"105.108959ms","start":"2026-04-28T14:43:51.24681Z","end":"2026-04-28T14:43:51.351919Z","steps":["trace[1213735383] 'process raft request' (duration: 104.957195ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:43:51.355404Z","caller":"traceutil/trace.go:171","msg":"trace[1436832036] transaction","detail":"{read_only:false; response_revision:26662; number_of_response:1; }","duration":"103.926621ms","start":"2026-04-28T14:43:51.251454Z","end":"2026-04-28T14:43:51.355381Z","steps":["trace[1436832036] 'process raft request' (duration: 103.44331ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:43:51.355886Z","caller":"traceutil/trace.go:171","msg":"trace[1347200161] transaction","detail":"{read_only:false; response_revision:26663; number_of_response:1; }","duration":"102.878446ms","start":"2026-04-28T14:43:51.252995Z","end":"2026-04-28T14:43:51.355873Z","steps":["trace[1347200161] 'process raft request' (duration: 102.026836ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:46:54.946344Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25524} {"level":"info","ts":"2026-04-28T14:46:55.098276Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25524,"took":"146.629232ms","hash":1787438389,"current-db-size-bytes":58081280,"current-db-size":"58 MB","current-db-size-in-use-bytes":35749888,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-04-28T14:46:55.098346Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1787438389,"revision":25524,"compact-revision":22190} {"level":"info","ts":"2026-04-28T14:48:12.504304Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"380f6760faea5f9e","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-28T14:48:12.511254Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-28T14:48:12.511357Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-04-28T14:51:54.953106Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28461} {"level":"info","ts":"2026-04-28T14:51:55.092076Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28461,"took":"135.549809ms","hash":3335826981,"current-db-size-bytes":58081280,"current-db-size":"58 MB","current-db-size-in-use-bytes":31137792,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-28T14:51:55.092145Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3335826981,"revision":28461,"compact-revision":25524} {"level":"info","ts":"2026-04-28T14:56:54.962985Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30735} {"level":"info","ts":"2026-04-28T14:56:55.085955Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30735,"took":"118.836182ms","hash":1380232134,"current-db-size-bytes":58081280,"current-db-size":"58 MB","current-db-size-in-use-bytes":31227904,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-28T14:56:55.08602Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1380232134,"revision":30735,"compact-revision":28461} {"level":"info","ts":"2026-04-28T15:01:54.974033Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33001} {"level":"info","ts":"2026-04-28T15:01:55.125468Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33001,"took":"148.531692ms","hash":4260853871,"current-db-size-bytes":59125760,"current-db-size":"59 MB","current-db-size-in-use-bytes":31125504,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-28T15:01:55.125519Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4260853871,"revision":33001,"compact-revision":30735} {"level":"info","ts":"2026-04-28T15:06:54.980835Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35361} {"level":"info","ts":"2026-04-28T15:06:55.124302Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35361,"took":"139.522259ms","hash":1228585859,"current-db-size-bytes":59592704,"current-db-size":"60 MB","current-db-size-in-use-bytes":31637504,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-28T15:06:55.124489Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1228585859,"revision":35361,"compact-revision":33001} {"level":"info","ts":"2026-04-28T15:09:28.773979Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"380f6760faea5f9e","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-28T15:09:28.779043Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-04-28T15:09:28.779178Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-04-28T15:11:54.987767Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37627} {"level":"info","ts":"2026-04-28T15:11:55.108598Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37627,"took":"117.594327ms","hash":3098929335,"current-db-size-bytes":59592704,"current-db-size":"60 MB","current-db-size-in-use-bytes":31416320,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-28T15:11:55.10868Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3098929335,"revision":37627,"compact-revision":35361}