{"level":"warn","ts":"2026-04-20T14:17:31.201659Z","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-20T14:17:31.201888Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227: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.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227: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-20T14:17:31.201955Z","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-20T14:17:31.201966Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-04-20T14:17:31.20198Z","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-20T14:17:31.202579Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-04-20T14:17:31.202646Z","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":8,"max-cpu-available":8,"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.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227: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-20T14:17:31.20688Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.768382ms"} {"level":"info","ts":"2026-04-20T14:17:31.21374Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-04-20T14:17:31.213868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T14:17:31.213907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-04-20T14:17:31.213921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T14:17:31.213936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-04-20T14:17:31.213987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-04-20T14:17:31.226234Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T14:17:31.228338Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T14:17:31.23047Z","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-20T14:17:31.23261Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T14:17:31.233456Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T14:17:31.233725Z","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-20T14:17:31.233809Z","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-20T14:17:31.233838Z","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-20T14:17:31.234156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-04-20T14:17:31.234326Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-04-20T14:17:31.240475Z","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-20T14:17:31.240648Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-04-20T14:17:31.240713Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-04-20T14:17:31.240853Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T14:17:31.240893Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T14:17:31.315104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T14:17:31.31517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T14:17:31.315194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-04-20T14:17:31.315214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-04-20T14:17:31.315231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-04-20T14:17:31.315248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-04-20T14:17:31.315267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-04-20T14:17:31.316572Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:17:31.317414Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T14:17:31.317577Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T14:17:31.317882Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:17:31.317633Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T14:17:31.318016Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:17:31.318069Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:17:31.318601Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T14:17:31.318624Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T14:17:31.319848Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"info","ts":"2026-04-20T14:17:31.321907Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-20T14:17:35.685347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.295158ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:17:35.685527Z","caller":"traceutil/trace.go:171","msg":"trace[582466261] linearizableReadLoop","detail":"{readStateIndex:211; appliedIndex:210; }","duration":"201.209456ms","start":"2026-04-20T14:17:35.484295Z","end":"2026-04-20T14:17:35.685504Z","steps":["trace[582466261] 'read index received' (duration: 46.402µs)","trace[582466261] 'applied index is now lower than readState.Index' (duration: 201.161674ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:17:35.685619Z","caller":"traceutil/trace.go:171","msg":"trace[2144487466] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"500.434276ms","start":"2026-04-20T14:17:35.18514Z","end":"2026-04-20T14:17:35.685575Z","steps":["trace[2144487466] 'process raft request' (duration: 243.19642ms)","trace[2144487466] 'compare' (duration: 256.092022ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:17:35.68644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.419501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-20T14:17:35.686935Z","caller":"traceutil/trace.go:171","msg":"trace[1030263185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:206; }","duration":"202.605217ms","start":"2026-04-20T14:17:35.484238Z","end":"2026-04-20T14:17:35.686843Z","steps":["trace[1030263185] 'agreement among raft nodes before linearized reading' (duration: 201.335689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:17:35.687092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.768632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T14:17:35.687385Z","caller":"traceutil/trace.go:171","msg":"trace[1453090884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:206; }","duration":"202.926586ms","start":"2026-04-20T14:17:35.484279Z","end":"2026-04-20T14:17:35.687205Z","steps":["trace[1453090884] 'agreement among raft nodes before linearized reading' (duration: 201.349209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:17:35.687568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:17:35.185131Z","time spent":"500.612472ms","remote":"127.0.0.1:58342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1342,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:17:36.556362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.481297ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:17:36.556643Z","caller":"traceutil/trace.go:171","msg":"trace[10744169] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"862.065363ms","start":"2026-04-20T14:17:35.694552Z","end":"2026-04-20T14:17:36.556617Z","steps":["trace[10744169] 'process raft request' (duration: 862.021012ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:17:36.556636Z","caller":"traceutil/trace.go:171","msg":"trace[834777833] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"862.97768ms","start":"2026-04-20T14:17:35.693615Z","end":"2026-04-20T14:17:36.556593Z","steps":["trace[834777833] 'process raft request' (duration: 501.117132ms)","trace[834777833] 'compare' (duration: 361.286402ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:17:36.556862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:17:35.693583Z","time spent":"863.192517ms","remote":"127.0.0.1:58566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":330,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:17:36.55698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:17:35.694544Z","time spent":"862.153816ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:17:36.557326Z","caller":"traceutil/trace.go:171","msg":"trace[929881799] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"862.870518ms","start":"2026-04-20T14:17:35.694423Z","end":"2026-04-20T14:17:36.557293Z","steps":["trace[929881799] 'process raft request' (duration: 862.078585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:17:36.557468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:17:35.694402Z","time spent":"862.989442ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:18:07.037467Z","caller":"traceutil/trace.go:171","msg":"trace[449704341] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"331.635171ms","start":"2026-04-20T14:18:06.705804Z","end":"2026-04-20T14:18:07.037439Z","steps":["trace[449704341] 'process raft request' (duration: 331.43226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:18:07.037659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:06.705734Z","time spent":"331.837141ms","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:18:07.14511Z","caller":"traceutil/trace.go:171","msg":"trace[1939651892] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"433.244146ms","start":"2026-04-20T14:18:06.711834Z","end":"2026-04-20T14:18:07.145078Z","steps":["trace[1939651892] 'process raft request' (duration: 349.659364ms)","trace[1939651892] 'compare' (duration: 83.164371ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:18:07.145236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:06.711824Z","time spent":"433.355437ms","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:18:07.145321Z","caller":"traceutil/trace.go:171","msg":"trace[658228722] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"433.364397ms","start":"2026-04-20T14:18:06.711886Z","end":"2026-04-20T14:18:07.14525Z","steps":["trace[658228722] 'process raft request' (duration: 433.216646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:18:07.145515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:06.711882Z","time spent":"433.544158ms","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:18:07.145579Z","caller":"traceutil/trace.go:171","msg":"trace[785975373] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"424.185486ms","start":"2026-04-20T14:18:06.721371Z","end":"2026-04-20T14:18:07.145557Z","steps":["trace[785975373] 'process raft request' (duration: 423.964844ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:07.145689Z","caller":"traceutil/trace.go:171","msg":"trace[1023541110] linearizableReadLoop","detail":"{readStateIndex:501; appliedIndex:498; }","duration":"428.597396ms","start":"2026-04-20T14:18:06.717029Z","end":"2026-04-20T14:18:07.145626Z","steps":["trace[1023541110] 'read index received' (duration: 320.248953ms)","trace[1023541110] 'applied index is now lower than readState.Index' (duration: 108.347343ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:18:07.145746Z","caller":"traceutil/trace.go:171","msg":"trace[657169693] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"425.897879ms","start":"2026-04-20T14:18:06.719836Z","end":"2026-04-20T14:18:07.145733Z","steps":["trace[657169693] 'process raft request' (duration: 425.404827ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:07.145603Z","caller":"traceutil/trace.go:171","msg":"trace[1085632125] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"425.772319ms","start":"2026-04-20T14:18:06.719795Z","end":"2026-04-20T14:18:07.145568Z","steps":["trace[1085632125] 'process raft request' (duration: 425.350687ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:07.145783Z","caller":"traceutil/trace.go:171","msg":"trace[1243639033] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"403.516295ms","start":"2026-04-20T14:18:06.742256Z","end":"2026-04-20T14:18:07.145772Z","steps":["trace[1243639033] 'process raft request' (duration: 403.115364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:18:07.145801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:06.719832Z","time spent":"425.946419ms","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:18:07.145775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:06.721363Z","time spent":"424.290616ms","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:18:07.145864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:06.742239Z","time spent":"403.569385ms","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:18:07.145879Z","caller":"traceutil/trace.go:171","msg":"trace[1704561833] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"280.670246ms","start":"2026-04-20T14:18:06.865191Z","end":"2026-04-20T14:18:07.145861Z","steps":["trace[1704561833] 'process raft request' (duration: 280.383186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:18:07.145948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:06.719787Z","time spent":"426.05098ms","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:18:07.146165Z","caller":"traceutil/trace.go:171","msg":"trace[852836444] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"385.872982ms","start":"2026-04-20T14:18:06.759988Z","end":"2026-04-20T14:18:07.145861Z","steps":["trace[852836444] 'process raft request' (duration: 385.525511ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:07.146233Z","caller":"traceutil/trace.go:171","msg":"trace[557132975] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"424.927687ms","start":"2026-04-20T14:18:06.721292Z","end":"2026-04-20T14:18:07.14622Z","steps":["trace[557132975] 'process raft request' (duration: 424.009724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:18:07.146303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:06.721276Z","time spent":"425.000887ms","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:18:07.14629Z","caller":"traceutil/trace.go:171","msg":"trace[619127577] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"401.022959ms","start":"2026-04-20T14:18:06.745209Z","end":"2026-04-20T14:18:07.146231Z","steps":["trace[619127577] 'process raft request' (duration: 400.256927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:18:07.146375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:06.75997Z","time spent":"386.305503ms","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:18:07.146397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:06.74519Z","time spent":"401.16262ms","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:18:07.145541Z","caller":"traceutil/trace.go:171","msg":"trace[1856346494] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"425.534288ms","start":"2026-04-20T14:18:06.719978Z","end":"2026-04-20T14:18:07.145512Z","steps":["trace[1856346494] 'process raft request' (duration: 425.293377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:18:07.146603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:06.719973Z","time spent":"426.560441ms","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:18:07.146906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.855739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-20T14:18:07.147035Z","caller":"traceutil/trace.go:171","msg":"trace[212506103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:491; }","duration":"430.003999ms","start":"2026-04-20T14:18:06.717008Z","end":"2026-04-20T14:18:07.147012Z","steps":["trace[212506103] 'agreement among raft nodes before linearized reading' (duration: 429.155077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:18:07.14713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:06.717Z","time spent":"430.114179ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T14:18:07.229922Z","caller":"traceutil/trace.go:171","msg":"trace[1730945599] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"183.188152ms","start":"2026-04-20T14:18:07.046712Z","end":"2026-04-20T14:18:07.2299Z","steps":["trace[1730945599] 'process raft request' (duration: 183.071122ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:07.229914Z","caller":"traceutil/trace.go:171","msg":"trace[917155258] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"183.774504ms","start":"2026-04-20T14:18:07.0461Z","end":"2026-04-20T14:18:07.229875Z","steps":["trace[917155258] 'process raft request' (duration: 183.550934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:18:07.229973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.665596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-20T14:18:07.230109Z","caller":"traceutil/trace.go:171","msg":"trace[386140104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:493; }","duration":"456.840806ms","start":"2026-04-20T14:18:06.77325Z","end":"2026-04-20T14:18:07.230091Z","steps":["trace[386140104] 'agreement among raft nodes before linearized reading' (duration: 456.637135ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:07.230173Z","caller":"traceutil/trace.go:171","msg":"trace[401666200] transaction","detail":"{read_only:false; number_of_response:1; response_revision:493; }","duration":"183.187063ms","start":"2026-04-20T14:18:07.046937Z","end":"2026-04-20T14:18:07.230124Z","steps":["trace[401666200] 'process raft request' (duration: 182.904012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:18:07.23018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:06.77323Z","time spent":"456.939326ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T14:18:07.230054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.676618ms","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-20T14:18:07.230252Z","caller":"traceutil/trace.go:171","msg":"trace[1422361108] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:493; }","duration":"346.940668ms","start":"2026-04-20T14:18:06.883302Z","end":"2026-04-20T14:18:07.230243Z","steps":["trace[1422361108] 'agreement among raft nodes before linearized reading' (duration: 346.659368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:18:07.230278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:06.883262Z","time spent":"347.007438ms","remote":"127.0.0.1:58496","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":"warn","ts":"2026-04-20T14:18:07.230063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.18923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:18:07.230449Z","caller":"traceutil/trace.go:171","msg":"trace[976391689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:493; }","duration":"286.585741ms","start":"2026-04-20T14:18:06.94378Z","end":"2026-04-20T14:18:07.230365Z","steps":["trace[976391689] 'agreement among raft nodes before linearized reading' (duration: 286.158279ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:07.310126Z","caller":"traceutil/trace.go:171","msg":"trace[1601456256] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"149.346787ms","start":"2026-04-20T14:18:07.160759Z","end":"2026-04-20T14:18:07.310105Z","steps":["trace[1601456256] 'process raft request' (duration: 149.130256ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:07.310171Z","caller":"traceutil/trace.go:171","msg":"trace[795723943] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"149.445287ms","start":"2026-04-20T14:18:07.160694Z","end":"2026-04-20T14:18:07.310139Z","steps":["trace[795723943] 'process raft request' (duration: 144.231972ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:07.310592Z","caller":"traceutil/trace.go:171","msg":"trace[1162127297] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"145.541556ms","start":"2026-04-20T14:18:07.16504Z","end":"2026-04-20T14:18:07.310582Z","steps":["trace[1162127297] 'process raft request' (duration: 144.911825ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:07.310691Z","caller":"traceutil/trace.go:171","msg":"trace[1016779066] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"143.929153ms","start":"2026-04-20T14:18:07.16675Z","end":"2026-04-20T14:18:07.310679Z","steps":["trace[1016779066] 'process raft request' (duration: 143.412051ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:07.310712Z","caller":"traceutil/trace.go:171","msg":"trace[803867456] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"145.600227ms","start":"2026-04-20T14:18:07.1651Z","end":"2026-04-20T14:18:07.3107Z","steps":["trace[803867456] 'process raft request' (duration: 144.909225ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:07.310823Z","caller":"traceutil/trace.go:171","msg":"trace[1463849834] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"142.657339ms","start":"2026-04-20T14:18:07.168155Z","end":"2026-04-20T14:18:07.310812Z","steps":["trace[1463849834] 'process raft request' (duration: 142.147236ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:07.310868Z","caller":"traceutil/trace.go:171","msg":"trace[1879813871] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"145.735827ms","start":"2026-04-20T14:18:07.165123Z","end":"2026-04-20T14:18:07.310858Z","steps":["trace[1879813871] 'process raft request' (duration: 144.933104ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:07.310944Z","caller":"traceutil/trace.go:171","msg":"trace[713094303] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"140.098012ms","start":"2026-04-20T14:18:07.170836Z","end":"2026-04-20T14:18:07.310934Z","steps":["trace[713094303] 'process raft request' (duration: 139.54552ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:07.311003Z","caller":"traceutil/trace.go:171","msg":"trace[81775095] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"137.422884ms","start":"2026-04-20T14:18:07.173569Z","end":"2026-04-20T14:18:07.310992Z","steps":["trace[81775095] 'process raft request' (duration: 136.867322ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:07.311035Z","caller":"traceutil/trace.go:171","msg":"trace[1932191113] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"100.963572ms","start":"2026-04-20T14:18:07.210063Z","end":"2026-04-20T14:18:07.311026Z","steps":["trace[1932191113] 'process raft request' (duration: 100.4184ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:07.452309Z","caller":"traceutil/trace.go:171","msg":"trace[1941434202] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"217.88577ms","start":"2026-04-20T14:18:07.23439Z","end":"2026-04-20T14:18:07.452276Z","steps":["trace[1941434202] 'process raft request' (duration: 209.514776ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:07.453635Z","caller":"traceutil/trace.go:171","msg":"trace[691925788] transaction","detail":"{read_only:false; number_of_response:0; response_revision:505; }","duration":"219.061593ms","start":"2026-04-20T14:18:07.234537Z","end":"2026-04-20T14:18:07.453598Z","steps":["trace[691925788] 'process raft request' (duration: 217.915479ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:07.453844Z","caller":"traceutil/trace.go:171","msg":"trace[1737930860] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"218.04312ms","start":"2026-04-20T14:18:07.234465Z","end":"2026-04-20T14:18:07.452508Z","steps":["trace[1737930860] 'process raft request' (duration: 217.763719ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:07.454627Z","caller":"traceutil/trace.go:171","msg":"trace[276735813] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"217.702119ms","start":"2026-04-20T14:18:07.236885Z","end":"2026-04-20T14:18:07.454587Z","steps":["trace[276735813] 'process raft request' (duration: 216.491935ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:07.454678Z","caller":"traceutil/trace.go:171","msg":"trace[1797064479] transaction","detail":"{read_only:false; number_of_response:1; response_revision:506; }","duration":"217.383828ms","start":"2026-04-20T14:18:07.23727Z","end":"2026-04-20T14:18:07.454654Z","steps":["trace[1797064479] 'process raft request' (duration: 216.467176ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:07.454889Z","caller":"traceutil/trace.go:171","msg":"trace[251606222] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"207.226309ms","start":"2026-04-20T14:18:07.247603Z","end":"2026-04-20T14:18:07.45483Z","steps":["trace[251606222] 'process raft request' (duration: 206.446797ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:07.454936Z","caller":"traceutil/trace.go:171","msg":"trace[998829855] transaction","detail":"{read_only:false; number_of_response:0; response_revision:507; }","duration":"139.286349ms","start":"2026-04-20T14:18:07.315626Z","end":"2026-04-20T14:18:07.454912Z","steps":["trace[998829855] 'process raft request' (duration: 139.103929ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:07.45534Z","caller":"traceutil/trace.go:171","msg":"trace[924141187] transaction","detail":"{read_only:false; number_of_response:0; response_revision:507; }","duration":"139.755431ms","start":"2026-04-20T14:18:07.315564Z","end":"2026-04-20T14:18:07.45532Z","steps":["trace[924141187] 'process raft request' (duration: 138.908179ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:07.456097Z","caller":"traceutil/trace.go:171","msg":"trace[820073756] transaction","detail":"{read_only:false; number_of_response:0; response_revision:507; }","duration":"140.233642ms","start":"2026-04-20T14:18:07.315841Z","end":"2026-04-20T14:18:07.456075Z","steps":["trace[820073756] 'process raft request' (duration: 138.910659ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:07.45629Z","caller":"traceutil/trace.go:171","msg":"trace[2062492884] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"131.544508ms","start":"2026-04-20T14:18:07.324618Z","end":"2026-04-20T14:18:07.456162Z","steps":["trace[2062492884] 'process raft request' (duration: 131.311888ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:07.456587Z","caller":"traceutil/trace.go:171","msg":"trace[2098079057] transaction","detail":"{read_only:false; number_of_response:1; response_revision:508; }","duration":"130.061024ms","start":"2026-04-20T14:18:07.3265Z","end":"2026-04-20T14:18:07.456561Z","steps":["trace[2098079057] 'process raft request' (duration: 129.570122ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:07.461978Z","caller":"traceutil/trace.go:171","msg":"trace[1920309118] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:523; }","duration":"112.015713ms","start":"2026-04-20T14:18:07.349923Z","end":"2026-04-20T14:18:07.461939Z","steps":["trace[1920309118] 'read index received' (duration: 94.105233ms)","trace[1920309118] 'applied index is now lower than readState.Index' (duration: 17.90961ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:18:07.463233Z","caller":"traceutil/trace.go:171","msg":"trace[1258647160] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"128.242208ms","start":"2026-04-20T14:18:07.334973Z","end":"2026-04-20T14:18:07.463215Z","steps":["trace[1258647160] 'process raft request' (duration: 121.203378ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:07.463503Z","caller":"traceutil/trace.go:171","msg":"trace[1521703295] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"128.499439ms","start":"2026-04-20T14:18:07.334992Z","end":"2026-04-20T14:18:07.463492Z","steps":["trace[1521703295] 'process raft request' (duration: 126.573493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:18:07.463681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.741477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-20T14:18:07.46371Z","caller":"traceutil/trace.go:171","msg":"trace[1927100762] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:512; }","duration":"113.816057ms","start":"2026-04-20T14:18:07.349886Z","end":"2026-04-20T14:18:07.463702Z","steps":["trace[1927100762] 'agreement among raft nodes before linearized reading' (duration: 113.740187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:18:08.086066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.861819ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:18:08.086176Z","caller":"traceutil/trace.go:171","msg":"trace[2130259463] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"167.817849ms","start":"2026-04-20T14:18:07.918344Z","end":"2026-04-20T14:18:08.086162Z","steps":["trace[2130259463] 'process raft request' (duration: 60.78892ms)","trace[2130259463] 'compare' (duration: 106.735239ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:18:08.114919Z","caller":"traceutil/trace.go:171","msg":"trace[400435052] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"127.626028ms","start":"2026-04-20T14:18:07.987253Z","end":"2026-04-20T14:18:08.114879Z","steps":["trace[400435052] 'process raft request' (duration: 127.326856ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:08.170297Z","caller":"traceutil/trace.go:171","msg":"trace[493832991] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"172.649682ms","start":"2026-04-20T14:18:07.997597Z","end":"2026-04-20T14:18:08.170247Z","steps":["trace[493832991] 'process raft request' (duration: 172.00945ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:08.454135Z","caller":"traceutil/trace.go:171","msg":"trace[372617089] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"136.166749ms","start":"2026-04-20T14:18:08.317945Z","end":"2026-04-20T14:18:08.454112Z","steps":["trace[372617089] 'process raft request' (duration: 64.484513ms)","trace[372617089] 'compare' (duration: 71.388896ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:18:08.455677Z","caller":"traceutil/trace.go:171","msg":"trace[771200991] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"119.375946ms","start":"2026-04-20T14:18:08.336287Z","end":"2026-04-20T14:18:08.455663Z","steps":["trace[771200991] 'process raft request' (duration: 119.269195ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:12.891228Z","caller":"traceutil/trace.go:171","msg":"trace[94058761] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"220.472023ms","start":"2026-04-20T14:18:12.670739Z","end":"2026-04-20T14:18:12.891211Z","steps":["trace[94058761] 'process raft request' (duration: 214.877448ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:12.89484Z","caller":"traceutil/trace.go:171","msg":"trace[1756217954] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"220.806005ms","start":"2026-04-20T14:18:12.674022Z","end":"2026-04-20T14:18:12.894828Z","steps":["trace[1756217954] 'process raft request' (duration: 220.736165ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:13.721449Z","caller":"traceutil/trace.go:171","msg":"trace[781647094] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"119.263346ms","start":"2026-04-20T14:18:13.602162Z","end":"2026-04-20T14:18:13.721425Z","steps":["trace[781647094] 'process raft request' (duration: 117.436917ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:14.991245Z","caller":"traceutil/trace.go:171","msg":"trace[437664112] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"186.967463ms","start":"2026-04-20T14:18:14.804254Z","end":"2026-04-20T14:18:14.991222Z","steps":["trace[437664112] 'process raft request' (duration: 181.984727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:18:15.82487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.351485ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-04-20T14:18:15.825124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:15.032484Z","time spent":"792.632574ms","remote":"127.0.0.1:58262","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-20T14:18:15.825275Z","caller":"traceutil/trace.go:171","msg":"trace[768047252] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"770.990486ms","start":"2026-04-20T14:18:15.054246Z","end":"2026-04-20T14:18:15.825237Z","steps":["trace[768047252] 'process raft request' (duration: 770.761574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:18:15.825396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.869008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hc4cl\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-04-20T14:18:15.825408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:15.05422Z","time spent":"771.123936ms","remote":"127.0.0.1:58568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:18:15.825447Z","caller":"traceutil/trace.go:171","msg":"trace[248950965] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hc4cl; range_end:; response_count:1; response_revision:585; }","duration":"597.962079ms","start":"2026-04-20T14:18:15.22747Z","end":"2026-04-20T14:18:15.825432Z","steps":["trace[248950965] 'agreement among raft nodes before linearized reading' (duration: 597.739027ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:18:15.825173Z","caller":"traceutil/trace.go:171","msg":"trace[1844291275] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:611; }","duration":"597.630087ms","start":"2026-04-20T14:18:15.227517Z","end":"2026-04-20T14:18:15.825147Z","steps":["trace[1844291275] 'read index received' (duration: 460.98498ms)","trace[1844291275] 'applied index is now lower than readState.Index' (duration: 136.643487ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:18:15.825492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:15.22745Z","time spent":"598.03327ms","remote":"127.0.0.1:58434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-hc4cl\" "} {"level":"warn","ts":"2026-04-20T14:18:15.825807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.576236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-20T14:18:15.82581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.323969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-20T14:18:15.825843Z","caller":"traceutil/trace.go:171","msg":"trace[1820418490] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:585; }","duration":"328.671887ms","start":"2026-04-20T14:18:15.497161Z","end":"2026-04-20T14:18:15.825833Z","steps":["trace[1820418490] 'agreement among raft nodes before linearized reading' (duration: 328.507136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:18:15.82587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:15.497142Z","time spent":"328.722947ms","remote":"127.0.0.1:58496","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-20T14:18:15.825864Z","caller":"traceutil/trace.go:171","msg":"trace[1259030611] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:585; }","duration":"294.41563ms","start":"2026-04-20T14:18:15.531431Z","end":"2026-04-20T14:18:15.825847Z","steps":["trace[1259030611] 'agreement among raft nodes before linearized reading' (duration: 294.189349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:18:15.826845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.046135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-20T14:18:15.827012Z","caller":"traceutil/trace.go:171","msg":"trace[2036803106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:585; }","duration":"328.199145ms","start":"2026-04-20T14:18:15.498752Z","end":"2026-04-20T14:18:15.826951Z","steps":["trace[2036803106] 'agreement among raft nodes before linearized reading' (duration: 326.916048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:18:15.827199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:15.498741Z","time spent":"328.441577ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T14:18:15.827464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.571409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-20T14:18:15.827524Z","caller":"traceutil/trace.go:171","msg":"trace[660735011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:585; }","duration":"472.071191ms","start":"2026-04-20T14:18:15.355434Z","end":"2026-04-20T14:18:15.827505Z","steps":["trace[660735011] 'agreement among raft nodes before linearized reading' (duration: 470.187291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:18:15.827558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:18:15.355416Z","time spent":"472.135001ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T14:18:45.614523Z","caller":"traceutil/trace.go:171","msg":"trace[1412790223] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"100.776417ms","start":"2026-04-20T14:18:45.513722Z","end":"2026-04-20T14:18:45.614499Z","steps":["trace[1412790223] 'process raft request' (duration: 100.606145ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:00.906288Z","caller":"traceutil/trace.go:171","msg":"trace[784805066] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"636.044806ms","start":"2026-04-20T14:19:00.270216Z","end":"2026-04-20T14:19:00.90626Z","steps":["trace[784805066] 'process raft request' (duration: 536.901921ms)","trace[784805066] 'compare' (duration: 98.81022ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:19:00.906587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:00.270185Z","time spent":"636.183618ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:19:00.906605Z","caller":"traceutil/trace.go:171","msg":"trace[2099445070] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"636.30418ms","start":"2026-04-20T14:19:00.27027Z","end":"2026-04-20T14:19:00.906575Z","steps":["trace[2099445070] 'process raft request' (duration: 635.937804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:00.907063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:00.270249Z","time spent":"636.694675ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:19:00.910776Z","caller":"traceutil/trace.go:171","msg":"trace[663706884] linearizableReadLoop","detail":"{readStateIndex:1199; appliedIndex:1196; }","duration":"504.329734ms","start":"2026-04-20T14:19:00.406411Z","end":"2026-04-20T14:19:00.910741Z","steps":["trace[663706884] 'read index received' (duration: 400.600279ms)","trace[663706884] 'applied index is now lower than readState.Index' (duration: 103.728065ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:19:00.910879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.482597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T14:19:00.910998Z","caller":"traceutil/trace.go:171","msg":"trace[644623485] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1157; }","duration":"504.640439ms","start":"2026-04-20T14:19:00.40634Z","end":"2026-04-20T14:19:00.910981Z","steps":["trace[644623485] 'agreement among raft nodes before linearized reading' (duration: 504.491557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:00.9111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:00.406225Z","time spent":"504.850762ms","remote":"127.0.0.1:58420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-04-20T14:19:32.555576Z","caller":"traceutil/trace.go:171","msg":"trace[1613980738] transaction","detail":"{read_only:false; response_revision:1534; number_of_response:1; }","duration":"103.316919ms","start":"2026-04-20T14:19:32.452205Z","end":"2026-04-20T14:19:32.555522Z","steps":["trace[1613980738] 'process raft request' (duration: 102.899341ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:32.555946Z","caller":"traceutil/trace.go:171","msg":"trace[1685398371] transaction","detail":"{read_only:false; response_revision:1535; number_of_response:1; }","duration":"103.436932ms","start":"2026-04-20T14:19:32.452472Z","end":"2026-04-20T14:19:32.555909Z","steps":["trace[1685398371] 'process raft request' (duration: 102.799329ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:32.556329Z","caller":"traceutil/trace.go:171","msg":"trace[1166609504] transaction","detail":"{read_only:false; response_revision:1536; number_of_response:1; }","duration":"103.739628ms","start":"2026-04-20T14:19:32.452523Z","end":"2026-04-20T14:19:32.556263Z","steps":["trace[1166609504] 'process raft request' (duration: 103.179357ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:32.556594Z","caller":"traceutil/trace.go:171","msg":"trace[882704218] transaction","detail":"{read_only:false; response_revision:1540; number_of_response:1; }","duration":"103.82026ms","start":"2026-04-20T14:19:32.452758Z","end":"2026-04-20T14:19:32.556578Z","steps":["trace[882704218] 'process raft request' (duration: 103.646066ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:32.556713Z","caller":"traceutil/trace.go:171","msg":"trace[713284944] transaction","detail":"{read_only:false; response_revision:1537; number_of_response:1; }","duration":"104.352379ms","start":"2026-04-20T14:19:32.452351Z","end":"2026-04-20T14:19:32.556703Z","steps":["trace[713284944] 'process raft request' (duration: 103.659566ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:32.556612Z","caller":"traceutil/trace.go:171","msg":"trace[1888271046] transaction","detail":"{read_only:false; response_revision:1538; number_of_response:1; }","duration":"103.952072ms","start":"2026-04-20T14:19:32.452622Z","end":"2026-04-20T14:19:32.556574Z","steps":["trace[1888271046] 'process raft request' (duration: 103.697967ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:32.557234Z","caller":"traceutil/trace.go:171","msg":"trace[391361098] transaction","detail":"{read_only:false; response_revision:1539; number_of_response:1; }","duration":"104.499993ms","start":"2026-04-20T14:19:32.45271Z","end":"2026-04-20T14:19:32.55721Z","steps":["trace[391361098] 'process raft request' (duration: 103.658447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:32.578069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.186646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-20T14:19:32.578145Z","caller":"traceutil/trace.go:171","msg":"trace[1010745752] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1541; }","duration":"107.278997ms","start":"2026-04-20T14:19:32.470847Z","end":"2026-04-20T14:19:32.578126Z","steps":["trace[1010745752] 'agreement among raft nodes before linearized reading' (duration: 106.976121ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:32.720761Z","caller":"traceutil/trace.go:171","msg":"trace[2033198057] transaction","detail":"{read_only:false; response_revision:1549; number_of_response:1; }","duration":"107.925428ms","start":"2026-04-20T14:19:32.612796Z","end":"2026-04-20T14:19:32.720722Z","steps":["trace[2033198057] 'process raft request' (duration: 107.598202ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:32.720841Z","caller":"traceutil/trace.go:171","msg":"trace[919874085] transaction","detail":"{read_only:false; response_revision:1550; number_of_response:1; }","duration":"107.386549ms","start":"2026-04-20T14:19:32.613401Z","end":"2026-04-20T14:19:32.720788Z","steps":["trace[919874085] 'process raft request' (duration: 107.149684ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:32.720965Z","caller":"traceutil/trace.go:171","msg":"trace[1364541400] transaction","detail":"{read_only:false; response_revision:1551; number_of_response:1; }","duration":"103.852832ms","start":"2026-04-20T14:19:32.617083Z","end":"2026-04-20T14:19:32.720936Z","steps":["trace[1364541400] 'process raft request' (duration: 103.519484ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:32.720968Z","caller":"traceutil/trace.go:171","msg":"trace[1242179321] transaction","detail":"{read_only:false; response_revision:1552; number_of_response:1; }","duration":"101.70334ms","start":"2026-04-20T14:19:32.619235Z","end":"2026-04-20T14:19:32.720938Z","steps":["trace[1242179321] 'process raft request' (duration: 101.557186ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:48.677971Z","caller":"traceutil/trace.go:171","msg":"trace[538973929] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"228.65071ms","start":"2026-04-20T14:19:48.448709Z","end":"2026-04-20T14:19:48.67736Z","steps":["trace[538973929] 'process raft request' (duration: 228.477556ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:49.286666Z","caller":"traceutil/trace.go:171","msg":"trace[2054950510] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"307.455897ms","start":"2026-04-20T14:19:48.979187Z","end":"2026-04-20T14:19:49.286643Z","steps":["trace[2054950510] 'process raft request' (duration: 307.315714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:49.286842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:19:48.979165Z","time spent":"307.58784ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:19:49.415697Z","caller":"traceutil/trace.go:171","msg":"trace[1709424199] linearizableReadLoop","detail":"{readStateIndex:1912; appliedIndex:1911; }","duration":"128.917227ms","start":"2026-04-20T14:19:49.286756Z","end":"2026-04-20T14:19:49.415673Z","steps":["trace[1709424199] 'read index received' (duration: 91.712622ms)","trace[1709424199] 'applied index is now lower than readState.Index' (duration: 37.203195ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:19:49.415773Z","caller":"traceutil/trace.go:171","msg":"trace[449570937] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"125.015986ms","start":"2026-04-20T14:19:49.290745Z","end":"2026-04-20T14:19:49.415761Z","steps":["trace[449570937] 'process raft request' (duration: 124.865093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:19:49.415953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.351454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T14:19:49.415995Z","caller":"traceutil/trace.go:171","msg":"trace[245917933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1834; }","duration":"185.436266ms","start":"2026-04-20T14:19:49.230549Z","end":"2026-04-20T14:19:49.415985Z","steps":["trace[245917933] 'agreement among raft nodes before linearized reading' (duration: 185.230621ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:19:49.416068Z","caller":"traceutil/trace.go:171","msg":"trace[1916485806] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"136.179878ms","start":"2026-04-20T14:19:49.279879Z","end":"2026-04-20T14:19:49.416059Z","steps":["trace[1916485806] 'process raft request' (duration: 98.601915ms)","trace[1916485806] 'compare' (duration: 36.989231ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:20:00.825975Z","caller":"traceutil/trace.go:171","msg":"trace[788540194] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"103.43677ms","start":"2026-04-20T14:20:00.722519Z","end":"2026-04-20T14:20:00.825956Z","steps":["trace[788540194] 'process raft request' (duration: 103.252766ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:21.650097Z","caller":"traceutil/trace.go:171","msg":"trace[437520626] transaction","detail":"{read_only:false; response_revision:2055; number_of_response:1; }","duration":"131.17217ms","start":"2026-04-20T14:20:21.518896Z","end":"2026-04-20T14:20:21.650068Z","steps":["trace[437520626] 'process raft request' (duration: 130.976655ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:27.149968Z","caller":"traceutil/trace.go:171","msg":"trace[1021720181] linearizableReadLoop","detail":"{readStateIndex:2173; appliedIndex:2172; }","duration":"175.938656ms","start":"2026-04-20T14:20:26.974011Z","end":"2026-04-20T14:20:27.14995Z","steps":["trace[1021720181] 'read index received' (duration: 175.781242ms)","trace[1021720181] 'applied index is now lower than readState.Index' (duration: 156.724µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:20:27.150049Z","caller":"traceutil/trace.go:171","msg":"trace[1363246632] transaction","detail":"{read_only:false; response_revision:2085; number_of_response:1; }","duration":"218.420465ms","start":"2026-04-20T14:20:26.931606Z","end":"2026-04-20T14:20:27.150026Z","steps":["trace[1363246632] 'process raft request' (duration: 218.233511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:20:27.150199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.15674ms","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-20T14:20:27.150281Z","caller":"traceutil/trace.go:171","msg":"trace[1199731112] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2085; }","duration":"176.304464ms","start":"2026-04-20T14:20:26.973968Z","end":"2026-04-20T14:20:27.150273Z","steps":["trace[1199731112] 'agreement among raft nodes before linearized reading' (duration: 176.11643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:20:27.150817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.693146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-20T14:20:27.150956Z","caller":"traceutil/trace.go:171","msg":"trace[1943241114] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2085; }","duration":"160.83176ms","start":"2026-04-20T14:20:26.990054Z","end":"2026-04-20T14:20:27.150885Z","steps":["trace[1943241114] 'agreement among raft nodes before linearized reading' (duration: 160.089753ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:27.359612Z","caller":"traceutil/trace.go:171","msg":"trace[1498818867] transaction","detail":"{read_only:false; response_revision:2086; number_of_response:1; }","duration":"204.530201ms","start":"2026-04-20T14:20:27.155052Z","end":"2026-04-20T14:20:27.359583Z","steps":["trace[1498818867] 'process raft request' (duration: 187.760107ms)","trace[1498818867] 'compare' (duration: 16.648291ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:20:27.37409Z","caller":"traceutil/trace.go:171","msg":"trace[995423335] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"218.988021ms","start":"2026-04-20T14:20:27.155081Z","end":"2026-04-20T14:20:27.374069Z","steps":["trace[995423335] 'process raft request' (duration: 218.848968ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:28.001135Z","caller":"traceutil/trace.go:171","msg":"trace[1982923054] linearizableReadLoop","detail":"{readStateIndex:2176; appliedIndex:2175; }","duration":"321.860516ms","start":"2026-04-20T14:20:27.679252Z","end":"2026-04-20T14:20:28.001113Z","steps":["trace[1982923054] 'read index received' (duration: 321.650991ms)","trace[1982923054] 'applied index is now lower than readState.Index' (duration: 208.785µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:20:28.001166Z","caller":"traceutil/trace.go:171","msg":"trace[570890664] transaction","detail":"{read_only:false; response_revision:2088; number_of_response:1; }","duration":"326.507183ms","start":"2026-04-20T14:20:27.674629Z","end":"2026-04-20T14:20:28.001136Z","steps":["trace[570890664] 'process raft request' (duration: 326.327629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:20:28.001313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:20:27.674605Z","time spent":"326.626395ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:20:28.001505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.240024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-20T14:20:28.001563Z","caller":"traceutil/trace.go:171","msg":"trace[1576498550] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2088; }","duration":"322.307676ms","start":"2026-04-20T14:20:27.679239Z","end":"2026-04-20T14:20:28.001546Z","steps":["trace[1576498550] 'agreement among raft nodes before linearized reading' (duration: 321.981208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:20:28.001635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:20:27.679219Z","time spent":"322.404078ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-20T14:20:28.002043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.38445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-20T14:20:28.002052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.417815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-20T14:20:28.002084Z","caller":"traceutil/trace.go:171","msg":"trace[145823018] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2089; }","duration":"115.516153ms","start":"2026-04-20T14:20:27.886558Z","end":"2026-04-20T14:20:28.002074Z","steps":["trace[145823018] 'agreement among raft nodes before linearized reading' (duration: 115.422731ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:28.002093Z","caller":"traceutil/trace.go:171","msg":"trace[1137351801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2089; }","duration":"300.495326ms","start":"2026-04-20T14:20:27.701585Z","end":"2026-04-20T14:20:28.002081Z","steps":["trace[1137351801] 'agreement among raft nodes before linearized reading' (duration: 300.319072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:20:28.002118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:20:27.701563Z","time spent":"300.549318ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T14:20:28.00237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.409704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-20T14:20:28.002443Z","caller":"traceutil/trace.go:171","msg":"trace[454843199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2089; }","duration":"300.530307ms","start":"2026-04-20T14:20:27.701904Z","end":"2026-04-20T14:20:28.002434Z","steps":["trace[454843199] 'agreement among raft nodes before linearized reading' (duration: 300.353303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:20:28.002584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:20:27.701886Z","time spent":"300.584129ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-20T14:20:46.257201Z","caller":"traceutil/trace.go:171","msg":"trace[1445045468] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"121.834097ms","start":"2026-04-20T14:20:46.135338Z","end":"2026-04-20T14:20:46.257173Z","steps":["trace[1445045468] 'process raft request' (duration: 121.272063ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:20:46.257284Z","caller":"traceutil/trace.go:171","msg":"trace[1299466054] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"121.52729ms","start":"2026-04-20T14:20:46.135714Z","end":"2026-04-20T14:20:46.257241Z","steps":["trace[1299466054] 'process raft request' (duration: 121.006667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:11.733709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.797352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-20T14:21:11.733786Z","caller":"traceutil/trace.go:171","msg":"trace[1910505656] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2380; }","duration":"151.939266ms","start":"2026-04-20T14:21:11.581832Z","end":"2026-04-20T14:21:11.733772Z","steps":["trace[1910505656] 'range keys from in-memory index tree' (duration: 151.630078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:11.733709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.885614ms","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-20T14:21:11.733924Z","caller":"traceutil/trace.go:171","msg":"trace[1759903862] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2380; }","duration":"152.164441ms","start":"2026-04-20T14:21:11.58175Z","end":"2026-04-20T14:21:11.733915Z","steps":["trace[1759903862] 'range keys from in-memory index tree' (duration: 151.749522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:11.733924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.203401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:21:11.733969Z","caller":"traceutil/trace.go:171","msg":"trace[1911475176] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:2380; }","duration":"103.281102ms","start":"2026-04-20T14:21:11.630674Z","end":"2026-04-20T14:21:11.733955Z","steps":["trace[1911475176] 'count revisions from in-memory index tree' (duration: 103.128519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:51.038978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.051372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-20T14:21:51.039709Z","caller":"traceutil/trace.go:171","msg":"trace[978086770] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2645; }","duration":"402.827651ms","start":"2026-04-20T14:21:50.636858Z","end":"2026-04-20T14:21:51.039686Z","steps":["trace[978086770] 'range keys from in-memory index tree' (duration: 401.835747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:51.039767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:50.636838Z","time spent":"402.914583ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T14:21:51.039074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.661308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-20T14:21:51.040515Z","caller":"traceutil/trace.go:171","msg":"trace[1431779091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2645; }","duration":"447.142954ms","start":"2026-04-20T14:21:50.593336Z","end":"2026-04-20T14:21:51.040479Z","steps":["trace[1431779091] 'range keys from in-memory index tree' (duration: 445.486503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:51.039091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.07218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:21:51.04152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.372571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-20T14:21:51.041587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:50.59331Z","time spent":"447.241467ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-20T14:21:51.041605Z","caller":"traceutil/trace.go:171","msg":"trace[142591283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2645; }","duration":"445.490494ms","start":"2026-04-20T14:21:50.596096Z","end":"2026-04-20T14:21:51.041586Z","steps":["trace[142591283] 'range keys from in-memory index tree' (duration: 445.150955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:51.041644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:50.596085Z","time spent":"445.551706ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T14:21:51.041587Z","caller":"traceutil/trace.go:171","msg":"trace[2078484548] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2645; }","duration":"390.583798ms","start":"2026-04-20T14:21:50.649964Z","end":"2026-04-20T14:21:51.040548Z","steps":["trace[2078484548] 'range keys from in-memory index tree' (duration: 388.884886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:51.041768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:50.649932Z","time spent":"391.827679ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T14:21:51.147066Z","caller":"traceutil/trace.go:171","msg":"trace[1743523179] transaction","detail":"{read_only:false; response_revision:2646; number_of_response:1; }","duration":"101.870047ms","start":"2026-04-20T14:21:51.045163Z","end":"2026-04-20T14:21:51.147033Z","steps":["trace[1743523179] 'process raft request' (duration: 101.659952ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:51.153943Z","caller":"traceutil/trace.go:171","msg":"trace[1294448138] transaction","detail":"{read_only:false; response_revision:2647; number_of_response:1; }","duration":"107.570719ms","start":"2026-04-20T14:21:51.046362Z","end":"2026-04-20T14:21:51.153932Z","steps":["trace[1294448138] 'process raft request' (duration: 107.463047ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:51.154277Z","caller":"traceutil/trace.go:171","msg":"trace[448490251] transaction","detail":"{read_only:false; response_revision:2648; number_of_response:1; }","duration":"107.709093ms","start":"2026-04-20T14:21:51.046525Z","end":"2026-04-20T14:21:51.154234Z","steps":["trace[448490251] 'process raft request' (duration: 107.380125ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:56.427967Z","caller":"traceutil/trace.go:171","msg":"trace[1493047164] transaction","detail":"{read_only:false; response_revision:2676; number_of_response:1; }","duration":"119.525739ms","start":"2026-04-20T14:21:56.308416Z","end":"2026-04-20T14:21:56.427942Z","steps":["trace[1493047164] 'process raft request' (duration: 119.343415ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:56.688138Z","caller":"traceutil/trace.go:171","msg":"trace[1133988807] linearizableReadLoop","detail":"{readStateIndex:2786; appliedIndex:2785; }","duration":"196.198428ms","start":"2026-04-20T14:21:56.491909Z","end":"2026-04-20T14:21:56.688108Z","steps":["trace[1133988807] 'read index received' (duration: 153.555272ms)","trace[1133988807] 'applied index is now lower than readState.Index' (duration: 42.641856ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:21:56.688398Z","caller":"traceutil/trace.go:171","msg":"trace[1075061681] transaction","detail":"{read_only:false; response_revision:2677; number_of_response:1; }","duration":"353.643136ms","start":"2026-04-20T14:21:56.334742Z","end":"2026-04-20T14:21:56.688385Z","steps":["trace[1075061681] 'process raft request' (duration: 310.681533ms)","trace[1075061681] 'compare' (duration: 42.553403ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:21:56.688982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.767459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-20T14:21:56.688992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.059419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-20T14:21:56.689095Z","caller":"traceutil/trace.go:171","msg":"trace[929676444] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2677; }","duration":"197.235004ms","start":"2026-04-20T14:21:56.491835Z","end":"2026-04-20T14:21:56.68907Z","steps":["trace[929676444] 'agreement among raft nodes before linearized reading' (duration: 196.937226ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:21:56.689084Z","caller":"traceutil/trace.go:171","msg":"trace[2040844259] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2677; }","duration":"166.935514ms","start":"2026-04-20T14:21:56.522117Z","end":"2026-04-20T14:21:56.689053Z","steps":["trace[2040844259] 'agreement among raft nodes before linearized reading' (duration: 166.641355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:21:56.689336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:21:56.334714Z","time spent":"353.734169ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:22:46.526389Z","caller":"traceutil/trace.go:171","msg":"trace[1539629951] transaction","detail":"{read_only:false; response_revision:2937; number_of_response:1; }","duration":"106.893924ms","start":"2026-04-20T14:22:46.419458Z","end":"2026-04-20T14:22:46.526352Z","steps":["trace[1539629951] 'process raft request' (duration: 106.467456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:46.83886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.317292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-20T14:22:46.839043Z","caller":"traceutil/trace.go:171","msg":"trace[438371222] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2938; }","duration":"172.509135ms","start":"2026-04-20T14:22:46.666455Z","end":"2026-04-20T14:22:46.838964Z","steps":["trace[438371222] 'range keys from in-memory index tree' (duration: 172.094068ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:22:46.905053Z","caller":"traceutil/trace.go:171","msg":"trace[1278786934] linearizableReadLoop","detail":"{readStateIndex:3059; appliedIndex:3058; }","duration":"107.015656ms","start":"2026-04-20T14:22:46.79801Z","end":"2026-04-20T14:22:46.905025Z","steps":["trace[1278786934] 'read index received' (duration: 106.733241ms)","trace[1278786934] 'applied index is now lower than readState.Index' (duration: 281.135µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:22:46.905244Z","caller":"traceutil/trace.go:171","msg":"trace[1143986670] transaction","detail":"{read_only:false; response_revision:2939; number_of_response:1; }","duration":"145.67585ms","start":"2026-04-20T14:22:46.759508Z","end":"2026-04-20T14:22:46.905184Z","steps":["trace[1143986670] 'process raft request' (duration: 145.328573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:46.905322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.296902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18a816973bb5d948\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-04-20T14:22:46.90537Z","caller":"traceutil/trace.go:171","msg":"trace[1903154096] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18a816973bb5d948; range_end:; response_count:1; response_revision:2939; }","duration":"107.408764ms","start":"2026-04-20T14:22:46.797947Z","end":"2026-04-20T14:22:46.905356Z","steps":["trace[1903154096] 'agreement among raft nodes before linearized reading' (duration: 107.215791ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:22:47.118713Z","caller":"traceutil/trace.go:171","msg":"trace[735953804] transaction","detail":"{read_only:false; response_revision:2940; number_of_response:1; }","duration":"209.794116ms","start":"2026-04-20T14:22:46.908871Z","end":"2026-04-20T14:22:47.118665Z","steps":["trace[735953804] 'process raft request' (duration: 147.346064ms)","trace[735953804] 'compare' (duration: 62.043034ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:22:47.118858Z","caller":"traceutil/trace.go:171","msg":"trace[1010006176] linearizableReadLoop","detail":"{readStateIndex:3060; appliedIndex:3059; }","duration":"174.780913ms","start":"2026-04-20T14:22:46.944033Z","end":"2026-04-20T14:22:47.118814Z","steps":["trace[1010006176] 'read index received' (duration: 112.199168ms)","trace[1010006176] 'applied index is now lower than readState.Index' (duration: 62.577845ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:22:47.119162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.100749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:22:47.119205Z","caller":"traceutil/trace.go:171","msg":"trace[774853953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2940; }","duration":"175.200441ms","start":"2026-04-20T14:22:46.943993Z","end":"2026-04-20T14:22:47.119193Z","steps":["trace[774853953] 'agreement among raft nodes before linearized reading' (duration: 175.011797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:22:47.120528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.359671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:22:47.120647Z","caller":"traceutil/trace.go:171","msg":"trace[1466762933] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2941; }","duration":"154.510934ms","start":"2026-04-20T14:22:46.96612Z","end":"2026-04-20T14:22:47.120631Z","steps":["trace[1466762933] 'agreement among raft nodes before linearized reading' (duration: 154.350671ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:22:47.121088Z","caller":"traceutil/trace.go:171","msg":"trace[517215936] transaction","detail":"{read_only:false; response_revision:2941; number_of_response:1; }","duration":"116.856871ms","start":"2026-04-20T14:22:47.004108Z","end":"2026-04-20T14:22:47.120965Z","steps":["trace[517215936] 'process raft request' (duration: 115.637717ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:25:50.36186Z","caller":"traceutil/trace.go:171","msg":"trace[559163376] transaction","detail":"{read_only:false; response_revision:4062; number_of_response:1; }","duration":"542.635037ms","start":"2026-04-20T14:25:49.8192Z","end":"2026-04-20T14:25:50.361835Z","steps":["trace[559163376] 'process raft request' (duration: 528.096529ms)","trace[559163376] 'compare' (duration: 14.433946ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:25:50.362127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:49.819162Z","time spent":"542.895863ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:25:50.373046Z","caller":"traceutil/trace.go:171","msg":"trace[587383014] transaction","detail":"{read_only:false; response_revision:4063; number_of_response:1; }","duration":"451.245373ms","start":"2026-04-20T14:25:49.921774Z","end":"2026-04-20T14:25:50.373019Z","steps":["trace[587383014] 'process raft request' (duration: 450.890985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:50.373205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:49.921742Z","time spent":"451.381077ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":451285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:25:50.373452Z","caller":"traceutil/trace.go:171","msg":"trace[1152841599] linearizableReadLoop","detail":"{readStateIndex:4240; appliedIndex:4237; }","duration":"321.68695ms","start":"2026-04-20T14:25:50.051747Z","end":"2026-04-20T14:25:50.373434Z","steps":["trace[1152841599] 'read index received' (duration: 295.646664ms)","trace[1152841599] 'applied index is now lower than readState.Index' (duration: 26.038436ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:25:50.373519Z","caller":"traceutil/trace.go:171","msg":"trace[757914691] transaction","detail":"{read_only:false; response_revision:4064; number_of_response:1; }","duration":"379.998297ms","start":"2026-04-20T14:25:49.993476Z","end":"2026-04-20T14:25:50.373474Z","steps":["trace[757914691] 'process raft request' (duration: 379.484765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:50.373715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:49.993449Z","time spent":"380.155ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:25:50.373857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.011408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T14:25:50.373948Z","caller":"traceutil/trace.go:171","msg":"trace[42785907] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:4064; }","duration":"322.235673ms","start":"2026-04-20T14:25:50.051693Z","end":"2026-04-20T14:25:50.373929Z","steps":["trace[42785907] 'agreement among raft nodes before linearized reading' (duration: 321.815563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:25:50.374157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:25:50.051672Z","time spent":"322.411258ms","remote":"127.0.0.1:58566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":23,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-04-20T14:26:00.669727Z","caller":"traceutil/trace.go:171","msg":"trace[838861131] transaction","detail":"{read_only:false; response_revision:4461; number_of_response:1; }","duration":"297.66397ms","start":"2026-04-20T14:26:00.372034Z","end":"2026-04-20T14:26:00.669698Z","steps":["trace[838861131] 'process raft request' (duration: 228.993757ms)","trace[838861131] 'compare' (duration: 68.541261ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:26:00.685133Z","caller":"traceutil/trace.go:171","msg":"trace[1214943692] transaction","detail":"{read_only:false; response_revision:4462; number_of_response:1; }","duration":"312.443056ms","start":"2026-04-20T14:26:00.372645Z","end":"2026-04-20T14:26:00.685088Z","steps":["trace[1214943692] 'process raft request' (duration: 312.18192ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:00.685212Z","caller":"traceutil/trace.go:171","msg":"trace[1539742049] transaction","detail":"{read_only:false; response_revision:4463; number_of_response:1; }","duration":"311.074444ms","start":"2026-04-20T14:26:00.374106Z","end":"2026-04-20T14:26:00.685181Z","steps":["trace[1539742049] 'process raft request' (duration: 310.884579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:00.685355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:00.374091Z","time spent":"311.175937ms","remote":"127.0.0.1:58470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3862,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:26:00.685358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:00.372613Z","time spent":"312.62307ms","remote":"127.0.0.1:58306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:26:00.685502Z","caller":"traceutil/trace.go:171","msg":"trace[773811183] transaction","detail":"{read_only:false; response_revision:4464; number_of_response:1; }","duration":"200.88681ms","start":"2026-04-20T14:26:00.484488Z","end":"2026-04-20T14:26:00.685375Z","steps":["trace[773811183] 'process raft request' (duration: 200.563683ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:00.733331Z","caller":"traceutil/trace.go:171","msg":"trace[2003453879] linearizableReadLoop","detail":"{readStateIndex:4649; appliedIndex:4646; }","duration":"274.859369ms","start":"2026-04-20T14:26:00.458444Z","end":"2026-04-20T14:26:00.733303Z","steps":["trace[2003453879] 'read index received' (duration: 142.546219ms)","trace[2003453879] 'applied index is now lower than readState.Index' (duration: 132.30917ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:26:00.733531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.074553ms","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-20T14:26:00.733598Z","caller":"traceutil/trace.go:171","msg":"trace[2030027813] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4465; }","duration":"275.201416ms","start":"2026-04-20T14:26:00.458381Z","end":"2026-04-20T14:26:00.733583Z","steps":["trace[2030027813] 'agreement among raft nodes before linearized reading' (duration: 274.999462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:00.734194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.61037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/ingress-nginx/ingress-nginx-controller-gbgst.18a81679e1112cd1\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-04-20T14:26:00.734236Z","caller":"traceutil/trace.go:171","msg":"trace[2086459209] range","detail":"{range_begin:/registry/events/ingress-nginx/ingress-nginx-controller-gbgst.18a81679e1112cd1; range_end:; response_count:1; response_revision:4465; }","duration":"133.711882ms","start":"2026-04-20T14:26:00.600512Z","end":"2026-04-20T14:26:00.734224Z","steps":["trace[2086459209] 'agreement among raft nodes before linearized reading' (duration: 133.596289ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:06.188426Z","caller":"traceutil/trace.go:171","msg":"trace[611542784] transaction","detail":"{read_only:false; response_revision:4598; number_of_response:1; }","duration":"107.920816ms","start":"2026-04-20T14:26:06.080479Z","end":"2026-04-20T14:26:06.1884Z","steps":["trace[611542784] 'process raft request' (duration: 107.759813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:06.511043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.389615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-55971326-7a9e-4bdf-998d-c5d22a2026f4\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-04-20T14:26:06.511099Z","caller":"traceutil/trace.go:171","msg":"trace[1538301333] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-55971326-7a9e-4bdf-998d-c5d22a2026f4; range_end:; response_count:1; response_revision:4602; }","duration":"116.479816ms","start":"2026-04-20T14:26:06.394607Z","end":"2026-04-20T14:26:06.511086Z","steps":["trace[1538301333] 'range keys from in-memory index tree' (duration: 116.280761ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:08.156316Z","caller":"traceutil/trace.go:171","msg":"trace[1558368211] transaction","detail":"{read_only:false; response_revision:4623; number_of_response:1; }","duration":"142.381634ms","start":"2026-04-20T14:26:08.013909Z","end":"2026-04-20T14:26:08.156291Z","steps":["trace[1558368211] 'process raft request' (duration: 142.22039ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:08.156368Z","caller":"traceutil/trace.go:171","msg":"trace[1450716672] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4623; }","duration":"141.435033ms","start":"2026-04-20T14:26:08.014897Z","end":"2026-04-20T14:26:08.156332Z","steps":["trace[1450716672] 'process raft request' (duration: 141.33572ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:09.315308Z","caller":"traceutil/trace.go:171","msg":"trace[1641343713] linearizableReadLoop","detail":"{readStateIndex:4850; appliedIndex:4848; }","duration":"141.124276ms","start":"2026-04-20T14:26:09.174159Z","end":"2026-04-20T14:26:09.315283Z","steps":["trace[1641343713] 'read index received' (duration: 29.668755ms)","trace[1641343713] 'applied index is now lower than readState.Index' (duration: 111.454201ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:26:09.315439Z","caller":"traceutil/trace.go:171","msg":"trace[1260928806] transaction","detail":"{read_only:false; response_revision:4659; number_of_response:1; }","duration":"147.839824ms","start":"2026-04-20T14:26:09.167586Z","end":"2026-04-20T14:26:09.315426Z","steps":["trace[1260928806] 'process raft request' (duration: 89.045876ms)","trace[1260928806] 'compare' (duration: 58.368938ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:26:09.315678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.040692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-20T14:26:09.315729Z","caller":"traceutil/trace.go:171","msg":"trace[1003349254] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4659; }","duration":"117.100563ms","start":"2026-04-20T14:26:09.198613Z","end":"2026-04-20T14:26:09.315714Z","steps":["trace[1003349254] 'agreement among raft nodes before linearized reading' (duration: 116.9472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:09.315747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.567577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-04-20T14:26:09.31583Z","caller":"traceutil/trace.go:171","msg":"trace[1231525597] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4659; }","duration":"141.69351ms","start":"2026-04-20T14:26:09.174117Z","end":"2026-04-20T14:26:09.315811Z","steps":["trace[1231525597] 'agreement among raft nodes before linearized reading' (duration: 141.493195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:09.315884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.457908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-20T14:26:09.315976Z","caller":"traceutil/trace.go:171","msg":"trace[1366371456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4659; }","duration":"113.650742ms","start":"2026-04-20T14:26:09.202311Z","end":"2026-04-20T14:26:09.315962Z","steps":["trace[1366371456] 'agreement among raft nodes before linearized reading' (duration: 113.319344ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:15.145562Z","caller":"traceutil/trace.go:171","msg":"trace[685095474] transaction","detail":"{read_only:false; response_revision:4782; number_of_response:1; }","duration":"246.22146ms","start":"2026-04-20T14:26:14.899319Z","end":"2026-04-20T14:26:15.14554Z","steps":["trace[685095474] 'process raft request' (duration: 246.076347ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:15.404806Z","caller":"traceutil/trace.go:171","msg":"trace[1873687621] linearizableReadLoop","detail":"{readStateIndex:4979; appliedIndex:4976; }","duration":"492.440101ms","start":"2026-04-20T14:26:14.912335Z","end":"2026-04-20T14:26:15.404775Z","steps":["trace[1873687621] 'read index received' (duration: 233.102492ms)","trace[1873687621] 'applied index is now lower than readState.Index' (duration: 259.336329ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:26:15.405088Z","caller":"traceutil/trace.go:171","msg":"trace[165496292] transaction","detail":"{read_only:false; response_revision:4783; number_of_response:1; }","duration":"498.819811ms","start":"2026-04-20T14:26:14.906178Z","end":"2026-04-20T14:26:15.404997Z","steps":["trace[165496292] 'process raft request' (duration: 498.161506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:15.405249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:14.906156Z","time spent":"498.975974ms","remote":"127.0.0.1:43596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2229,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:26:15.405889Z","caller":"traceutil/trace.go:171","msg":"trace[880198315] transaction","detail":"{read_only:false; response_revision:4784; number_of_response:1; }","duration":"499.534388ms","start":"2026-04-20T14:26:14.906345Z","end":"2026-04-20T14:26:15.405879Z","steps":["trace[880198315] 'process raft request' (duration: 498.239468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:15.405997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:14.906306Z","time spent":"499.612539ms","remote":"127.0.0.1:58434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:26:15.406224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.859784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-04-20T14:26:15.406325Z","caller":"traceutil/trace.go:171","msg":"trace[1755263477] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4785; }","duration":"494.008037ms","start":"2026-04-20T14:26:14.912292Z","end":"2026-04-20T14:26:15.4063Z","steps":["trace[1755263477] 'agreement among raft nodes before linearized reading' (duration: 493.745251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:15.406341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.822819ms","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-20T14:26:15.406377Z","caller":"traceutil/trace.go:171","msg":"trace[762154573] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4785; }","duration":"489.903002ms","start":"2026-04-20T14:26:14.916465Z","end":"2026-04-20T14:26:15.406368Z","steps":["trace[762154573] 'agreement among raft nodes before linearized reading' (duration: 489.731568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:15.406409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:14.912277Z","time spent":"494.121201ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1844,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"warn","ts":"2026-04-20T14:26:15.406435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:14.916448Z","time spent":"489.947182ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-20T14:26:15.406786Z","caller":"traceutil/trace.go:171","msg":"trace[349489564] transaction","detail":"{read_only:false; response_revision:4785; number_of_response:1; }","duration":"462.289405ms","start":"2026-04-20T14:26:14.944475Z","end":"2026-04-20T14:26:15.406765Z","steps":["trace[349489564] 'process raft request' (duration: 460.226156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:15.407439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.72427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-20T14:26:15.407523Z","caller":"traceutil/trace.go:171","msg":"trace[39268021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4785; }","duration":"179.863182ms","start":"2026-04-20T14:26:15.227639Z","end":"2026-04-20T14:26:15.407502Z","steps":["trace[39268021] 'agreement among raft nodes before linearized reading' (duration: 179.560025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:15.407638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.723917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T14:26:15.407689Z","caller":"traceutil/trace.go:171","msg":"trace[1573989725] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:4785; }","duration":"408.820978ms","start":"2026-04-20T14:26:14.998853Z","end":"2026-04-20T14:26:15.407674Z","steps":["trace[1573989725] 'agreement among raft nodes before linearized reading' (duration: 408.695495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:15.407724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:14.998834Z","time spent":"408.87964ms","remote":"127.0.0.1:58616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:26:15.407856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.557287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-20T14:26:15.407894Z","caller":"traceutil/trace.go:171","msg":"trace[1505135492] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4785; }","duration":"435.593698ms","start":"2026-04-20T14:26:14.972288Z","end":"2026-04-20T14:26:15.407881Z","steps":["trace[1505135492] 'agreement among raft nodes before linearized reading' (duration: 435.464245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:15.407917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:14.972269Z","time spent":"435.642789ms","remote":"127.0.0.1:58408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-04-20T14:26:15.407949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.791552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T14:26:15.408Z","caller":"traceutil/trace.go:171","msg":"trace[1462049197] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:4785; }","duration":"346.864464ms","start":"2026-04-20T14:26:15.061109Z","end":"2026-04-20T14:26:15.407974Z","steps":["trace[1462049197] 'agreement among raft nodes before linearized reading' (duration: 346.770692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:15.408048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:15.061085Z","time spent":"346.948896ms","remote":"127.0.0.1:58262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.227\" "} {"level":"warn","ts":"2026-04-20T14:26:15.40694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:14.944452Z","time spent":"462.384587ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:26:15.822396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.606658ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:26:15.822504Z","caller":"traceutil/trace.go:171","msg":"trace[850490667] transaction","detail":"{read_only:false; response_revision:4798; number_of_response:1; }","duration":"283.888466ms","start":"2026-04-20T14:26:15.538597Z","end":"2026-04-20T14:26:15.822486Z","steps":["trace[850490667] 'process raft request' (duration: 55.124444ms)","trace[850490667] 'compare' (duration: 228.403923ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:26:16.150354Z","caller":"traceutil/trace.go:171","msg":"trace[1703826408] transaction","detail":"{read_only:false; response_revision:4800; number_of_response:1; }","duration":"143.715935ms","start":"2026-04-20T14:26:16.006617Z","end":"2026-04-20T14:26:16.150333Z","steps":["trace[1703826408] 'process raft request' (duration: 96.337323ms)","trace[1703826408] 'compare' (duration: 47.28541ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:26:18.961344Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.035442953s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T14:26:18.969638Z","caller":"traceutil/trace.go:171","msg":"trace[431895917] transaction","detail":"{read_only:false; response_revision:4810; number_of_response:1; }","duration":"2.04381603s","start":"2026-04-20T14:26:16.925804Z","end":"2026-04-20T14:26:18.96962Z","steps":["trace[431895917] 'process raft request' (duration: 2.035698429s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:18.969768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:16.925781Z","time spent":"2.043940732s","remote":"127.0.0.1:58306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":853,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:26:18.969928Z","caller":"traceutil/trace.go:171","msg":"trace[1367087479] linearizableReadLoop","detail":"{readStateIndex:5006; appliedIndex:5005; }","duration":"328.738919ms","start":"2026-04-20T14:26:18.641173Z","end":"2026-04-20T14:26:18.969912Z","steps":["trace[1367087479] 'read index received' (duration: 320.282941ms)","trace[1367087479] 'applied index is now lower than readState.Index' (duration: 8.454208ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:26:18.970103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.911874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:26:18.970132Z","caller":"traceutil/trace.go:171","msg":"trace[1235079923] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:4810; }","duration":"328.954495ms","start":"2026-04-20T14:26:18.64117Z","end":"2026-04-20T14:26:18.970124Z","steps":["trace[1235079923] 'agreement among raft nodes before linearized reading' (duration: 328.825781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:18.970155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:18.641125Z","time spent":"329.025127ms","remote":"127.0.0.1:41860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:26:18.990025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.397034ms","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:7"} {"level":"info","ts":"2026-04-20T14:26:18.990189Z","caller":"traceutil/trace.go:171","msg":"trace[463168892] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:4814; }","duration":"311.597138ms","start":"2026-04-20T14:26:18.678566Z","end":"2026-04-20T14:26:18.990163Z","steps":["trace[463168892] 'agreement among raft nodes before linearized reading' (duration: 311.364522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:18.990245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:18.67855Z","time spent":"311.67895ms","remote":"127.0.0.1:32860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":8,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:26:18.990296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.292975ms","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-20T14:26:18.990349Z","caller":"traceutil/trace.go:171","msg":"trace[420748799] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4814; }","duration":"324.364277ms","start":"2026-04-20T14:26:18.665974Z","end":"2026-04-20T14:26:18.990338Z","steps":["trace[420748799] 'agreement among raft nodes before linearized reading' (duration: 324.213843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:18.99037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:18.665971Z","time spent":"324.394848ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T14:26:18.990448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.48419ms","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-20T14:26:18.99046Z","caller":"traceutil/trace.go:171","msg":"trace[2090948749] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4814; }","duration":"324.495671ms","start":"2026-04-20T14:26:18.66596Z","end":"2026-04-20T14:26:18.990456Z","steps":["trace[2090948749] 'agreement among raft nodes before linearized reading' (duration: 324.45075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:18.990473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:18.66595Z","time spent":"324.519561ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T14:26:18.990486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.220361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-20T14:26:18.990523Z","caller":"traceutil/trace.go:171","msg":"trace[559276615] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4814; }","duration":"349.264982ms","start":"2026-04-20T14:26:18.641246Z","end":"2026-04-20T14:26:18.990511Z","steps":["trace[559276615] 'agreement among raft nodes before linearized reading' (duration: 349.17721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:18.990545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:18.641242Z","time spent":"349.296412ms","remote":"127.0.0.1:58408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-04-20T14:26:18.990615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.665584ms","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":"info","ts":"2026-04-20T14:26:18.990651Z","caller":"traceutil/trace.go:171","msg":"trace[1647141935] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4814; }","duration":"324.748246ms","start":"2026-04-20T14:26:18.665893Z","end":"2026-04-20T14:26:18.990641Z","steps":["trace[1647141935] 'agreement among raft nodes before linearized reading' (duration: 324.665584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:18.99068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:18.665882Z","time spent":"324.789478ms","remote":"127.0.0.1:59682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:26:18.9907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.379225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d2c549c0-792a-45db-beea-64cc4f4ca137\" ","response":"range_response_count:1 size:3936"} {"level":"info","ts":"2026-04-20T14:26:18.99072Z","caller":"traceutil/trace.go:171","msg":"trace[289344520] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d2c549c0-792a-45db-beea-64cc4f4ca137; range_end:; response_count:1; response_revision:4814; }","duration":"349.406125ms","start":"2026-04-20T14:26:18.641308Z","end":"2026-04-20T14:26:18.990714Z","steps":["trace[289344520] 'agreement among raft nodes before linearized reading' (duration: 349.342964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:18.990737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:18.641305Z","time spent":"349.428556ms","remote":"127.0.0.1:58434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3959,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d2c549c0-792a-45db-beea-64cc4f4ca137\" "} {"level":"warn","ts":"2026-04-20T14:26:20.389729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.520088ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-20T14:26:21.010879Z","caller":"traceutil/trace.go:171","msg":"trace[1661274349] transaction","detail":"{read_only:false; response_revision:4848; number_of_response:1; }","duration":"105.645323ms","start":"2026-04-20T14:26:20.905168Z","end":"2026-04-20T14:26:21.010813Z","steps":["trace[1661274349] 'process raft request' (duration: 101.614619ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:30.304566Z","caller":"traceutil/trace.go:171","msg":"trace[970413749] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"246.516256ms","start":"2026-04-20T14:26:30.058027Z","end":"2026-04-20T14:26:30.304544Z","steps":["trace[970413749] 'process raft request' (duration: 246.350201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:30.57826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.636726ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:26:30.578479Z","caller":"traceutil/trace.go:171","msg":"trace[1508486664] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"267.255935ms","start":"2026-04-20T14:26:30.311192Z","end":"2026-04-20T14:26:30.578448Z","steps":["trace[1508486664] 'process raft request' (duration: 15.326892ms)","trace[1508486664] 'compare' (duration: 251.455142ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:26:30.578703Z","caller":"traceutil/trace.go:171","msg":"trace[72667019] transaction","detail":"{read_only:false; response_revision:4962; number_of_response:1; }","duration":"267.4637ms","start":"2026-04-20T14:26:30.311213Z","end":"2026-04-20T14:26:30.578677Z","steps":["trace[72667019] 'process raft request' (duration: 267.138242ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:30.655495Z","caller":"traceutil/trace.go:171","msg":"trace[1503325515] transaction","detail":"{read_only:false; response_revision:4963; number_of_response:1; }","duration":"343.638688ms","start":"2026-04-20T14:26:30.311833Z","end":"2026-04-20T14:26:30.655471Z","steps":["trace[1503325515] 'process raft request' (duration: 343.443374ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:26:30.655681Z","caller":"traceutil/trace.go:171","msg":"trace[1813000383] transaction","detail":"{read_only:false; response_revision:4964; number_of_response:1; }","duration":"342.817628ms","start":"2026-04-20T14:26:30.312832Z","end":"2026-04-20T14:26:30.65565Z","steps":["trace[1813000383] 'process raft request' (duration: 342.582043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:26:30.655731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:30.311822Z","time spent":"343.822782ms","remote":"127.0.0.1:58412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:26:30.655854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:26:30.312802Z","time spent":"342.952992ms","remote":"127.0.0.1:58504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1429,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:26:31.068141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.262018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:26:31.068238Z","caller":"traceutil/trace.go:171","msg":"trace[1509278679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4965; }","duration":"123.408962ms","start":"2026-04-20T14:26:30.944807Z","end":"2026-04-20T14:26:31.068216Z","steps":["trace[1509278679] 'range keys from in-memory index tree' (duration: 123.190507ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:27:31.840819Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2861} {"level":"info","ts":"2026-04-20T14:27:32.061166Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2861,"took":"218.393739ms","hash":2230975368,"current-db-size-bytes":53252096,"current-db-size":"53 MB","current-db-size-in-use-bytes":28213248,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-20T14:27:32.061241Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2230975368,"revision":2861,"compact-revision":-1} {"level":"warn","ts":"2026-04-20T14:27:48.034132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.824246ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:27:48.03431Z","caller":"traceutil/trace.go:171","msg":"trace[2049889845] transaction","detail":"{read_only:false; response_revision:5605; number_of_response:1; }","duration":"249.615554ms","start":"2026-04-20T14:27:47.784665Z","end":"2026-04-20T14:27:48.03428Z","steps":["trace[2049889845] 'process raft request' (duration: 59.560621ms)","trace[2049889845] 'compare' (duration: 189.485938ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:27:48.034249Z","caller":"traceutil/trace.go:171","msg":"trace[1895941826] linearizableReadLoop","detail":"{readStateIndex:5826; appliedIndex:5825; }","duration":"212.84842ms","start":"2026-04-20T14:27:47.821382Z","end":"2026-04-20T14:27:48.034231Z","steps":["trace[1895941826] 'read index received' (duration: 22.763766ms)","trace[1895941826] 'applied index is now lower than readState.Index' (duration: 190.083324ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:27:48.034972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.525336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T14:27:48.035135Z","caller":"traceutil/trace.go:171","msg":"trace[492217270] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:5605; }","duration":"213.785223ms","start":"2026-04-20T14:27:47.821299Z","end":"2026-04-20T14:27:48.035085Z","steps":["trace[492217270] 'agreement among raft nodes before linearized reading' (duration: 213.359153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:02.458004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.666445ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:28:02.458082Z","caller":"traceutil/trace.go:171","msg":"trace[1270496454] linearizableReadLoop","detail":"{readStateIndex:5925; appliedIndex:5924; }","duration":"680.670048ms","start":"2026-04-20T14:28:01.777399Z","end":"2026-04-20T14:28:02.45807Z","steps":["trace[1270496454] 'read index received' (duration: 56.803178ms)","trace[1270496454] 'applied index is now lower than readState.Index' (duration: 623.86575ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:28:02.458152Z","caller":"traceutil/trace.go:171","msg":"trace[100313677] transaction","detail":"{read_only:false; response_revision:5701; number_of_response:1; }","duration":"682.246595ms","start":"2026-04-20T14:28:01.775896Z","end":"2026-04-20T14:28:02.458142Z","steps":["trace[100313677] 'process raft request' (duration: 58.356874ms)","trace[100313677] 'compare' (duration: 623.313267ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:28:02.458196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:01.775878Z","time spent":"682.297016ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:28:02.458596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.161802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:28:02.458685Z","caller":"traceutil/trace.go:171","msg":"trace[1261040715] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:5701; }","duration":"588.329276ms","start":"2026-04-20T14:28:01.870332Z","end":"2026-04-20T14:28:02.458662Z","steps":["trace[1261040715] 'agreement among raft nodes before linearized reading' (duration: 588.165781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:02.458734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:01.870312Z","time spent":"588.411697ms","remote":"127.0.0.1:42782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:28:02.458793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.699963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-20T14:28:02.458836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.093511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-20T14:28:02.458857Z","caller":"traceutil/trace.go:171","msg":"trace[1763093002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5701; }","duration":"627.788525ms","start":"2026-04-20T14:28:01.831052Z","end":"2026-04-20T14:28:02.458841Z","steps":["trace[1763093002] 'agreement among raft nodes before linearized reading' (duration: 627.652342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:02.458876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.456858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-20T14:28:02.458897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:01.831037Z","time spent":"627.851316ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-20T14:28:02.458907Z","caller":"traceutil/trace.go:171","msg":"trace[1299750534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5701; }","duration":"647.52563ms","start":"2026-04-20T14:28:01.811374Z","end":"2026-04-20T14:28:02.4589Z","steps":["trace[1299750534] 'agreement among raft nodes before linearized reading' (duration: 647.454579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:02.458929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:01.811357Z","time spent":"647.568112ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T14:28:02.458885Z","caller":"traceutil/trace.go:171","msg":"trace[1783879380] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5701; }","duration":"253.144112ms","start":"2026-04-20T14:28:02.205728Z","end":"2026-04-20T14:28:02.458872Z","steps":["trace[1783879380] 'agreement among raft nodes before linearized reading' (duration: 252.996688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:02.45864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.64629ms","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":"warn","ts":"2026-04-20T14:28:02.459225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.824196ms","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-20T14:28:02.459313Z","caller":"traceutil/trace.go:171","msg":"trace[254197613] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5701; }","duration":"682.00296ms","start":"2026-04-20T14:28:01.777295Z","end":"2026-04-20T14:28:02.459298Z","steps":["trace[254197613] 'agreement among raft nodes before linearized reading' (duration: 681.033346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:02.459352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:01.777212Z","time spent":"682.132043ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-20T14:28:02.459263Z","caller":"traceutil/trace.go:171","msg":"trace[1080022447] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5701; }","duration":"657.285015ms","start":"2026-04-20T14:28:01.801963Z","end":"2026-04-20T14:28:02.459248Z","steps":["trace[1080022447] 'agreement among raft nodes before linearized reading' (duration: 656.457524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:02.459477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:01.801921Z","time spent":"657.541671ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T14:28:04.94356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000424045s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-20T14:28:04.943643Z","caller":"traceutil/trace.go:171","msg":"trace[159710410] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000550828s","start":"2026-04-20T14:28:02.943073Z","end":"2026-04-20T14:28:04.943624Z","steps":["trace[159710410] 'agreement among raft nodes before linearized reading' (duration: 2.000449125s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:04.94369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:02.943056Z","time spent":"2.00062495s","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/20 14:28:04 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-20T14:28:06.944203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00012967s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-20T14:28:06.944289Z","caller":"traceutil/trace.go:171","msg":"trace[1419141668] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000264293s","start":"2026-04-20T14:28:04.944005Z","end":"2026-04-20T14:28:06.94427Z","steps":["trace[1419141668] 'agreement among raft nodes before linearized reading' (duration: 2.00015752s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:06.944373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:04.943989Z","time spent":"2.000366366s","remote":"127.0.0.1:58244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/20 14:28:06 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-20T14:28:07.462975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:02.463268Z","time spent":"4.999699474s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/04/20 14:28:07 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-20T14:28:08.531326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:03.532557Z","time spent":"4.99876278s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/04/20 14:28:08 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-20T14:28:08.532605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:03.533905Z","time spent":"4.998693429s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-20T14:28:08.952074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000517623s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-20T14:28:08.95216Z","caller":"traceutil/trace.go:171","msg":"trace[1885578672] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000645308s","start":"2026-04-20T14:28:06.951495Z","end":"2026-04-20T14:28:08.952141Z","steps":["trace[1885578672] 'agreement among raft nodes before linearized reading' (duration: 2.000533914s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:08.952218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:06.951487Z","time spent":"2.000720169s","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T14:28:09.147486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.8280138s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:28:09.147638Z","caller":"traceutil/trace.go:171","msg":"trace[1679535723] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5702; }","duration":"4.828172984s","start":"2026-04-20T14:28:04.319441Z","end":"2026-04-20T14:28:09.147614Z","steps":["trace[1679535723] 'range keys from in-memory index tree' (duration: 4.82799416s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.152839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.534866327s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:28:09.153Z","caller":"traceutil/trace.go:171","msg":"trace[841811021] transaction","detail":"{read_only:false; response_revision:5703; number_of_response:1; }","duration":"6.689871947s","start":"2026-04-20T14:28:02.46311Z","end":"2026-04-20T14:28:09.152982Z","steps":["trace[841811021] 'process raft request' (duration: 154.794405ms)","trace[841811021] 'compare' (duration: 6.528726389s)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:28:09.15307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:02.463093Z","time spent":"6.689943588s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:28:09.154273Z","caller":"traceutil/trace.go:171","msg":"trace[1077436832] linearizableReadLoop","detail":"{readStateIndex:5929; appliedIndex:5926; }","duration":"6.335607973s","start":"2026-04-20T14:28:02.818652Z","end":"2026-04-20T14:28:09.15426Z","steps":["trace[1077436832] 'read index received' (duration: 68.442µs)","trace[1077436832] 'applied index is now lower than readState.Index' (duration: 6.335538551s)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:28:09.154546Z","caller":"traceutil/trace.go:171","msg":"trace[1580708965] transaction","detail":"{read_only:false; response_revision:5705; number_of_response:1; }","duration":"6.691037835s","start":"2026-04-20T14:28:02.463493Z","end":"2026-04-20T14:28:09.154531Z","steps":["trace[1580708965] 'process raft request' (duration: 6.690717128s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.154626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:02.463485Z","time spent":"6.691098917s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:28:09.154632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.336001081s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/keystone-rabbit-init-x6l7v\" ","response":"range_response_count:1 size:2832"} {"level":"info","ts":"2026-04-20T14:28:09.154679Z","caller":"traceutil/trace.go:171","msg":"trace[878220999] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/keystone-rabbit-init-x6l7v; range_end:; response_count:1; response_revision:5705; }","duration":"6.336098334s","start":"2026-04-20T14:28:02.818568Z","end":"2026-04-20T14:28:09.154667Z","steps":["trace[878220999] 'agreement among raft nodes before linearized reading' (duration: 6.3359035s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.154723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:02.81792Z","time spent":"6.336789461s","remote":"127.0.0.1:43596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":2855,"request content":"key:\"/registry/cilium.io/ciliumendpoints/openstack/keystone-rabbit-init-x6l7v\" "} {"level":"warn","ts":"2026-04-20T14:28:09.156723Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"5.624847738s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T14:28:09.157122Z","caller":"traceutil/trace.go:171","msg":"trace[1071452711] transaction","detail":"{read_only:false; response_revision:5706; number_of_response:1; }","duration":"5.62536697s","start":"2026-04-20T14:28:03.531744Z","end":"2026-04-20T14:28:09.157111Z","steps":["trace[1071452711] 'process raft request' (duration: 5.625272718s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.157213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:03.531719Z","time spent":"5.625430082s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:28:09.178298Z","caller":"traceutil/trace.go:171","msg":"trace[467340048] transaction","detail":"{read_only:false; response_revision:5709; number_of_response:1; }","duration":"5.058365114s","start":"2026-04-20T14:28:04.119893Z","end":"2026-04-20T14:28:09.178258Z","steps":["trace[467340048] 'process raft request' (duration: 5.058253711s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.178457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:04.11987Z","time spent":"5.058514858s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:28:09.178515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.355106382s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T14:28:09.178566Z","caller":"traceutil/trace.go:171","msg":"trace[604072944] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:5711; }","duration":"2.355229585s","start":"2026-04-20T14:28:06.823316Z","end":"2026-04-20T14:28:09.178545Z","steps":["trace[604072944] 'agreement among raft nodes before linearized reading' (duration: 2.355113762s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.17861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.78271608s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T14:28:09.178603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:06.823287Z","time spent":"2.355307157s","remote":"127.0.0.1:58634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:28:09.178741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.291971307s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-20T14:28:09.17878Z","caller":"traceutil/trace.go:171","msg":"trace[1080677465] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5711; }","duration":"5.292043919s","start":"2026-04-20T14:28:03.886729Z","end":"2026-04-20T14:28:09.178773Z","steps":["trace[1080677465] 'agreement among raft nodes before linearized reading' (duration: 5.291943727s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.178815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:03.886704Z","time spent":"5.29210189s","remote":"127.0.0.1:58434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-20T14:28:09.178827Z","caller":"traceutil/trace.go:171","msg":"trace[2136174159] transaction","detail":"{read_only:false; response_revision:5711; number_of_response:1; }","duration":"1.006245073s","start":"2026-04-20T14:28:08.172569Z","end":"2026-04-20T14:28:09.178814Z","steps":["trace[2136174159] 'process raft request' (duration: 1.005656629s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.178844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.063205222s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T14:28:09.178867Z","caller":"traceutil/trace.go:171","msg":"trace[1708111939] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:5711; }","duration":"4.063266303s","start":"2026-04-20T14:28:05.115592Z","end":"2026-04-20T14:28:09.178859Z","steps":["trace[1708111939] 'agreement among raft nodes before linearized reading' (duration: 4.063205562s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.178863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.632071284s","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":"warn","ts":"2026-04-20T14:28:09.178931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14066875s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T14:28:09.178936Z","caller":"traceutil/trace.go:171","msg":"trace[998458019] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5711; }","duration":"4.632170897s","start":"2026-04-20T14:28:04.546751Z","end":"2026-04-20T14:28:09.178922Z","steps":["trace[998458019] 'agreement among raft nodes before linearized reading' (duration: 4.632020103s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.178978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.770783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:551"} {"level":"info","ts":"2026-04-20T14:28:09.178995Z","caller":"traceutil/trace.go:171","msg":"trace[1398010508] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:5711; }","duration":"643.811494ms","start":"2026-04-20T14:28:08.535179Z","end":"2026-04-20T14:28:09.17899Z","steps":["trace[1398010508] 'agreement among raft nodes before linearized reading' (duration: 643.782463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.179011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:08.53517Z","time spent":"643.837035ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":574,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-04-20T14:28:09.178656Z","caller":"traceutil/trace.go:171","msg":"trace[1390116297] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:5711; }","duration":"4.782762491s","start":"2026-04-20T14:28:04.395884Z","end":"2026-04-20T14:28:09.178646Z","steps":["trace[1390116297] 'agreement among raft nodes before linearized reading' (duration: 4.78270044s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.179044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:04.395863Z","time spent":"4.783172793s","remote":"127.0.0.1:58534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:28:09.179053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.369580306s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:28:09.179078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.500986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-04-20T14:28:09.179083Z","caller":"traceutil/trace.go:171","msg":"trace[2069842480] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:5711; }","duration":"5.369611077s","start":"2026-04-20T14:28:03.809464Z","end":"2026-04-20T14:28:09.179075Z","steps":["trace[2069842480] 'agreement among raft nodes before linearized reading' (duration: 5.369562935s)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:09.179096Z","caller":"traceutil/trace.go:171","msg":"trace[1099424258] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:5711; }","duration":"645.518217ms","start":"2026-04-20T14:28:08.533573Z","end":"2026-04-20T14:28:09.179091Z","steps":["trace[1099424258] 'agreement among raft nodes before linearized reading' (duration: 645.486506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.179113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:08.533508Z","time spent":"645.601188ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":548,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-04-20T14:28:09.179113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:03.809393Z","time spent":"5.369712858s","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:28:09.179007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:04.546735Z","time spent":"4.63225759s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-20T14:28:09.17895Z","caller":"traceutil/trace.go:171","msg":"trace[1852025207] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:5711; }","duration":"1.140727691s","start":"2026-04-20T14:28:08.038218Z","end":"2026-04-20T14:28:09.178945Z","steps":["trace[1852025207] 'agreement among raft nodes before linearized reading' (duration: 1.140696941s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.179177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.552003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-04-20T14:28:09.179192Z","caller":"traceutil/trace.go:171","msg":"trace[399264044] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5711; }","duration":"972.567043ms","start":"2026-04-20T14:28:08.20662Z","end":"2026-04-20T14:28:09.179187Z","steps":["trace[399264044] 'agreement among raft nodes before linearized reading' (duration: 972.526892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.179201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:08.038197Z","time spent":"1.140993039s","remote":"127.0.0.1:58736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:28:09.179213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:08.206569Z","time spent":"972.639695ms","remote":"127.0.0.1:58408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1876,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-20T14:28:09.179307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.437446769s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-rabbit-init-x6l7v\" ","response":"range_response_count:1 size:6886"} {"level":"warn","ts":"2026-04-20T14:28:09.179008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.726076387s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-04-20T14:28:09.178893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:05.11557Z","time spent":"4.063317765s","remote":"127.0.0.1:43624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":34,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-04-20T14:28:09.179345Z","caller":"traceutil/trace.go:171","msg":"trace[1370764981] range","detail":"{range_begin:/registry/pods/openstack/keystone-rabbit-init-x6l7v; range_end:; response_count:1; response_revision:5711; }","duration":"5.437521851s","start":"2026-04-20T14:28:03.741814Z","end":"2026-04-20T14:28:09.179336Z","steps":["trace[1370764981] 'agreement among raft nodes before linearized reading' (duration: 5.437419068s)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:09.179355Z","caller":"traceutil/trace.go:171","msg":"trace[1472847706] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5711; }","duration":"1.726449907s","start":"2026-04-20T14:28:07.452895Z","end":"2026-04-20T14:28:09.179345Z","steps":["trace[1472847706] 'agreement among raft nodes before linearized reading' (duration: 1.726047306s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.179372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:03.741789Z","time spent":"5.437576841s","remote":"127.0.0.1:58434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":6909,"request content":"key:\"/registry/pods/openstack/keystone-rabbit-init-x6l7v\" "} {"level":"warn","ts":"2026-04-20T14:28:09.179396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:07.452867Z","time spent":"1.726520859s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-20T14:28:09.178716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.715651997s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-20T14:28:09.178888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:08.172549Z","time spent":"1.006306974s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:28:09.179499Z","caller":"traceutil/trace.go:171","msg":"trace[655827681] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5711; }","duration":"4.716487977s","start":"2026-04-20T14:28:04.463Z","end":"2026-04-20T14:28:09.179488Z","steps":["trace[655827681] 'agreement among raft nodes before linearized reading' (duration: 4.715478602s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.179532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:04.462967Z","time spent":"4.716556519s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T14:28:09.178928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.136716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:28:09.17878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.112313884s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T14:28:09.179889Z","caller":"traceutil/trace.go:171","msg":"trace[326821415] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5711; }","duration":"227.12482ms","start":"2026-04-20T14:28:08.952749Z","end":"2026-04-20T14:28:09.179874Z","steps":["trace[326821415] 'agreement among raft nodes before linearized reading' (duration: 226.158467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.178561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.735807279s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-20T14:28:09.179937Z","caller":"traceutil/trace.go:171","msg":"trace[1292602284] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5711; }","duration":"5.737233314s","start":"2026-04-20T14:28:03.442693Z","end":"2026-04-20T14:28:09.179926Z","steps":["trace[1292602284] 'agreement among raft nodes before linearized reading' (duration: 5.735730117s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.17998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:03.442672Z","time spent":"5.737298045s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-20T14:28:09.179931Z","caller":"traceutil/trace.go:171","msg":"trace[363970309] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:5711; }","duration":"4.113491173s","start":"2026-04-20T14:28:05.066423Z","end":"2026-04-20T14:28:09.179914Z","steps":["trace[363970309] 'agreement among raft nodes before linearized reading' (duration: 4.112292424s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.18006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:05.066404Z","time spent":"4.113632777s","remote":"127.0.0.1:58262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.227\" "} {"level":"info","ts":"2026-04-20T14:28:09.178339Z","caller":"traceutil/trace.go:171","msg":"trace[1350580880] transaction","detail":"{read_only:false; response_revision:5710; number_of_response:1; }","duration":"2.230634236s","start":"2026-04-20T14:28:06.947658Z","end":"2026-04-20T14:28:09.178292Z","steps":["trace[1350580880] 'process raft request' (duration: 2.230524393s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:09.182261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:28:06.947633Z","time spent":"2.232538382s","remote":"127.0.0.1:58306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:28:35.483886Z","caller":"traceutil/trace.go:171","msg":"trace[199913620] transaction","detail":"{read_only:false; response_revision:5865; number_of_response:1; }","duration":"185.907032ms","start":"2026-04-20T14:28:35.297948Z","end":"2026-04-20T14:28:35.483855Z","steps":["trace[199913620] 'process raft request' (duration: 162.042846ms)","trace[199913620] 'compare' (duration: 23.63468ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:28:35.513784Z","caller":"traceutil/trace.go:171","msg":"trace[512249680] linearizableReadLoop","detail":"{readStateIndex:6097; appliedIndex:6095; }","duration":"212.29335ms","start":"2026-04-20T14:28:35.301467Z","end":"2026-04-20T14:28:35.51376Z","steps":["trace[512249680] 'read index received' (duration: 158.504851ms)","trace[512249680] 'applied index is now lower than readState.Index' (duration: 53.787399ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:28:35.513892Z","caller":"traceutil/trace.go:171","msg":"trace[2091813641] transaction","detail":"{read_only:false; response_revision:5866; number_of_response:1; }","duration":"212.543976ms","start":"2026-04-20T14:28:35.30129Z","end":"2026-04-20T14:28:35.513834Z","steps":["trace[2091813641] 'process raft request' (duration: 212.28943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:35.513991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.637613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-20T14:28:35.514068Z","caller":"traceutil/trace.go:171","msg":"trace[119439310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5866; }","duration":"211.739625ms","start":"2026-04-20T14:28:35.302319Z","end":"2026-04-20T14:28:35.514059Z","steps":["trace[119439310] 'agreement among raft nodes before linearized reading' (duration: 211.569781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:35.51407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.580937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-20T14:28:35.514248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.179608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-20T14:28:35.514251Z","caller":"traceutil/trace.go:171","msg":"trace[1433971129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5866; }","duration":"212.71507ms","start":"2026-04-20T14:28:35.301428Z","end":"2026-04-20T14:28:35.514143Z","steps":["trace[1433971129] 'agreement among raft nodes before linearized reading' (duration: 212.465585ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:35.514321Z","caller":"traceutil/trace.go:171","msg":"trace[29447011] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5866; }","duration":"150.27731ms","start":"2026-04-20T14:28:35.364028Z","end":"2026-04-20T14:28:35.514306Z","steps":["trace[29447011] 'agreement among raft nodes before linearized reading' (duration: 150.116876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:35.514431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.641897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-20T14:28:35.514447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.099021ms","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-20T14:28:35.514474Z","caller":"traceutil/trace.go:171","msg":"trace[1101290945] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5866; }","duration":"154.737269ms","start":"2026-04-20T14:28:35.359724Z","end":"2026-04-20T14:28:35.514461Z","steps":["trace[1101290945] 'agreement among raft nodes before linearized reading' (duration: 154.489043ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:35.514504Z","caller":"traceutil/trace.go:171","msg":"trace[1187576129] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5866; }","duration":"151.196244ms","start":"2026-04-20T14:28:35.363293Z","end":"2026-04-20T14:28:35.514489Z","steps":["trace[1187576129] 'agreement among raft nodes before linearized reading' (duration: 151.04234ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:35.679626Z","caller":"traceutil/trace.go:171","msg":"trace[1508619512] transaction","detail":"{read_only:false; response_revision:5868; number_of_response:1; }","duration":"160.100878ms","start":"2026-04-20T14:28:35.519495Z","end":"2026-04-20T14:28:35.679596Z","steps":["trace[1508619512] 'process raft request' (duration: 144.968252ms)","trace[1508619512] 'compare' (duration: 14.91606ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:28:35.679976Z","caller":"traceutil/trace.go:171","msg":"trace[775927182] transaction","detail":"{read_only:false; response_revision:5870; number_of_response:1; }","duration":"159.617977ms","start":"2026-04-20T14:28:35.520325Z","end":"2026-04-20T14:28:35.679943Z","steps":["trace[775927182] 'process raft request' (duration: 159.255388ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:35.680049Z","caller":"traceutil/trace.go:171","msg":"trace[399635411] transaction","detail":"{read_only:false; response_revision:5869; number_of_response:1; }","duration":"160.419656ms","start":"2026-04-20T14:28:35.519614Z","end":"2026-04-20T14:28:35.680033Z","steps":["trace[399635411] 'process raft request' (duration: 159.916444ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:35.680142Z","caller":"traceutil/trace.go:171","msg":"trace[1931806186] transaction","detail":"{read_only:false; response_revision:5871; number_of_response:1; }","duration":"159.697519ms","start":"2026-04-20T14:28:35.520329Z","end":"2026-04-20T14:28:35.680026Z","steps":["trace[1931806186] 'process raft request' (duration: 159.288709ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:37.699688Z","caller":"traceutil/trace.go:171","msg":"trace[317704801] transaction","detail":"{read_only:false; response_revision:5876; number_of_response:1; }","duration":"123.138905ms","start":"2026-04-20T14:28:37.57653Z","end":"2026-04-20T14:28:37.699669Z","steps":["trace[317704801] 'process raft request' (duration: 103.429589ms)","trace[317704801] 'compare' (duration: 19.193064ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:28:39.925955Z","caller":"traceutil/trace.go:171","msg":"trace[529600936] linearizableReadLoop","detail":"{readStateIndex:6124; appliedIndex:6120; }","duration":"122.710797ms","start":"2026-04-20T14:28:39.803217Z","end":"2026-04-20T14:28:39.925927Z","steps":["trace[529600936] 'read index received' (duration: 2.745896ms)","trace[529600936] 'applied index is now lower than readState.Index' (duration: 119.963251ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:28:39.926038Z","caller":"traceutil/trace.go:171","msg":"trace[1609541797] transaction","detail":"{read_only:false; response_revision:5893; number_of_response:1; }","duration":"142.087156ms","start":"2026-04-20T14:28:39.783924Z","end":"2026-04-20T14:28:39.926012Z","steps":["trace[1609541797] 'process raft request' (duration: 141.949282ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:39.92596Z","caller":"traceutil/trace.go:171","msg":"trace[1438554056] transaction","detail":"{read_only:false; response_revision:5891; number_of_response:1; }","duration":"143.436047ms","start":"2026-04-20T14:28:39.782468Z","end":"2026-04-20T14:28:39.925904Z","steps":["trace[1438554056] 'process raft request' (duration: 143.12984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:39.926231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.992154ms","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-20T14:28:39.926298Z","caller":"traceutil/trace.go:171","msg":"trace[1327043379] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5893; }","duration":"123.114036ms","start":"2026-04-20T14:28:39.803168Z","end":"2026-04-20T14:28:39.926282Z","steps":["trace[1327043379] 'agreement among raft nodes before linearized reading' (duration: 122.86287ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:39.926682Z","caller":"traceutil/trace.go:171","msg":"trace[200869477] transaction","detail":"{read_only:false; response_revision:5892; number_of_response:1; }","duration":"144.125173ms","start":"2026-04-20T14:28:39.782501Z","end":"2026-04-20T14:28:39.926626Z","steps":["trace[200869477] 'process raft request' (duration: 143.303344ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:44.100197Z","caller":"traceutil/trace.go:171","msg":"trace[1960580855] linearizableReadLoop","detail":"{readStateIndex:6141; appliedIndex:6140; }","duration":"126.13736ms","start":"2026-04-20T14:28:43.974013Z","end":"2026-04-20T14:28:44.10015Z","steps":["trace[1960580855] 'read index received' (duration: 86.646405ms)","trace[1960580855] 'applied index is now lower than readState.Index' (duration: 39.489575ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:28:44.10028Z","caller":"traceutil/trace.go:171","msg":"trace[181974454] transaction","detail":"{read_only:false; response_revision:5909; number_of_response:1; }","duration":"157.473979ms","start":"2026-04-20T14:28:43.942768Z","end":"2026-04-20T14:28:44.100242Z","steps":["trace[181974454] 'process raft request' (duration: 117.913802ms)","trace[181974454] 'compare' (duration: 39.167247ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:28:44.100543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.4938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-20T14:28:44.100646Z","caller":"traceutil/trace.go:171","msg":"trace[1433783455] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5909; }","duration":"126.641693ms","start":"2026-04-20T14:28:43.973973Z","end":"2026-04-20T14:28:44.100615Z","steps":["trace[1433783455] 'agreement among raft nodes before linearized reading' (duration: 126.335275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:28:44.100642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.790314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-20T14:28:44.100692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.498059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-20T14:28:44.100576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.447368ms","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-20T14:28:44.100737Z","caller":"traceutil/trace.go:171","msg":"trace[1577586263] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5909; }","duration":"116.933617ms","start":"2026-04-20T14:28:43.983784Z","end":"2026-04-20T14:28:44.100717Z","steps":["trace[1577586263] 'agreement among raft nodes before linearized reading' (duration: 116.690261ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:44.100743Z","caller":"traceutil/trace.go:171","msg":"trace[1184864418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5909; }","duration":"126.600682ms","start":"2026-04-20T14:28:43.974127Z","end":"2026-04-20T14:28:44.100727Z","steps":["trace[1184864418] 'agreement among raft nodes before linearized reading' (duration: 126.480199ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:28:44.100777Z","caller":"traceutil/trace.go:171","msg":"trace[562303213] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5909; }","duration":"126.732875ms","start":"2026-04-20T14:28:43.974027Z","end":"2026-04-20T14:28:44.10076Z","steps":["trace[562303213] 'agreement among raft nodes before linearized reading' (duration: 126.340636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:29:30.801509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.374059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/keystone-bootstrap\" ","response":"range_response_count:1 size:540"} {"level":"info","ts":"2026-04-20T14:29:30.801836Z","caller":"traceutil/trace.go:171","msg":"trace[2112321422] range","detail":"{range_begin:/registry/serviceaccounts/openstack/keystone-bootstrap; range_end:; response_count:1; response_revision:6174; }","duration":"189.764418ms","start":"2026-04-20T14:29:30.612057Z","end":"2026-04-20T14:29:30.801821Z","steps":["trace[2112321422] 'range keys from in-memory index tree' (duration: 189.221416ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:30:34.26884Z","caller":"traceutil/trace.go:171","msg":"trace[349091046] transaction","detail":"{read_only:false; response_revision:6721; number_of_response:1; }","duration":"391.970133ms","start":"2026-04-20T14:30:33.876837Z","end":"2026-04-20T14:30:34.268807Z","steps":["trace[349091046] 'process raft request' (duration: 391.804009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:30:34.269116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:30:33.876823Z","time spent":"392.160637ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:30:34.268871Z","caller":"traceutil/trace.go:171","msg":"trace[556199692] linearizableReadLoop","detail":"{readStateIndex:6983; appliedIndex:6983; }","duration":"326.822196ms","start":"2026-04-20T14:30:33.942012Z","end":"2026-04-20T14:30:34.268835Z","steps":["trace[556199692] 'read index received' (duration: 326.808375ms)","trace[556199692] 'applied index is now lower than readState.Index' (duration: 11.23µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:30:34.269424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.049891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:30:34.269519Z","caller":"traceutil/trace.go:171","msg":"trace[1996676919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6721; }","duration":"327.499262ms","start":"2026-04-20T14:30:33.942006Z","end":"2026-04-20T14:30:34.269505Z","steps":["trace[1996676919] 'agreement among raft nodes before linearized reading' (duration: 326.936038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:30:34.272041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:30:33.941961Z","time spent":"330.029423ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T14:31:14.641337Z","caller":"traceutil/trace.go:171","msg":"trace[1712982909] transaction","detail":"{read_only:false; response_revision:6934; number_of_response:1; }","duration":"112.165556ms","start":"2026-04-20T14:31:14.529143Z","end":"2026-04-20T14:31:14.641308Z","steps":["trace[1712982909] 'process raft request' (duration: 111.974782ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:31:14.652631Z","caller":"traceutil/trace.go:171","msg":"trace[612235866] transaction","detail":"{read_only:false; response_revision:6935; number_of_response:1; }","duration":"122.234149ms","start":"2026-04-20T14:31:14.530374Z","end":"2026-04-20T14:31:14.652608Z","steps":["trace[612235866] 'process raft request' (duration: 122.072325ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:31:14.652887Z","caller":"traceutil/trace.go:171","msg":"trace[788387234] transaction","detail":"{read_only:false; response_revision:6936; number_of_response:1; }","duration":"120.987384ms","start":"2026-04-20T14:31:14.531843Z","end":"2026-04-20T14:31:14.65283Z","steps":["trace[788387234] 'process raft request' (duration: 120.705939ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:32:31.859382Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5469} {"level":"info","ts":"2026-04-20T14:32:31.92872Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5469,"took":"67.884518ms","hash":3869274087,"current-db-size-bytes":53252096,"current-db-size":"53 MB","current-db-size-in-use-bytes":18313216,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-20T14:32:31.928829Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3869274087,"revision":5469,"compact-revision":2861}