{"level":"warn","ts":"2026-07-31T12:40:57.877058Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-31T12:40:57.877225Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.177: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.19.213.177:2380","--initial-cluster=instance=https://199.19.213.177:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.177:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.177:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-31T12:40:57.877402Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-31T12:40:57.877422Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.177:2380"]} {"level":"info","ts":"2026-07-31T12:40:57.877463Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-31T12:40:57.879654Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.177:2379"]} {"level":"info","ts":"2026-07-31T12:40:57.879902Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.177:2380"],"listen-peer-urls":["https://199.19.213.177:2380"],"advertise-client-urls":["https://199.19.213.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.177:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-31T12:40:57.886076Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.67217ms"} {"level":"info","ts":"2026-07-31T12:40:57.891479Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f577bc5ae95d61e1","cluster-id":"735860b212d0f0b6"} {"level":"info","ts":"2026-07-31T12:40:57.891577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 switched to configuration voters=()"} {"level":"info","ts":"2026-07-31T12:40:57.891606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 became follower at term 0"} {"level":"info","ts":"2026-07-31T12:40:57.891625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f577bc5ae95d61e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-31T12:40:57.891641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 became follower at term 1"} {"level":"info","ts":"2026-07-31T12:40:57.891716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 switched to configuration voters=(17687813160169202145)"} {"level":"warn","ts":"2026-07-31T12:40:57.895009Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-31T12:40:57.904523Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-31T12:40:57.906424Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-31T12:40:57.909149Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f577bc5ae95d61e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-31T12:40:57.909553Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-31T12:40:57.909678Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-31T12:40:57.909605Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f577bc5ae95d61e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-31T12:40:57.909716Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-31T12:40:57.911428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 switched to configuration voters=(17687813160169202145)"} {"level":"info","ts":"2026-07-31T12:40:57.911588Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"735860b212d0f0b6","local-member-id":"f577bc5ae95d61e1","added-peer-id":"f577bc5ae95d61e1","added-peer-peer-urls":["https://199.19.213.177:2380"]} {"level":"info","ts":"2026-07-31T12:40:57.914807Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-31T12:40:57.914893Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.177:2380"} {"level":"info","ts":"2026-07-31T12:40:57.914945Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.177:2380"} {"level":"info","ts":"2026-07-31T12:40:57.915194Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f577bc5ae95d61e1","initial-advertise-peer-urls":["https://199.19.213.177:2380"],"listen-peer-urls":["https://199.19.213.177:2380"],"advertise-client-urls":["https://199.19.213.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-31T12:40:57.915249Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-31T12:40:58.092395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 is starting a new election at term 1"} {"level":"info","ts":"2026-07-31T12:40:58.092502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-31T12:40:58.092547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 received MsgPreVoteResp from f577bc5ae95d61e1 at term 1"} {"level":"info","ts":"2026-07-31T12:40:58.092568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 became candidate at term 2"} {"level":"info","ts":"2026-07-31T12:40:58.092576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 received MsgVoteResp from f577bc5ae95d61e1 at term 2"} {"level":"info","ts":"2026-07-31T12:40:58.092591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 became leader at term 2"} {"level":"info","ts":"2026-07-31T12:40:58.092602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f577bc5ae95d61e1 elected leader f577bc5ae95d61e1 at term 2"} {"level":"info","ts":"2026-07-31T12:40:58.093901Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f577bc5ae95d61e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.177:2379]}","request-path":"/0/members/f577bc5ae95d61e1/attributes","cluster-id":"735860b212d0f0b6","publish-timeout":"7s"} {"level":"info","ts":"2026-07-31T12:40:58.093937Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-31T12:40:58.094064Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-31T12:40:58.094228Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-31T12:40:58.094317Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-31T12:40:58.094317Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-31T12:40:58.095732Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"735860b212d0f0b6","local-member-id":"f577bc5ae95d61e1","cluster-version":"3.5"} {"level":"info","ts":"2026-07-31T12:40:58.095854Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-31T12:40:58.095975Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-31T12:40:58.09783Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.177:2379"} {"level":"info","ts":"2026-07-31T12:40:58.098461Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-31T12:41:01.712008Z","caller":"traceutil/trace.go:171","msg":"trace[1463620062] transaction","detail":"{read_only:false; response_revision:156; number_of_response:1; }","duration":"129.697537ms","start":"2026-07-31T12:41:01.582281Z","end":"2026-07-31T12:41:01.711979Z","steps":["trace[1463620062] 'process raft request' (duration: 119.495338ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:41:16.149907Z","caller":"traceutil/trace.go:171","msg":"trace[300569602] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"324.798259ms","start":"2026-07-31T12:41:15.825067Z","end":"2026-07-31T12:41:16.149865Z","steps":["trace[300569602] 'process raft request' (duration: 324.44639ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:41:16.150808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:41:15.825039Z","time spent":"325.025714ms","remote":"127.0.0.1:34608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:41:45.009478Z","caller":"traceutil/trace.go:171","msg":"trace[280413275] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"132.618096ms","start":"2026-07-31T12:41:44.876823Z","end":"2026-07-31T12:41:45.009441Z","steps":["trace[280413275] 'process raft request' (duration: 132.328117ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:41:45.432729Z","caller":"traceutil/trace.go:171","msg":"trace[1070114086] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"305.010347ms","start":"2026-07-31T12:41:45.127679Z","end":"2026-07-31T12:41:45.432689Z","steps":["trace[1070114086] 'process raft request' (duration: 304.064041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:41:45.433015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:41:45.127633Z","time spent":"305.205562ms","remote":"127.0.0.1:34508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:41:45.930643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.606577ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T12:41:45.930811Z","caller":"traceutil/trace.go:171","msg":"trace[301221440] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:620; }","duration":"761.921206ms","start":"2026-07-31T12:41:45.168869Z","end":"2026-07-31T12:41:45.93079Z","steps":["trace[301221440] 'read index received' (duration: 262.456096ms)","trace[301221440] 'applied index is now lower than readState.Index' (duration: 499.46383ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:41:45.930896Z","caller":"traceutil/trace.go:171","msg":"trace[2083020817] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"784.958523ms","start":"2026-07-31T12:41:45.145926Z","end":"2026-07-31T12:41:45.930885Z","steps":["trace[2083020817] 'process raft request' (duration: 490.807602ms)","trace[2083020817] 'compare' (duration: 293.416342ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:41:45.930965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:41:45.145896Z","time spent":"785.037137ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:41:45.931331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.427591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nhds6\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-31T12:41:45.931482Z","caller":"traceutil/trace.go:171","msg":"trace[54988042] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nhds6; range_end:; response_count:1; response_revision:602; }","duration":"762.649366ms","start":"2026-07-31T12:41:45.168809Z","end":"2026-07-31T12:41:45.931459Z","steps":["trace[54988042] 'agreement among raft nodes before linearized reading' (duration: 762.308448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:41:45.931536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:41:45.168784Z","time spent":"762.738839ms","remote":"127.0.0.1:34608","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-nhds6\" "} {"level":"warn","ts":"2026-07-31T12:41:47.583752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.478696s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-31T12:41:47.584381Z","caller":"traceutil/trace.go:171","msg":"trace[1787971006] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"1.653501047s","start":"2026-07-31T12:41:45.930839Z","end":"2026-07-31T12:41:47.58434Z","steps":["trace[1787971006] 'read index received' (duration: 174.063728ms)","trace[1787971006] 'applied index is now lower than readState.Index' (duration: 1.479403158s)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:41:47.584461Z","caller":"traceutil/trace.go:171","msg":"trace[1269670488] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"2.090765278s","start":"2026-07-31T12:41:45.493639Z","end":"2026-07-31T12:41:47.584404Z","steps":["trace[1269670488] 'process raft request' (duration: 611.232717ms)","trace[1269670488] 'compare' (duration: 1.4782659s)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:41:47.584648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:41:45.493597Z","time spent":"2.090962793s","remote":"127.0.0.1:34972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-31T12:41:47.584625Z","caller":"traceutil/trace.go:171","msg":"trace[672017423] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"1.638308915s","start":"2026-07-31T12:41:45.946278Z","end":"2026-07-31T12:41:47.584587Z","steps":["trace[672017423] 'process raft request' (duration: 1.637677371s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:41:47.585121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:41:45.946258Z","time spent":"1.638621552s","remote":"127.0.0.1:34608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:41:47.593342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.078666ms","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-07-31T12:41:47.59338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.896521027s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-31T12:41:47.593421Z","caller":"traceutil/trace.go:171","msg":"trace[863174957] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:604; }","duration":"579.179578ms","start":"2026-07-31T12:41:47.01422Z","end":"2026-07-31T12:41:47.593399Z","steps":["trace[863174957] 'agreement among raft nodes before linearized reading' (duration: 578.930942ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:41:47.593438Z","caller":"traceutil/trace.go:171","msg":"trace[1182367038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:604; }","duration":"1.896634039s","start":"2026-07-31T12:41:45.69679Z","end":"2026-07-31T12:41:47.593424Z","steps":["trace[1182367038] 'agreement among raft nodes before linearized reading' (duration: 1.887656975s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:41:47.59347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:41:47.014139Z","time spent":"579.323152ms","remote":"127.0.0.1:34698","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-07-31T12:41:47.593475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:41:45.696779Z","time spent":"1.896687391s","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-31T12:41:47.593756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.906821357s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:41:47.593783Z","caller":"traceutil/trace.go:171","msg":"trace[1738924052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"1.906846208s","start":"2026-07-31T12:41:45.686928Z","end":"2026-07-31T12:41:47.593774Z","steps":["trace[1738924052] 'agreement among raft nodes before linearized reading' (duration: 1.897631999s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:41:47.593804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:41:45.686858Z","time spent":"1.90694109s","remote":"127.0.0.1:55100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T12:41:47.593812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.885659579s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-31T12:41:47.59384Z","caller":"traceutil/trace.go:171","msg":"trace[628078698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:604; }","duration":"1.88571254s","start":"2026-07-31T12:41:45.708121Z","end":"2026-07-31T12:41:47.593833Z","steps":["trace[628078698] 'agreement among raft nodes before linearized reading' (duration: 1.876323327s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:41:47.593867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:41:45.708106Z","time spent":"1.885753941s","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-31T12:41:47.594002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.902395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-31T12:41:47.59403Z","caller":"traceutil/trace.go:171","msg":"trace[995674013] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:604; }","duration":"566.976927ms","start":"2026-07-31T12:41:47.027045Z","end":"2026-07-31T12:41:47.594021Z","steps":["trace[995674013] 'agreement among raft nodes before linearized reading' (duration: 566.890005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:41:47.594052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:41:47.027008Z","time spent":"567.037898ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-31T12:41:50.486154Z","caller":"traceutil/trace.go:171","msg":"trace[763534016] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"140.984277ms","start":"2026-07-31T12:41:50.345135Z","end":"2026-07-31T12:41:50.486119Z","steps":["trace[763534016] 'process raft request' (duration: 140.788642ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:42:03.87415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.493558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T12:42:03.87422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.112766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-31T12:42:03.874293Z","caller":"traceutil/trace.go:171","msg":"trace[1469418081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:769; }","duration":"188.719213ms","start":"2026-07-31T12:42:03.685543Z","end":"2026-07-31T12:42:03.874262Z","steps":["trace[1469418081] 'range keys from in-memory index tree' (duration: 188.319424ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:42:03.874335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.96171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-31T12:42:03.874448Z","caller":"traceutil/trace.go:171","msg":"trace[249758494] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:769; }","duration":"460.165795ms","start":"2026-07-31T12:42:03.414257Z","end":"2026-07-31T12:42:03.874422Z","steps":["trace[249758494] 'count revisions from in-memory index tree' (duration: 459.748374ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:42:03.874506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:42:03.414223Z","time spent":"460.270458ms","remote":"127.0.0.1:34512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":13,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-07-31T12:42:03.874524Z","caller":"traceutil/trace.go:171","msg":"trace[1591626526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:769; }","duration":"169.306821ms","start":"2026-07-31T12:42:03.704999Z","end":"2026-07-31T12:42:03.874306Z","steps":["trace[1591626526] 'range keys from in-memory index tree' (duration: 168.715228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:42:03.874746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.976846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-31T12:42:03.874742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.843533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-31T12:42:03.875051Z","caller":"traceutil/trace.go:171","msg":"trace[1228330540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:769; }","duration":"170.267824ms","start":"2026-07-31T12:42:03.704733Z","end":"2026-07-31T12:42:03.875001Z","steps":["trace[1228330540] 'range keys from in-memory index tree' (duration: 169.753832ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:42:03.875108Z","caller":"traceutil/trace.go:171","msg":"trace[1517714064] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:769; }","duration":"170.245953ms","start":"2026-07-31T12:42:03.704844Z","end":"2026-07-31T12:42:03.87509Z","steps":["trace[1517714064] 'range keys from in-memory index tree' (duration: 169.630178ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:42:03.875465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.396107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-31T12:42:03.875558Z","caller":"traceutil/trace.go:171","msg":"trace[631759699] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:769; }","duration":"170.57663ms","start":"2026-07-31T12:42:03.704961Z","end":"2026-07-31T12:42:03.875537Z","steps":["trace[631759699] 'range keys from in-memory index tree' (duration: 169.985677ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:42:49.9637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.270918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:42:49.963848Z","caller":"traceutil/trace.go:171","msg":"trace[1478942721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1309; }","duration":"276.493493ms","start":"2026-07-31T12:42:49.687332Z","end":"2026-07-31T12:42:49.963826Z","steps":["trace[1478942721] 'range keys from in-memory index tree' (duration: 276.150943ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:43:24.496927Z","caller":"traceutil/trace.go:171","msg":"trace[1999725233] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"115.007406ms","start":"2026-07-31T12:43:24.381884Z","end":"2026-07-31T12:43:24.496891Z","steps":["trace[1999725233] 'process raft request' (duration: 114.832372ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:43:25.249863Z","caller":"traceutil/trace.go:171","msg":"trace[1876823607] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"867.390575ms","start":"2026-07-31T12:43:24.382437Z","end":"2026-07-31T12:43:25.249827Z","steps":["trace[1876823607] 'process raft request' (duration: 867.229461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:43:25.250327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:43:24.382414Z","time spent":"867.559309ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:43:25.250859Z","caller":"traceutil/trace.go:171","msg":"trace[1100692710] linearizableReadLoop","detail":"{readStateIndex:1937; appliedIndex:1933; }","duration":"773.432844ms","start":"2026-07-31T12:43:24.477376Z","end":"2026-07-31T12:43:25.250809Z","steps":["trace[1100692710] 'read index received' (duration: 19.331955ms)","trace[1100692710] 'applied index is now lower than readState.Index' (duration: 754.099459ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:43:25.250874Z","caller":"traceutil/trace.go:171","msg":"trace[1323244213] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"868.374277ms","start":"2026-07-31T12:43:24.382485Z","end":"2026-07-31T12:43:25.250859Z","steps":["trace[1323244213] 'process raft request' (duration: 867.289652ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:43:25.250508Z","caller":"traceutil/trace.go:171","msg":"trace[1293295556] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"868.077502ms","start":"2026-07-31T12:43:24.382373Z","end":"2026-07-31T12:43:25.25045Z","steps":["trace[1293295556] 'process raft request' (duration: 802.421173ms)","trace[1293295556] 'compare' (duration: 64.421749ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:43:25.251225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:43:24.382345Z","time spent":"868.758248ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:43:25.251185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:43:24.382473Z","time spent":"868.524402ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:43:25.251291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.872766ms","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-07-31T12:43:25.251394Z","caller":"traceutil/trace.go:171","msg":"trace[1378929976] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1868; }","duration":"774.015809ms","start":"2026-07-31T12:43:24.477354Z","end":"2026-07-31T12:43:25.25137Z","steps":["trace[1378929976] 'agreement among raft nodes before linearized reading' (duration: 773.556489ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:43:25.251477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:43:24.477268Z","time spent":"774.197164ms","remote":"127.0.0.1:34698","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-07-31T12:43:25.448458Z","caller":"traceutil/trace.go:171","msg":"trace[2043109749] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1937; }","duration":"197.50519ms","start":"2026-07-31T12:43:25.250921Z","end":"2026-07-31T12:43:25.448427Z","steps":["trace[2043109749] 'read index received' (duration: 197.283385ms)","trace[2043109749] 'applied index is now lower than readState.Index' (duration: 220.085µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:43:25.448685Z","caller":"traceutil/trace.go:171","msg":"trace[130518933] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"362.559101ms","start":"2026-07-31T12:43:25.086077Z","end":"2026-07-31T12:43:25.448636Z","steps":["trace[130518933] 'process raft request' (duration: 362.122751ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:43:25.448754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.184117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-07-31T12:43:25.448732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.513635ms","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-07-31T12:43:25.448804Z","caller":"traceutil/trace.go:171","msg":"trace[1456751131] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1869; }","duration":"299.29317ms","start":"2026-07-31T12:43:25.149496Z","end":"2026-07-31T12:43:25.448789Z","steps":["trace[1456751131] 'agreement among raft nodes before linearized reading' (duration: 299.067354ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:43:25.448841Z","caller":"traceutil/trace.go:171","msg":"trace[884191125] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1869; }","duration":"277.70655ms","start":"2026-07-31T12:43:25.171104Z","end":"2026-07-31T12:43:25.448811Z","steps":["trace[884191125] 'agreement among raft nodes before linearized reading' (duration: 277.397373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:43:25.448888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.727707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:43:25.449022Z","caller":"traceutil/trace.go:171","msg":"trace[603115709] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1869; }","duration":"762.914642ms","start":"2026-07-31T12:43:24.686075Z","end":"2026-07-31T12:43:25.44899Z","steps":["trace[603115709] 'agreement among raft nodes before linearized reading' (duration: 762.660056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:43:25.448928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:43:25.086053Z","time spent":"362.744035ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:43:25.449082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:43:24.686051Z","time spent":"763.022744ms","remote":"127.0.0.1:55100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T12:43:25.449506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.463536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ced00890-3300-45d9-8002-8fa4460a2eeb\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-31T12:43:25.449573Z","caller":"traceutil/trace.go:171","msg":"trace[269086628] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ced00890-3300-45d9-8002-8fa4460a2eeb; range_end:; response_count:1; response_revision:1869; }","duration":"729.568069ms","start":"2026-07-31T12:43:24.719988Z","end":"2026-07-31T12:43:25.449556Z","steps":["trace[269086628] 'agreement among raft nodes before linearized reading' (duration: 728.681618ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:43:25.449694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:43:24.719966Z","time spent":"729.634691ms","remote":"127.0.0.1:34608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ced00890-3300-45d9-8002-8fa4460a2eeb\" "} {"level":"info","ts":"2026-07-31T12:43:25.470447Z","caller":"traceutil/trace.go:171","msg":"trace[2078238286] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"213.393133ms","start":"2026-07-31T12:43:25.257008Z","end":"2026-07-31T12:43:25.470401Z","steps":["trace[2078238286] 'process raft request' (duration: 213.095516ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:43:25.630642Z","caller":"traceutil/trace.go:171","msg":"trace[294019738] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"173.312919ms","start":"2026-07-31T12:43:25.457288Z","end":"2026-07-31T12:43:25.630601Z","steps":["trace[294019738] 'process raft request' (duration: 77.496645ms)","trace[294019738] 'compare' (duration: 95.558518ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:43:25.630642Z","caller":"traceutil/trace.go:171","msg":"trace[1556492619] linearizableReadLoop","detail":"{readStateIndex:1940; appliedIndex:1939; }","duration":"160.263284ms","start":"2026-07-31T12:43:25.470343Z","end":"2026-07-31T12:43:25.630606Z","steps":["trace[1556492619] 'read index received' (duration: 64.315607ms)","trace[1556492619] 'applied index is now lower than readState.Index' (duration: 95.946207ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:43:25.630807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.013439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:43:25.630877Z","caller":"traceutil/trace.go:171","msg":"trace[575900582] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1871; }","duration":"180.145633ms","start":"2026-07-31T12:43:25.450714Z","end":"2026-07-31T12:43:25.63086Z","steps":["trace[575900582] 'agreement among raft nodes before linearized reading' (duration: 179.982929ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:43:26.130643Z","caller":"traceutil/trace.go:171","msg":"trace[644309376] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"385.126974ms","start":"2026-07-31T12:43:25.745488Z","end":"2026-07-31T12:43:26.130615Z","steps":["trace[644309376] 'process raft request' (duration: 383.445684ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:43:26.130773Z","caller":"traceutil/trace.go:171","msg":"trace[1944881870] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"376.096508ms","start":"2026-07-31T12:43:25.754654Z","end":"2026-07-31T12:43:26.13075Z","steps":["trace[1944881870] 'process raft request' (duration: 375.892153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:43:26.130971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:43:25.745452Z","time spent":"385.280358ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:43:26.131131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:43:25.754602Z","time spent":"376.21675ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:43:38.31595Z","caller":"traceutil/trace.go:171","msg":"trace[121630728] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"263.322274ms","start":"2026-07-31T12:43:38.052594Z","end":"2026-07-31T12:43:38.315917Z","steps":["trace[121630728] 'process raft request' (duration: 263.079928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:43:38.60014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.758393ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T12:43:38.600373Z","caller":"traceutil/trace.go:171","msg":"trace[1696736138] transaction","detail":"{read_only:false; response_revision:1986; number_of_response:1; }","duration":"425.548047ms","start":"2026-07-31T12:43:38.17475Z","end":"2026-07-31T12:43:38.600299Z","steps":["trace[1696736138] 'process raft request' (duration: 238.512348ms)","trace[1696736138] 'compare' (duration: 186.590178ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:43:38.600624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:43:38.174716Z","time spent":"425.792742ms","remote":"127.0.0.1:34698","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-07-31T12:43:42.980201Z","caller":"traceutil/trace.go:171","msg":"trace[2060543198] linearizableReadLoop","detail":"{readStateIndex:2084; appliedIndex:2083; }","duration":"347.378326ms","start":"2026-07-31T12:43:42.632792Z","end":"2026-07-31T12:43:42.98017Z","steps":["trace[2060543198] 'read index received' (duration: 347.062278ms)","trace[2060543198] 'applied index is now lower than readState.Index' (duration: 314.817µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:43:42.980328Z","caller":"traceutil/trace.go:171","msg":"trace[321084860] transaction","detail":"{read_only:false; response_revision:2010; number_of_response:1; }","duration":"367.219383ms","start":"2026-07-31T12:43:42.613037Z","end":"2026-07-31T12:43:42.980257Z","steps":["trace[321084860] 'process raft request' (duration: 366.948946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:43:42.980559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:43:42.613007Z","time spent":"367.431938ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:43:42.98057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.010277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-31T12:43:42.980617Z","caller":"traceutil/trace.go:171","msg":"trace[237366233] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2010; }","duration":"342.104669ms","start":"2026-07-31T12:43:42.6385Z","end":"2026-07-31T12:43:42.980604Z","steps":["trace[237366233] 'agreement among raft nodes before linearized reading' (duration: 341.860483ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:43:42.980679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:43:42.638489Z","time spent":"342.178831ms","remote":"127.0.0.1:34698","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-07-31T12:43:42.980663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.832637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-07-31T12:43:42.980695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.246402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11989"} {"level":"info","ts":"2026-07-31T12:43:42.980766Z","caller":"traceutil/trace.go:171","msg":"trace[197815596] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2010; }","duration":"348.002231ms","start":"2026-07-31T12:43:42.632737Z","end":"2026-07-31T12:43:42.980739Z","steps":["trace[197815596] 'agreement among raft nodes before linearized reading' (duration: 347.615122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:43:42.980727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.138545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T12:43:42.980819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:43:42.632709Z","time spent":"348.100274ms","remote":"127.0.0.1:51004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-31T12:43:42.980816Z","caller":"traceutil/trace.go:171","msg":"trace[1375513408] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2010; }","duration":"268.436396ms","start":"2026-07-31T12:43:42.712348Z","end":"2026-07-31T12:43:42.980784Z","steps":["trace[1375513408] 'agreement among raft nodes before linearized reading' (duration: 268.059297ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:43:42.980862Z","caller":"traceutil/trace.go:171","msg":"trace[951725192] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2010; }","duration":"294.354661ms","start":"2026-07-31T12:43:42.686475Z","end":"2026-07-31T12:43:42.98083Z","steps":["trace[951725192] 'agreement among raft nodes before linearized reading' (duration: 294.092014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:44:21.923283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.699341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-31T12:44:21.923348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.491923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-31T12:44:21.923405Z","caller":"traceutil/trace.go:171","msg":"trace[870646797] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2217; }","duration":"684.591025ms","start":"2026-07-31T12:44:21.238801Z","end":"2026-07-31T12:44:21.923392Z","steps":["trace[870646797] 'range keys from in-memory index tree' (duration: 684.36435ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:44:21.923405Z","caller":"traceutil/trace.go:171","msg":"trace[1681558370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2217; }","duration":"353.891795ms","start":"2026-07-31T12:44:21.569492Z","end":"2026-07-31T12:44:21.923383Z","steps":["trace[1681558370] 'range keys from in-memory index tree' (duration: 353.402003ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:44:21.923447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:44:21.238774Z","time spent":"684.663607ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-31T12:44:21.923447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:44:21.569423Z","time spent":"354.014308ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-31T12:44:21.923673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.844001ms","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-07-31T12:44:21.923676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.213875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-31T12:44:21.923283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.273512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-07-31T12:44:21.923722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.334071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-31T12:44:21.923782Z","caller":"traceutil/trace.go:171","msg":"trace[655081930] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:2217; }","duration":"437.384729ms","start":"2026-07-31T12:44:21.486369Z","end":"2026-07-31T12:44:21.923754Z","steps":["trace[655081930] 'count revisions from in-memory index tree' (duration: 437.01708ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:44:21.923849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:44:21.486341Z","time spent":"437.494672ms","remote":"127.0.0.1:34456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":54,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-07-31T12:44:21.92386Z","caller":"traceutil/trace.go:171","msg":"trace[527620938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2217; }","duration":"353.516726ms","start":"2026-07-31T12:44:21.57031Z","end":"2026-07-31T12:44:21.923827Z","steps":["trace[527620938] 'range keys from in-memory index tree' (duration: 353.096735ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:44:21.923863Z","caller":"traceutil/trace.go:171","msg":"trace[1421279691] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2217; }","duration":"644.839925ms","start":"2026-07-31T12:44:21.278972Z","end":"2026-07-31T12:44:21.923811Z","steps":["trace[1421279691] 'range keys from in-memory index tree' (duration: 644.101378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:44:21.923917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:44:21.570296Z","time spent":"353.610217ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-31T12:44:21.923952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:44:21.278944Z","time spent":"644.979109ms","remote":"127.0.0.1:51004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-31T12:44:21.923771Z","caller":"traceutil/trace.go:171","msg":"trace[1989616352] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2217; }","duration":"361.015755ms","start":"2026-07-31T12:44:21.562731Z","end":"2026-07-31T12:44:21.923746Z","steps":["trace[1989616352] 'range keys from in-memory index tree' (duration: 360.264787ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:44:21.924181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:44:21.562699Z","time spent":"361.467965ms","remote":"127.0.0.1:34698","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-07-31T12:44:22.187226Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7053094104886621444,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-31T12:44:22.311021Z","caller":"traceutil/trace.go:171","msg":"trace[1927957833] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"642.313795ms","start":"2026-07-31T12:44:21.668673Z","end":"2026-07-31T12:44:22.310987Z","steps":["trace[1927957833] 'process raft request' (duration: 642.10333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:44:22.311246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:44:21.668641Z","time spent":"642.484939ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:44:22.467748Z","caller":"traceutil/trace.go:171","msg":"trace[378985323] transaction","detail":"{read_only:false; response_revision:2219; number_of_response:1; }","duration":"599.180154ms","start":"2026-07-31T12:44:21.868478Z","end":"2026-07-31T12:44:22.467659Z","steps":["trace[378985323] 'process raft request' (duration: 598.850537ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:44:22.467902Z","caller":"traceutil/trace.go:171","msg":"trace[1720787902] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"598.58373ms","start":"2026-07-31T12:44:21.86906Z","end":"2026-07-31T12:44:22.467643Z","steps":["trace[1720787902] 'process raft request' (duration: 598.477198ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:44:22.46796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:44:21.868425Z","time spent":"599.42739ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:44:22.469513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:44:21.869043Z","time spent":"598.960629ms","remote":"127.0.0.1:34698","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-07-31T12:44:22.469482Z","caller":"traceutil/trace.go:171","msg":"trace[1144029563] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"539.207551ms","start":"2026-07-31T12:44:21.928807Z","end":"2026-07-31T12:44:22.468014Z","steps":["trace[1144029563] 'process raft request' (duration: 538.779861ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:44:22.469761Z","caller":"traceutil/trace.go:171","msg":"trace[1874455071] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"540.784609ms","start":"2026-07-31T12:44:21.928916Z","end":"2026-07-31T12:44:22.469701Z","steps":["trace[1874455071] 'process raft request' (duration: 538.991446ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:44:22.469905Z","caller":"traceutil/trace.go:171","msg":"trace[285242787] transaction","detail":"{read_only:false; response_revision:2224; number_of_response:1; }","duration":"540.056492ms","start":"2026-07-31T12:44:21.929828Z","end":"2026-07-31T12:44:22.469885Z","steps":["trace[285242787] 'process raft request' (duration: 539.881808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:44:22.469861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:44:21.928778Z","time spent":"540.865181ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:44:22.470014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:44:21.9298Z","time spent":"540.152414ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:44:22.470074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.842656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:44:22.470171Z","caller":"traceutil/trace.go:171","msg":"trace[738329673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2224; }","duration":"784.00033ms","start":"2026-07-31T12:44:21.686152Z","end":"2026-07-31T12:44:22.470153Z","steps":["trace[738329673] 'agreement among raft nodes before linearized reading' (duration: 783.787305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:44:22.470228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:44:21.686123Z","time spent":"784.094272ms","remote":"127.0.0.1:55100","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-07-31T12:44:22.469873Z","caller":"traceutil/trace.go:171","msg":"trace[823777037] linearizableReadLoop","detail":"{readStateIndex:2306; appliedIndex:2299; }","duration":"783.604961ms","start":"2026-07-31T12:44:21.686232Z","end":"2026-07-31T12:44:22.469837Z","steps":["trace[823777037] 'read index received' (duration: 624.612622ms)","trace[823777037] 'applied index is now lower than readState.Index' (duration: 158.990649ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:44:22.470175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:44:21.928908Z","time spent":"540.993064ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:44:22.469837Z","caller":"traceutil/trace.go:171","msg":"trace[1953861657] transaction","detail":"{read_only:false; response_revision:2223; number_of_response:1; }","duration":"540.291857ms","start":"2026-07-31T12:44:21.929528Z","end":"2026-07-31T12:44:22.46982Z","steps":["trace[1953861657] 'process raft request' (duration: 538.538275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:44:22.470487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.085166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:44:22.470538Z","caller":"traceutil/trace.go:171","msg":"trace[1693157580] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:2224; }","duration":"343.142897ms","start":"2026-07-31T12:44:22.127382Z","end":"2026-07-31T12:44:22.470525Z","steps":["trace[1693157580] 'agreement among raft nodes before linearized reading' (duration: 343.049125ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:44:22.470582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:44:22.127264Z","time spent":"343.309221ms","remote":"127.0.0.1:50828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"warn","ts":"2026-07-31T12:44:22.470571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:44:21.9295Z","time spent":"540.970544ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:44:22.470634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.680942ms","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-07-31T12:44:22.470714Z","caller":"traceutil/trace.go:171","msg":"trace[2049137602] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2224; }","duration":"693.795265ms","start":"2026-07-31T12:44:21.776878Z","end":"2026-07-31T12:44:22.470674Z","steps":["trace[2049137602] 'agreement among raft nodes before linearized reading' (duration: 693.525889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:44:22.470759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:44:21.776844Z","time spent":"693.906807ms","remote":"127.0.0.1:34698","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-07-31T12:44:22.471004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.850378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.177\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-31T12:44:22.471152Z","caller":"traceutil/trace.go:171","msg":"trace[767378333] range","detail":"{range_begin:/registry/masterleases/199.19.213.177; range_end:; response_count:1; response_revision:2224; }","duration":"489.044203ms","start":"2026-07-31T12:44:21.982083Z","end":"2026-07-31T12:44:22.471127Z","steps":["trace[767378333] 'agreement among raft nodes before linearized reading' (duration: 488.530811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:44:22.471265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:44:21.982059Z","time spent":"489.189366ms","remote":"127.0.0.1:34438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.177\" "} {"level":"warn","ts":"2026-07-31T12:44:22.938511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.046244ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-07-31T12:44:22.938945Z","caller":"traceutil/trace.go:171","msg":"trace[1146274213] linearizableReadLoop","detail":"{readStateIndex:2308; appliedIndex:2306; }","duration":"320.123508ms","start":"2026-07-31T12:44:22.61879Z","end":"2026-07-31T12:44:22.938913Z","steps":["trace[1146274213] 'read index received' (duration: 123.740966ms)","trace[1146274213] 'applied index is now lower than readState.Index' (duration: 196.381231ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:44:22.938959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:44:22.472558Z","time spent":"466.388651ms","remote":"127.0.0.1:34438","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-31T12:44:22.939386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.581268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c76074ef16c3b1\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-07-31T12:44:22.939494Z","caller":"traceutil/trace.go:171","msg":"trace[531387062] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18c76074ef16c3b1; range_end:; response_count:1; response_revision:2226; }","duration":"320.756383ms","start":"2026-07-31T12:44:22.618714Z","end":"2026-07-31T12:44:22.939471Z","steps":["trace[531387062] 'agreement among raft nodes before linearized reading' (duration: 320.466286ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:44:22.939533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:44:22.618683Z","time spent":"320.841604ms","remote":"127.0.0.1:34488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c76074ef16c3b1\" "} {"level":"info","ts":"2026-07-31T12:44:22.939729Z","caller":"traceutil/trace.go:171","msg":"trace[2082752726] transaction","detail":"{read_only:false; response_revision:2225; number_of_response:1; }","duration":"463.318539ms","start":"2026-07-31T12:44:22.476401Z","end":"2026-07-31T12:44:22.93972Z","steps":["trace[2082752726] 'process raft request' (duration: 462.278874ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:44:22.939822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:44:22.476372Z","time spent":"463.387591ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:44:22.939836Z","caller":"traceutil/trace.go:171","msg":"trace[1711245383] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"225.558629ms","start":"2026-07-31T12:44:22.714239Z","end":"2026-07-31T12:44:22.939797Z","steps":["trace[1711245383] 'process raft request' (duration: 224.610516ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:45:31.378184Z","caller":"traceutil/trace.go:171","msg":"trace[1610450923] transaction","detail":"{read_only:false; response_revision:2754; number_of_response:1; }","duration":"189.033004ms","start":"2026-07-31T12:45:31.189121Z","end":"2026-07-31T12:45:31.378154Z","steps":["trace[1610450923] 'process raft request' (duration: 188.87692ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:45:31.379884Z","caller":"traceutil/trace.go:171","msg":"trace[554579535] transaction","detail":"{read_only:false; response_revision:2755; number_of_response:1; }","duration":"168.563244ms","start":"2026-07-31T12:45:31.211289Z","end":"2026-07-31T12:45:31.379852Z","steps":["trace[554579535] 'process raft request' (duration: 168.159644ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:45:35.020335Z","caller":"traceutil/trace.go:171","msg":"trace[1658219699] linearizableReadLoop","detail":"{readStateIndex:2883; appliedIndex:2882; }","duration":"132.770307ms","start":"2026-07-31T12:45:34.887519Z","end":"2026-07-31T12:45:35.020289Z","steps":["trace[1658219699] 'read index received' (duration: 132.625804ms)","trace[1658219699] 'applied index is now lower than readState.Index' (duration: 143.083µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:45:35.020397Z","caller":"traceutil/trace.go:171","msg":"trace[1837706054] transaction","detail":"{read_only:false; response_revision:2777; number_of_response:1; }","duration":"177.404695ms","start":"2026-07-31T12:45:34.842956Z","end":"2026-07-31T12:45:35.020361Z","steps":["trace[1837706054] 'process raft request' (duration: 177.18679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:45:35.020586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.999473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-31T12:45:35.020634Z","caller":"traceutil/trace.go:171","msg":"trace[945248563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2777; }","duration":"133.072774ms","start":"2026-07-31T12:45:34.887551Z","end":"2026-07-31T12:45:35.020624Z","steps":["trace[945248563] 'agreement among raft nodes before linearized reading' (duration: 132.91029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:45:35.020666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.102605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-31T12:45:35.020798Z","caller":"traceutil/trace.go:171","msg":"trace[1540712983] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2777; }","duration":"133.2924ms","start":"2026-07-31T12:45:34.88748Z","end":"2026-07-31T12:45:35.020772Z","steps":["trace[1540712983] 'agreement among raft nodes before linearized reading' (duration: 132.942361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:45:35.020785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.061794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-31T12:45:35.020905Z","caller":"traceutil/trace.go:171","msg":"trace[1303933629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2777; }","duration":"133.235818ms","start":"2026-07-31T12:45:34.887641Z","end":"2026-07-31T12:45:35.020877Z","steps":["trace[1303933629] 'agreement among raft nodes before linearized reading' (duration: 132.9012ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:45:35.681909Z","caller":"traceutil/trace.go:171","msg":"trace[209303587] transaction","detail":"{read_only:false; response_revision:2781; number_of_response:1; }","duration":"275.317338ms","start":"2026-07-31T12:45:35.406561Z","end":"2026-07-31T12:45:35.681878Z","steps":["trace[209303587] 'process raft request' (duration: 275.161724ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:45:35.685365Z","caller":"traceutil/trace.go:171","msg":"trace[392908180] transaction","detail":"{read_only:false; response_revision:2782; number_of_response:1; }","duration":"244.030529ms","start":"2026-07-31T12:45:35.441283Z","end":"2026-07-31T12:45:35.685313Z","steps":["trace[392908180] 'process raft request' (duration: 243.826254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:45:45.297194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.203937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-31T12:45:45.297296Z","caller":"traceutil/trace.go:171","msg":"trace[1557583955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2843; }","duration":"194.633437ms","start":"2026-07-31T12:45:45.102645Z","end":"2026-07-31T12:45:45.297278Z","steps":["trace[1557583955] 'range keys from in-memory index tree' (duration: 191.905433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:45:45.297776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.152231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-31T12:45:45.297903Z","caller":"traceutil/trace.go:171","msg":"trace[2063134085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2843; }","duration":"195.351615ms","start":"2026-07-31T12:45:45.102521Z","end":"2026-07-31T12:45:45.297872Z","steps":["trace[2063134085] 'range keys from in-memory index tree' (duration: 194.878474ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:45:45.298048Z","caller":"traceutil/trace.go:171","msg":"trace[335990176] transaction","detail":"{read_only:false; response_revision:2844; number_of_response:1; }","duration":"193.46708ms","start":"2026-07-31T12:45:45.104567Z","end":"2026-07-31T12:45:45.298034Z","steps":["trace[335990176] 'process raft request' (duration: 187.62186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:08.341905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.454719ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T12:46:08.342051Z","caller":"traceutil/trace.go:171","msg":"trace[184199452] transaction","detail":"{read_only:false; response_revision:2979; number_of_response:1; }","duration":"497.221994ms","start":"2026-07-31T12:46:07.844804Z","end":"2026-07-31T12:46:08.342026Z","steps":["trace[184199452] 'process raft request' (duration: 109.513119ms)","trace[184199452] 'compare' (duration: 387.293095ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:46:08.342119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:07.844786Z","time spent":"497.302065ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:46:08.350182Z","caller":"traceutil/trace.go:171","msg":"trace[108950711] transaction","detail":"{read_only:false; response_revision:2980; number_of_response:1; }","duration":"286.273227ms","start":"2026-07-31T12:46:08.063875Z","end":"2026-07-31T12:46:08.350149Z","steps":["trace[108950711] 'process raft request' (duration: 286.107293ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:46:09.183141Z","caller":"traceutil/trace.go:171","msg":"trace[918841745] linearizableReadLoop","detail":"{readStateIndex:3094; appliedIndex:3093; }","duration":"408.160119ms","start":"2026-07-31T12:46:08.774936Z","end":"2026-07-31T12:46:09.183096Z","steps":["trace[918841745] 'read index received' (duration: 407.869792ms)","trace[918841745] 'applied index is now lower than readState.Index' (duration: 288.737µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:46:09.183285Z","caller":"traceutil/trace.go:171","msg":"trace[385149498] transaction","detail":"{read_only:false; response_revision:2981; number_of_response:1; }","duration":"414.328956ms","start":"2026-07-31T12:46:08.768893Z","end":"2026-07-31T12:46:09.183222Z","steps":["trace[385149498] 'process raft request' (duration: 413.952717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:09.183451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.477317ms","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-07-31T12:46:09.183505Z","caller":"traceutil/trace.go:171","msg":"trace[1724280919] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2981; }","duration":"408.61549ms","start":"2026-07-31T12:46:08.774875Z","end":"2026-07-31T12:46:09.183491Z","steps":["trace[1724280919] 'agreement among raft nodes before linearized reading' (duration: 408.375984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:09.183557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:08.774853Z","time spent":"408.693871ms","remote":"127.0.0.1:34616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-07-31T12:46:09.183681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:08.768858Z","time spent":"414.599811ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:46:09.344581Z","caller":"traceutil/trace.go:171","msg":"trace[1475930698] linearizableReadLoop","detail":"{readStateIndex:3096; appliedIndex:3094; }","duration":"156.531936ms","start":"2026-07-31T12:46:09.188017Z","end":"2026-07-31T12:46:09.344549Z","steps":["trace[1475930698] 'read index received' (duration: 74.985509ms)","trace[1475930698] 'applied index is now lower than readState.Index' (duration: 81.544687ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:46:09.344626Z","caller":"traceutil/trace.go:171","msg":"trace[684813099] transaction","detail":"{read_only:false; response_revision:2982; number_of_response:1; }","duration":"463.223636ms","start":"2026-07-31T12:46:08.881345Z","end":"2026-07-31T12:46:09.344569Z","steps":["trace[684813099] 'process raft request' (duration: 381.745802ms)","trace[684813099] 'compare' (duration: 81.234049ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:46:09.344764Z","caller":"traceutil/trace.go:171","msg":"trace[1905961974] transaction","detail":"{read_only:false; response_revision:2983; number_of_response:1; }","duration":"459.762024ms","start":"2026-07-31T12:46:08.88496Z","end":"2026-07-31T12:46:09.344722Z","steps":["trace[1905961974] 'process raft request' (duration: 459.526088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:09.344854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:08.881309Z","time spent":"463.419061ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:46:09.344932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.939295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1443"} {"level":"warn","ts":"2026-07-31T12:46:09.344965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:46:08.884943Z","time spent":"459.913207ms","remote":"127.0.0.1:34698","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-07-31T12:46:09.34499Z","caller":"traceutil/trace.go:171","msg":"trace[170427739] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2983; }","duration":"157.066939ms","start":"2026-07-31T12:46:09.187912Z","end":"2026-07-31T12:46:09.344979Z","steps":["trace[170427739] 'agreement among raft nodes before linearized reading' (duration: 156.832203ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:46:09.344923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.07074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-31T12:46:09.3451Z","caller":"traceutil/trace.go:171","msg":"trace[613316566] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2983; }","duration":"149.288284ms","start":"2026-07-31T12:46:09.195784Z","end":"2026-07-31T12:46:09.345072Z","steps":["trace[613316566] 'agreement among raft nodes before linearized reading' (duration: 148.948046ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:46:14.501882Z","caller":"traceutil/trace.go:171","msg":"trace[2047744434] transaction","detail":"{read_only:false; response_revision:3015; number_of_response:1; }","duration":"126.751509ms","start":"2026-07-31T12:46:14.375094Z","end":"2026-07-31T12:46:14.501846Z","steps":["trace[2047744434] 'process raft request' (duration: 126.562955ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:48:22.591552Z","caller":"traceutil/trace.go:171","msg":"trace[1928285952] linearizableReadLoop","detail":"{readStateIndex:3953; appliedIndex:3952; }","duration":"123.482637ms","start":"2026-07-31T12:48:22.468048Z","end":"2026-07-31T12:48:22.591531Z","steps":["trace[1928285952] 'read index received' (duration: 123.303603ms)","trace[1928285952] 'applied index is now lower than readState.Index' (duration: 178.094µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:48:22.591671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.604519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/secretgen-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:48:22.591699Z","caller":"traceutil/trace.go:171","msg":"trace[847270299] range","detail":"{range_begin:/registry/namespaces/secretgen-controller; range_end:; response_count:0; response_revision:3807; }","duration":"123.690411ms","start":"2026-07-31T12:48:22.468001Z","end":"2026-07-31T12:48:22.591691Z","steps":["trace[847270299] 'agreement among raft nodes before linearized reading' (duration: 123.62178ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:48:22.591797Z","caller":"traceutil/trace.go:171","msg":"trace[500387199] transaction","detail":"{read_only:false; response_revision:3807; number_of_response:1; }","duration":"278.017839ms","start":"2026-07-31T12:48:22.313765Z","end":"2026-07-31T12:48:22.591783Z","steps":["trace[500387199] 'process raft request' (duration: 277.611529ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:48:50.793229Z","caller":"traceutil/trace.go:171","msg":"trace[2106880352] transaction","detail":"{read_only:false; response_revision:4034; number_of_response:1; }","duration":"106.018109ms","start":"2026-07-31T12:48:50.687181Z","end":"2026-07-31T12:48:50.793199Z","steps":["trace[2106880352] 'process raft request' (duration: 105.828453ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:48:56.084624Z","caller":"traceutil/trace.go:171","msg":"trace[25823497] transaction","detail":"{read_only:false; response_revision:4058; number_of_response:1; }","duration":"109.653093ms","start":"2026-07-31T12:48:55.974949Z","end":"2026-07-31T12:48:56.084602Z","steps":["trace[25823497] 'process raft request' (duration: 109.49206ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:48:56.113676Z","caller":"traceutil/trace.go:171","msg":"trace[724111100] transaction","detail":"{read_only:false; response_revision:4059; number_of_response:1; }","duration":"137.591266ms","start":"2026-07-31T12:48:55.976054Z","end":"2026-07-31T12:48:56.113646Z","steps":["trace[724111100] 'process raft request' (duration: 137.309889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:48:56.406009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.282172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-31T12:48:56.406149Z","caller":"traceutil/trace.go:171","msg":"trace[1729762567] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4059; }","duration":"224.497127ms","start":"2026-07-31T12:48:56.181625Z","end":"2026-07-31T12:48:56.406122Z","steps":["trace[1729762567] 'range keys from in-memory index tree' (duration: 224.047057ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:50:13.296146Z","caller":"traceutil/trace.go:171","msg":"trace[1042449014] transaction","detail":"{read_only:false; response_revision:4979; number_of_response:1; }","duration":"247.869159ms","start":"2026-07-31T12:50:13.048252Z","end":"2026-07-31T12:50:13.296121Z","steps":["trace[1042449014] 'process raft request' (duration: 247.720175ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:50:13.304174Z","caller":"traceutil/trace.go:171","msg":"trace[1139090736] transaction","detail":"{read_only:false; response_revision:4980; number_of_response:1; }","duration":"216.020717ms","start":"2026-07-31T12:50:13.088138Z","end":"2026-07-31T12:50:13.304158Z","steps":["trace[1139090736] 'process raft request' (duration: 215.764511ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:50:13.489055Z","caller":"traceutil/trace.go:171","msg":"trace[1884511650] linearizableReadLoop","detail":"{readStateIndex:5172; appliedIndex:5171; }","duration":"149.104038ms","start":"2026-07-31T12:50:13.339926Z","end":"2026-07-31T12:50:13.48903Z","steps":["trace[1884511650] 'read index received' (duration: 148.878613ms)","trace[1884511650] 'applied index is now lower than readState.Index' (duration: 224.545µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:50:13.489173Z","caller":"traceutil/trace.go:171","msg":"trace[391703224] transaction","detail":"{read_only:false; response_revision:4981; number_of_response:1; }","duration":"170.205726ms","start":"2026-07-31T12:50:13.318957Z","end":"2026-07-31T12:50:13.489162Z","steps":["trace[391703224] 'process raft request' (duration: 169.91566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:13.489335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.395476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:50:13.489378Z","caller":"traceutil/trace.go:171","msg":"trace[1241719534] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:4981; }","duration":"149.492957ms","start":"2026-07-31T12:50:13.339872Z","end":"2026-07-31T12:50:13.489365Z","steps":["trace[1241719534] 'agreement among raft nodes before linearized reading' (duration: 149.411915ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:50:16.471938Z","caller":"traceutil/trace.go:171","msg":"trace[761666111] transaction","detail":"{read_only:false; response_revision:5035; number_of_response:1; }","duration":"100.989883ms","start":"2026-07-31T12:50:16.370916Z","end":"2026-07-31T12:50:16.471906Z","steps":["trace[761666111] 'process raft request' (duration: 100.827869ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:50:16.472818Z","caller":"traceutil/trace.go:171","msg":"trace[734978463] transaction","detail":"{read_only:false; response_revision:5036; number_of_response:1; }","duration":"101.556257ms","start":"2026-07-31T12:50:16.371237Z","end":"2026-07-31T12:50:16.472793Z","steps":["trace[734978463] 'process raft request' (duration: 101.449904ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:50:19.597268Z","caller":"traceutil/trace.go:171","msg":"trace[1174928926] transaction","detail":"{read_only:false; response_revision:5111; number_of_response:1; }","duration":"239.259187ms","start":"2026-07-31T12:50:19.357975Z","end":"2026-07-31T12:50:19.597234Z","steps":["trace[1174928926] 'process raft request' (duration: 239.041522ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:20.127627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.241267ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T12:50:20.127749Z","caller":"traceutil/trace.go:171","msg":"trace[1855996494] transaction","detail":"{read_only:false; response_revision:5113; number_of_response:1; }","duration":"481.270937ms","start":"2026-07-31T12:50:19.646458Z","end":"2026-07-31T12:50:20.127729Z","steps":["trace[1855996494] 'process raft request' (duration: 121.837825ms)","trace[1855996494] 'compare' (duration: 358.799687ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:50:20.127808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:50:19.646436Z","time spent":"481.344349ms","remote":"127.0.0.1:34710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1853,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:50:20.153164Z","caller":"traceutil/trace.go:171","msg":"trace[1734299186] linearizableReadLoop","detail":"{readStateIndex:5312; appliedIndex:5310; }","duration":"314.897231ms","start":"2026-07-31T12:50:19.83824Z","end":"2026-07-31T12:50:20.153138Z","steps":["trace[1734299186] 'read index received' (duration: 314.746717ms)","trace[1734299186] 'applied index is now lower than readState.Index' (duration: 148.584µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:50:20.153237Z","caller":"traceutil/trace.go:171","msg":"trace[555919588] transaction","detail":"{read_only:false; response_revision:5114; number_of_response:1; }","duration":"506.334419ms","start":"2026-07-31T12:50:19.646842Z","end":"2026-07-31T12:50:20.153177Z","steps":["trace[555919588] 'process raft request' (duration: 506.073393ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:50:20.153294Z","caller":"traceutil/trace.go:171","msg":"trace[1114224511] transaction","detail":"{read_only:false; response_revision:5115; number_of_response:1; }","duration":"506.311678ms","start":"2026-07-31T12:50:19.646942Z","end":"2026-07-31T12:50:20.153254Z","steps":["trace[1114224511] 'process raft request' (duration: 506.137094ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:20.153504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:50:19.646821Z","time spent":"506.551194ms","remote":"127.0.0.1:34930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4883,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:50:20.153518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:50:19.646932Z","time spent":"506.467932ms","remote":"127.0.0.1:34582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1249,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:50:20.153522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.240238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-31T12:50:20.15362Z","caller":"traceutil/trace.go:171","msg":"trace[1268617313] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5115; }","duration":"315.390202ms","start":"2026-07-31T12:50:19.838203Z","end":"2026-07-31T12:50:20.153593Z","steps":["trace[1268617313] 'agreement among raft nodes before linearized reading' (duration: 315.084365ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:20.153695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:50:19.838185Z","time spent":"315.494954ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-31T12:50:20.20102Z","caller":"traceutil/trace.go:171","msg":"trace[2021714996] transaction","detail":"{read_only:false; response_revision:5116; number_of_response:1; }","duration":"104.242769ms","start":"2026-07-31T12:50:20.096747Z","end":"2026-07-31T12:50:20.20099Z","steps":["trace[2021714996] 'process raft request' (duration: 103.702227ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:20.201114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.238153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/monitoring/loki-memcached-chunks-cache\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:50:20.201184Z","caller":"traceutil/trace.go:171","msg":"trace[2006360474] range","detail":"{range_begin:/registry/poddisruptionbudgets/monitoring/loki-memcached-chunks-cache; range_end:; response_count:0; response_revision:5117; }","duration":"202.355264ms","start":"2026-07-31T12:50:19.998809Z","end":"2026-07-31T12:50:20.201164Z","steps":["trace[2006360474] 'agreement among raft nodes before linearized reading' (duration: 202.227732ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:20.201202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.070489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-8nl7c\" ","response":"range_response_count:1 size:9757"} {"level":"warn","ts":"2026-07-31T12:50:20.201201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.020753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-n9tc2\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-07-31T12:50:20.201272Z","caller":"traceutil/trace.go:171","msg":"trace[881362200] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-8nl7c; range_end:; response_count:1; response_revision:5117; }","duration":"105.189501ms","start":"2026-07-31T12:50:20.096061Z","end":"2026-07-31T12:50:20.20125Z","steps":["trace[881362200] 'agreement among raft nodes before linearized reading' (duration: 104.947056ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:50:20.201304Z","caller":"traceutil/trace.go:171","msg":"trace[1418167499] range","detail":"{range_begin:/registry/pods/openstack/keepalived-n9tc2; range_end:; response_count:1; response_revision:5117; }","duration":"326.167726ms","start":"2026-07-31T12:50:19.875113Z","end":"2026-07-31T12:50:20.201281Z","steps":["trace[1418167499] 'agreement among raft nodes before linearized reading' (duration: 325.8837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:20.201355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:50:19.875095Z","time spent":"326.252229ms","remote":"127.0.0.1:34608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-n9tc2\" "} {"level":"warn","ts":"2026-07-31T12:50:20.201391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.421538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:50:20.201606Z","caller":"traceutil/trace.go:171","msg":"trace[1270088381] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5117; }","duration":"250.707905ms","start":"2026-07-31T12:50:19.950878Z","end":"2026-07-31T12:50:20.201586Z","steps":["trace[1270088381] 'agreement among raft nodes before linearized reading' (duration: 250.346677ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:50:20.428658Z","caller":"traceutil/trace.go:171","msg":"trace[1444127176] transaction","detail":"{read_only:false; response_revision:5118; number_of_response:1; }","duration":"270.711647ms","start":"2026-07-31T12:50:20.157917Z","end":"2026-07-31T12:50:20.428629Z","steps":["trace[1444127176] 'process raft request' (duration: 270.483172ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:50:20.428828Z","caller":"traceutil/trace.go:171","msg":"trace[1776490529] linearizableReadLoop","detail":"{readStateIndex:5316; appliedIndex:5314; }","duration":"224.146579ms","start":"2026-07-31T12:50:20.204659Z","end":"2026-07-31T12:50:20.428805Z","steps":["trace[1776490529] 'read index received' (duration: 223.78755ms)","trace[1776490529] 'applied index is now lower than readState.Index' (duration: 353.199µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:50:20.428984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.307403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/monitoring/loki-memcached-results-cache\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:50:20.428963Z","caller":"traceutil/trace.go:171","msg":"trace[24510129] transaction","detail":"{read_only:false; response_revision:5119; number_of_response:1; }","duration":"268.582368ms","start":"2026-07-31T12:50:20.160347Z","end":"2026-07-31T12:50:20.428929Z","steps":["trace[24510129] 'process raft request' (duration: 268.214518ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:50:20.429051Z","caller":"traceutil/trace.go:171","msg":"trace[655631916] range","detail":"{range_begin:/registry/poddisruptionbudgets/monitoring/loki-memcached-results-cache; range_end:; response_count:0; response_revision:5119; }","duration":"224.421476ms","start":"2026-07-31T12:50:20.204609Z","end":"2026-07-31T12:50:20.42903Z","steps":["trace[655631916] 'agreement among raft nodes before linearized reading' (duration: 224.304894ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:50:20.44296Z","caller":"traceutil/trace.go:171","msg":"trace[133371697] transaction","detail":"{read_only:false; response_revision:5120; number_of_response:1; }","duration":"232.426265ms","start":"2026-07-31T12:50:20.210523Z","end":"2026-07-31T12:50:20.442949Z","steps":["trace[133371697] 'process raft request' (duration: 232.289022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:20.443474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.70155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62980"} {"level":"info","ts":"2026-07-31T12:50:20.443577Z","caller":"traceutil/trace.go:171","msg":"trace[567987802] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:5120; }","duration":"237.853523ms","start":"2026-07-31T12:50:20.205702Z","end":"2026-07-31T12:50:20.443556Z","steps":["trace[567987802] 'agreement among raft nodes before linearized reading' (duration: 237.319011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:23.422903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.113094ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T12:50:23.423229Z","caller":"traceutil/trace.go:171","msg":"trace[92907221] transaction","detail":"{read_only:false; response_revision:5262; number_of_response:1; }","duration":"273.065833ms","start":"2026-07-31T12:50:23.150109Z","end":"2026-07-31T12:50:23.423175Z","steps":["trace[92907221] 'process raft request' (duration: 66.593072ms)","trace[92907221] 'compare' (duration: 205.98885ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:50:23.423305Z","caller":"traceutil/trace.go:171","msg":"trace[844918900] transaction","detail":"{read_only:false; response_revision:5263; number_of_response:1; }","duration":"183.313445ms","start":"2026-07-31T12:50:23.239957Z","end":"2026-07-31T12:50:23.423271Z","steps":["trace[844918900] 'process raft request' (duration: 183.050739ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:50:23.813095Z","caller":"traceutil/trace.go:171","msg":"trace[455112584] transaction","detail":"{read_only:false; response_revision:5264; number_of_response:1; }","duration":"381.545365ms","start":"2026-07-31T12:50:23.431526Z","end":"2026-07-31T12:50:23.813072Z","steps":["trace[455112584] 'process raft request' (duration: 381.420022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:23.813213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:50:23.4315Z","time spent":"381.662377ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2500,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-31T12:50:23.857836Z","caller":"traceutil/trace.go:171","msg":"trace[1375322574] transaction","detail":"{read_only:false; response_revision:5265; number_of_response:1; }","duration":"247.253776ms","start":"2026-07-31T12:50:23.610554Z","end":"2026-07-31T12:50:23.857808Z","steps":["trace[1375322574] 'process raft request' (duration: 236.200335ms)","trace[1375322574] 'compare' (duration: 10.826795ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:50:23.857895Z","caller":"traceutil/trace.go:171","msg":"trace[1851663728] linearizableReadLoop","detail":"{readStateIndex:5467; appliedIndex:5465; }","duration":"172.548802ms","start":"2026-07-31T12:50:23.685324Z","end":"2026-07-31T12:50:23.857873Z","steps":["trace[1851663728] 'read index received' (duration: 127.96944ms)","trace[1851663728] 'applied index is now lower than readState.Index' (duration: 44.578152ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:50:23.857948Z","caller":"traceutil/trace.go:171","msg":"trace[317273594] transaction","detail":"{read_only:false; response_revision:5266; number_of_response:1; }","duration":"221.274702ms","start":"2026-07-31T12:50:23.636653Z","end":"2026-07-31T12:50:23.857928Z","steps":["trace[317273594] 'process raft request' (duration: 221.090467ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:23.858038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.722446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:50:23.8581Z","caller":"traceutil/trace.go:171","msg":"trace[842925236] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5266; }","duration":"172.808038ms","start":"2026-07-31T12:50:23.685283Z","end":"2026-07-31T12:50:23.858091Z","steps":["trace[842925236] 'agreement among raft nodes before linearized reading' (duration: 172.701966ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:50:24.378297Z","caller":"traceutil/trace.go:171","msg":"trace[493013000] linearizableReadLoop","detail":"{readStateIndex:5468; appliedIndex:5467; }","duration":"231.951963ms","start":"2026-07-31T12:50:24.146321Z","end":"2026-07-31T12:50:24.378273Z","steps":["trace[493013000] 'read index received' (duration: 231.783969ms)","trace[493013000] 'applied index is now lower than readState.Index' (duration: 167.044µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:50:24.378349Z","caller":"traceutil/trace.go:171","msg":"trace[652205638] transaction","detail":"{read_only:false; response_revision:5267; number_of_response:1; }","duration":"249.484328ms","start":"2026-07-31T12:50:24.128824Z","end":"2026-07-31T12:50:24.378308Z","steps":["trace[652205638] 'process raft request' (duration: 249.312944ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:24.378801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.464675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:787136"} {"level":"info","ts":"2026-07-31T12:50:24.378874Z","caller":"traceutil/trace.go:171","msg":"trace[194703499] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5267; }","duration":"232.587348ms","start":"2026-07-31T12:50:24.146259Z","end":"2026-07-31T12:50:24.378847Z","steps":["trace[194703499] 'agreement among raft nodes before linearized reading' (duration: 232.076955ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:50:24.407924Z","caller":"traceutil/trace.go:171","msg":"trace[2113807662] transaction","detail":"{read_only:false; response_revision:5268; number_of_response:1; }","duration":"195.013263ms","start":"2026-07-31T12:50:24.212888Z","end":"2026-07-31T12:50:24.407901Z","steps":["trace[2113807662] 'process raft request' (duration: 194.90649ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:26.107003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.957489ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T12:50:26.107144Z","caller":"traceutil/trace.go:171","msg":"trace[1915574970] linearizableReadLoop","detail":"{readStateIndex:5511; appliedIndex:5510; }","duration":"206.234411ms","start":"2026-07-31T12:50:25.90089Z","end":"2026-07-31T12:50:26.107125Z","steps":["trace[1915574970] 'read index received' (duration: 51.981µs)","trace[1915574970] 'applied index is now lower than readState.Index' (duration: 206.18072ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:50:26.107205Z","caller":"traceutil/trace.go:171","msg":"trace[990488168] transaction","detail":"{read_only:false; response_revision:5309; number_of_response:1; }","duration":"243.797532ms","start":"2026-07-31T12:50:25.863353Z","end":"2026-07-31T12:50:26.10715Z","steps":["trace[990488168] 'process raft request' (duration: 36.594787ms)","trace[990488168] 'compare' (duration: 206.804315ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:50:26.107285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.365475ms","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-07-31T12:50:26.107389Z","caller":"traceutil/trace.go:171","msg":"trace[201171571] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5309; }","duration":"206.5219ms","start":"2026-07-31T12:50:25.900838Z","end":"2026-07-31T12:50:26.10736Z","steps":["trace[201171571] 'agreement among raft nodes before linearized reading' (duration: 206.336016ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:50:26.398077Z","caller":"traceutil/trace.go:171","msg":"trace[1803054017] linearizableReadLoop","detail":"{readStateIndex:5513; appliedIndex:5512; }","duration":"220.278264ms","start":"2026-07-31T12:50:26.177766Z","end":"2026-07-31T12:50:26.398044Z","steps":["trace[1803054017] 'read index received' (duration: 212.44468ms)","trace[1803054017] 'applied index is now lower than readState.Index' (duration: 7.831974ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:50:26.398177Z","caller":"traceutil/trace.go:171","msg":"trace[612561238] transaction","detail":"{read_only:false; response_revision:5311; number_of_response:1; }","duration":"226.501741ms","start":"2026-07-31T12:50:26.171637Z","end":"2026-07-31T12:50:26.398139Z","steps":["trace[612561238] 'process raft request' (duration: 218.642165ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:26.398368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.580041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12974"} {"level":"info","ts":"2026-07-31T12:50:26.398414Z","caller":"traceutil/trace.go:171","msg":"trace[1222820569] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5311; }","duration":"220.691274ms","start":"2026-07-31T12:50:26.177709Z","end":"2026-07-31T12:50:26.3984Z","steps":["trace[1222820569] 'agreement among raft nodes before linearized reading' (duration: 220.475869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:26.398793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.409348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-9f9596859-rf2z5.18c760d05460a9b1\" ","response":"range_response_count:1 size:895"} {"level":"info","ts":"2026-07-31T12:50:26.398878Z","caller":"traceutil/trace.go:171","msg":"trace[57997323] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-9f9596859-rf2z5.18c760d05460a9b1; range_end:; response_count:1; response_revision:5311; }","duration":"220.544581ms","start":"2026-07-31T12:50:26.178317Z","end":"2026-07-31T12:50:26.398861Z","steps":["trace[57997323] 'agreement among raft nodes before linearized reading' (duration: 220.281965ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:26.398912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.366821ms","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-07-31T12:50:26.399019Z","caller":"traceutil/trace.go:171","msg":"trace[498527550] 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:5311; }","duration":"145.503324ms","start":"2026-07-31T12:50:26.253486Z","end":"2026-07-31T12:50:26.398989Z","steps":["trace[498527550] 'agreement among raft nodes before linearized reading' (duration: 145.131966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:37.141576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.881624ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T12:50:37.141778Z","caller":"traceutil/trace.go:171","msg":"trace[322963702] transaction","detail":"{read_only:false; response_revision:5508; number_of_response:1; }","duration":"629.931901ms","start":"2026-07-31T12:50:36.511802Z","end":"2026-07-31T12:50:37.141734Z","steps":["trace[322963702] 'process raft request' (duration: 17.788591ms)","trace[322963702] 'compare' (duration: 611.784572ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:50:37.141867Z","caller":"traceutil/trace.go:171","msg":"trace[175319645] linearizableReadLoop","detail":"{readStateIndex:5716; appliedIndex:5715; }","duration":"621.325838ms","start":"2026-07-31T12:50:36.520502Z","end":"2026-07-31T12:50:37.141827Z","steps":["trace[175319645] 'read index received' (duration: 9.056224ms)","trace[175319645] 'applied index is now lower than readState.Index' (duration: 612.267364ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:50:37.141899Z","caller":"traceutil/trace.go:171","msg":"trace[6219559] transaction","detail":"{read_only:false; response_revision:5509; number_of_response:1; }","duration":"593.95795ms","start":"2026-07-31T12:50:36.547922Z","end":"2026-07-31T12:50:37.14188Z","steps":["trace[6219559] 'process raft request' (duration: 593.772625ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:50:37.142012Z","caller":"traceutil/trace.go:171","msg":"trace[881039187] transaction","detail":"{read_only:false; response_revision:5510; number_of_response:1; }","duration":"592.504606ms","start":"2026-07-31T12:50:36.549495Z","end":"2026-07-31T12:50:37.141999Z","steps":["trace[881039187] 'process raft request' (duration: 592.315432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:37.142007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:50:36.511786Z","time spent":"630.113636ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:50:37.142121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.615636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-n9tc2\" ","response":"range_response_count:1 size:6530"} {"level":"warn","ts":"2026-07-31T12:50:37.142137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:50:36.549469Z","time spent":"592.583788ms","remote":"127.0.0.1:34698","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-07-31T12:50:37.142182Z","caller":"traceutil/trace.go:171","msg":"trace[1043558678] range","detail":"{range_begin:/registry/pods/openstack/keepalived-n9tc2; range_end:; response_count:1; response_revision:5510; }","duration":"621.703637ms","start":"2026-07-31T12:50:36.520464Z","end":"2026-07-31T12:50:37.142168Z","steps":["trace[1043558678] 'agreement among raft nodes before linearized reading' (duration: 621.487652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:37.142126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:50:36.547904Z","time spent":"594.088833ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:50:37.142218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:50:36.520452Z","time spent":"621.757998ms","remote":"127.0.0.1:34608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-n9tc2\" "} {"level":"warn","ts":"2026-07-31T12:50:37.223277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.468807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-31T12:50:37.223341Z","caller":"traceutil/trace.go:171","msg":"trace[394270229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5510; }","duration":"263.56476ms","start":"2026-07-31T12:50:36.959761Z","end":"2026-07-31T12:50:37.223326Z","steps":["trace[394270229] 'agreement among raft nodes before linearized reading' (duration: 263.369935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:37.22336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.191291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T12:50:37.223275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.236641ms","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-07-31T12:50:37.223458Z","caller":"traceutil/trace.go:171","msg":"trace[1051408174] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:5510; }","duration":"323.344205ms","start":"2026-07-31T12:50:36.900089Z","end":"2026-07-31T12:50:37.223433Z","steps":["trace[1051408174] 'agreement among raft nodes before linearized reading' (duration: 323.14811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:37.223441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.924666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-31T12:50:37.223527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:50:36.900071Z","time spent":"323.444827ms","remote":"127.0.0.1:56978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true "} {"level":"info","ts":"2026-07-31T12:50:37.223554Z","caller":"traceutil/trace.go:171","msg":"trace[856671440] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5510; }","duration":"269.069749ms","start":"2026-07-31T12:50:36.954462Z","end":"2026-07-31T12:50:37.223532Z","steps":["trace[856671440] 'agreement among raft nodes before linearized reading' (duration: 268.737712ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:50:37.223461Z","caller":"traceutil/trace.go:171","msg":"trace[24257953] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5510; }","duration":"268.476855ms","start":"2026-07-31T12:50:36.954969Z","end":"2026-07-31T12:50:37.223446Z","steps":["trace[24257953] 'agreement among raft nodes before linearized reading' (duration: 268.179088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:37.223444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.047507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-31T12:50:37.223712Z","caller":"traceutil/trace.go:171","msg":"trace[203573780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5510; }","duration":"263.395465ms","start":"2026-07-31T12:50:36.960298Z","end":"2026-07-31T12:50:37.223694Z","steps":["trace[203573780] 'agreement among raft nodes before linearized reading' (duration: 262.968525ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:50:38.401862Z","caller":"traceutil/trace.go:171","msg":"trace[1727377859] linearizableReadLoop","detail":"{readStateIndex:5734; appliedIndex:5733; }","duration":"103.842618ms","start":"2026-07-31T12:50:38.297991Z","end":"2026-07-31T12:50:38.401834Z","steps":["trace[1727377859] 'read index received' (duration: 103.569962ms)","trace[1727377859] 'applied index is now lower than readState.Index' (duration: 271.376µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:50:38.401903Z","caller":"traceutil/trace.go:171","msg":"trace[753312145] transaction","detail":"{read_only:false; response_revision:5525; number_of_response:1; }","duration":"191.195896ms","start":"2026-07-31T12:50:38.210677Z","end":"2026-07-31T12:50:38.401873Z","steps":["trace[753312145] 'process raft request' (duration: 190.9423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:38.402115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.109315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/goldpinger-8fghz\" ","response":"range_response_count:1 size:4995"} {"level":"info","ts":"2026-07-31T12:50:38.402233Z","caller":"traceutil/trace.go:171","msg":"trace[1061882477] range","detail":"{range_begin:/registry/pods/monitoring/goldpinger-8fghz; range_end:; response_count:1; response_revision:5525; }","duration":"104.228627ms","start":"2026-07-31T12:50:38.29793Z","end":"2026-07-31T12:50:38.402159Z","steps":["trace[1061882477] 'agreement among raft nodes before linearized reading' (duration: 104.022733ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:44.468899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.173418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-0\" ","response":"range_response_count:1 size:5835"} {"level":"info","ts":"2026-07-31T12:50:44.46902Z","caller":"traceutil/trace.go:171","msg":"trace[337826323] range","detail":"{range_begin:/registry/pods/monitoring/loki-0; range_end:; response_count:1; response_revision:5669; }","duration":"110.338992ms","start":"2026-07-31T12:50:44.358654Z","end":"2026-07-31T12:50:44.468993Z","steps":["trace[337826323] 'range keys from in-memory index tree' (duration: 109.980613ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:50:44.664588Z","caller":"traceutil/trace.go:171","msg":"trace[1136428411] linearizableReadLoop","detail":"{readStateIndex:5883; appliedIndex:5882; }","duration":"217.286634ms","start":"2026-07-31T12:50:44.447274Z","end":"2026-07-31T12:50:44.66456Z","steps":["trace[1136428411] 'read index received' (duration: 217.253163ms)","trace[1136428411] 'applied index is now lower than readState.Index' (duration: 32.461µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:50:44.66465Z","caller":"traceutil/trace.go:171","msg":"trace[1364493468] transaction","detail":"{read_only:false; response_revision:5670; number_of_response:1; }","duration":"243.903834ms","start":"2026-07-31T12:50:44.420704Z","end":"2026-07-31T12:50:44.664608Z","steps":["trace[1364493468] 'process raft request' (duration: 243.71948ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:44.664951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.643853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-07-31T12:50:44.665059Z","caller":"traceutil/trace.go:171","msg":"trace[309828101] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5670; }","duration":"217.820277ms","start":"2026-07-31T12:50:44.447214Z","end":"2026-07-31T12:50:44.665034Z","steps":["trace[309828101] 'agreement among raft nodes before linearized reading' (duration: 217.456178ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:45.285092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.936931ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T12:50:45.285307Z","caller":"traceutil/trace.go:171","msg":"trace[601907790] transaction","detail":"{read_only:false; response_revision:5671; number_of_response:1; }","duration":"806.644204ms","start":"2026-07-31T12:50:44.478625Z","end":"2026-07-31T12:50:45.285269Z","steps":["trace[601907790] 'process raft request' (duration: 435.426797ms)","trace[601907790] 'compare' (duration: 370.333866ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:50:45.285447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:50:44.478604Z","time spent":"806.772768ms","remote":"127.0.0.1:34608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:50:45.285613Z","caller":"traceutil/trace.go:171","msg":"trace[2136963905] transaction","detail":"{read_only:false; response_revision:5672; number_of_response:1; }","duration":"767.570151ms","start":"2026-07-31T12:50:44.517984Z","end":"2026-07-31T12:50:45.285555Z","steps":["trace[2136963905] 'process raft request' (duration: 767.230252ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:50:45.285672Z","caller":"traceutil/trace.go:171","msg":"trace[711988380] linearizableReadLoop","detail":"{readStateIndex:5885; appliedIndex:5883; }","duration":"620.930329ms","start":"2026-07-31T12:50:44.664697Z","end":"2026-07-31T12:50:45.285627Z","steps":["trace[711988380] 'read index received' (duration: 249.270501ms)","trace[711988380] 'applied index is now lower than readState.Index' (duration: 371.655668ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:50:45.285686Z","caller":"traceutil/trace.go:171","msg":"trace[378330290] transaction","detail":"{read_only:false; response_revision:5673; number_of_response:1; }","duration":"112.552366ms","start":"2026-07-31T12:50:45.173102Z","end":"2026-07-31T12:50:45.285654Z","steps":["trace[378330290] 'process raft request' (duration: 112.442393ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:50:45.285766Z","caller":"traceutil/trace.go:171","msg":"trace[1982456321] transaction","detail":"{read_only:false; response_revision:5674; number_of_response:1; }","duration":"112.610837ms","start":"2026-07-31T12:50:45.173129Z","end":"2026-07-31T12:50:45.28574Z","steps":["trace[1982456321] 'process raft request' (duration: 112.476724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:45.285881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:50:44.517963Z","time spent":"767.769275ms","remote":"127.0.0.1:34698","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-07-31T12:50:45.285946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.199765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cecf970a-30a5-44a2-8caf-4bf2e81f6d3a\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-31T12:50:45.286006Z","caller":"traceutil/trace.go:171","msg":"trace[1475590930] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cecf970a-30a5-44a2-8caf-4bf2e81f6d3a; range_end:; response_count:1; response_revision:5674; }","duration":"787.309408ms","start":"2026-07-31T12:50:44.498677Z","end":"2026-07-31T12:50:45.285986Z","steps":["trace[1475590930] 'agreement among raft nodes before linearized reading' (duration: 787.099403ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:45.285998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.15384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-47c08da0-0c1a-4675-bcf4-8e8da9d1df77\" ","response":"range_response_count:1 size:1275"} {"level":"warn","ts":"2026-07-31T12:50:45.286027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.844199ms","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-07-31T12:50:45.286049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:50:44.498655Z","time spent":"787.38483ms","remote":"127.0.0.1:34608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cecf970a-30a5-44a2-8caf-4bf2e81f6d3a\" "} {"level":"warn","ts":"2026-07-31T12:50:45.28606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.760695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:50:45.286086Z","caller":"traceutil/trace.go:171","msg":"trace[1188311680] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5674; }","duration":"601.786966ms","start":"2026-07-31T12:50:44.684291Z","end":"2026-07-31T12:50:45.286078Z","steps":["trace[1188311680] 'agreement among raft nodes before linearized reading' (duration: 601.750965ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:50:45.28608Z","caller":"traceutil/trace.go:171","msg":"trace[1459210949] range","detail":"{range_begin:/registry/persistentvolumes/pvc-47c08da0-0c1a-4675-bcf4-8e8da9d1df77; range_end:; response_count:1; response_revision:5674; }","duration":"617.286023ms","start":"2026-07-31T12:50:44.668772Z","end":"2026-07-31T12:50:45.286058Z","steps":["trace[1459210949] 'agreement among raft nodes before linearized reading' (duration: 617.049558ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:45.286103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:50:44.684236Z","time spent":"601.863287ms","remote":"127.0.0.1:55100","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-07-31T12:50:45.286084Z","caller":"traceutil/trace.go:171","msg":"trace[828174823] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5674; }","duration":"106.944032ms","start":"2026-07-31T12:50:45.179125Z","end":"2026-07-31T12:50:45.286069Z","steps":["trace[828174823] 'agreement among raft nodes before linearized reading' (duration: 106.739527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:45.28613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:50:44.668751Z","time spent":"617.371066ms","remote":"127.0.0.1:34562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1298,"request content":"key:\"/registry/persistentvolumes/pvc-47c08da0-0c1a-4675-bcf4-8e8da9d1df77\" "} {"level":"warn","ts":"2026-07-31T12:50:45.741161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.995546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T12:50:45.741505Z","caller":"traceutil/trace.go:171","msg":"trace[1554534290] transaction","detail":"{read_only:false; response_revision:5677; number_of_response:1; }","duration":"447.997535ms","start":"2026-07-31T12:50:45.293472Z","end":"2026-07-31T12:50:45.741469Z","steps":["trace[1554534290] 'process raft request' (duration: 447.78964ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:50:45.741637Z","caller":"traceutil/trace.go:171","msg":"trace[675433708] linearizableReadLoop","detail":"{readStateIndex:5894; appliedIndex:5887; }","duration":"447.877533ms","start":"2026-07-31T12:50:45.293741Z","end":"2026-07-31T12:50:45.741618Z","steps":["trace[675433708] 'read index received' (duration: 45.045708ms)","trace[675433708] 'applied index is now lower than readState.Index' (duration: 402.830705ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:50:45.741729Z","caller":"traceutil/trace.go:171","msg":"trace[1767253551] transaction","detail":"{read_only:false; response_revision:5678; number_of_response:1; }","duration":"448.183059ms","start":"2026-07-31T12:50:45.293536Z","end":"2026-07-31T12:50:45.741719Z","steps":["trace[1767253551] 'process raft request' (duration: 447.78712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:45.741723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:50:45.293452Z","time spent":"448.151638ms","remote":"127.0.0.1:34710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1394,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:50:45.741793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:50:45.293518Z","time spent":"448.243861ms","remote":"127.0.0.1:34710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1463,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-31T12:50:45.741899Z","caller":"traceutil/trace.go:171","msg":"trace[927692437] transaction","detail":"{read_only:false; response_revision:5682; number_of_response:1; }","duration":"447.733738ms","start":"2026-07-31T12:50:45.29412Z","end":"2026-07-31T12:50:45.741853Z","steps":["trace[927692437] 'process raft request' (duration: 447.355349ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:50:45.74192Z","caller":"traceutil/trace.go:171","msg":"trace[1422085472] transaction","detail":"{read_only:false; response_revision:5676; number_of_response:1; }","duration":"448.468986ms","start":"2026-07-31T12:50:45.293433Z","end":"2026-07-31T12:50:45.741902Z","steps":["trace[1422085472] 'process raft request' (duration: 97.636352ms)","trace[1422085472] 'compare' (duration: 349.868402ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:50:45.741921Z","caller":"traceutil/trace.go:171","msg":"trace[1027763884] transaction","detail":"{read_only:false; response_revision:5685; number_of_response:1; }","duration":"446.754525ms","start":"2026-07-31T12:50:45.295128Z","end":"2026-07-31T12:50:45.741883Z","steps":["trace[1027763884] 'process raft request' (duration: 446.448158ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:50:45.742009Z","caller":"traceutil/trace.go:171","msg":"trace[1237873422] transaction","detail":"{read_only:false; response_revision:5679; number_of_response:1; }","duration":"448.417196ms","start":"2026-07-31T12:50:45.293578Z","end":"2026-07-31T12:50:45.741995Z","steps":["trace[1237873422] 'process raft request' (duration: 447.77918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:45.742022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:50:45.293416Z","time spent":"448.567439ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:50:45.742068Z","caller":"traceutil/trace.go:171","msg":"trace[1389378325] transaction","detail":"{read_only:false; response_revision:5681; number_of_response:1; }","duration":"448.386275ms","start":"2026-07-31T12:50:45.293669Z","end":"2026-07-31T12:50:45.742056Z","steps":["trace[1389378325] 'process raft request' (duration: 447.76823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:45.742105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.357235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cecf970a-30a5-44a2-8caf-4bf2e81f6d3a\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-07-31T12:50:45.742131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:50:45.293664Z","time spent":"448.434766ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:50:45.742128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:50:45.293571Z","time spent":"448.496018ms","remote":"127.0.0.1:34582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1082,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:50:45.742154Z","caller":"traceutil/trace.go:171","msg":"trace[77833419] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cecf970a-30a5-44a2-8caf-4bf2e81f6d3a; range_end:; response_count:1; response_revision:5685; }","duration":"448.420546ms","start":"2026-07-31T12:50:45.293722Z","end":"2026-07-31T12:50:45.742142Z","steps":["trace[77833419] 'agreement among raft nodes before linearized reading' (duration: 448.280423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:45.742193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:50:45.293716Z","time spent":"448.470367ms","remote":"127.0.0.1:34608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cecf970a-30a5-44a2-8caf-4bf2e81f6d3a\" "} {"level":"info","ts":"2026-07-31T12:50:45.742196Z","caller":"traceutil/trace.go:171","msg":"trace[1109912424] transaction","detail":"{read_only:false; response_revision:5684; number_of_response:1; }","duration":"447.105324ms","start":"2026-07-31T12:50:45.295079Z","end":"2026-07-31T12:50:45.742185Z","steps":["trace[1109912424] 'process raft request' (duration: 446.460488ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:45.742286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:50:45.295069Z","time spent":"447.175216ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:50:45.742156Z","caller":"traceutil/trace.go:171","msg":"trace[947438364] transaction","detail":"{read_only:false; response_revision:5683; number_of_response:1; }","duration":"447.994885ms","start":"2026-07-31T12:50:45.294146Z","end":"2026-07-31T12:50:45.742141Z","steps":["trace[947438364] 'process raft request' (duration: 447.361969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:45.742013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:50:45.294111Z","time spent":"447.844771ms","remote":"127.0.0.1:34582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":967,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:50:45.742388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.931993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-07-31T12:50:45.74246Z","caller":"traceutil/trace.go:171","msg":"trace[717506544] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5685; }","duration":"350.047046ms","start":"2026-07-31T12:50:45.3924Z","end":"2026-07-31T12:50:45.742447Z","steps":["trace[717506544] 'agreement among raft nodes before linearized reading' (duration: 349.872252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:45.742482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.810045ms","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-07-31T12:50:45.742501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:50:45.392367Z","time spent":"350.125928ms","remote":"127.0.0.1:34576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1804,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"info","ts":"2026-07-31T12:50:45.742522Z","caller":"traceutil/trace.go:171","msg":"trace[1845380627] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:5685; }","duration":"105.887757ms","start":"2026-07-31T12:50:45.63662Z","end":"2026-07-31T12:50:45.742508Z","steps":["trace[1845380627] 'agreement among raft nodes before linearized reading' (duration: 105.825295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:45.7421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:50:45.29511Z","time spent":"446.89178ms","remote":"127.0.0.1:34698","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-07-31T12:50:45.74239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:50:45.294133Z","time spent":"448.217721ms","remote":"127.0.0.1:34582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:50:45.7426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.142126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-07-31T12:50:45.742649Z","caller":"traceutil/trace.go:171","msg":"trace[240514633] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5685; }","duration":"194.230889ms","start":"2026-07-31T12:50:45.548402Z","end":"2026-07-31T12:50:45.742633Z","steps":["trace[240514633] 'agreement among raft nodes before linearized reading' (duration: 194.068155ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:50:45.74194Z","caller":"traceutil/trace.go:171","msg":"trace[2092001116] transaction","detail":"{read_only:false; response_revision:5680; number_of_response:1; }","duration":"448.276001ms","start":"2026-07-31T12:50:45.293629Z","end":"2026-07-31T12:50:45.741905Z","steps":["trace[2092001116] 'process raft request' (duration: 447.77611ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:45.742817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:50:45.293619Z","time spent":"449.122973ms","remote":"127.0.0.1:34710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1498,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:50:47.602827Z","caller":"traceutil/trace.go:171","msg":"trace[2137638718] transaction","detail":"{read_only:false; response_revision:5727; number_of_response:1; }","duration":"157.908979ms","start":"2026-07-31T12:50:47.444888Z","end":"2026-07-31T12:50:47.602797Z","steps":["trace[2137638718] 'process raft request' (duration: 156.68564ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:50:56.579984Z","caller":"traceutil/trace.go:171","msg":"trace[49023825] transaction","detail":"{read_only:false; response_revision:5820; number_of_response:1; }","duration":"108.504828ms","start":"2026-07-31T12:50:56.471447Z","end":"2026-07-31T12:50:56.579952Z","steps":["trace[49023825] 'process raft request' (duration: 107.916974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:57.234182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.962191ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-31T12:50:57.45016Z","caller":"traceutil/trace.go:171","msg":"trace[1458704031] linearizableReadLoop","detail":"{readStateIndex:6047; appliedIndex:6044; }","duration":"105.858936ms","start":"2026-07-31T12:50:57.344269Z","end":"2026-07-31T12:50:57.450128Z","steps":["trace[1458704031] 'read index received' (duration: 41.172924ms)","trace[1458704031] 'applied index is now lower than readState.Index' (duration: 64.685202ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:50:57.450241Z","caller":"traceutil/trace.go:171","msg":"trace[50147678] transaction","detail":"{read_only:false; response_revision:5829; number_of_response:1; }","duration":"115.32968ms","start":"2026-07-31T12:50:57.334883Z","end":"2026-07-31T12:50:57.450213Z","steps":["trace[50147678] 'process raft request' (duration: 110.860143ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:50:57.450298Z","caller":"traceutil/trace.go:171","msg":"trace[1652979220] transaction","detail":"{read_only:false; response_revision:5830; number_of_response:1; }","duration":"113.359253ms","start":"2026-07-31T12:50:57.336899Z","end":"2026-07-31T12:50:57.450259Z","steps":["trace[1652979220] 'process raft request' (duration: 113.180099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:50:57.45047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.177513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-n9tc2\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-07-31T12:50:57.45057Z","caller":"traceutil/trace.go:171","msg":"trace[205038181] range","detail":"{range_begin:/registry/pods/openstack/keepalived-n9tc2; range_end:; response_count:1; response_revision:5830; }","duration":"106.315946ms","start":"2026-07-31T12:50:57.344233Z","end":"2026-07-31T12:50:57.450549Z","steps":["trace[205038181] 'agreement among raft nodes before linearized reading' (duration: 106.03781ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:50:58.501786Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2925} {"level":"info","ts":"2026-07-31T12:50:58.586401Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2925,"took":"83.260951ms","hash":1257355919,"current-db-size-bytes":57634816,"current-db-size":"58 MB","current-db-size-in-use-bytes":32137216,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-31T12:50:58.586454Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1257355919,"revision":2925,"compact-revision":-1} {"level":"info","ts":"2026-07-31T12:51:36.195015Z","caller":"traceutil/trace.go:171","msg":"trace[1050703058] transaction","detail":"{read_only:false; response_revision:6170; number_of_response:1; }","duration":"115.744782ms","start":"2026-07-31T12:51:36.079241Z","end":"2026-07-31T12:51:36.194986Z","steps":["trace[1050703058] 'process raft request' (duration: 115.405643ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:55:58.508281Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5837} {"level":"info","ts":"2026-07-31T12:55:58.586607Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5837,"took":"76.69839ms","hash":226370111,"current-db-size-bytes":57634816,"current-db-size":"58 MB","current-db-size-in-use-bytes":25923584,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-31T12:55:58.586717Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":226370111,"revision":5837,"compact-revision":2925} {"level":"info","ts":"2026-07-31T12:57:10.95634Z","caller":"traceutil/trace.go:171","msg":"trace[614543648] transaction","detail":"{read_only:false; response_revision:8609; number_of_response:1; }","duration":"152.038414ms","start":"2026-07-31T12:57:10.804253Z","end":"2026-07-31T12:57:10.956291Z","steps":["trace[614543648] 'process raft request' (duration: 89.861086ms)","trace[614543648] 'compare' (duration: 62.058165ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:57:10.994175Z","caller":"traceutil/trace.go:171","msg":"trace[47292901] transaction","detail":"{read_only:false; response_revision:8610; number_of_response:1; }","duration":"183.588574ms","start":"2026-07-31T12:57:10.810566Z","end":"2026-07-31T12:57:10.994155Z","steps":["trace[47292901] 'process raft request' (duration: 183.454201ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:57:12.143391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.755758ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T12:57:12.143503Z","caller":"traceutil/trace.go:171","msg":"trace[664832460] linearizableReadLoop","detail":"{readStateIndex:8918; appliedIndex:8917; }","duration":"232.338137ms","start":"2026-07-31T12:57:11.911146Z","end":"2026-07-31T12:57:12.143484Z","steps":["trace[664832460] 'read index received' (duration: 61.592µs)","trace[664832460] 'applied index is now lower than readState.Index' (duration: 232.274735ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:57:12.143545Z","caller":"traceutil/trace.go:171","msg":"trace[905074658] transaction","detail":"{read_only:false; response_revision:8613; number_of_response:1; }","duration":"367.002472ms","start":"2026-07-31T12:57:11.77651Z","end":"2026-07-31T12:57:12.143513Z","steps":["trace[905074658] 'process raft request' (duration: 81.034569ms)","trace[905074658] 'compare' (duration: 285.547694ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:57:12.143693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:57:11.776484Z","time spent":"367.136426ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T12:57:12.143708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.578262ms","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-07-31T12:57:12.143742Z","caller":"traceutil/trace.go:171","msg":"trace[1937786458] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8613; }","duration":"232.650043ms","start":"2026-07-31T12:57:11.911082Z","end":"2026-07-31T12:57:12.143733Z","steps":["trace[1937786458] 'agreement among raft nodes before linearized reading' (duration: 232.49021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:57:12.157074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.737878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-31T12:57:12.157127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.890005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.177\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-31T12:57:12.157201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.950753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-31T12:57:12.157203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.108016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-31T12:57:12.157237Z","caller":"traceutil/trace.go:171","msg":"trace[445902871] range","detail":"{range_begin:/registry/masterleases/199.19.213.177; range_end:; response_count:1; response_revision:8614; }","duration":"136.026038ms","start":"2026-07-31T12:57:12.021184Z","end":"2026-07-31T12:57:12.15721Z","steps":["trace[445902871] 'agreement among raft nodes before linearized reading' (duration: 135.764482ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:57:12.157279Z","caller":"traceutil/trace.go:171","msg":"trace[529815704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8614; }","duration":"164.086166ms","start":"2026-07-31T12:57:11.993174Z","end":"2026-07-31T12:57:12.157261Z","steps":["trace[529815704] 'agreement among raft nodes before linearized reading' (duration: 163.867391ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:57:12.157283Z","caller":"traceutil/trace.go:171","msg":"trace[1971151389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8614; }","duration":"164.189138ms","start":"2026-07-31T12:57:11.99308Z","end":"2026-07-31T12:57:12.157269Z","steps":["trace[1971151389] 'agreement among raft nodes before linearized reading' (duration: 163.992483ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:57:12.15716Z","caller":"traceutil/trace.go:171","msg":"trace[703375141] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8614; }","duration":"169.871211ms","start":"2026-07-31T12:57:11.987268Z","end":"2026-07-31T12:57:12.157139Z","steps":["trace[703375141] 'agreement among raft nodes before linearized reading' (duration: 169.652956ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:57:13.208021Z","caller":"traceutil/trace.go:171","msg":"trace[1000703816] transaction","detail":"{read_only:false; response_revision:8622; number_of_response:1; }","duration":"246.820276ms","start":"2026-07-31T12:57:12.961173Z","end":"2026-07-31T12:57:13.207993Z","steps":["trace[1000703816] 'process raft request' (duration: 246.614481ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:57:13.300586Z","caller":"traceutil/trace.go:171","msg":"trace[324165009] transaction","detail":"{read_only:false; response_revision:8623; number_of_response:1; }","duration":"301.573118ms","start":"2026-07-31T12:57:12.998983Z","end":"2026-07-31T12:57:13.300556Z","steps":["trace[324165009] 'process raft request' (duration: 273.272195ms)","trace[324165009] 'compare' (duration: 28.17094ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:57:13.300749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T12:57:12.99896Z","time spent":"301.700401ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T12:57:25.267478Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002328.wal"} {"level":"info","ts":"2026-07-31T12:57:40.521529Z","caller":"traceutil/trace.go:171","msg":"trace[886618138] transaction","detail":"{read_only:false; response_revision:8819; number_of_response:1; }","duration":"271.806531ms","start":"2026-07-31T12:57:40.24968Z","end":"2026-07-31T12:57:40.521487Z","steps":["trace[886618138] 'process raft request' (duration: 271.518025ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:57:40.52195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.357569ms","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-07-31T12:57:40.521993Z","caller":"traceutil/trace.go:171","msg":"trace[1035442189] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8819; }","duration":"224.447291ms","start":"2026-07-31T12:57:40.297534Z","end":"2026-07-31T12:57:40.521981Z","steps":["trace[1035442189] 'agreement among raft nodes before linearized reading' (duration: 224.270467ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:57:40.521996Z","caller":"traceutil/trace.go:171","msg":"trace[1514422106] linearizableReadLoop","detail":"{readStateIndex:9132; appliedIndex:9131; }","duration":"223.821606ms","start":"2026-07-31T12:57:40.297571Z","end":"2026-07-31T12:57:40.521393Z","steps":["trace[1514422106] 'read index received' (duration: 223.490758ms)","trace[1514422106] 'applied index is now lower than readState.Index' (duration: 329.888µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:57:40.522358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.203227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-31T12:57:40.522398Z","caller":"traceutil/trace.go:171","msg":"trace[2102652875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8819; }","duration":"166.278498ms","start":"2026-07-31T12:57:40.356107Z","end":"2026-07-31T12:57:40.522385Z","steps":["trace[2102652875] 'agreement among raft nodes before linearized reading' (duration: 166.108014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:57:40.522394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.472241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-31T12:57:40.522526Z","caller":"traceutil/trace.go:171","msg":"trace[1199849897] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8819; }","duration":"177.612754ms","start":"2026-07-31T12:57:40.344871Z","end":"2026-07-31T12:57:40.522484Z","steps":["trace[1199849897] 'agreement among raft nodes before linearized reading' (duration: 177.275636ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:57:40.522361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.793876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-31T12:57:40.522809Z","caller":"traceutil/trace.go:171","msg":"trace[908852502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8819; }","duration":"163.261696ms","start":"2026-07-31T12:57:40.359523Z","end":"2026-07-31T12:57:40.522784Z","steps":["trace[908852502] 'agreement among raft nodes before linearized reading' (duration: 162.676943ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:57:40.79101Z","caller":"traceutil/trace.go:171","msg":"trace[1188151220] transaction","detail":"{read_only:false; response_revision:8820; number_of_response:1; }","duration":"264.860959ms","start":"2026-07-31T12:57:40.526128Z","end":"2026-07-31T12:57:40.790989Z","steps":["trace[1188151220] 'process raft request' (duration: 245.838753ms)","trace[1188151220] 'compare' (duration: 18.946193ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:57:40.795546Z","caller":"traceutil/trace.go:171","msg":"trace[2101692533] transaction","detail":"{read_only:false; response_revision:8822; number_of_response:1; }","duration":"268.372531ms","start":"2026-07-31T12:57:40.527155Z","end":"2026-07-31T12:57:40.795527Z","steps":["trace[2101692533] 'process raft request' (duration: 268.32397ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:57:40.7956Z","caller":"traceutil/trace.go:171","msg":"trace[258902510] transaction","detail":"{read_only:false; response_revision:8821; number_of_response:1; }","duration":"269.20809ms","start":"2026-07-31T12:57:40.526358Z","end":"2026-07-31T12:57:40.795566Z","steps":["trace[258902510] 'process raft request' (duration: 269.041556ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:57:40.795685Z","caller":"traceutil/trace.go:171","msg":"trace[253468006] linearizableReadLoop","detail":"{readStateIndex:9136; appliedIndex:9132; }","duration":"110.640723ms","start":"2026-07-31T12:57:40.685026Z","end":"2026-07-31T12:57:40.795667Z","steps":["trace[253468006] 'read index received' (duration: 86.952158ms)","trace[253468006] 'applied index is now lower than readState.Index' (duration: 23.687245ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:57:40.795742Z","caller":"traceutil/trace.go:171","msg":"trace[673182850] transaction","detail":"{read_only:false; response_revision:8823; number_of_response:1; }","duration":"268.276279ms","start":"2026-07-31T12:57:40.52743Z","end":"2026-07-31T12:57:40.795706Z","steps":["trace[673182850] 'process raft request' (duration: 268.071174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:57:40.795788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.747115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:57:40.795922Z","caller":"traceutil/trace.go:171","msg":"trace[1758152090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8823; }","duration":"110.912309ms","start":"2026-07-31T12:57:40.684984Z","end":"2026-07-31T12:57:40.795896Z","steps":["trace[1758152090] 'agreement among raft nodes before linearized reading' (duration: 110.729475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:57:43.98797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.922485ms","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-07-31T12:57:43.988119Z","caller":"traceutil/trace.go:171","msg":"trace[45593138] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8840; }","duration":"101.088619ms","start":"2026-07-31T12:57:43.88701Z","end":"2026-07-31T12:57:43.988099Z","steps":["trace[45593138] 'range keys from in-memory index tree' (duration: 100.901235ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:57:52.977961Z","caller":"traceutil/trace.go:171","msg":"trace[1661419223] linearizableReadLoop","detail":"{readStateIndex:9243; appliedIndex:9242; }","duration":"133.309826ms","start":"2026-07-31T12:57:52.844627Z","end":"2026-07-31T12:57:52.977937Z","steps":["trace[1661419223] 'read index received' (duration: 133.176932ms)","trace[1661419223] 'applied index is now lower than readState.Index' (duration: 132.093µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:57:52.978106Z","caller":"traceutil/trace.go:171","msg":"trace[287618889] transaction","detail":"{read_only:false; response_revision:8925; number_of_response:1; }","duration":"136.827687ms","start":"2026-07-31T12:57:52.841226Z","end":"2026-07-31T12:57:52.978054Z","steps":["trace[287618889] 'process raft request' (duration: 136.546291ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:57:52.978213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.561851ms","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-07-31T12:57:52.978251Z","caller":"traceutil/trace.go:171","msg":"trace[257933635] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8925; }","duration":"133.642993ms","start":"2026-07-31T12:57:52.844598Z","end":"2026-07-31T12:57:52.978241Z","steps":["trace[257933635] 'agreement among raft nodes before linearized reading' (duration: 133.450648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:57:52.981142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.13977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-31T12:57:52.981227Z","caller":"traceutil/trace.go:171","msg":"trace[2104438558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8927; }","duration":"124.250062ms","start":"2026-07-31T12:57:52.856958Z","end":"2026-07-31T12:57:52.981208Z","steps":["trace[2104438558] 'agreement among raft nodes before linearized reading' (duration: 124.021617ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:57:52.981646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.472357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-31T12:57:52.981701Z","caller":"traceutil/trace.go:171","msg":"trace[422609260] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8927; }","duration":"124.59944ms","start":"2026-07-31T12:57:52.857091Z","end":"2026-07-31T12:57:52.98169Z","steps":["trace[422609260] 'agreement among raft nodes before linearized reading' (duration: 124.479627ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:57:53.220919Z","caller":"traceutil/trace.go:171","msg":"trace[115232931] transaction","detail":"{read_only:false; response_revision:8929; number_of_response:1; }","duration":"236.403162ms","start":"2026-07-31T12:57:52.984496Z","end":"2026-07-31T12:57:53.220899Z","steps":["trace[115232931] 'process raft request' (duration: 236.195667ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:57:53.220988Z","caller":"traceutil/trace.go:171","msg":"trace[1528692902] transaction","detail":"{read_only:false; response_revision:8930; number_of_response:1; }","duration":"234.807524ms","start":"2026-07-31T12:57:52.986112Z","end":"2026-07-31T12:57:53.22092Z","steps":["trace[1528692902] 'process raft request' (duration: 234.707502ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:57:53.22135Z","caller":"traceutil/trace.go:171","msg":"trace[1223532444] linearizableReadLoop","detail":"{readStateIndex:9249; appliedIndex:9246; }","duration":"164.048466ms","start":"2026-07-31T12:57:53.057285Z","end":"2026-07-31T12:57:53.221333Z","steps":["trace[1223532444] 'read index received' (duration: 163.445202ms)","trace[1223532444] 'applied index is now lower than readState.Index' (duration: 602.024µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:57:53.221446Z","caller":"traceutil/trace.go:171","msg":"trace[1274670028] transaction","detail":"{read_only:false; response_revision:8931; number_of_response:1; }","duration":"233.168695ms","start":"2026-07-31T12:57:52.988257Z","end":"2026-07-31T12:57:53.221426Z","steps":["trace[1274670028] 'process raft request' (duration: 232.590031ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T12:57:53.221496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.173538ms","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-07-31T12:57:53.221538Z","caller":"traceutil/trace.go:171","msg":"trace[1345271643] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:8931; }","duration":"164.24936ms","start":"2026-07-31T12:57:53.057279Z","end":"2026-07-31T12:57:53.221528Z","steps":["trace[1345271643] 'agreement among raft nodes before linearized reading' (duration: 164.115667ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:57:54.416964Z","caller":"traceutil/trace.go:171","msg":"trace[786933616] transaction","detail":"{read_only:false; response_revision:8936; number_of_response:1; }","duration":"214.440787ms","start":"2026-07-31T12:57:54.202488Z","end":"2026-07-31T12:57:54.416929Z","steps":["trace[786933616] 'process raft request' (duration: 214.17287ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:57:54.473723Z","caller":"traceutil/trace.go:171","msg":"trace[436823142] transaction","detail":"{read_only:false; response_revision:8937; number_of_response:1; }","duration":"149.622468ms","start":"2026-07-31T12:57:54.324059Z","end":"2026-07-31T12:57:54.473681Z","steps":["trace[436823142] 'process raft request' (duration: 149.454764ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T12:59:07.17617Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f577bc5ae95d61e1","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-31T12:59:07.182114Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-31T12:59:07.182353Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-31T12:59:11.788735Z","caller":"traceutil/trace.go:171","msg":"trace[1798120396] transaction","detail":"{read_only:false; response_revision:9688; number_of_response:1; }","duration":"142.702495ms","start":"2026-07-31T12:59:11.645988Z","end":"2026-07-31T12:59:11.788691Z","steps":["trace[1798120396] 'process raft request' (duration: 111.000609ms)","trace[1798120396] 'compare' (duration: 31.480531ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T12:59:11.78884Z","caller":"traceutil/trace.go:171","msg":"trace[596680720] linearizableReadLoop","detail":"{readStateIndex:10027; appliedIndex:10025; }","duration":"103.34422ms","start":"2026-07-31T12:59:11.685469Z","end":"2026-07-31T12:59:11.788813Z","steps":["trace[596680720] 'read index received' (duration: 13.501258ms)","trace[596680720] 'applied index is now lower than readState.Index' (duration: 89.841372ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T12:59:11.788948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.458543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T12:59:11.788977Z","caller":"traceutil/trace.go:171","msg":"trace[1862025103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9689; }","duration":"103.534805ms","start":"2026-07-31T12:59:11.685433Z","end":"2026-07-31T12:59:11.788968Z","steps":["trace[1862025103] 'agreement among raft nodes before linearized reading' (duration: 103.465943ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:00:58.515506Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8030} {"level":"info","ts":"2026-07-31T13:00:58.583318Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8030,"took":"65.558626ms","hash":1407255157,"current-db-size-bytes":57634816,"current-db-size":"58 MB","current-db-size-in-use-bytes":28745728,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-31T13:00:58.583403Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1407255157,"revision":8030,"compact-revision":5837} {"level":"info","ts":"2026-07-31T13:03:23.363024Z","caller":"traceutil/trace.go:171","msg":"trace[268216312] transaction","detail":"{read_only:false; response_revision:11317; number_of_response:1; }","duration":"131.949613ms","start":"2026-07-31T13:03:23.231049Z","end":"2026-07-31T13:03:23.362998Z","steps":["trace[268216312] 'process raft request' (duration: 131.761998ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:04:45.556998Z","caller":"traceutil/trace.go:171","msg":"trace[2101122667] transaction","detail":"{read_only:false; response_revision:12095; number_of_response:1; }","duration":"119.993134ms","start":"2026-07-31T13:04:45.436979Z","end":"2026-07-31T13:04:45.556972Z","steps":["trace[2101122667] 'process raft request' (duration: 119.84584ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:04:46.0528Z","caller":"traceutil/trace.go:171","msg":"trace[1837793578] linearizableReadLoop","detail":"{readStateIndex:12511; appliedIndex:12510; }","duration":"147.99046ms","start":"2026-07-31T13:04:45.904788Z","end":"2026-07-31T13:04:46.052778Z","steps":["trace[1837793578] 'read index received' (duration: 147.651393ms)","trace[1837793578] 'applied index is now lower than readState.Index' (duration: 337.867µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T13:04:46.052893Z","caller":"traceutil/trace.go:171","msg":"trace[1313498505] transaction","detail":"{read_only:false; response_revision:12096; number_of_response:1; }","duration":"155.415594ms","start":"2026-07-31T13:04:45.897441Z","end":"2026-07-31T13:04:46.052857Z","steps":["trace[1313498505] 'process raft request' (duration: 154.975774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:04:46.053022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.216986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-31T13:04:46.053114Z","caller":"traceutil/trace.go:171","msg":"trace[1516001456] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12096; }","duration":"148.343599ms","start":"2026-07-31T13:04:45.904749Z","end":"2026-07-31T13:04:46.053092Z","steps":["trace[1516001456] 'agreement among raft nodes before linearized reading' (duration: 148.116593ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:04:46.220058Z","caller":"traceutil/trace.go:171","msg":"trace[1939635152] linearizableReadLoop","detail":"{readStateIndex:12514; appliedIndex:12512; }","duration":"121.282014ms","start":"2026-07-31T13:04:46.098745Z","end":"2026-07-31T13:04:46.220027Z","steps":["trace[1939635152] 'read index received' (duration: 1.875313ms)","trace[1939635152] 'applied index is now lower than readState.Index' (duration: 119.405611ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T13:04:46.220117Z","caller":"traceutil/trace.go:171","msg":"trace[1504703564] transaction","detail":"{read_only:false; response_revision:12099; number_of_response:1; }","duration":"136.203534ms","start":"2026-07-31T13:04:46.083884Z","end":"2026-07-31T13:04:46.220088Z","steps":["trace[1504703564] 'process raft request' (duration: 118.278424ms)","trace[1504703564] 'compare' (duration: 17.730936ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T13:04:46.220293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.527939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-07-31T13:04:46.220383Z","caller":"traceutil/trace.go:171","msg":"trace[1362137472] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:12099; }","duration":"121.636222ms","start":"2026-07-31T13:04:46.098728Z","end":"2026-07-31T13:04:46.220364Z","steps":["trace[1362137472] 'agreement among raft nodes before linearized reading' (duration: 121.390516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:04:46.220443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.163664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/cinder-backup-85c65c57c8-4tmfc.18c7619159321edf\" ","response":"range_response_count:1 size:804"} {"level":"info","ts":"2026-07-31T13:04:46.220563Z","caller":"traceutil/trace.go:171","msg":"trace[1616328680] range","detail":"{range_begin:/registry/events/openstack/cinder-backup-85c65c57c8-4tmfc.18c7619159321edf; range_end:; response_count:1; response_revision:12099; }","duration":"116.326408ms","start":"2026-07-31T13:04:46.104206Z","end":"2026-07-31T13:04:46.220533Z","steps":["trace[1616328680] 'agreement among raft nodes before linearized reading' (duration: 116.00046ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:04:46.616329Z","caller":"traceutil/trace.go:171","msg":"trace[1083482881] transaction","detail":"{read_only:false; response_revision:12101; number_of_response:1; }","duration":"137.32948ms","start":"2026-07-31T13:04:46.47895Z","end":"2026-07-31T13:04:46.616279Z","steps":["trace[1083482881] 'process raft request' (duration: 137.146666ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:04:47.094039Z","caller":"traceutil/trace.go:171","msg":"trace[656767340] linearizableReadLoop","detail":"{readStateIndex:12517; appliedIndex:12516; }","duration":"152.200649ms","start":"2026-07-31T13:04:46.941814Z","end":"2026-07-31T13:04:47.094014Z","steps":["trace[656767340] 'read index received' (duration: 152.097727ms)","trace[656767340] 'applied index is now lower than readState.Index' (duration: 101.392µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T13:04:47.094107Z","caller":"traceutil/trace.go:171","msg":"trace[238036004] transaction","detail":"{read_only:false; response_revision:12102; number_of_response:1; }","duration":"230.067415ms","start":"2026-07-31T13:04:46.864003Z","end":"2026-07-31T13:04:47.094071Z","steps":["trace[238036004] 'process raft request' (duration: 229.854641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:04:47.094199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.356453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T13:04:47.094314Z","caller":"traceutil/trace.go:171","msg":"trace[125291718] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12102; }","duration":"152.524966ms","start":"2026-07-31T13:04:46.941766Z","end":"2026-07-31T13:04:47.094291Z","steps":["trace[125291718] 'agreement among raft nodes before linearized reading' (duration: 152.349182ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:04:47.110967Z","caller":"traceutil/trace.go:171","msg":"trace[1604633542] transaction","detail":"{read_only:false; response_revision:12103; number_of_response:1; }","duration":"167.766954ms","start":"2026-07-31T13:04:46.943174Z","end":"2026-07-31T13:04:47.110941Z","steps":["trace[1604633542] 'process raft request' (duration: 167.535809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:04:47.125472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.508233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T13:04:47.125536Z","caller":"traceutil/trace.go:171","msg":"trace[36076013] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12103; }","duration":"183.605645ms","start":"2026-07-31T13:04:46.941915Z","end":"2026-07-31T13:04:47.125521Z","steps":["trace[36076013] 'agreement among raft nodes before linearized reading' (duration: 183.500822ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:04:47.12569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.736544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"warn","ts":"2026-07-31T13:04:47.125778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.900841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T13:04:47.125789Z","caller":"traceutil/trace.go:171","msg":"trace[1820650978] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12103; }","duration":"173.848877ms","start":"2026-07-31T13:04:46.951914Z","end":"2026-07-31T13:04:47.125763Z","steps":["trace[1820650978] 'agreement among raft nodes before linearized reading' (duration: 173.660613ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:04:47.125824Z","caller":"traceutil/trace.go:171","msg":"trace[44913435] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12103; }","duration":"183.950803ms","start":"2026-07-31T13:04:46.941856Z","end":"2026-07-31T13:04:47.125807Z","steps":["trace[44913435] 'agreement among raft nodes before linearized reading' (duration: 183.901512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:04:47.125722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.870971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T13:04:47.12587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.316266ms","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-07-31T13:04:47.125955Z","caller":"traceutil/trace.go:171","msg":"trace[1618453313] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:12103; }","duration":"130.468919ms","start":"2026-07-31T13:04:46.995464Z","end":"2026-07-31T13:04:47.125933Z","steps":["trace[1618453313] 'agreement among raft nodes before linearized reading' (duration: 130.116721ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:04:47.12598Z","caller":"traceutil/trace.go:171","msg":"trace[701130759] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12103; }","duration":"184.111716ms","start":"2026-07-31T13:04:46.94179Z","end":"2026-07-31T13:04:47.125901Z","steps":["trace[701130759] 'agreement among raft nodes before linearized reading' (duration: 183.876321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:04:47.126255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.840646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-31T13:04:47.126418Z","caller":"traceutil/trace.go:171","msg":"trace[432950811] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12103; }","duration":"122.562533ms","start":"2026-07-31T13:04:47.003829Z","end":"2026-07-31T13:04:47.126391Z","steps":["trace[432950811] 'agreement among raft nodes before linearized reading' (duration: 121.640801ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:05:58.521799Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10431} {"level":"info","ts":"2026-07-31T13:05:58.615708Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10431,"took":"91.765114ms","hash":1294324242,"current-db-size-bytes":57634816,"current-db-size":"58 MB","current-db-size-in-use-bytes":26169344,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-31T13:05:58.615956Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1294324242,"revision":10431,"compact-revision":8030} {"level":"warn","ts":"2026-07-31T13:10:51.158075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.459525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-31T13:10:51.158239Z","caller":"traceutil/trace.go:171","msg":"trace[1843602818] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14597; }","duration":"171.633069ms","start":"2026-07-31T13:10:50.986587Z","end":"2026-07-31T13:10:51.158221Z","steps":["trace[1843602818] 'range keys from in-memory index tree' (duration: 171.004205ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:10:53.991763Z","caller":"traceutil/trace.go:171","msg":"trace[774685997] transaction","detail":"{read_only:false; response_revision:14613; number_of_response:1; }","duration":"115.300693ms","start":"2026-07-31T13:10:53.876439Z","end":"2026-07-31T13:10:53.99174Z","steps":["trace[774685997] 'process raft request' (duration: 115.16233ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:10:58.533584Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12604} {"level":"info","ts":"2026-07-31T13:10:58.612116Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12604,"took":"75.891432ms","hash":2613461250,"current-db-size-bytes":57634816,"current-db-size":"58 MB","current-db-size-in-use-bytes":28041216,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-31T13:10:58.612475Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2613461250,"revision":12604,"compact-revision":10431} {"level":"warn","ts":"2026-07-31T13:11:27.979129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.403034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T13:11:27.979453Z","caller":"traceutil/trace.go:171","msg":"trace[10485191] transaction","detail":"{read_only:false; response_revision:14795; number_of_response:1; }","duration":"278.67061ms","start":"2026-07-31T13:11:27.700759Z","end":"2026-07-31T13:11:27.97943Z","steps":["trace[10485191] 'process raft request' (duration: 278.535297ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:11:27.979545Z","caller":"traceutil/trace.go:171","msg":"trace[524899415] linearizableReadLoop","detail":"{readStateIndex:15304; appliedIndex:15303; }","duration":"296.276242ms","start":"2026-07-31T13:11:27.683191Z","end":"2026-07-31T13:11:27.979467Z","steps":["trace[524899415] 'read index received' (duration: 142.408267ms)","trace[524899415] 'applied index is now lower than readState.Index' (duration: 153.864735ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T13:11:27.979646Z","caller":"traceutil/trace.go:171","msg":"trace[1720890129] transaction","detail":"{read_only:false; response_revision:14794; number_of_response:1; }","duration":"310.435862ms","start":"2026-07-31T13:11:27.669141Z","end":"2026-07-31T13:11:27.979577Z","steps":["trace[1720890129] 'process raft request' (duration: 156.447325ms)","trace[1720890129] 'compare' (duration: 153.158378ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T13:11:27.979666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.451315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T13:11:27.979769Z","caller":"traceutil/trace.go:171","msg":"trace[1709786133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14795; }","duration":"296.599789ms","start":"2026-07-31T13:11:27.683145Z","end":"2026-07-31T13:11:27.979745Z","steps":["trace[1709786133] 'agreement among raft nodes before linearized reading' (duration: 296.424905ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:11:27.97984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T13:11:27.669114Z","time spent":"310.616987ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T13:11:27.98002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.305721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-n9tc2\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-07-31T13:11:27.980147Z","caller":"traceutil/trace.go:171","msg":"trace[770769099] range","detail":"{range_begin:/registry/pods/openstack/keepalived-n9tc2; range_end:; response_count:1; response_revision:14795; }","duration":"117.470035ms","start":"2026-07-31T13:11:27.862663Z","end":"2026-07-31T13:11:27.980133Z","steps":["trace[770769099] 'agreement among raft nodes before linearized reading' (duration: 117.123897ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:11:44.942209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.170773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T13:11:44.94232Z","caller":"traceutil/trace.go:171","msg":"trace[520254349] linearizableReadLoop","detail":"{readStateIndex:15413; appliedIndex:15412; }","duration":"257.719321ms","start":"2026-07-31T13:11:44.684582Z","end":"2026-07-31T13:11:44.942301Z","steps":["trace[520254349] 'read index received' (duration: 32.341µs)","trace[520254349] 'applied index is now lower than readState.Index' (duration: 257.68545ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T13:11:44.942391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.815753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T13:11:44.942373Z","caller":"traceutil/trace.go:171","msg":"trace[356108427] transaction","detail":"{read_only:false; response_revision:14898; number_of_response:1; }","duration":"367.121986ms","start":"2026-07-31T13:11:44.575221Z","end":"2026-07-31T13:11:44.942343Z","steps":["trace[356108427] 'process raft request' (duration: 45.708777ms)","trace[356108427] 'compare' (duration: 320.931128ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T13:11:44.942429Z","caller":"traceutil/trace.go:171","msg":"trace[57257079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14898; }","duration":"257.880175ms","start":"2026-07-31T13:11:44.684539Z","end":"2026-07-31T13:11:44.942419Z","steps":["trace[57257079] 'agreement among raft nodes before linearized reading' (duration: 257.810383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:11:44.942504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T13:11:44.575201Z","time spent":"367.236049ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T13:12:06.233424Z","caller":"traceutil/trace.go:171","msg":"trace[1359457603] linearizableReadLoop","detail":"{readStateIndex:15727; appliedIndex:15726; }","duration":"196.657074ms","start":"2026-07-31T13:12:06.036738Z","end":"2026-07-31T13:12:06.233395Z","steps":["trace[1359457603] 'read index received' (duration: 196.524011ms)","trace[1359457603] 'applied index is now lower than readState.Index' (duration: 132.053µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T13:12:06.233564Z","caller":"traceutil/trace.go:171","msg":"trace[1702679562] transaction","detail":"{read_only:false; response_revision:15201; number_of_response:1; }","duration":"398.482819ms","start":"2026-07-31T13:12:05.835042Z","end":"2026-07-31T13:12:06.233525Z","steps":["trace[1702679562] 'process raft request' (duration: 398.173752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:12:06.233614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.850298ms","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":"warn","ts":"2026-07-31T13:12:06.233783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T13:12:05.835014Z","time spent":"398.665503ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T13:12:06.233822Z","caller":"traceutil/trace.go:171","msg":"trace[2021161684] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:15201; }","duration":"197.094885ms","start":"2026-07-31T13:12:06.036692Z","end":"2026-07-31T13:12:06.233787Z","steps":["trace[2021161684] 'agreement among raft nodes before linearized reading' (duration: 196.845768ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:12:06.270609Z","caller":"traceutil/trace.go:171","msg":"trace[1030006133] transaction","detail":"{read_only:false; response_revision:15202; number_of_response:1; }","duration":"158.116174ms","start":"2026-07-31T13:12:06.112467Z","end":"2026-07-31T13:12:06.270583Z","steps":["trace[1030006133] 'process raft request' (duration: 157.92282ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:12:06.270709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.240588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-31T13:12:06.270769Z","caller":"traceutil/trace.go:171","msg":"trace[1272883333] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:15202; }","duration":"155.334489ms","start":"2026-07-31T13:12:06.115418Z","end":"2026-07-31T13:12:06.270752Z","steps":["trace[1272883333] 'agreement among raft nodes before linearized reading' (duration: 155.176096ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:12:06.270932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.406054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-31T13:12:06.27107Z","caller":"traceutil/trace.go:171","msg":"trace[1610729669] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15202; }","duration":"174.593958ms","start":"2026-07-31T13:12:06.096461Z","end":"2026-07-31T13:12:06.271055Z","steps":["trace[1610729669] 'agreement among raft nodes before linearized reading' (duration: 174.081026ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:12:06.428015Z","caller":"traceutil/trace.go:171","msg":"trace[1418240005] transaction","detail":"{read_only:false; response_revision:15205; number_of_response:1; }","duration":"104.744897ms","start":"2026-07-31T13:12:06.32323Z","end":"2026-07-31T13:12:06.427975Z","steps":["trace[1418240005] 'process raft request' (duration: 104.412819ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:12:57.584535Z","caller":"traceutil/trace.go:171","msg":"trace[1320084527] transaction","detail":"{read_only:false; response_revision:15830; number_of_response:1; }","duration":"167.152315ms","start":"2026-07-31T13:12:57.417342Z","end":"2026-07-31T13:12:57.584495Z","steps":["trace[1320084527] 'process raft request' (duration: 166.727976ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:12:57.584762Z","caller":"traceutil/trace.go:171","msg":"trace[2130560162] linearizableReadLoop","detail":"{readStateIndex:16372; appliedIndex:16371; }","duration":"165.921987ms","start":"2026-07-31T13:12:57.418807Z","end":"2026-07-31T13:12:57.584729Z","steps":["trace[2130560162] 'read index received' (duration: 165.050467ms)","trace[2130560162] 'applied index is now lower than readState.Index' (duration: 870.04µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T13:12:57.585051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.963654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-07-31T13:12:57.585056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.223493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-45jpf\" ","response":"range_response_count:1 size:22099"} {"level":"info","ts":"2026-07-31T13:12:57.585154Z","caller":"traceutil/trace.go:171","msg":"trace[743101854] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-45jpf; range_end:; response_count:1; response_revision:15830; }","duration":"166.334496ms","start":"2026-07-31T13:12:57.418795Z","end":"2026-07-31T13:12:57.58513Z","steps":["trace[743101854] 'agreement among raft nodes before linearized reading' (duration: 166.032259ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:12:57.585351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.845573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-45jpf\" ","response":"range_response_count:1 size:22099"} {"level":"info","ts":"2026-07-31T13:12:57.585411Z","caller":"traceutil/trace.go:171","msg":"trace[992146348] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-45jpf; range_end:; response_count:1; response_revision:15830; }","duration":"135.941765ms","start":"2026-07-31T13:12:57.449455Z","end":"2026-07-31T13:12:57.585397Z","steps":["trace[992146348] 'agreement among raft nodes before linearized reading' (duration: 135.74301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:12:57.585556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.361465ms","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-07-31T13:12:57.585582Z","caller":"traceutil/trace.go:171","msg":"trace[409188817] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:15830; }","duration":"118.444667ms","start":"2026-07-31T13:12:57.467129Z","end":"2026-07-31T13:12:57.585574Z","steps":["trace[409188817] 'agreement among raft nodes before linearized reading' (duration: 118.397936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:12:57.585625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.762624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-z7678\" ","response":"range_response_count:1 size:20643"} {"level":"info","ts":"2026-07-31T13:12:57.58568Z","caller":"traceutil/trace.go:171","msg":"trace[1569908953] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-z7678; range_end:; response_count:1; response_revision:15830; }","duration":"139.848707ms","start":"2026-07-31T13:12:57.44582Z","end":"2026-07-31T13:12:57.585669Z","steps":["trace[1569908953] 'agreement among raft nodes before linearized reading' (duration: 139.733544ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:12:57.585945Z","caller":"traceutil/trace.go:171","msg":"trace[1056340725] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15830; }","duration":"165.044036ms","start":"2026-07-31T13:12:57.420032Z","end":"2026-07-31T13:12:57.585076Z","steps":["trace[1056340725] 'agreement among raft nodes before linearized reading' (duration: 164.850931ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:13:31.605807Z","caller":"traceutil/trace.go:171","msg":"trace[2112145638] linearizableReadLoop","detail":"{readStateIndex:16556; appliedIndex:16555; }","duration":"216.621354ms","start":"2026-07-31T13:13:31.389155Z","end":"2026-07-31T13:13:31.605777Z","steps":["trace[2112145638] 'read index received' (duration: 216.433079ms)","trace[2112145638] 'applied index is now lower than readState.Index' (duration: 186.945µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T13:13:31.606105Z","caller":"traceutil/trace.go:171","msg":"trace[1533368419] transaction","detail":"{read_only:false; response_revision:16008; number_of_response:1; }","duration":"281.971327ms","start":"2026-07-31T13:13:31.323968Z","end":"2026-07-31T13:13:31.60594Z","steps":["trace[1533368419] 'process raft request' (duration: 281.64043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:13:31.606387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.213697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T13:13:31.606646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.317029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T13:13:31.606693Z","caller":"traceutil/trace.go:171","msg":"trace[199561510] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:16008; }","duration":"217.38599ms","start":"2026-07-31T13:13:31.389296Z","end":"2026-07-31T13:13:31.606682Z","steps":["trace[199561510] 'agreement among raft nodes before linearized reading' (duration: 217.311529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:13:31.607105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.07599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-07-31T13:13:31.60716Z","caller":"traceutil/trace.go:171","msg":"trace[607909596] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16008; }","duration":"210.153621ms","start":"2026-07-31T13:13:31.396996Z","end":"2026-07-31T13:13:31.60715Z","steps":["trace[607909596] 'agreement among raft nodes before linearized reading' (duration: 210.003298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:13:31.607536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.53716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-31T13:13:31.606433Z","caller":"traceutil/trace.go:171","msg":"trace[870129322] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16008; }","duration":"217.309009ms","start":"2026-07-31T13:13:31.389112Z","end":"2026-07-31T13:13:31.606421Z","steps":["trace[870129322] 'agreement among raft nodes before linearized reading' (duration: 217.225277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:13:31.608409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.348993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-07-31T13:13:31.608455Z","caller":"traceutil/trace.go:171","msg":"trace[413061108] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:16008; }","duration":"218.416165ms","start":"2026-07-31T13:13:31.390029Z","end":"2026-07-31T13:13:31.608445Z","steps":["trace[413061108] 'agreement among raft nodes before linearized reading' (duration: 218.304621ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:13:31.607577Z","caller":"traceutil/trace.go:171","msg":"trace[1878195398] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16008; }","duration":"216.594632ms","start":"2026-07-31T13:13:31.390972Z","end":"2026-07-31T13:13:31.607567Z","steps":["trace[1878195398] 'agreement among raft nodes before linearized reading' (duration: 216.487169ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:13:31.846155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.428561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T13:13:31.846266Z","caller":"traceutil/trace.go:171","msg":"trace[1562841032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16009; }","duration":"160.592175ms","start":"2026-07-31T13:13:31.685647Z","end":"2026-07-31T13:13:31.846239Z","steps":["trace[1562841032] 'range keys from in-memory index tree' (duration: 160.36321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:13:31.846723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.141663ms","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-07-31T13:13:31.846776Z","caller":"traceutil/trace.go:171","msg":"trace[1604618202] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16009; }","duration":"114.231474ms","start":"2026-07-31T13:13:31.732532Z","end":"2026-07-31T13:13:31.846763Z","steps":["trace[1604618202] 'range keys from in-memory index tree' (duration: 114.028799ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:13:31.847138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.930427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-31T13:13:31.847182Z","caller":"traceutil/trace.go:171","msg":"trace[1792986442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16009; }","duration":"108.001229ms","start":"2026-07-31T13:13:31.739169Z","end":"2026-07-31T13:13:31.847171Z","steps":["trace[1792986442] 'range keys from in-memory index tree' (duration: 107.829235ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:13:32.1308Z","caller":"traceutil/trace.go:171","msg":"trace[1022725311] linearizableReadLoop","detail":"{readStateIndex:16560; appliedIndex:16559; }","duration":"175.951634ms","start":"2026-07-31T13:13:31.95482Z","end":"2026-07-31T13:13:32.130772Z","steps":["trace[1022725311] 'read index received' (duration: 138.4915ms)","trace[1022725311] 'applied index is now lower than readState.Index' (duration: 37.458804ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T13:13:32.130963Z","caller":"traceutil/trace.go:171","msg":"trace[1516657086] transaction","detail":"{read_only:false; response_revision:16012; number_of_response:1; }","duration":"183.856208ms","start":"2026-07-31T13:13:31.947066Z","end":"2026-07-31T13:13:32.130922Z","steps":["trace[1516657086] 'process raft request' (duration: 146.259401ms)","trace[1516657086] 'compare' (duration: 37.30357ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T13:13:32.13119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.347763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-45jpf\" ","response":"range_response_count:1 size:22099"} {"level":"info","ts":"2026-07-31T13:13:32.131272Z","caller":"traceutil/trace.go:171","msg":"trace[2001471603] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-45jpf; range_end:; response_count:1; response_revision:16012; }","duration":"176.471016ms","start":"2026-07-31T13:13:31.954781Z","end":"2026-07-31T13:13:32.131252Z","steps":["trace[2001471603] 'agreement among raft nodes before linearized reading' (duration: 176.22461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:13:32.131618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.328829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-z7678\" ","response":"range_response_count:1 size:20643"} {"level":"info","ts":"2026-07-31T13:13:32.131673Z","caller":"traceutil/trace.go:171","msg":"trace[1271638464] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-z7678; range_end:; response_count:1; response_revision:16012; }","duration":"163.414961ms","start":"2026-07-31T13:13:31.968242Z","end":"2026-07-31T13:13:32.131657Z","steps":["trace[1271638464] 'agreement among raft nodes before linearized reading' (duration: 163.217586ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:13:32.132274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.650547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-45jpf\" ","response":"range_response_count:1 size:22099"} {"level":"info","ts":"2026-07-31T13:13:32.132345Z","caller":"traceutil/trace.go:171","msg":"trace[960545951] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-45jpf; range_end:; response_count:1; response_revision:16012; }","duration":"133.775321ms","start":"2026-07-31T13:13:31.998559Z","end":"2026-07-31T13:13:32.132334Z","steps":["trace[960545951] 'agreement among raft nodes before linearized reading' (duration: 133.621986ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:15:58.54081Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14643} {"level":"info","ts":"2026-07-31T13:15:58.649082Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14643,"took":"105.049619ms","hash":2527513895,"current-db-size-bytes":57634816,"current-db-size":"58 MB","current-db-size-in-use-bytes":32079872,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-31T13:15:58.649135Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2527513895,"revision":14643,"compact-revision":12604} {"level":"info","ts":"2026-07-31T13:19:38.590824Z","caller":"traceutil/trace.go:171","msg":"trace[1638297723] transaction","detail":"{read_only:false; response_revision:18689; number_of_response:1; }","duration":"164.243486ms","start":"2026-07-31T13:19:38.426562Z","end":"2026-07-31T13:19:38.590805Z","steps":["trace[1638297723] 'process raft request' (duration: 163.55926ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:19:38.720442Z","caller":"traceutil/trace.go:171","msg":"trace[2122691747] transaction","detail":"{read_only:false; response_revision:18690; number_of_response:1; }","duration":"293.070561ms","start":"2026-07-31T13:19:38.427358Z","end":"2026-07-31T13:19:38.720428Z","steps":["trace[2122691747] 'process raft request' (duration: 292.845646ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:19:38.720369Z","caller":"traceutil/trace.go:171","msg":"trace[39772481] linearizableReadLoop","detail":"{readStateIndex:19328; appliedIndex:19326; }","duration":"282.546415ms","start":"2026-07-31T13:19:38.437802Z","end":"2026-07-31T13:19:38.720348Z","steps":["trace[39772481] 'read index received' (duration: 152.439421ms)","trace[39772481] 'applied index is now lower than readState.Index' (duration: 130.106124ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T13:19:38.721909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.098421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:953532"} {"level":"info","ts":"2026-07-31T13:19:38.722235Z","caller":"traceutil/trace.go:171","msg":"trace[1941740455] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:18690; }","duration":"284.429968ms","start":"2026-07-31T13:19:38.437783Z","end":"2026-07-31T13:19:38.722213Z","steps":["trace[1941740455] 'agreement among raft nodes before linearized reading' (duration: 282.936574ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:19:45.223962Z","caller":"traceutil/trace.go:171","msg":"trace[604688159] transaction","detail":"{read_only:false; response_revision:18728; number_of_response:1; }","duration":"225.346705ms","start":"2026-07-31T13:19:44.998591Z","end":"2026-07-31T13:19:45.223938Z","steps":["trace[604688159] 'process raft request' (duration: 225.191842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:19:45.870334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.20851ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T13:19:45.870461Z","caller":"traceutil/trace.go:171","msg":"trace[747404016] transaction","detail":"{read_only:false; response_revision:18730; number_of_response:1; }","duration":"164.032351ms","start":"2026-07-31T13:19:45.706405Z","end":"2026-07-31T13:19:45.870437Z","steps":["trace[747404016] 'process raft request' (duration: 56.624456ms)","trace[747404016] 'compare' (duration: 107.076297ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T13:19:46.484875Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7053094104886669052,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-31T13:19:46.580849Z","caller":"traceutil/trace.go:171","msg":"trace[1255447172] transaction","detail":"{read_only:false; response_revision:18731; number_of_response:1; }","duration":"707.796146ms","start":"2026-07-31T13:19:45.873025Z","end":"2026-07-31T13:19:46.580821Z","steps":["trace[1255447172] 'process raft request' (duration: 707.605241ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:19:46.581087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T13:19:45.873005Z","time spent":"708.000841ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T13:19:46.679325Z","caller":"traceutil/trace.go:171","msg":"trace[961051427] linearizableReadLoop","detail":"{readStateIndex:19372; appliedIndex:19369; }","duration":"694.706811ms","start":"2026-07-31T13:19:45.984594Z","end":"2026-07-31T13:19:46.6793Z","steps":["trace[961051427] 'read index received' (duration: 596.054848ms)","trace[961051427] 'applied index is now lower than readState.Index' (duration: 98.651073ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T13:19:46.67937Z","caller":"traceutil/trace.go:171","msg":"trace[1446322875] transaction","detail":"{read_only:false; response_revision:18732; number_of_response:1; }","duration":"805.64744ms","start":"2026-07-31T13:19:45.873683Z","end":"2026-07-31T13:19:46.679331Z","steps":["trace[1446322875] 'process raft request' (duration: 754.291506ms)","trace[1446322875] 'compare' (duration: 51.085058ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T13:19:46.679387Z","caller":"traceutil/trace.go:171","msg":"trace[1937690847] transaction","detail":"{read_only:false; response_revision:18733; number_of_response:1; }","duration":"400.821143ms","start":"2026-07-31T13:19:46.278521Z","end":"2026-07-31T13:19:46.679342Z","steps":["trace[1937690847] 'process raft request' (duration: 400.734431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:19:46.67948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.939839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T13:19:46.679514Z","caller":"traceutil/trace.go:171","msg":"trace[1273030981] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:18733; }","duration":"312.04043ms","start":"2026-07-31T13:19:46.367462Z","end":"2026-07-31T13:19:46.679503Z","steps":["trace[1273030981] 'agreement among raft nodes before linearized reading' (duration: 311.957658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:19:46.67954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T13:19:46.367441Z","time spent":"312.093552ms","remote":"127.0.0.1:36494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true "} {"level":"warn","ts":"2026-07-31T13:19:46.679584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T13:19:45.873662Z","time spent":"805.762922ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T13:19:46.67964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T13:19:46.278486Z","time spent":"401.025877ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T13:19:46.679692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.06905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-31T13:19:46.679793Z","caller":"traceutil/trace.go:171","msg":"trace[1375361426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18733; }","duration":"695.211383ms","start":"2026-07-31T13:19:45.98456Z","end":"2026-07-31T13:19:46.679771Z","steps":["trace[1375361426] 'agreement among raft nodes before linearized reading' (duration: 694.849255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:19:46.679839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T13:19:45.984543Z","time spent":"695.288085ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-31T13:20:35.942421Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f577bc5ae95d61e1","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-31T13:20:35.947744Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-31T13:20:35.947842Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-31T13:20:58.547869Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17066} {"level":"info","ts":"2026-07-31T13:20:58.643231Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17066,"took":"91.608368ms","hash":1255720630,"current-db-size-bytes":57634816,"current-db-size":"58 MB","current-db-size-in-use-bytes":33325056,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-31T13:20:58.643306Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1255720630,"revision":17066,"compact-revision":14643} {"level":"info","ts":"2026-07-31T13:25:41.602537Z","caller":"traceutil/trace.go:171","msg":"trace[804400588] transaction","detail":"{read_only:false; response_revision:21466; number_of_response:1; }","duration":"172.18566ms","start":"2026-07-31T13:25:41.430314Z","end":"2026-07-31T13:25:41.6025Z","steps":["trace[804400588] 'process raft request' (duration: 172.012166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:25:41.885404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.203145ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T13:25:41.885517Z","caller":"traceutil/trace.go:171","msg":"trace[973930385] linearizableReadLoop","detail":"{readStateIndex:22197; appliedIndex:22196; }","duration":"199.854042ms","start":"2026-07-31T13:25:41.685645Z","end":"2026-07-31T13:25:41.885499Z","steps":["trace[973930385] 'read index received' (duration: 10.450473ms)","trace[973930385] 'applied index is now lower than readState.Index' (duration: 189.402449ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T13:25:41.885598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.960714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T13:25:41.885632Z","caller":"traceutil/trace.go:171","msg":"trace[751630449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21467; }","duration":"200.018146ms","start":"2026-07-31T13:25:41.685602Z","end":"2026-07-31T13:25:41.88562Z","steps":["trace[751630449] 'agreement among raft nodes before linearized reading' (duration: 199.940424ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:25:41.885843Z","caller":"traceutil/trace.go:171","msg":"trace[155553493] transaction","detail":"{read_only:false; response_revision:21467; number_of_response:1; }","duration":"270.682167ms","start":"2026-07-31T13:25:41.615071Z","end":"2026-07-31T13:25:41.885753Z","steps":["trace[155553493] 'process raft request' (duration: 81.040452ms)","trace[155553493] 'compare' (duration: 189.068712ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T13:25:42.106614Z","caller":"traceutil/trace.go:171","msg":"trace[1678880] transaction","detail":"{read_only:false; response_revision:21468; number_of_response:1; }","duration":"234.887515ms","start":"2026-07-31T13:25:41.871683Z","end":"2026-07-31T13:25:42.106571Z","steps":["trace[1678880] 'process raft request' (duration: 205.716807ms)","trace[1678880] 'compare' (duration: 29.002544ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T13:25:42.106747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.977909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T13:25:42.106831Z","caller":"traceutil/trace.go:171","msg":"trace[1233830124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21468; }","duration":"220.098732ms","start":"2026-07-31T13:25:41.886711Z","end":"2026-07-31T13:25:42.10681Z","steps":["trace[1233830124] 'agreement among raft nodes before linearized reading' (duration: 219.959188ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:25:42.106603Z","caller":"traceutil/trace.go:171","msg":"trace[871827965] linearizableReadLoop","detail":"{readStateIndex:22198; appliedIndex:22197; }","duration":"219.802415ms","start":"2026-07-31T13:25:41.886741Z","end":"2026-07-31T13:25:42.106544Z","steps":["trace[871827965] 'read index received' (duration: 190.81157ms)","trace[871827965] 'applied index is now lower than readState.Index' (duration: 28.989584ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T13:25:42.534756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.390245ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T13:25:42.535311Z","caller":"traceutil/trace.go:171","msg":"trace[816298620] linearizableReadLoop","detail":"{readStateIndex:22200; appliedIndex:22198; }","duration":"419.573284ms","start":"2026-07-31T13:25:42.115716Z","end":"2026-07-31T13:25:42.535289Z","steps":["trace[816298620] 'read index received' (duration: 224.518404ms)","trace[816298620] 'applied index is now lower than readState.Index' (duration: 195.05358ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T13:25:42.535467Z","caller":"traceutil/trace.go:171","msg":"trace[118306992] transaction","detail":"{read_only:false; response_revision:21470; number_of_response:1; }","duration":"582.399895ms","start":"2026-07-31T13:25:41.953055Z","end":"2026-07-31T13:25:42.535455Z","steps":["trace[118306992] 'process raft request' (duration: 582.107679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:25:42.535598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T13:25:41.953044Z","time spent":"582.502137ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T13:25:42.5353Z","caller":"traceutil/trace.go:171","msg":"trace[1429504160] transaction","detail":"{read_only:false; response_revision:21469; number_of_response:1; }","duration":"584.196798ms","start":"2026-07-31T13:25:41.951061Z","end":"2026-07-31T13:25:42.535258Z","steps":["trace[1429504160] 'process raft request' (duration: 389.184628ms)","trace[1429504160] 'compare' (duration: 193.976025ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T13:25:42.535844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T13:25:41.951036Z","time spent":"584.701419ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T13:25:42.535847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.117647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.177\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-31T13:25:42.535976Z","caller":"traceutil/trace.go:171","msg":"trace[1613202144] range","detail":"{range_begin:/registry/masterleases/199.19.213.177; range_end:; response_count:1; response_revision:21470; }","duration":"420.279601ms","start":"2026-07-31T13:25:42.115672Z","end":"2026-07-31T13:25:42.535951Z","steps":["trace[1613202144] 'agreement among raft nodes before linearized reading' (duration: 419.892962ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:25:42.536088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T13:25:42.115655Z","time spent":"420.415373ms","remote":"127.0.0.1:34438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/199.19.213.177\" "} {"level":"warn","ts":"2026-07-31T13:25:42.916603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.565964ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-07-31T13:25:42.916843Z","caller":"traceutil/trace.go:171","msg":"trace[59302160] linearizableReadLoop","detail":"{readStateIndex:22204; appliedIndex:22201; }","duration":"332.638585ms","start":"2026-07-31T13:25:42.584167Z","end":"2026-07-31T13:25:42.916806Z","steps":["trace[59302160] 'read index received' (duration: 148.860847ms)","trace[59302160] 'applied index is now lower than readState.Index' (duration: 183.776648ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T13:25:42.916845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T13:25:42.53732Z","time spent":"379.516224ms","remote":"127.0.0.1:34438","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-31T13:25:42.916867Z","caller":"traceutil/trace.go:171","msg":"trace[1863482223] transaction","detail":"{read_only:false; response_revision:21472; number_of_response:1; }","duration":"372.202374ms","start":"2026-07-31T13:25:42.544635Z","end":"2026-07-31T13:25:42.916837Z","steps":["trace[1863482223] 'process raft request' (duration: 372.04551ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:25:42.916923Z","caller":"traceutil/trace.go:171","msg":"trace[588037122] transaction","detail":"{read_only:false; response_revision:21473; number_of_response:1; }","duration":"371.729843ms","start":"2026-07-31T13:25:42.545126Z","end":"2026-07-31T13:25:42.916856Z","steps":["trace[588037122] 'process raft request' (duration: 371.649002ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:25:42.917112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T13:25:42.544616Z","time spent":"372.42368ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T13:25:42.917132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.43085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-31T13:25:42.917178Z","caller":"traceutil/trace.go:171","msg":"trace[727886384] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21473; }","duration":"208.510132ms","start":"2026-07-31T13:25:42.708655Z","end":"2026-07-31T13:25:42.917165Z","steps":["trace[727886384] 'agreement among raft nodes before linearized reading' (duration: 208.292107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:25:42.917183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.981177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T13:25:42.917274Z","caller":"traceutil/trace.go:171","msg":"trace[1084644533] 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:21473; }","duration":"179.11816ms","start":"2026-07-31T13:25:42.738133Z","end":"2026-07-31T13:25:42.917251Z","steps":["trace[1084644533] 'agreement among raft nodes before linearized reading' (duration: 178.938305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:25:42.917056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.881731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-31T13:25:42.917488Z","caller":"traceutil/trace.go:171","msg":"trace[2082740004] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21473; }","duration":"333.353462ms","start":"2026-07-31T13:25:42.584124Z","end":"2026-07-31T13:25:42.917477Z","steps":["trace[2082740004] 'agreement among raft nodes before linearized reading' (duration: 332.777099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:25:42.917519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T13:25:42.584103Z","time spent":"333.407952ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-31T13:25:42.91837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T13:25:42.545104Z","time spent":"371.986509ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T13:25:43.30528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.900121ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T13:25:43.305366Z","caller":"traceutil/trace.go:171","msg":"trace[41746926] linearizableReadLoop","detail":"{readStateIndex:22206; appliedIndex:22205; }","duration":"378.242354ms","start":"2026-07-31T13:25:42.927108Z","end":"2026-07-31T13:25:43.305351Z","steps":["trace[41746926] 'read index received' (duration: 250.304532ms)","trace[41746926] 'applied index is now lower than readState.Index' (duration: 127.936782ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T13:25:43.305538Z","caller":"traceutil/trace.go:171","msg":"trace[54607038] transaction","detail":"{read_only:false; response_revision:21475; number_of_response:1; }","duration":"383.013635ms","start":"2026-07-31T13:25:42.922511Z","end":"2026-07-31T13:25:43.305525Z","steps":["trace[54607038] 'process raft request' (duration: 254.758176ms)","trace[54607038] 'compare' (duration: 127.698396ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T13:25:43.305585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T13:25:42.922499Z","time spent":"383.060337ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T13:25:43.305722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.591492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-07-31T13:25:43.305787Z","caller":"traceutil/trace.go:171","msg":"trace[430645133] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:21475; }","duration":"378.684215ms","start":"2026-07-31T13:25:42.927089Z","end":"2026-07-31T13:25:43.305773Z","steps":["trace[430645133] 'agreement among raft nodes before linearized reading' (duration: 378.47681ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:25:43.305818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T13:25:42.927079Z","time spent":"378.732225ms","remote":"127.0.0.1:34582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-07-31T13:25:43.31756Z","caller":"traceutil/trace.go:171","msg":"trace[526821143] transaction","detail":"{read_only:false; response_revision:21476; number_of_response:1; }","duration":"190.456663ms","start":"2026-07-31T13:25:43.127063Z","end":"2026-07-31T13:25:43.317519Z","steps":["trace[526821143] 'process raft request' (duration: 190.180037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:25:43.317683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.383666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18261"} {"level":"info","ts":"2026-07-31T13:25:43.317778Z","caller":"traceutil/trace.go:171","msg":"trace[2045722456] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:21476; }","duration":"390.529269ms","start":"2026-07-31T13:25:42.927223Z","end":"2026-07-31T13:25:43.317753Z","steps":["trace[2045722456] 'agreement among raft nodes before linearized reading' (duration: 390.217402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:25:43.317829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T13:25:42.927217Z","time spent":"390.603621ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18284,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-31T13:25:43.650029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.693974ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T13:25:43.650215Z","caller":"traceutil/trace.go:171","msg":"trace[762417579] transaction","detail":"{read_only:false; response_revision:21477; number_of_response:1; }","duration":"231.63923ms","start":"2026-07-31T13:25:43.418541Z","end":"2026-07-31T13:25:43.65018Z","steps":["trace[762417579] 'process raft request' (duration: 60.70593ms)","trace[762417579] 'compare' (duration: 170.478299ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T13:25:44.003814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.839323ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T13:25:44.003942Z","caller":"traceutil/trace.go:171","msg":"trace[1252366248] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:21477; }","duration":"116.980277ms","start":"2026-07-31T13:25:43.886944Z","end":"2026-07-31T13:25:44.003924Z","steps":["trace[1252366248] 'range keys from in-memory index tree' (duration: 116.822323ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:25:44.004081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.945067ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T13:25:44.00419Z","caller":"traceutil/trace.go:171","msg":"trace[521047789] linearizableReadLoop","detail":"{readStateIndex:22209; appliedIndex:22208; }","duration":"319.582061ms","start":"2026-07-31T13:25:43.684591Z","end":"2026-07-31T13:25:44.004173Z","steps":["trace[521047789] 'read index received' (duration: 177.403979ms)","trace[521047789] 'applied index is now lower than readState.Index' (duration: 142.176672ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T13:25:44.004229Z","caller":"traceutil/trace.go:171","msg":"trace[1134891781] transaction","detail":"{read_only:false; response_revision:21478; number_of_response:1; }","duration":"350.49254ms","start":"2026-07-31T13:25:43.653705Z","end":"2026-07-31T13:25:44.004198Z","steps":["trace[1134891781] 'process raft request' (duration: 208.294408ms)","trace[1134891781] 'compare' (duration: 141.651999ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T13:25:44.004333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.723714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T13:25:44.004363Z","caller":"traceutil/trace.go:171","msg":"trace[212452779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21478; }","duration":"319.811026ms","start":"2026-07-31T13:25:43.684543Z","end":"2026-07-31T13:25:44.004354Z","steps":["trace[212452779] 'agreement among raft nodes before linearized reading' (duration: 319.723074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:25:44.004395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T13:25:43.684524Z","time spent":"319.864218ms","remote":"127.0.0.1:55100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T13:25:44.004423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T13:25:43.653688Z","time spent":"350.606342ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T13:25:44.004663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.43208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-31T13:25:44.004741Z","caller":"traceutil/trace.go:171","msg":"trace[1296489794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21478; }","duration":"115.571934ms","start":"2026-07-31T13:25:43.889152Z","end":"2026-07-31T13:25:44.004723Z","steps":["trace[1296489794] 'agreement among raft nodes before linearized reading' (duration: 115.315178ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:25:44.005341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.418106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-31T13:25:44.005415Z","caller":"traceutil/trace.go:171","msg":"trace[2136921692] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:21478; }","duration":"213.5588ms","start":"2026-07-31T13:25:43.791841Z","end":"2026-07-31T13:25:44.0054Z","steps":["trace[2136921692] 'agreement among raft nodes before linearized reading' (duration: 212.505245ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:25:44.248417Z","caller":"traceutil/trace.go:171","msg":"trace[936819791] transaction","detail":"{read_only:false; response_revision:21480; number_of_response:1; }","duration":"136.000278ms","start":"2026-07-31T13:25:44.112385Z","end":"2026-07-31T13:25:44.248385Z","steps":["trace[936819791] 'process raft request' (duration: 135.413605ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:25:58.554036Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19519} {"level":"info","ts":"2026-07-31T13:25:58.696423Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19519,"took":"138.046267ms","hash":2194413410,"current-db-size-bytes":57634816,"current-db-size":"58 MB","current-db-size-in-use-bytes":29655040,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-31T13:25:58.696486Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2194413410,"revision":19519,"compact-revision":17066} {"level":"info","ts":"2026-07-31T13:29:47.695409Z","caller":"traceutil/trace.go:171","msg":"trace[256905315] transaction","detail":"{read_only:false; response_revision:23970; number_of_response:1; }","duration":"132.474596ms","start":"2026-07-31T13:29:47.562897Z","end":"2026-07-31T13:29:47.695372Z","steps":["trace[256905315] 'process raft request' (duration: 132.132119ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:29:47.696421Z","caller":"traceutil/trace.go:171","msg":"trace[1825292549] transaction","detail":"{read_only:false; response_revision:23972; number_of_response:1; }","duration":"130.364677ms","start":"2026-07-31T13:29:47.566036Z","end":"2026-07-31T13:29:47.696401Z","steps":["trace[1825292549] 'process raft request' (duration: 130.07962ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:29:47.696416Z","caller":"traceutil/trace.go:171","msg":"trace[970012094] transaction","detail":"{read_only:false; response_revision:23971; number_of_response:1; }","duration":"131.175685ms","start":"2026-07-31T13:29:47.565204Z","end":"2026-07-31T13:29:47.69638Z","steps":["trace[970012094] 'process raft request' (duration: 130.07728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:29:48.194934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.563378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-31T13:29:48.195023Z","caller":"traceutil/trace.go:171","msg":"trace[1141311729] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:23972; }","duration":"383.739382ms","start":"2026-07-31T13:29:47.811261Z","end":"2026-07-31T13:29:48.195001Z","steps":["trace[1141311729] 'count revisions from in-memory index tree' (duration: 383.426604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:29:48.19507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T13:29:47.811235Z","time spent":"383.822833ms","remote":"127.0.0.1:51084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-07-31T13:29:48.19547Z","caller":"traceutil/trace.go:171","msg":"trace[1686316755] linearizableReadLoop","detail":"{readStateIndex:24780; appliedIndex:24779; }","duration":"151.883546ms","start":"2026-07-31T13:29:48.043523Z","end":"2026-07-31T13:29:48.195406Z","steps":["trace[1686316755] 'read index received' (duration: 151.044927ms)","trace[1686316755] 'applied index is now lower than readState.Index' (duration: 836.699µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T13:29:48.195517Z","caller":"traceutil/trace.go:171","msg":"trace[1303214124] transaction","detail":"{read_only:false; response_revision:23973; number_of_response:1; }","duration":"328.436448ms","start":"2026-07-31T13:29:47.86704Z","end":"2026-07-31T13:29:48.195477Z","steps":["trace[1303214124] 'process raft request' (duration: 327.577557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:29:48.195653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.133934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-31T13:29:48.1957Z","caller":"traceutil/trace.go:171","msg":"trace[81835878] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:23973; }","duration":"152.214356ms","start":"2026-07-31T13:29:48.04347Z","end":"2026-07-31T13:29:48.195685Z","steps":["trace[81835878] 'agreement among raft nodes before linearized reading' (duration: 152.029672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T13:29:48.195755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T13:29:47.867003Z","time spent":"328.622782ms","remote":"127.0.0.1:34698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T13:29:48.1965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.144095ms","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:2083"} {"level":"info","ts":"2026-07-31T13:29:48.196615Z","caller":"traceutil/trace.go:171","msg":"trace[297585501] 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:23974; }","duration":"137.288828ms","start":"2026-07-31T13:29:48.059285Z","end":"2026-07-31T13:29:48.196574Z","steps":["trace[297585501] 'agreement among raft nodes before linearized reading' (duration: 136.956701ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T13:30:58.599333Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21551} {"level":"info","ts":"2026-07-31T13:30:58.869825Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21551,"took":"258.47283ms","hash":1890801777,"current-db-size-bytes":57634816,"current-db-size":"58 MB","current-db-size-in-use-bytes":43950080,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-07-31T13:30:58.869899Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1890801777,"revision":21551,"compact-revision":19519} {"level":"info","ts":"2026-07-31T13:33:00.166816Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000067cf.wal"} {"level":"info","ts":"2026-07-31T13:35:58.606073Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24801} {"level":"info","ts":"2026-07-31T13:35:58.761824Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24801,"took":"151.08701ms","hash":4289877209,"current-db-size-bytes":57634816,"current-db-size":"58 MB","current-db-size-in-use-bytes":31358976,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-31T13:35:58.761892Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4289877209,"revision":24801,"compact-revision":21551}