{"level":"warn","ts":"2026-06-09T22:14:21.66661Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-09T22:14:21.666751Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.38:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.38:2380","--initial-cluster=instance=https://199.204.45.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.38: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-06-09T22:14:21.666877Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-09T22:14:21.666909Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-06-09T22:14:21.666943Z","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-06-09T22:14:21.667846Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"]} {"level":"info","ts":"2026-06-09T22:14:21.667954Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.38: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-06-09T22:14:21.673133Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.856894ms"} {"level":"info","ts":"2026-06-09T22:14:21.681445Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"331455d6226d3423","cluster-id":"25c7803fb83a1455"} {"level":"info","ts":"2026-06-09T22:14:21.68173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=()"} {"level":"info","ts":"2026-06-09T22:14:21.682366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 0"} {"level":"info","ts":"2026-06-09T22:14:21.682421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 331455d6226d3423 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-09T22:14:21.68244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 1"} {"level":"info","ts":"2026-06-09T22:14:21.682506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"warn","ts":"2026-06-09T22:14:21.688873Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-09T22:14:21.691341Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-09T22:14:21.693762Z","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-06-09T22:14:21.697382Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"331455d6226d3423","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-09T22:14:21.697508Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"331455d6226d3423","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-09T22:14:21.697868Z","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-06-09T22:14:21.697995Z","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-06-09T22:14:21.698005Z","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-06-09T22:14:21.698589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"info","ts":"2026-06-09T22:14:21.69876Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","added-peer-id":"331455d6226d3423","added-peer-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-06-09T22:14:21.700186Z","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-06-09T22:14:21.700336Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-06-09T22:14:21.700382Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-06-09T22:14:21.700422Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"331455d6226d3423","initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-09T22:14:21.700454Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-09T22:14:22.183275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 is starting a new election at term 1"} {"level":"info","ts":"2026-06-09T22:14:22.183375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-09T22:14:22.183484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgPreVoteResp from 331455d6226d3423 at term 1"} {"level":"info","ts":"2026-06-09T22:14:22.183543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became candidate at term 2"} {"level":"info","ts":"2026-06-09T22:14:22.183581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgVoteResp from 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-06-09T22:14:22.183661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became leader at term 2"} {"level":"info","ts":"2026-06-09T22:14:22.183699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 331455d6226d3423 elected leader 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-06-09T22:14:22.184757Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T22:14:22.18555Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"331455d6226d3423","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.38:2379]}","request-path":"/0/members/331455d6226d3423/attributes","cluster-id":"25c7803fb83a1455","publish-timeout":"7s"} {"level":"info","ts":"2026-06-09T22:14:22.185557Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-09T22:14:22.185603Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-09T22:14:22.18594Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-09T22:14:22.185987Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-09T22:14:22.186154Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T22:14:22.186272Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T22:14:22.186361Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T22:14:22.188432Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.38:2379"} {"level":"info","ts":"2026-06-09T22:14:22.190594Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-09T22:14:24.88502Z","caller":"traceutil/trace.go:171","msg":"trace[145303907] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"292.505523ms","start":"2026-06-09T22:14:24.592482Z","end":"2026-06-09T22:14:24.884987Z","steps":["trace[145303907] 'process raft request' (duration: 292.280501ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:14:24.884968Z","caller":"traceutil/trace.go:171","msg":"trace[1273271427] linearizableReadLoop","detail":"{readStateIndex:75; appliedIndex:74; }","duration":"291.475858ms","start":"2026-06-09T22:14:24.593388Z","end":"2026-06-09T22:14:24.884864Z","steps":["trace[1273271427] 'read index received' (duration: 291.362142ms)","trace[1273271427] 'applied index is now lower than readState.Index' (duration: 112.756µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:14:24.885237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.817576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-09T22:14:24.885328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.82313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-09T22:14:24.885392Z","caller":"traceutil/trace.go:171","msg":"trace[2009550981] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:71; }","duration":"292.002476ms","start":"2026-06-09T22:14:24.593365Z","end":"2026-06-09T22:14:24.885368Z","steps":["trace[2009550981] 'agreement among raft nodes before linearized reading' (duration: 291.677359ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:14:24.885409Z","caller":"traceutil/trace.go:171","msg":"trace[1270559223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:71; }","duration":"186.951347ms","start":"2026-06-09T22:14:24.698443Z","end":"2026-06-09T22:14:24.885394Z","steps":["trace[1270559223] 'agreement among raft nodes before linearized reading' (duration: 186.840181ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:14:25.478137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.868674ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-09T22:14:25.478258Z","caller":"traceutil/trace.go:171","msg":"trace[792299506] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"587.95537ms","start":"2026-06-09T22:14:24.890285Z","end":"2026-06-09T22:14:25.478241Z","steps":["trace[792299506] 'read index received' (duration: 307.350577ms)","trace[792299506] 'applied index is now lower than readState.Index' (duration: 280.603753ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:14:25.478343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.051596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-09T22:14:25.478329Z","caller":"traceutil/trace.go:171","msg":"trace[1537307398] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"589.540234ms","start":"2026-06-09T22:14:24.888745Z","end":"2026-06-09T22:14:25.478285Z","steps":["trace[1537307398] 'process raft request' (duration: 308.981034ms)","trace[1537307398] 'compare' (duration: 279.730126ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:14:25.478385Z","caller":"traceutil/trace.go:171","msg":"trace[1435451372] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:72; }","duration":"588.124809ms","start":"2026-06-09T22:14:24.890242Z","end":"2026-06-09T22:14:25.478367Z","steps":["trace[1435451372] 'agreement among raft nodes before linearized reading' (duration: 588.044385ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:14:25.478569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:14:24.888734Z","time spent":"589.736475ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T22:14:25.478658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:14:24.890225Z","time spent":"588.389764ms","remote":"127.0.0.1:49686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:aggregate-to-edit\" "} {"level":"info","ts":"2026-06-09T22:14:25.479094Z","caller":"traceutil/trace.go:171","msg":"trace[1003355641] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"588.408514ms","start":"2026-06-09T22:14:24.890671Z","end":"2026-06-09T22:14:25.479079Z","steps":["trace[1003355641] 'process raft request' (duration: 588.254396ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:14:25.479329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:14:24.890659Z","time spent":"588.483868ms","remote":"127.0.0.1:49702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T22:14:26.606969Z","caller":"traceutil/trace.go:171","msg":"trace[291967541] transaction","detail":"{read_only:false; response_revision:153; number_of_response:1; }","duration":"218.085102ms","start":"2026-06-09T22:14:26.388858Z","end":"2026-06-09T22:14:26.606943Z","steps":["trace[291967541] 'process raft request' (duration: 218.038549ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:14:26.607021Z","caller":"traceutil/trace.go:171","msg":"trace[1317028268] transaction","detail":"{read_only:false; response_revision:152; number_of_response:1; }","duration":"335.580457ms","start":"2026-06-09T22:14:26.271391Z","end":"2026-06-09T22:14:26.606972Z","steps":["trace[1317028268] 'process raft request' (duration: 311.568879ms)","trace[1317028268] 'compare' (duration: 23.786687ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:14:26.607526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:14:26.271372Z","time spent":"336.021241ms","remote":"127.0.0.1:49688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":744,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T22:14:37.974132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.057674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-06-09T22:14:37.974287Z","caller":"traceutil/trace.go:171","msg":"trace[329679601] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:405; }","duration":"198.256224ms","start":"2026-06-09T22:14:37.776005Z","end":"2026-06-09T22:14:37.974261Z","steps":["trace[329679601] 'range keys from in-memory index tree' (duration: 197.866043ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:15:17.897924Z","caller":"traceutil/trace.go:171","msg":"trace[329316350] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"111.555216ms","start":"2026-06-09T22:15:17.786344Z","end":"2026-06-09T22:15:17.8979Z","steps":["trace[329316350] 'process raft request' (duration: 111.404708ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:15:18.574199Z","caller":"traceutil/trace.go:171","msg":"trace[1562403935] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"186.873894ms","start":"2026-06-09T22:15:18.387285Z","end":"2026-06-09T22:15:18.574159Z","steps":["trace[1562403935] 'process raft request' (duration: 186.699507ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:15:18.575707Z","caller":"traceutil/trace.go:171","msg":"trace[1813939199] linearizableReadLoop","detail":"{readStateIndex:746; appliedIndex:746; }","duration":"184.75098ms","start":"2026-06-09T22:15:18.390927Z","end":"2026-06-09T22:15:18.575678Z","steps":["trace[1813939199] 'read index received' (duration: 184.74068ms)","trace[1813939199] 'applied index is now lower than readState.Index' (duration: 8.18µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:15:18.576059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.084595ms","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-06-09T22:15:18.576194Z","caller":"traceutil/trace.go:171","msg":"trace[414374566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:717; }","duration":"185.256992ms","start":"2026-06-09T22:15:18.390908Z","end":"2026-06-09T22:15:18.576165Z","steps":["trace[414374566] 'agreement among raft nodes before linearized reading' (duration: 184.897397ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:15:18.759836Z","caller":"traceutil/trace.go:171","msg":"trace[220511206] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"178.572435ms","start":"2026-06-09T22:15:18.581235Z","end":"2026-06-09T22:15:18.759807Z","steps":["trace[220511206] 'process raft request' (duration: 158.410976ms)","trace[220511206] 'compare' (duration: 20.031672ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:15:18.773897Z","caller":"traceutil/trace.go:171","msg":"trace[1623995543] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"192.009938ms","start":"2026-06-09T22:15:18.581856Z","end":"2026-06-09T22:15:18.773866Z","steps":["trace[1623995543] 'process raft request' (duration: 191.807081ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:15:35.153019Z","caller":"traceutil/trace.go:171","msg":"trace[1781182848] linearizableReadLoop","detail":"{readStateIndex:865; appliedIndex:864; }","duration":"302.091352ms","start":"2026-06-09T22:15:34.850884Z","end":"2026-06-09T22:15:35.152975Z","steps":["trace[1781182848] 'read index received' (duration: 301.293083ms)","trace[1781182848] 'applied index is now lower than readState.Index' (duration: 797.299µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:15:35.153167Z","caller":"traceutil/trace.go:171","msg":"trace[1027285240] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"303.915438ms","start":"2026-06-09T22:15:34.849242Z","end":"2026-06-09T22:15:35.153157Z","steps":["trace[1027285240] 'process raft request' (duration: 303.049439ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:15:35.153432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:15:34.84922Z","time spent":"303.973978ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:15:35.153756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.257128ms","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-06-09T22:15:35.153802Z","caller":"traceutil/trace.go:171","msg":"trace[1874730276] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:831; }","duration":"179.955166ms","start":"2026-06-09T22:15:34.973832Z","end":"2026-06-09T22:15:35.153788Z","steps":["trace[1874730276] 'agreement among raft nodes before linearized reading' (duration: 179.840496ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:15:35.154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.091282ms","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-06-09T22:15:35.154051Z","caller":"traceutil/trace.go:171","msg":"trace[989435553] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:831; }","duration":"303.180212ms","start":"2026-06-09T22:15:34.85085Z","end":"2026-06-09T22:15:35.15403Z","steps":["trace[989435553] 'agreement among raft nodes before linearized reading' (duration: 302.747591ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:15:35.154081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:15:34.850837Z","time spent":"303.236001ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-09T22:15:35.154165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.135779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-z66kj\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-06-09T22:15:35.154262Z","caller":"traceutil/trace.go:171","msg":"trace[1935166141] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-z66kj; range_end:; response_count:1; response_revision:831; }","duration":"262.273659ms","start":"2026-06-09T22:15:34.891964Z","end":"2026-06-09T22:15:35.154238Z","steps":["trace[1935166141] 'agreement among raft nodes before linearized reading' (duration: 261.23232ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:15:35.262687Z","caller":"traceutil/trace.go:171","msg":"trace[797181191] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"101.783777ms","start":"2026-06-09T22:15:35.160873Z","end":"2026-06-09T22:15:35.262656Z","steps":["trace[797181191] 'process raft request' (duration: 101.563777ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:16:29.953839Z","caller":"traceutil/trace.go:171","msg":"trace[1753942838] transaction","detail":"{read_only:false; response_revision:1497; number_of_response:1; }","duration":"101.55117ms","start":"2026-06-09T22:16:29.852257Z","end":"2026-06-09T22:16:29.953808Z","steps":["trace[1753942838] 'process raft request' (duration: 101.355524ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:16:56.957614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.383387ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T22:16:56.957813Z","caller":"traceutil/trace.go:171","msg":"trace[9133176] linearizableReadLoop","detail":"{readStateIndex:2002; appliedIndex:2001; }","duration":"226.278069ms","start":"2026-06-09T22:16:56.73148Z","end":"2026-06-09T22:16:56.957758Z","steps":["trace[9133176] 'read index received' (duration: 119.866912ms)","trace[9133176] 'applied index is now lower than readState.Index' (duration: 106.408897ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:16:56.957923Z","caller":"traceutil/trace.go:171","msg":"trace[512585111] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"358.63608ms","start":"2026-06-09T22:16:56.59921Z","end":"2026-06-09T22:16:56.957846Z","steps":["trace[512585111] 'process raft request' (duration: 251.889741ms)","trace[512585111] 'compare' (duration: 105.11918ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:16:56.958004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.510817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:16:56.95807Z","caller":"traceutil/trace.go:171","msg":"trace[964044777] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1922; }","duration":"226.619852ms","start":"2026-06-09T22:16:56.731429Z","end":"2026-06-09T22:16:56.958049Z","steps":["trace[964044777] 'agreement among raft nodes before linearized reading' (duration: 226.485797ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:16:56.95819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:16:56.599182Z","time spent":"358.833728ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:16:57.020326Z","caller":"traceutil/trace.go:171","msg":"trace[1152785064] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"257.552477ms","start":"2026-06-09T22:16:56.762749Z","end":"2026-06-09T22:16:57.020301Z","steps":["trace[1152785064] 'process raft request' (duration: 257.294848ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:16:57.020459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.739638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-09T22:16:57.02076Z","caller":"traceutil/trace.go:171","msg":"trace[2081580810] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:1923; }","duration":"108.089171ms","start":"2026-06-09T22:16:56.912618Z","end":"2026-06-09T22:16:57.020707Z","steps":["trace[2081580810] 'agreement among raft nodes before linearized reading' (duration: 107.615474ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:16:57.917476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.848755ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-06-09T22:16:57.917582Z","caller":"traceutil/trace.go:171","msg":"trace[55400713] linearizableReadLoop","detail":"{readStateIndex:2004; appliedIndex:2003; }","duration":"870.219647ms","start":"2026-06-09T22:16:57.047343Z","end":"2026-06-09T22:16:57.917563Z","steps":["trace[55400713] 'read index received' (duration: 386.748208ms)","trace[55400713] 'applied index is now lower than readState.Index' (duration: 483.468969ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:16:57.917711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:16:57.021877Z","time spent":"895.781942ms","remote":"127.0.0.1:49404","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-09T22:16:57.918071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.701364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-09T22:16:57.91816Z","caller":"traceutil/trace.go:171","msg":"trace[1984632607] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1923; }","duration":"870.811648ms","start":"2026-06-09T22:16:57.047327Z","end":"2026-06-09T22:16:57.918139Z","steps":["trace[1984632607] 'agreement among raft nodes before linearized reading' (duration: 870.548458ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:16:57.918214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:16:57.047251Z","time spent":"870.952203ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":580,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-06-09T22:16:57.92606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.297546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-09T22:16:57.926133Z","caller":"traceutil/trace.go:171","msg":"trace[294254008] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"346.097097ms","start":"2026-06-09T22:16:57.580014Z","end":"2026-06-09T22:16:57.926111Z","steps":["trace[294254008] 'process raft request' (duration: 345.697322ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:16:57.92619Z","caller":"traceutil/trace.go:171","msg":"trace[695514394] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1924; }","duration":"774.457521ms","start":"2026-06-09T22:16:57.151697Z","end":"2026-06-09T22:16:57.926154Z","steps":["trace[695514394] 'agreement among raft nodes before linearized reading' (duration: 774.164459ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:16:57.926235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:16:57.151675Z","time spent":"774.551204ms","remote":"127.0.0.1:46752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-09T22:16:57.92626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:16:57.579982Z","time spent":"346.198141ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:16:57.926431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.213031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-09T22:16:57.926502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.84358ms","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-06-09T22:16:57.926539Z","caller":"traceutil/trace.go:171","msg":"trace[690812508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1924; }","duration":"743.391007ms","start":"2026-06-09T22:16:57.183125Z","end":"2026-06-09T22:16:57.926516Z","steps":["trace[690812508] 'agreement among raft nodes before linearized reading' (duration: 743.161759ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:16:57.926589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.417076ms","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-06-09T22:16:57.92664Z","caller":"traceutil/trace.go:171","msg":"trace[1412343502] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1924; }","duration":"105.577792ms","start":"2026-06-09T22:16:57.82105Z","end":"2026-06-09T22:16:57.926628Z","steps":["trace[1412343502] 'agreement among raft nodes before linearized reading' (duration: 105.450637ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:16:57.92606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.31348ms","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-06-09T22:16:57.926742Z","caller":"traceutil/trace.go:171","msg":"trace[1845610558] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1924; }","duration":"188.044996ms","start":"2026-06-09T22:16:57.738688Z","end":"2026-06-09T22:16:57.926733Z","steps":["trace[1845610558] 'agreement among raft nodes before linearized reading' (duration: 187.199744ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:16:57.926598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:16:57.183064Z","time spent":"743.511461ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-09T22:16:57.926457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.128266ms","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-06-09T22:16:57.926913Z","caller":"traceutil/trace.go:171","msg":"trace[1387787782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1924; }","duration":"105.619343ms","start":"2026-06-09T22:16:57.821283Z","end":"2026-06-09T22:16:57.926902Z","steps":["trace[1387787782] 'agreement among raft nodes before linearized reading' (duration: 104.933048ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:16:57.927139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.043163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3352c74d-7477-47cd-b0b7-dca18552f48c\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-06-09T22:16:57.927268Z","caller":"traceutil/trace.go:171","msg":"trace[1459924273] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3352c74d-7477-47cd-b0b7-dca18552f48c; range_end:; response_count:1; response_revision:1924; }","duration":"659.214249ms","start":"2026-06-09T22:16:57.268043Z","end":"2026-06-09T22:16:57.927257Z","steps":["trace[1459924273] 'agreement among raft nodes before linearized reading' (duration: 659.016352ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:16:57.927387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:16:57.268015Z","time spent":"659.358825ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3352c74d-7477-47cd-b0b7-dca18552f48c\" "} {"level":"info","ts":"2026-06-09T22:16:57.927292Z","caller":"traceutil/trace.go:171","msg":"trace[2055020930] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1924; }","duration":"193.012496ms","start":"2026-06-09T22:16:57.733536Z","end":"2026-06-09T22:16:57.926548Z","steps":["trace[2055020930] 'agreement among raft nodes before linearized reading' (duration: 192.725596ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:16:57.92788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.638377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3352c74d-7477-47cd-b0b7-dca18552f48c\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-06-09T22:16:57.927928Z","caller":"traceutil/trace.go:171","msg":"trace[1700020876] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3352c74d-7477-47cd-b0b7-dca18552f48c; range_end:; response_count:1; response_revision:1924; }","duration":"619.73336ms","start":"2026-06-09T22:16:57.308183Z","end":"2026-06-09T22:16:57.927916Z","steps":["trace[1700020876] 'agreement among raft nodes before linearized reading' (duration: 617.911975ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:16:57.927966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:16:57.308156Z","time spent":"619.800343ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3352c74d-7477-47cd-b0b7-dca18552f48c\" "} {"level":"warn","ts":"2026-06-09T22:16:58.716612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.355111ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T22:16:58.717229Z","caller":"traceutil/trace.go:171","msg":"trace[1229455509] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"471.250451ms","start":"2026-06-09T22:16:58.245952Z","end":"2026-06-09T22:16:58.717202Z","steps":["trace[1229455509] 'process raft request' (duration: 471.187288ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:16:58.717272Z","caller":"traceutil/trace.go:171","msg":"trace[172240291] transaction","detail":"{read_only:false; response_revision:1931; number_of_response:1; }","duration":"783.403853ms","start":"2026-06-09T22:16:57.933833Z","end":"2026-06-09T22:16:58.717237Z","steps":["trace[172240291] 'process raft request' (duration: 783.119343ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:16:58.71734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:16:58.245925Z","time spent":"471.363015ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:16:58.717355Z","caller":"traceutil/trace.go:171","msg":"trace[1014988084] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"783.762915ms","start":"2026-06-09T22:16:57.933563Z","end":"2026-06-09T22:16:58.717326Z","steps":["trace[1014988084] 'process raft request' (duration: 134.547723ms)","trace[1014988084] 'compare' (duration: 648.108432ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:16:58.717478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:16:57.933828Z","time spent":"783.542258ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:16:58.717663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:16:57.93353Z","time spent":"783.941442ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:16:58.717767Z","caller":"traceutil/trace.go:171","msg":"trace[1274882049] transaction","detail":"{read_only:false; response_revision:1932; number_of_response:1; }","duration":"782.944896ms","start":"2026-06-09T22:16:57.934729Z","end":"2026-06-09T22:16:58.717674Z","steps":["trace[1274882049] 'process raft request' (duration: 782.318983ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:16:58.717808Z","caller":"traceutil/trace.go:171","msg":"trace[1167535677] linearizableReadLoop","detail":"{readStateIndex:2013; appliedIndex:2007; }","duration":"768.96551ms","start":"2026-06-09T22:16:57.948819Z","end":"2026-06-09T22:16:58.717785Z","steps":["trace[1167535677] 'read index received' (duration: 119.222459ms)","trace[1167535677] 'applied index is now lower than readState.Index' (duration: 649.74061ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:16:58.71794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:16:57.934719Z","time spent":"783.130102ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:16:58.717996Z","caller":"traceutil/trace.go:171","msg":"trace[1069015675] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"784.155419ms","start":"2026-06-09T22:16:57.933793Z","end":"2026-06-09T22:16:58.717949Z","steps":["trace[1069015675] 'process raft request' (duration: 783.034079ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:16:58.718197Z","caller":"traceutil/trace.go:171","msg":"trace[544975483] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"784.378308ms","start":"2026-06-09T22:16:57.933804Z","end":"2026-06-09T22:16:58.718182Z","steps":["trace[544975483] 'process raft request' (duration: 783.090221ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:16:58.718094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.207008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"warn","ts":"2026-06-09T22:16:58.718233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:16:57.933787Z","time spent":"784.319865ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:16:58.718267Z","caller":"traceutil/trace.go:171","msg":"trace[683047341] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1933; }","duration":"769.485398ms","start":"2026-06-09T22:16:57.948763Z","end":"2026-06-09T22:16:58.718249Z","steps":["trace[683047341] 'agreement among raft nodes before linearized reading' (duration: 769.192078ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:16:58.718291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:16:57.933772Z","time spent":"784.479221ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:16:58.718381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:16:57.948738Z","time spent":"769.624473ms","remote":"127.0.0.1:49542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":443,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-06-09T22:16:58.719752Z","caller":"traceutil/trace.go:171","msg":"trace[1279783090] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"785.965336ms","start":"2026-06-09T22:16:57.933743Z","end":"2026-06-09T22:16:58.719708Z","steps":["trace[1279783090] 'process raft request' (duration: 783.009318ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:16:58.719844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:16:57.933736Z","time spent":"786.064009ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:16:58.720032Z","caller":"traceutil/trace.go:171","msg":"trace[120312147] transaction","detail":"{read_only:false; response_revision:1935; number_of_response:1; }","duration":"247.314506ms","start":"2026-06-09T22:16:58.472682Z","end":"2026-06-09T22:16:58.719996Z","steps":["trace[120312147] 'process raft request' (duration: 246.935163ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:16:58.720173Z","caller":"traceutil/trace.go:171","msg":"trace[598273148] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"367.378188ms","start":"2026-06-09T22:16:58.352782Z","end":"2026-06-09T22:16:58.72016Z","steps":["trace[598273148] 'process raft request' (duration: 366.398253ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:16:58.720254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:16:58.352751Z","time spent":"367.458831ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:16:58.720178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.953793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T22:16:58.720505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.227751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-06-09T22:16:58.720543Z","caller":"traceutil/trace.go:171","msg":"trace[741452750] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1935; }","duration":"652.302093ms","start":"2026-06-09T22:16:58.068231Z","end":"2026-06-09T22:16:58.720533Z","steps":["trace[741452750] 'agreement among raft nodes before linearized reading' (duration: 652.176599ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:16:58.720574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:16:58.068208Z","time spent":"652.360696ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":551,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-06-09T22:16:58.720508Z","caller":"traceutil/trace.go:171","msg":"trace[2144721865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1935; }","duration":"747.429171ms","start":"2026-06-09T22:16:57.973053Z","end":"2026-06-09T22:16:58.720482Z","steps":["trace[2144721865] 'agreement among raft nodes before linearized reading' (duration: 746.658903ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:16:58.720861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:16:57.973024Z","time spent":"747.817995ms","remote":"127.0.0.1:49380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T22:16:58.720374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.584063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:16:58.721286Z","caller":"traceutil/trace.go:171","msg":"trace[1650837574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1935; }","duration":"171.532977ms","start":"2026-06-09T22:16:58.549729Z","end":"2026-06-09T22:16:58.721262Z","steps":["trace[1650837574] 'agreement among raft nodes before linearized reading' (duration: 170.611884ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:17:13.93391Z","caller":"traceutil/trace.go:171","msg":"trace[415270863] linearizableReadLoop","detail":"{readStateIndex:2145; appliedIndex:2144; }","duration":"172.55955ms","start":"2026-06-09T22:17:13.761326Z","end":"2026-06-09T22:17:13.933886Z","steps":["trace[415270863] 'read index received' (duration: 172.392103ms)","trace[415270863] 'applied index is now lower than readState.Index' (duration: 166.347µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:17:13.934175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.793828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-06-09T22:17:13.934212Z","caller":"traceutil/trace.go:171","msg":"trace[1682821212] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2060; }","duration":"172.930054ms","start":"2026-06-09T22:17:13.761269Z","end":"2026-06-09T22:17:13.934199Z","steps":["trace[1682821212] 'agreement among raft nodes before linearized reading' (duration: 172.713786ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:17:13.93447Z","caller":"traceutil/trace.go:171","msg":"trace[948794704] transaction","detail":"{read_only:false; response_revision:2060; number_of_response:1; }","duration":"189.35831ms","start":"2026-06-09T22:17:13.745053Z","end":"2026-06-09T22:17:13.934411Z","steps":["trace[948794704] 'process raft request' (duration: 188.670493ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:17:14.259031Z","caller":"traceutil/trace.go:171","msg":"trace[562722769] transaction","detail":"{read_only:false; response_revision:2062; number_of_response:1; }","duration":"273.572587ms","start":"2026-06-09T22:17:13.985417Z","end":"2026-06-09T22:17:14.25899Z","steps":["trace[562722769] 'process raft request' (duration: 259.919997ms)","trace[562722769] 'compare' (duration: 13.030395ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:17:14.259146Z","caller":"traceutil/trace.go:171","msg":"trace[1060643804] linearizableReadLoop","detail":"{readStateIndex:2147; appliedIndex:2146; }","duration":"188.227197ms","start":"2026-06-09T22:17:14.070905Z","end":"2026-06-09T22:17:14.259133Z","steps":["trace[1060643804] 'read index received' (duration: 174.454133ms)","trace[1060643804] 'applied index is now lower than readState.Index' (duration: 13.771264ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:17:14.259289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.368043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-09T22:17:14.259324Z","caller":"traceutil/trace.go:171","msg":"trace[98617338] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2062; }","duration":"188.463487ms","start":"2026-06-09T22:17:14.070852Z","end":"2026-06-09T22:17:14.259315Z","steps":["trace[98617338] 'agreement among raft nodes before linearized reading' (duration: 188.324211ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:17:37.09082Z","caller":"traceutil/trace.go:171","msg":"trace[583061016] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"177.482857ms","start":"2026-06-09T22:17:36.913308Z","end":"2026-06-09T22:17:37.09079Z","steps":["trace[583061016] 'process raft request' (duration: 177.31301ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:17:37.110277Z","caller":"traceutil/trace.go:171","msg":"trace[1382020714] linearizableReadLoop","detail":"{readStateIndex:2278; appliedIndex:2276; }","duration":"172.721446ms","start":"2026-06-09T22:17:36.937501Z","end":"2026-06-09T22:17:37.110223Z","steps":["trace[1382020714] 'read index received' (duration: 153.465941ms)","trace[1382020714] 'applied index is now lower than readState.Index' (duration: 19.253305ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:17:37.1107Z","caller":"traceutil/trace.go:171","msg":"trace[264503168] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"195.734758ms","start":"2026-06-09T22:17:36.914944Z","end":"2026-06-09T22:17:37.110678Z","steps":["trace[264503168] 'process raft request' (duration: 195.088201ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:17:37.111155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.617153ms","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-06-09T22:17:37.111237Z","caller":"traceutil/trace.go:171","msg":"trace[1475630109] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2187; }","duration":"173.745869ms","start":"2026-06-09T22:17:36.937473Z","end":"2026-06-09T22:17:37.111219Z","steps":["trace[1475630109] 'agreement among raft nodes before linearized reading' (duration: 173.252468ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:17:37.481268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.595256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:17:37.481421Z","caller":"traceutil/trace.go:171","msg":"trace[234001944] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2194; }","duration":"143.824326ms","start":"2026-06-09T22:17:37.337569Z","end":"2026-06-09T22:17:37.481393Z","steps":["trace[234001944] 'count revisions from in-memory index tree' (duration: 143.450549ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:17:37.481857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.450115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T22:17:37.481902Z","caller":"traceutil/trace.go:171","msg":"trace[2092959097] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:2194; }","duration":"152.540668ms","start":"2026-06-09T22:17:37.329348Z","end":"2026-06-09T22:17:37.481889Z","steps":["trace[2092959097] 'count revisions from in-memory index tree' (duration: 152.340749ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:17:38.572416Z","caller":"traceutil/trace.go:171","msg":"trace[1405510373] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"232.665606ms","start":"2026-06-09T22:17:38.33972Z","end":"2026-06-09T22:17:38.572386Z","steps":["trace[1405510373] 'process raft request' (duration: 232.486638ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:17:39.563308Z","caller":"traceutil/trace.go:171","msg":"trace[1579451756] linearizableReadLoop","detail":"{readStateIndex:2289; appliedIndex:2288; }","duration":"384.086349ms","start":"2026-06-09T22:17:39.179175Z","end":"2026-06-09T22:17:39.563261Z","steps":["trace[1579451756] 'read index received' (duration: 316.088383ms)","trace[1579451756] 'applied index is now lower than readState.Index' (duration: 67.996226ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:17:39.563361Z","caller":"traceutil/trace.go:171","msg":"trace[1950383954] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"445.435739ms","start":"2026-06-09T22:17:39.117876Z","end":"2026-06-09T22:17:39.563311Z","steps":["trace[1950383954] 'process raft request' (duration: 377.298977ms)","trace[1950383954] 'compare' (duration: 67.756696ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:17:39.56358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:17:39.117838Z","time spent":"445.615197ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:17:39.563607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.403653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-09T22:17:39.56368Z","caller":"traceutil/trace.go:171","msg":"trace[1850629580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2198; }","duration":"384.526649ms","start":"2026-06-09T22:17:39.179134Z","end":"2026-06-09T22:17:39.56366Z","steps":["trace[1850629580] 'agreement among raft nodes before linearized reading' (duration: 384.300338ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:17:39.563737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:17:39.179117Z","time spent":"384.607003ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-09T22:17:39.570824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.886669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-09T22:17:39.570914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.451454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:17:39.57092Z","caller":"traceutil/trace.go:171","msg":"trace[1818968578] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2199; }","duration":"389.984582ms","start":"2026-06-09T22:17:39.180902Z","end":"2026-06-09T22:17:39.570886Z","steps":["trace[1818968578] 'agreement among raft nodes before linearized reading' (duration: 389.766983ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:17:39.570961Z","caller":"traceutil/trace.go:171","msg":"trace[1607137226] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:2199; }","duration":"344.501056ms","start":"2026-06-09T22:17:39.226448Z","end":"2026-06-09T22:17:39.570949Z","steps":["trace[1607137226] 'agreement among raft nodes before linearized reading' (duration: 344.416472ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:17:39.57098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:17:39.180893Z","time spent":"390.077567ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-09T22:17:39.570999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:17:39.226349Z","time spent":"344.640642ms","remote":"127.0.0.1:49650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-06-09T22:17:39.571185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.874785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-09T22:17:39.571307Z","caller":"traceutil/trace.go:171","msg":"trace[633491653] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2199; }","duration":"392.064012ms","start":"2026-06-09T22:17:39.179215Z","end":"2026-06-09T22:17:39.571279Z","steps":["trace[633491653] 'agreement among raft nodes before linearized reading' (duration: 391.225476ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:17:39.571986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:17:39.179209Z","time spent":"392.67734ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-09T22:17:39.570799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.638957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-06-09T22:17:39.571492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.698606ms","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-06-09T22:17:39.572519Z","caller":"traceutil/trace.go:171","msg":"trace[31567146] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2199; }","duration":"392.760422ms","start":"2026-06-09T22:17:39.179748Z","end":"2026-06-09T22:17:39.572508Z","steps":["trace[31567146] 'agreement among raft nodes before linearized reading' (duration: 391.642944ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:17:39.572622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:17:39.179739Z","time spent":"392.871027ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-09T22:17:39.571583Z","caller":"traceutil/trace.go:171","msg":"trace[968885231] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"383.855919ms","start":"2026-06-09T22:17:39.186769Z","end":"2026-06-09T22:17:39.570624Z","steps":["trace[968885231] 'process raft request' (duration: 383.501794ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:17:39.572338Z","caller":"traceutil/trace.go:171","msg":"trace[498518270] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2199; }","duration":"391.222946ms","start":"2026-06-09T22:17:39.181097Z","end":"2026-06-09T22:17:39.57232Z","steps":["trace[498518270] 'agreement among raft nodes before linearized reading' (duration: 389.420759ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:17:39.572867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:17:39.181086Z","time spent":"391.770299ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-09T22:17:39.572902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:17:39.186757Z","time spent":"386.053643ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:17:39.952445Z","caller":"traceutil/trace.go:171","msg":"trace[471848417] linearizableReadLoop","detail":"{readStateIndex:2296; appliedIndex:2293; }","duration":"336.389557ms","start":"2026-06-09T22:17:39.616024Z","end":"2026-06-09T22:17:39.952413Z","steps":["trace[471848417] 'read index received' (duration: 332.384525ms)","trace[471848417] 'applied index is now lower than readState.Index' (duration: 4.003511ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:17:39.952461Z","caller":"traceutil/trace.go:171","msg":"trace[836790406] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"375.394686ms","start":"2026-06-09T22:17:39.57702Z","end":"2026-06-09T22:17:39.952415Z","steps":["trace[836790406] 'process raft request' (duration: 371.502018ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:17:39.952491Z","caller":"traceutil/trace.go:171","msg":"trace[1128404276] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"375.012909ms","start":"2026-06-09T22:17:39.57744Z","end":"2026-06-09T22:17:39.952452Z","steps":["trace[1128404276] 'process raft request' (duration: 374.782779ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:17:39.952615Z","caller":"traceutil/trace.go:171","msg":"trace[2024278012] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"374.075258ms","start":"2026-06-09T22:17:39.578497Z","end":"2026-06-09T22:17:39.952572Z","steps":["trace[2024278012] 'process raft request' (duration: 373.859089ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:17:39.953682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:17:39.577425Z","time spent":"376.145449ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:17:39.953673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:17:39.576972Z","time spent":"376.278404ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:17:39.952779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.322787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-09T22:17:39.954053Z","caller":"traceutil/trace.go:171","msg":"trace[1738613160] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2205; }","duration":"374.637463ms","start":"2026-06-09T22:17:39.579399Z","end":"2026-06-09T22:17:39.954036Z","steps":["trace[1738613160] 'agreement among raft nodes before linearized reading' (duration: 373.17804ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:17:39.954092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:17:39.579389Z","time spent":"374.689676ms","remote":"127.0.0.1:46752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-09T22:17:39.953834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:17:39.578487Z","time spent":"375.242319ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:18:27.45203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.907671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-09T22:18:27.452142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.326462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-09T22:18:27.45217Z","caller":"traceutil/trace.go:171","msg":"trace[881708877] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2503; }","duration":"176.11187ms","start":"2026-06-09T22:18:27.276025Z","end":"2026-06-09T22:18:27.452136Z","steps":["trace[881708877] 'count revisions from in-memory index tree' (duration: 175.753294ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:18:27.452219Z","caller":"traceutil/trace.go:171","msg":"trace[1097956683] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2503; }","duration":"195.454188ms","start":"2026-06-09T22:18:27.256746Z","end":"2026-06-09T22:18:27.452201Z","steps":["trace[1097956683] 'range keys from in-memory index tree' (duration: 195.079831ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:18:27.455895Z","caller":"traceutil/trace.go:171","msg":"trace[120226465] transaction","detail":"{read_only:false; response_revision:2504; number_of_response:1; }","duration":"116.674032ms","start":"2026-06-09T22:18:27.3392Z","end":"2026-06-09T22:18:27.455874Z","steps":["trace[120226465] 'process raft request' (duration: 116.435341ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:18:36.78674Z","caller":"traceutil/trace.go:171","msg":"trace[500343110] linearizableReadLoop","detail":"{readStateIndex:2717; appliedIndex:2716; }","duration":"237.240666ms","start":"2026-06-09T22:18:36.549471Z","end":"2026-06-09T22:18:36.786712Z","steps":["trace[500343110] 'read index received' (duration: 237.11149ms)","trace[500343110] 'applied index is now lower than readState.Index' (duration: 127.666µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:18:36.786799Z","caller":"traceutil/trace.go:171","msg":"trace[798604813] transaction","detail":"{read_only:false; response_revision:2613; number_of_response:1; }","duration":"262.307803ms","start":"2026-06-09T22:18:36.524448Z","end":"2026-06-09T22:18:36.786755Z","steps":["trace[798604813] 'process raft request' (duration: 262.058081ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:36.786982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.473887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:18:36.787103Z","caller":"traceutil/trace.go:171","msg":"trace[559707471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2613; }","duration":"237.639514ms","start":"2026-06-09T22:18:36.549436Z","end":"2026-06-09T22:18:36.787076Z","steps":["trace[559707471] 'agreement among raft nodes before linearized reading' (duration: 237.407373ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:36.787103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.310535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:18:36.787793Z","caller":"traceutil/trace.go:171","msg":"trace[1300098517] 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:2613; }","duration":"160.083921ms","start":"2026-06-09T22:18:36.627678Z","end":"2026-06-09T22:18:36.787762Z","steps":["trace[1300098517] 'agreement among raft nodes before linearized reading' (duration: 159.287744ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:18:38.004526Z","caller":"traceutil/trace.go:171","msg":"trace[1092927191] transaction","detail":"{read_only:false; response_revision:2616; number_of_response:1; }","duration":"513.189879ms","start":"2026-06-09T22:18:37.491308Z","end":"2026-06-09T22:18:38.004498Z","steps":["trace[1092927191] 'process raft request' (duration: 512.668483ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:38.004633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:18:37.491282Z","time spent":"513.291183ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:18:38.0048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.120596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-09T22:18:38.004918Z","caller":"traceutil/trace.go:171","msg":"trace[2000893690] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2616; }","duration":"366.295205ms","start":"2026-06-09T22:18:37.638608Z","end":"2026-06-09T22:18:38.004903Z","steps":["trace[2000893690] 'agreement among raft nodes before linearized reading' (duration: 365.991951ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:38.004953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:18:37.638581Z","time spent":"366.361728ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-06-09T22:18:38.004343Z","caller":"traceutil/trace.go:171","msg":"trace[762176381] linearizableReadLoop","detail":"{readStateIndex:2721; appliedIndex:2720; }","duration":"365.660745ms","start":"2026-06-09T22:18:37.638664Z","end":"2026-06-09T22:18:38.004325Z","steps":["trace[762176381] 'read index received' (duration: 365.547288ms)","trace[762176381] 'applied index is now lower than readState.Index' (duration: 112.697µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:18:38.020762Z","caller":"traceutil/trace.go:171","msg":"trace[912315012] transaction","detail":"{read_only:false; response_revision:2617; number_of_response:1; }","duration":"215.56693ms","start":"2026-06-09T22:18:37.805179Z","end":"2026-06-09T22:18:38.020746Z","steps":["trace[912315012] 'process raft request' (duration: 215.437134ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:38.021106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.350043ms","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-06-09T22:18:38.021153Z","caller":"traceutil/trace.go:171","msg":"trace[791176377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2618; }","duration":"137.433807ms","start":"2026-06-09T22:18:37.883708Z","end":"2026-06-09T22:18:38.021142Z","steps":["trace[791176377] 'agreement among raft nodes before linearized reading' (duration: 137.336241ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:18:38.021154Z","caller":"traceutil/trace.go:171","msg":"trace[550038346] transaction","detail":"{read_only:false; response_revision:2618; number_of_response:1; }","duration":"213.677661ms","start":"2026-06-09T22:18:37.807467Z","end":"2026-06-09T22:18:38.021144Z","steps":["trace[550038346] 'process raft request' (duration: 213.2531ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:18:38.021119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.528055ms","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-06-09T22:18:38.021275Z","caller":"traceutil/trace.go:171","msg":"trace[1745290961] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2618; }","duration":"116.731715ms","start":"2026-06-09T22:18:37.904535Z","end":"2026-06-09T22:18:38.021267Z","steps":["trace[1745290961] 'agreement among raft nodes before linearized reading' (duration: 116.501753ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:19:08.77446Z","caller":"traceutil/trace.go:171","msg":"trace[141047393] linearizableReadLoop","detail":"{readStateIndex:2901; appliedIndex:2899; }","duration":"224.323187ms","start":"2026-06-09T22:19:08.550119Z","end":"2026-06-09T22:19:08.774442Z","steps":["trace[141047393] 'read index received' (duration: 153.875688ms)","trace[141047393] 'applied index is now lower than readState.Index' (duration: 70.446499ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:19:08.774577Z","caller":"traceutil/trace.go:171","msg":"trace[1372939439] transaction","detail":"{read_only:false; response_revision:2790; number_of_response:1; }","duration":"251.935088ms","start":"2026-06-09T22:19:08.522631Z","end":"2026-06-09T22:19:08.774566Z","steps":["trace[1372939439] 'process raft request' (duration: 251.775061ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:19:08.77454Z","caller":"traceutil/trace.go:171","msg":"trace[1304183011] transaction","detail":"{read_only:false; response_revision:2789; number_of_response:1; }","duration":"252.783459ms","start":"2026-06-09T22:19:08.521706Z","end":"2026-06-09T22:19:08.77449Z","steps":["trace[1304183011] 'process raft request' (duration: 182.263866ms)","trace[1304183011] 'compare' (duration: 70.270051ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:19:08.775256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.492503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-09T22:19:08.775319Z","caller":"traceutil/trace.go:171","msg":"trace[1782205855] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2790; }","duration":"178.604298ms","start":"2026-06-09T22:19:08.596699Z","end":"2026-06-09T22:19:08.775303Z","steps":["trace[1782205855] 'agreement among raft nodes before linearized reading' (duration: 178.393788ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:19:08.775378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.454412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T22:19:08.775492Z","caller":"traceutil/trace.go:171","msg":"trace[2052881907] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2790; }","duration":"178.600419ms","start":"2026-06-09T22:19:08.596867Z","end":"2026-06-09T22:19:08.775468Z","steps":["trace[2052881907] 'agreement among raft nodes before linearized reading' (duration: 178.323814ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:19:08.775345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.80218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:19:08.775941Z","caller":"traceutil/trace.go:171","msg":"trace[551825290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2790; }","duration":"225.913925ms","start":"2026-06-09T22:19:08.550018Z","end":"2026-06-09T22:19:08.775932Z","steps":["trace[551825290] 'agreement among raft nodes before linearized reading' (duration: 224.833062ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:21:01.50297Z","caller":"traceutil/trace.go:171","msg":"trace[233193924] transaction","detail":"{read_only:false; response_revision:3386; number_of_response:1; }","duration":"100.529919ms","start":"2026-06-09T22:21:01.402421Z","end":"2026-06-09T22:21:01.502951Z","steps":["trace[233193924] 'process raft request' (duration: 100.500298ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:21:01.503277Z","caller":"traceutil/trace.go:171","msg":"trace[235281314] transaction","detail":"{read_only:false; response_revision:3385; number_of_response:1; }","duration":"101.113608ms","start":"2026-06-09T22:21:01.402155Z","end":"2026-06-09T22:21:01.503268Z","steps":["trace[235281314] 'process raft request' (duration: 49.934224ms)","trace[235281314] 'compare' (duration: 50.747417ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:21:37.911606Z","caller":"traceutil/trace.go:171","msg":"trace[2101860912] linearizableReadLoop","detail":"{readStateIndex:3808; appliedIndex:3807; }","duration":"370.241287ms","start":"2026-06-09T22:21:37.541342Z","end":"2026-06-09T22:21:37.911584Z","steps":["trace[2101860912] 'read index received' (duration: 370.021282ms)","trace[2101860912] 'applied index is now lower than readState.Index' (duration: 219.225µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:21:37.911744Z","caller":"traceutil/trace.go:171","msg":"trace[250292853] transaction","detail":"{read_only:false; response_revision:3662; number_of_response:1; }","duration":"378.304293ms","start":"2026-06-09T22:21:37.533393Z","end":"2026-06-09T22:21:37.911697Z","steps":["trace[250292853] 'process raft request' (duration: 378.046497ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:21:37.911844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.493262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-alertmanager-client-secret\" ","response":"range_response_count:1 size:698"} {"level":"info","ts":"2026-06-09T22:21:37.911894Z","caller":"traceutil/trace.go:171","msg":"trace[1643939596] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-alertmanager-client-secret; range_end:; response_count:1; response_revision:3662; }","duration":"370.596575ms","start":"2026-06-09T22:21:37.541284Z","end":"2026-06-09T22:21:37.911881Z","steps":["trace[1643939596] 'agreement among raft nodes before linearized reading' (duration: 370.436931ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:21:37.91193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:21:37.54126Z","time spent":"370.662227ms","remote":"127.0.0.1:49486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":720,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-alertmanager-client-secret\" "} {"level":"warn","ts":"2026-06-09T22:21:37.912009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.947852ms","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-06-09T22:21:37.912063Z","caller":"traceutil/trace.go:171","msg":"trace[363988146] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3662; }","duration":"252.042695ms","start":"2026-06-09T22:21:37.660008Z","end":"2026-06-09T22:21:37.91205Z","steps":["trace[363988146] 'agreement among raft nodes before linearized reading' (duration: 251.918542ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:21:37.912374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.568544ms","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-06-09T22:21:37.912522Z","caller":"traceutil/trace.go:171","msg":"trace[1010774586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3662; }","duration":"234.796749ms","start":"2026-06-09T22:21:37.677694Z","end":"2026-06-09T22:21:37.91249Z","steps":["trace[1010774586] 'agreement among raft nodes before linearized reading' (duration: 234.276467ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:21:37.912566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:21:37.533369Z","time spent":"378.471236ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:21:43.195805Z","caller":"traceutil/trace.go:171","msg":"trace[1929745346] linearizableReadLoop","detail":"{readStateIndex:3835; appliedIndex:3834; }","duration":"207.885223ms","start":"2026-06-09T22:21:42.987899Z","end":"2026-06-09T22:21:43.195784Z","steps":["trace[1929745346] 'read index received' (duration: 207.697488ms)","trace[1929745346] 'applied index is now lower than readState.Index' (duration: 186.665µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:21:43.195996Z","caller":"traceutil/trace.go:171","msg":"trace[1749578826] transaction","detail":"{read_only:false; response_revision:3688; number_of_response:1; }","duration":"228.509152ms","start":"2026-06-09T22:21:42.967472Z","end":"2026-06-09T22:21:43.195981Z","steps":["trace[1749578826] 'process raft request' (duration: 228.150014ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:21:43.196313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.412636ms","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-06-09T22:21:43.196381Z","caller":"traceutil/trace.go:171","msg":"trace[1179341475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3688; }","duration":"208.519627ms","start":"2026-06-09T22:21:42.987843Z","end":"2026-06-09T22:21:43.196363Z","steps":["trace[1179341475] 'agreement among raft nodes before linearized reading' (duration: 208.412605ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:21:48.118088Z","caller":"traceutil/trace.go:171","msg":"trace[1421818743] transaction","detail":"{read_only:false; response_revision:3714; number_of_response:1; }","duration":"125.324345ms","start":"2026-06-09T22:21:47.992726Z","end":"2026-06-09T22:21:48.11805Z","steps":["trace[1421818743] 'process raft request' (duration: 80.45392ms)","trace[1421818743] 'compare' (duration: 44.681581ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:21:48.118444Z","caller":"traceutil/trace.go:171","msg":"trace[10213068] transaction","detail":"{read_only:false; response_revision:3715; number_of_response:1; }","duration":"124.629557ms","start":"2026-06-09T22:21:47.993788Z","end":"2026-06-09T22:21:48.118418Z","steps":["trace[10213068] 'process raft request' (duration: 124.220997ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:21:55.435219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.229029ms","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-06-09T22:21:55.435311Z","caller":"traceutil/trace.go:171","msg":"trace[1965786426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3752; }","duration":"139.375983ms","start":"2026-06-09T22:21:55.295916Z","end":"2026-06-09T22:21:55.435292Z","steps":["trace[1965786426] 'range keys from in-memory index tree' (duration: 139.058134ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:21:55.435526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.540717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-cookie-secret\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:21:55.435624Z","caller":"traceutil/trace.go:171","msg":"trace[591906074] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-cookie-secret; range_end:; response_count:0; response_revision:3752; }","duration":"146.742652ms","start":"2026-06-09T22:21:55.288859Z","end":"2026-06-09T22:21:55.435602Z","steps":["trace[591906074] 'range keys from in-memory index tree' (duration: 146.420103ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:21:55.608031Z","caller":"traceutil/trace.go:171","msg":"trace[1362916501] linearizableReadLoop","detail":"{readStateIndex:3903; appliedIndex:3901; }","duration":"165.565244ms","start":"2026-06-09T22:21:55.44244Z","end":"2026-06-09T22:21:55.608005Z","steps":["trace[1362916501] 'read index received' (duration: 10.345504ms)","trace[1362916501] 'applied index is now lower than readState.Index' (duration: 155.21877ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:21:55.608182Z","caller":"traceutil/trace.go:171","msg":"trace[1852672096] transaction","detail":"{read_only:false; response_revision:3754; number_of_response:1; }","duration":"167.018412ms","start":"2026-06-09T22:21:55.441129Z","end":"2026-06-09T22:21:55.608147Z","steps":["trace[1852672096] 'process raft request' (duration: 118.109798ms)","trace[1852672096] 'compare' (duration: 48.657478ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:21:55.608226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.756999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/monitoring/\" range_end:\"/registry/resourcequotas/monitoring0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:21:55.608296Z","caller":"traceutil/trace.go:171","msg":"trace[2051415401] range","detail":"{range_begin:/registry/resourcequotas/monitoring/; range_end:/registry/resourcequotas/monitoring0; response_count:0; response_revision:3754; }","duration":"165.868441ms","start":"2026-06-09T22:21:55.442406Z","end":"2026-06-09T22:21:55.608274Z","steps":["trace[2051415401] 'agreement among raft nodes before linearized reading' (duration: 165.752968ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:21:55.608496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.013852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T22:21:55.608618Z","caller":"traceutil/trace.go:171","msg":"trace[108695040] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:3754; }","duration":"128.205477ms","start":"2026-06-09T22:21:55.480393Z","end":"2026-06-09T22:21:55.608598Z","steps":["trace[108695040] 'agreement among raft nodes before linearized reading' (duration: 127.914599ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:21:55.839723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.776209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T22:21:55.839818Z","caller":"traceutil/trace.go:171","msg":"trace[1870900174] linearizableReadLoop","detail":"{readStateIndex:3906; appliedIndex:3905; }","duration":"130.629988ms","start":"2026-06-09T22:21:55.709172Z","end":"2026-06-09T22:21:55.839802Z","steps":["trace[1870900174] 'read index received' (duration: 12.710656ms)","trace[1870900174] 'applied index is now lower than readState.Index' (duration: 117.917822ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:21:55.839881Z","caller":"traceutil/trace.go:171","msg":"trace[1938645020] transaction","detail":"{read_only:false; response_revision:3757; number_of_response:1; }","duration":"145.733256ms","start":"2026-06-09T22:21:55.694111Z","end":"2026-06-09T22:21:55.839844Z","steps":["trace[1938645020] 'process raft request' (duration: 27.753721ms)","trace[1938645020] 'compare' (duration: 117.46197ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:21:55.839924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.742261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T22:21:55.839966Z","caller":"traceutil/trace.go:171","msg":"trace[1370939608] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:3757; }","duration":"130.788892ms","start":"2026-06-09T22:21:55.709167Z","end":"2026-06-09T22:21:55.839956Z","steps":["trace[1370939608] 'agreement among raft nodes before linearized reading' (duration: 130.67748ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:21:58.387084Z","caller":"traceutil/trace.go:171","msg":"trace[516244749] transaction","detail":"{read_only:false; response_revision:3771; number_of_response:1; }","duration":"150.281805ms","start":"2026-06-09T22:21:58.236758Z","end":"2026-06-09T22:21:58.38704Z","steps":["trace[516244749] 'process raft request' (duration: 150.226153ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:21:58.387164Z","caller":"traceutil/trace.go:171","msg":"trace[294428508] transaction","detail":"{read_only:false; response_revision:3770; number_of_response:1; }","duration":"151.320111ms","start":"2026-06-09T22:21:58.235804Z","end":"2026-06-09T22:21:58.387124Z","steps":["trace[294428508] 'process raft request' (duration: 109.702267ms)","trace[294428508] 'compare' (duration: 41.273475ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:22:12.417832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.692257ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-09T22:22:13.507833Z","caller":"traceutil/trace.go:171","msg":"trace[892603369] transaction","detail":"{read_only:false; response_revision:3883; number_of_response:1; }","duration":"110.571882ms","start":"2026-06-09T22:22:13.397232Z","end":"2026-06-09T22:22:13.507804Z","steps":["trace[892603369] 'process raft request' (duration: 96.195069ms)","trace[892603369] 'compare' (duration: 14.222768ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:22:45.854523Z","caller":"traceutil/trace.go:171","msg":"trace[1504391995] linearizableReadLoop","detail":"{readStateIndex:4268; appliedIndex:4267; }","duration":"123.020119ms","start":"2026-06-09T22:22:45.731484Z","end":"2026-06-09T22:22:45.854505Z","steps":["trace[1504391995] 'read index received' (duration: 122.831073ms)","trace[1504391995] 'applied index is now lower than readState.Index' (duration: 188.216µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:22:45.854606Z","caller":"traceutil/trace.go:171","msg":"trace[830931864] transaction","detail":"{read_only:false; response_revision:4094; number_of_response:1; }","duration":"132.336911ms","start":"2026-06-09T22:22:45.722233Z","end":"2026-06-09T22:22:45.85457Z","steps":["trace[830931864] 'process raft request' (duration: 132.139025ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:22:45.854639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.142333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:22:45.8548Z","caller":"traceutil/trace.go:171","msg":"trace[1790833279] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:4094; }","duration":"123.36461ms","start":"2026-06-09T22:22:45.731427Z","end":"2026-06-09T22:22:45.854792Z","steps":["trace[1790833279] 'agreement among raft nodes before linearized reading' (duration: 123.161294ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:22:46.121666Z","caller":"traceutil/trace.go:171","msg":"trace[1797956820] transaction","detail":"{read_only:false; response_revision:4096; number_of_response:1; }","duration":"105.317475ms","start":"2026-06-09T22:22:46.016319Z","end":"2026-06-09T22:22:46.121636Z","steps":["trace[1797956820] 'process raft request' (duration: 37.374556ms)","trace[1797956820] 'compare' (duration: 67.811264ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:22:56.226252Z","caller":"traceutil/trace.go:171","msg":"trace[1080620480] linearizableReadLoop","detail":"{readStateIndex:4347; appliedIndex:4346; }","duration":"155.695239ms","start":"2026-06-09T22:22:56.070536Z","end":"2026-06-09T22:22:56.226231Z","steps":["trace[1080620480] 'read index received' (duration: 155.478041ms)","trace[1080620480] 'applied index is now lower than readState.Index' (duration: 216.278µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:22:56.226391Z","caller":"traceutil/trace.go:171","msg":"trace[230130129] transaction","detail":"{read_only:false; response_revision:4170; number_of_response:1; }","duration":"230.272082ms","start":"2026-06-09T22:22:55.996105Z","end":"2026-06-09T22:22:56.226377Z","steps":["trace[230130129] 'process raft request' (duration: 229.973732ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:22:56.226664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.36243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:22:56.226755Z","caller":"traceutil/trace.go:171","msg":"trace[2110784525] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:4170; }","duration":"139.501995ms","start":"2026-06-09T22:22:56.087231Z","end":"2026-06-09T22:22:56.226733Z","steps":["trace[2110784525] 'agreement among raft nodes before linearized reading' (duration: 139.33998ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:22:56.226807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.230297ms","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-06-09T22:22:56.226923Z","caller":"traceutil/trace.go:171","msg":"trace[262805984] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4170; }","duration":"156.434292ms","start":"2026-06-09T22:22:56.070464Z","end":"2026-06-09T22:22:56.226898Z","steps":["trace[262805984] 'agreement among raft nodes before linearized reading' (duration: 156.104692ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:00.268073Z","caller":"traceutil/trace.go:171","msg":"trace[69185393] transaction","detail":"{read_only:false; response_revision:4376; number_of_response:1; }","duration":"224.48709ms","start":"2026-06-09T22:23:00.043554Z","end":"2026-06-09T22:23:00.268042Z","steps":["trace[69185393] 'process raft request' (duration: 143.163101ms)","trace[69185393] 'compare' (duration: 81.131043ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:23:00.268173Z","caller":"traceutil/trace.go:171","msg":"trace[558240850] transaction","detail":"{read_only:false; response_revision:4377; number_of_response:1; }","duration":"224.553223ms","start":"2026-06-09T22:23:00.043601Z","end":"2026-06-09T22:23:00.268154Z","steps":["trace[558240850] 'process raft request' (duration: 224.370917ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:00.268249Z","caller":"traceutil/trace.go:171","msg":"trace[1761343206] transaction","detail":"{read_only:false; response_revision:4378; number_of_response:1; }","duration":"224.626335ms","start":"2026-06-09T22:23:00.043612Z","end":"2026-06-09T22:23:00.268239Z","steps":["trace[1761343206] 'process raft request' (duration: 224.388538ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:00.268458Z","caller":"traceutil/trace.go:171","msg":"trace[248549017] transaction","detail":"{read_only:false; response_revision:4379; number_of_response:1; }","duration":"224.78337ms","start":"2026-06-09T22:23:00.043651Z","end":"2026-06-09T22:23:00.268434Z","steps":["trace[248549017] 'process raft request' (duration: 224.450219ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:00.268567Z","caller":"traceutil/trace.go:171","msg":"trace[538690052] transaction","detail":"{read_only:false; response_revision:4380; number_of_response:1; }","duration":"224.426409ms","start":"2026-06-09T22:23:00.04413Z","end":"2026-06-09T22:23:00.268556Z","steps":["trace[538690052] 'process raft request' (duration: 223.994755ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:00.268711Z","caller":"traceutil/trace.go:171","msg":"trace[2008951535] transaction","detail":"{read_only:false; response_revision:4381; number_of_response:1; }","duration":"223.54632ms","start":"2026-06-09T22:23:00.045155Z","end":"2026-06-09T22:23:00.268701Z","steps":["trace[2008951535] 'process raft request' (duration: 223.372294ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:00.268723Z","caller":"traceutil/trace.go:171","msg":"trace[530605419] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4380; }","duration":"224.380288ms","start":"2026-06-09T22:23:00.044309Z","end":"2026-06-09T22:23:00.268689Z","steps":["trace[530605419] 'process raft request' (duration: 224.129359ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:00.269158Z","caller":"traceutil/trace.go:171","msg":"trace[1805418784] transaction","detail":"{read_only:false; response_revision:4384; number_of_response:1; }","duration":"215.406274ms","start":"2026-06-09T22:23:00.053735Z","end":"2026-06-09T22:23:00.269141Z","steps":["trace[1805418784] 'process raft request' (duration: 215.30743ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:00.269208Z","caller":"traceutil/trace.go:171","msg":"trace[1191050192] transaction","detail":"{read_only:false; response_revision:4383; number_of_response:1; }","duration":"221.138662ms","start":"2026-06-09T22:23:00.048061Z","end":"2026-06-09T22:23:00.2692Z","steps":["trace[1191050192] 'process raft request' (duration: 220.835952ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:00.269248Z","caller":"traceutil/trace.go:171","msg":"trace[1853431105] transaction","detail":"{read_only:false; response_revision:4382; number_of_response:1; }","duration":"222.839088ms","start":"2026-06-09T22:23:00.0464Z","end":"2026-06-09T22:23:00.269239Z","steps":["trace[1853431105] 'process raft request' (duration: 222.27263ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:00.269507Z","caller":"traceutil/trace.go:171","msg":"trace[1136391094] linearizableReadLoop","detail":"{readStateIndex:4566; appliedIndex:4553; }","duration":"190.338854ms","start":"2026-06-09T22:23:00.079154Z","end":"2026-06-09T22:23:00.269492Z","steps":["trace[1136391094] 'read index received' (duration: 1.450787ms)","trace[1136391094] 'applied index is now lower than readState.Index' (duration: 188.887097ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:23:00.269844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.677435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:23:00.272826Z","caller":"traceutil/trace.go:171","msg":"trace[1373029008] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:4386; }","duration":"193.702624ms","start":"2026-06-09T22:23:00.079107Z","end":"2026-06-09T22:23:00.27281Z","steps":["trace[1373029008] 'agreement among raft nodes before linearized reading' (duration: 190.427156ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:00.269888Z","caller":"traceutil/trace.go:171","msg":"trace[1087064439] transaction","detail":"{read_only:false; response_revision:4386; number_of_response:1; }","duration":"178.950462ms","start":"2026-06-09T22:23:00.090927Z","end":"2026-06-09T22:23:00.269877Z","steps":["trace[1087064439] 'process raft request' (duration: 178.400494ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:00.273056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.283444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-operator\" ","response":"range_response_count:1 size:1231"} {"level":"info","ts":"2026-06-09T22:23:00.273095Z","caller":"traceutil/trace.go:171","msg":"trace[1471177674] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-operator; range_end:; response_count:1; response_revision:4387; }","duration":"109.368697ms","start":"2026-06-09T22:23:00.163716Z","end":"2026-06-09T22:23:00.273085Z","steps":["trace[1471177674] 'agreement among raft nodes before linearized reading' (duration: 109.216312ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:00.270047Z","caller":"traceutil/trace.go:171","msg":"trace[458634957] transaction","detail":"{read_only:false; response_revision:4387; number_of_response:1; }","duration":"166.619588ms","start":"2026-06-09T22:23:00.103419Z","end":"2026-06-09T22:23:00.270039Z","steps":["trace[458634957] 'process raft request' (duration: 166.144082ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:00.269426Z","caller":"traceutil/trace.go:171","msg":"trace[1262986010] transaction","detail":"{read_only:false; response_revision:4385; number_of_response:1; }","duration":"215.417305ms","start":"2026-06-09T22:23:00.053998Z","end":"2026-06-09T22:23:00.269416Z","steps":["trace[1262986010] 'process raft request' (duration: 215.103154ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:00.273485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.706907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-kube-state-metrics\" ","response":"range_response_count:1 size:1104"} {"level":"info","ts":"2026-06-09T22:23:00.273521Z","caller":"traceutil/trace.go:171","msg":"trace[1667391402] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-kube-state-metrics; range_end:; response_count:1; response_revision:4387; }","duration":"109.78856ms","start":"2026-06-09T22:23:00.163722Z","end":"2026-06-09T22:23:00.27351Z","steps":["trace[1667391402] 'agreement among raft nodes before linearized reading' (duration: 109.684587ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:00.273904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.044329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-grafana\" ","response":"range_response_count:1 size:891"} {"level":"info","ts":"2026-06-09T22:23:00.273938Z","caller":"traceutil/trace.go:171","msg":"trace[1828724666] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-grafana; range_end:; response_count:1; response_revision:4387; }","duration":"110.09611ms","start":"2026-06-09T22:23:00.163834Z","end":"2026-06-09T22:23:00.27393Z","steps":["trace[1828724666] 'agreement among raft nodes before linearized reading' (duration: 109.79892ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:00.273935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.7751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus-node-exporter\" ","response":"range_response_count:1 size:1176"} {"level":"info","ts":"2026-06-09T22:23:00.273998Z","caller":"traceutil/trace.go:171","msg":"trace[905234618] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus-node-exporter; range_end:; response_count:1; response_revision:4387; }","duration":"110.151631ms","start":"2026-06-09T22:23:00.163828Z","end":"2026-06-09T22:23:00.273979Z","steps":["trace[905234618] 'agreement among raft nodes before linearized reading' (duration: 109.693127ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:01.774418Z","caller":"traceutil/trace.go:171","msg":"trace[1124336584] transaction","detail":"{read_only:false; response_revision:4523; number_of_response:1; }","duration":"115.29237ms","start":"2026-06-09T22:23:01.659098Z","end":"2026-06-09T22:23:01.774391Z","steps":["trace[1124336584] 'process raft request' (duration: 115.105244ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:01.77977Z","caller":"traceutil/trace.go:171","msg":"trace[2064131998] transaction","detail":"{read_only:false; response_revision:4524; number_of_response:1; }","duration":"112.008883ms","start":"2026-06-09T22:23:01.667741Z","end":"2026-06-09T22:23:01.77975Z","steps":["trace[2064131998] 'process raft request' (duration: 111.538837ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:03.146316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.775822ms","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-06-09T22:23:03.146326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.505135ms","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-06-09T22:23:03.146393Z","caller":"traceutil/trace.go:171","msg":"trace[228140946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4543; }","duration":"389.896026ms","start":"2026-06-09T22:23:02.756483Z","end":"2026-06-09T22:23:03.146379Z","steps":["trace[228140946] 'range keys from in-memory index tree' (duration: 389.585266ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:03.146417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:02.756464Z","time spent":"389.947378ms","remote":"127.0.0.1:49622","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":"info","ts":"2026-06-09T22:23:03.146411Z","caller":"traceutil/trace.go:171","msg":"trace[1668198661] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4543; }","duration":"393.621879ms","start":"2026-06-09T22:23:02.75277Z","end":"2026-06-09T22:23:03.146392Z","steps":["trace[1668198661] 'range keys from in-memory index tree' (duration: 393.340619ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:03.146491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:02.752754Z","time spent":"393.722092ms","remote":"127.0.0.1:49622","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-06-09T22:23:03.203383Z","caller":"traceutil/trace.go:171","msg":"trace[1347485145] transaction","detail":"{read_only:false; response_revision:4544; number_of_response:1; }","duration":"114.503112ms","start":"2026-06-09T22:23:03.088854Z","end":"2026-06-09T22:23:03.203358Z","steps":["trace[1347485145] 'process raft request' (duration: 114.319156ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:03.398178Z","caller":"traceutil/trace.go:171","msg":"trace[927760677] transaction","detail":"{read_only:false; response_revision:4545; number_of_response:1; }","duration":"254.763073ms","start":"2026-06-09T22:23:03.143381Z","end":"2026-06-09T22:23:03.398144Z","steps":["trace[927760677] 'process raft request' (duration: 254.411342ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:03.398364Z","caller":"traceutil/trace.go:171","msg":"trace[74982916] transaction","detail":"{read_only:false; response_revision:4547; number_of_response:1; }","duration":"246.353107ms","start":"2026-06-09T22:23:03.151985Z","end":"2026-06-09T22:23:03.398338Z","steps":["trace[74982916] 'process raft request' (duration: 246.127219ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:03.398437Z","caller":"traceutil/trace.go:171","msg":"trace[384021787] transaction","detail":"{read_only:false; response_revision:4546; number_of_response:1; }","duration":"247.801865ms","start":"2026-06-09T22:23:03.150571Z","end":"2026-06-09T22:23:03.398373Z","steps":["trace[384021787] 'process raft request' (duration: 247.473774ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:03.39861Z","caller":"traceutil/trace.go:171","msg":"trace[1323886109] transaction","detail":"{read_only:false; response_revision:4548; number_of_response:1; }","duration":"244.677052ms","start":"2026-06-09T22:23:03.153922Z","end":"2026-06-09T22:23:03.398599Z","steps":["trace[1323886109] 'process raft request' (duration: 244.242088ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:03.399351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.656473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-09T22:23:03.399389Z","caller":"traceutil/trace.go:171","msg":"trace[216849831] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4549; }","duration":"248.721725ms","start":"2026-06-09T22:23:03.150657Z","end":"2026-06-09T22:23:03.399379Z","steps":["trace[216849831] 'agreement among raft nodes before linearized reading' (duration: 248.57501ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:03.399389Z","caller":"traceutil/trace.go:171","msg":"trace[2047248624] linearizableReadLoop","detail":"{readStateIndex:4731; appliedIndex:4728; }","duration":"248.364284ms","start":"2026-06-09T22:23:03.150677Z","end":"2026-06-09T22:23:03.399041Z","steps":["trace[2047248624] 'read index received' (duration: 52.49562ms)","trace[2047248624] 'applied index is now lower than readState.Index' (duration: 195.867553ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:23:03.39923Z","caller":"traceutil/trace.go:171","msg":"trace[961718339] transaction","detail":"{read_only:false; response_revision:4549; number_of_response:1; }","duration":"215.123708ms","start":"2026-06-09T22:23:03.184043Z","end":"2026-06-09T22:23:03.399167Z","steps":["trace[961718339] 'process raft request' (duration: 214.186747ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:05.171978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.043094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2fb39b64-984f-4606-bc1c-8e34706769a5\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-06-09T22:23:05.172071Z","caller":"traceutil/trace.go:171","msg":"trace[1470826464] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2fb39b64-984f-4606-bc1c-8e34706769a5; range_end:; response_count:1; response_revision:4624; }","duration":"107.184159ms","start":"2026-06-09T22:23:05.064869Z","end":"2026-06-09T22:23:05.172054Z","steps":["trace[1470826464] 'range keys from in-memory index tree' (duration: 106.862789ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:05.17205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.692661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2fb39b64-984f-4606-bc1c-8e34706769a5\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-06-09T22:23:05.172142Z","caller":"traceutil/trace.go:171","msg":"trace[1505179467] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2fb39b64-984f-4606-bc1c-8e34706769a5; range_end:; response_count:1; response_revision:4624; }","duration":"128.836705ms","start":"2026-06-09T22:23:05.043284Z","end":"2026-06-09T22:23:05.172121Z","steps":["trace[1505179467] 'range keys from in-memory index tree' (duration: 128.505925ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:07.297869Z","caller":"traceutil/trace.go:171","msg":"trace[1091940508] linearizableReadLoop","detail":"{readStateIndex:4837; appliedIndex:4835; }","duration":"116.046761ms","start":"2026-06-09T22:23:07.1818Z","end":"2026-06-09T22:23:07.297847Z","steps":["trace[1091940508] 'read index received' (duration: 6.810747ms)","trace[1091940508] 'applied index is now lower than readState.Index' (duration: 109.235064ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:23:07.297942Z","caller":"traceutil/trace.go:171","msg":"trace[335911664] transaction","detail":"{read_only:false; response_revision:4651; number_of_response:1; }","duration":"119.484285ms","start":"2026-06-09T22:23:07.178429Z","end":"2026-06-09T22:23:07.297913Z","steps":["trace[335911664] 'process raft request' (duration: 119.280698ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:07.298285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.460224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2fb39b64-984f-4606-bc1c-8e34706769a5\" ","response":"range_response_count:1 size:4076"} {"level":"info","ts":"2026-06-09T22:23:07.298413Z","caller":"traceutil/trace.go:171","msg":"trace[765052399] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2fb39b64-984f-4606-bc1c-8e34706769a5; range_end:; response_count:1; response_revision:4651; }","duration":"116.61526ms","start":"2026-06-09T22:23:07.181749Z","end":"2026-06-09T22:23:07.298364Z","steps":["trace[765052399] 'agreement among raft nodes before linearized reading' (duration: 116.194726ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:07.320659Z","caller":"traceutil/trace.go:171","msg":"trace[1929622943] transaction","detail":"{read_only:false; response_revision:4653; number_of_response:1; }","duration":"102.090947ms","start":"2026-06-09T22:23:07.218539Z","end":"2026-06-09T22:23:07.32063Z","steps":["trace[1929622943] 'process raft request' (duration: 102.031775ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:07.320679Z","caller":"traceutil/trace.go:171","msg":"trace[1966095567] transaction","detail":"{read_only:false; response_revision:4652; number_of_response:1; }","duration":"123.563581ms","start":"2026-06-09T22:23:07.197088Z","end":"2026-06-09T22:23:07.320652Z","steps":["trace[1966095567] 'process raft request' (duration: 123.313863ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:08.580339Z","caller":"traceutil/trace.go:171","msg":"trace[1488061597] linearizableReadLoop","detail":"{readStateIndex:4875; appliedIndex:4874; }","duration":"176.655921ms","start":"2026-06-09T22:23:08.403657Z","end":"2026-06-09T22:23:08.580313Z","steps":["trace[1488061597] 'read index received' (duration: 114.672014ms)","trace[1488061597] 'applied index is now lower than readState.Index' (duration: 61.982497ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:23:08.58077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.75993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-09T22:23:08.580803Z","caller":"traceutil/trace.go:171","msg":"trace[26097778] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4688; }","duration":"100.858003ms","start":"2026-06-09T22:23:08.479938Z","end":"2026-06-09T22:23:08.580796Z","steps":["trace[26097778] 'agreement among raft nodes before linearized reading' (duration: 100.695508ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:08.580741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.045885ms","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-06-09T22:23:08.580875Z","caller":"traceutil/trace.go:171","msg":"trace[995440177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4688; }","duration":"177.223561ms","start":"2026-06-09T22:23:08.403621Z","end":"2026-06-09T22:23:08.580845Z","steps":["trace[995440177] 'agreement among raft nodes before linearized reading' (duration: 176.855128ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:08.581231Z","caller":"traceutil/trace.go:171","msg":"trace[797199984] transaction","detail":"{read_only:false; response_revision:4688; number_of_response:1; }","duration":"179.071822ms","start":"2026-06-09T22:23:08.402142Z","end":"2026-06-09T22:23:08.581214Z","steps":["trace[797199984] 'process raft request' (duration: 116.183134ms)","trace[797199984] 'compare' (duration: 61.804191ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:23:12.01731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.644576ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T22:23:12.017618Z","caller":"traceutil/trace.go:171","msg":"trace[2147329134] linearizableReadLoop","detail":"{readStateIndex:4951; appliedIndex:4949; }","duration":"493.484273ms","start":"2026-06-09T22:23:11.524109Z","end":"2026-06-09T22:23:12.017593Z","steps":["trace[2147329134] 'read index received' (duration: 262.408492ms)","trace[2147329134] 'applied index is now lower than readState.Index' (duration: 231.07397ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:23:12.017612Z","caller":"traceutil/trace.go:171","msg":"trace[483441409] transaction","detail":"{read_only:false; response_revision:4760; number_of_response:1; }","duration":"569.540725ms","start":"2026-06-09T22:23:11.448018Z","end":"2026-06-09T22:23:12.017559Z","steps":["trace[483441409] 'process raft request' (duration: 338.535417ms)","trace[483441409] 'compare' (duration: 230.416329ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:23:12.017627Z","caller":"traceutil/trace.go:171","msg":"trace[1721589667] transaction","detail":"{read_only:false; response_revision:4761; number_of_response:1; }","duration":"514.687964ms","start":"2026-06-09T22:23:11.502913Z","end":"2026-06-09T22:23:12.017601Z","steps":["trace[1721589667] 'process raft request' (duration: 514.5771ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:12.017783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:11.502887Z","time spent":"514.807098ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:23:12.017788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:11.447982Z","time spent":"569.710632ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:23:12.017833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.284993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:23:12.017892Z","caller":"traceutil/trace.go:171","msg":"trace[669382625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4761; }","duration":"467.394417ms","start":"2026-06-09T22:23:11.550483Z","end":"2026-06-09T22:23:12.017878Z","steps":["trace[669382625] 'agreement among raft nodes before linearized reading' (duration: 467.293184ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:12.017928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:11.550461Z","time spent":"467.458788ms","remote":"127.0.0.1:49380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T22:23:12.017993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.847698ms","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-06-09T22:23:12.018021Z","caller":"traceutil/trace.go:171","msg":"trace[1137172361] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4761; }","duration":"449.90238ms","start":"2026-06-09T22:23:11.568111Z","end":"2026-06-09T22:23:12.018014Z","steps":["trace[1137172361] 'agreement among raft nodes before linearized reading' (duration: 449.788796ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:12.018042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:11.568097Z","time spent":"449.93976ms","remote":"127.0.0.1:49622","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-06-09T22:23:12.018104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.918247ms","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-06-09T22:23:12.018215Z","caller":"traceutil/trace.go:171","msg":"trace[1386755677] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4761; }","duration":"494.156676ms","start":"2026-06-09T22:23:11.52404Z","end":"2026-06-09T22:23:12.018196Z","steps":["trace[1386755677] 'agreement among raft nodes before linearized reading' (duration: 493.67962ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:12.018301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:11.524009Z","time spent":"494.27959ms","remote":"127.0.0.1:49622","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-06-09T22:23:12.149284Z","caller":"traceutil/trace.go:171","msg":"trace[558710449] transaction","detail":"{read_only:false; response_revision:4764; number_of_response:1; }","duration":"125.746071ms","start":"2026-06-09T22:23:12.023516Z","end":"2026-06-09T22:23:12.149262Z","steps":["trace[558710449] 'process raft request' (duration: 125.700749ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:12.149426Z","caller":"traceutil/trace.go:171","msg":"trace[2120631045] transaction","detail":"{read_only:false; response_revision:4763; number_of_response:1; }","duration":"126.985334ms","start":"2026-06-09T22:23:12.022433Z","end":"2026-06-09T22:23:12.149419Z","steps":["trace[2120631045] 'process raft request' (duration: 96.902621ms)","trace[2120631045] 'compare' (duration: 29.761951ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:23:12.508289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.26551ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-06-09T22:23:18.697943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.040946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1346"} {"level":"info","ts":"2026-06-09T22:23:18.698055Z","caller":"traceutil/trace.go:171","msg":"trace[1319417634] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4871; }","duration":"350.181011ms","start":"2026-06-09T22:23:18.347845Z","end":"2026-06-09T22:23:18.698026Z","steps":["trace[1319417634] 'range keys from in-memory index tree' (duration: 349.820349ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:18.698113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:18.347832Z","time spent":"350.267374ms","remote":"127.0.0.1:49486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":1368,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" "} {"level":"info","ts":"2026-06-09T22:23:19.079768Z","caller":"traceutil/trace.go:171","msg":"trace[377839034] transaction","detail":"{read_only:false; response_revision:4872; number_of_response:1; }","duration":"678.132949ms","start":"2026-06-09T22:23:18.401607Z","end":"2026-06-09T22:23:19.07974Z","steps":["trace[377839034] 'process raft request' (duration: 677.925182ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:19.079929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:18.401576Z","time spent":"678.256283ms","remote":"127.0.0.1:50194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2227,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T22:23:19.079746Z","caller":"traceutil/trace.go:171","msg":"trace[868366783] linearizableReadLoop","detail":"{readStateIndex:5065; appliedIndex:5064; }","duration":"453.205288ms","start":"2026-06-09T22:23:18.626508Z","end":"2026-06-09T22:23:19.079713Z","steps":["trace[868366783] 'read index received' (duration: 453.072134ms)","trace[868366783] 'applied index is now lower than readState.Index' (duration: 131.904µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:23:19.080004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.493989ms","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-06-09T22:23:19.080063Z","caller":"traceutil/trace.go:171","msg":"trace[1583441970] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4872; }","duration":"453.825971ms","start":"2026-06-09T22:23:18.626219Z","end":"2026-06-09T22:23:19.080044Z","steps":["trace[1583441970] 'agreement among raft nodes before linearized reading' (duration: 453.588392ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:19.080107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:18.626179Z","time spent":"453.917324ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-09T22:23:19.135565Z","caller":"traceutil/trace.go:171","msg":"trace[1816215132] transaction","detail":"{read_only:false; response_revision:4873; number_of_response:1; }","duration":"500.034869ms","start":"2026-06-09T22:23:18.635487Z","end":"2026-06-09T22:23:19.135522Z","steps":["trace[1816215132] 'process raft request' (duration: 475.285344ms)","trace[1816215132] 'compare' (duration: 22.869892ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:23:19.135759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.141112ms","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-06-09T22:23:19.135794Z","caller":"traceutil/trace.go:171","msg":"trace[421520316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4874; }","duration":"502.244515ms","start":"2026-06-09T22:23:18.633541Z","end":"2026-06-09T22:23:19.135785Z","steps":["trace[421520316] 'agreement among raft nodes before linearized reading' (duration: 502.039348ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:19.135816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:18.633506Z","time spent":"502.304677ms","remote":"127.0.0.1:49622","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-06-09T22:23:19.135812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:18.635456Z","time spent":"500.250887ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:23:19.135884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.941238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-06-09T22:23:19.1359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.330471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1346"} {"level":"info","ts":"2026-06-09T22:23:19.135953Z","caller":"traceutil/trace.go:171","msg":"trace[58431928] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4874; }","duration":"354.096953ms","start":"2026-06-09T22:23:18.781837Z","end":"2026-06-09T22:23:19.135934Z","steps":["trace[58431928] 'agreement among raft nodes before linearized reading' (duration: 353.872486ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:19.135978Z","caller":"traceutil/trace.go:171","msg":"trace[255612051] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4874; }","duration":"432.415014ms","start":"2026-06-09T22:23:18.703542Z","end":"2026-06-09T22:23:19.135957Z","steps":["trace[255612051] 'agreement among raft nodes before linearized reading' (duration: 432.229498ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:19.136017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:18.781818Z","time spent":"354.188917ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-09T22:23:19.136022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:18.703493Z","time spent":"432.520607ms","remote":"127.0.0.1:49486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":1368,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" "} {"level":"warn","ts":"2026-06-09T22:23:19.137575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.034467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1b0b9b23-5dde-4d94-968a-84750f406e7a\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-06-09T22:23:19.137703Z","caller":"traceutil/trace.go:171","msg":"trace[1846818986] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1b0b9b23-5dde-4d94-968a-84750f406e7a; range_end:; response_count:1; response_revision:4874; }","duration":"149.555458ms","start":"2026-06-09T22:23:18.988091Z","end":"2026-06-09T22:23:19.137647Z","steps":["trace[1846818986] 'agreement among raft nodes before linearized reading' (duration: 147.761917ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:19.567149Z","caller":"traceutil/trace.go:171","msg":"trace[1773052771] transaction","detail":"{read_only:false; response_revision:4879; number_of_response:1; }","duration":"280.927669ms","start":"2026-06-09T22:23:19.286199Z","end":"2026-06-09T22:23:19.567127Z","steps":["trace[1773052771] 'process raft request' (duration: 280.718372ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:19.567428Z","caller":"traceutil/trace.go:171","msg":"trace[693881046] linearizableReadLoop","detail":"{readStateIndex:5074; appliedIndex:5070; }","duration":"279.890033ms","start":"2026-06-09T22:23:19.287518Z","end":"2026-06-09T22:23:19.567408Z","steps":["trace[693881046] 'read index received' (duration: 43.050611ms)","trace[693881046] 'applied index is now lower than readState.Index' (duration: 236.838462ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:23:19.567452Z","caller":"traceutil/trace.go:171","msg":"trace[821376319] transaction","detail":"{read_only:false; response_revision:4880; number_of_response:1; }","duration":"280.922499ms","start":"2026-06-09T22:23:19.286506Z","end":"2026-06-09T22:23:19.567429Z","steps":["trace[821376319] 'process raft request' (duration: 280.558477ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:19.567536Z","caller":"traceutil/trace.go:171","msg":"trace[1687182873] transaction","detail":"{read_only:false; response_revision:4881; number_of_response:1; }","duration":"280.713723ms","start":"2026-06-09T22:23:19.286794Z","end":"2026-06-09T22:23:19.567508Z","steps":["trace[1687182873] 'process raft request' (duration: 280.492014ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:19.56769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.143513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-09T22:23:19.567768Z","caller":"traceutil/trace.go:171","msg":"trace[548285006] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4881; }","duration":"280.251786ms","start":"2026-06-09T22:23:19.2875Z","end":"2026-06-09T22:23:19.567752Z","steps":["trace[548285006] 'agreement among raft nodes before linearized reading' (duration: 280.013508ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:19.710839Z","caller":"traceutil/trace.go:171","msg":"trace[679803184] linearizableReadLoop","detail":"{readStateIndex:5076; appliedIndex:5074; }","duration":"143.315097ms","start":"2026-06-09T22:23:19.567482Z","end":"2026-06-09T22:23:19.710798Z","steps":["trace[679803184] 'read index received' (duration: 122.773335ms)","trace[679803184] 'applied index is now lower than readState.Index' (duration: 20.540502ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:23:19.710923Z","caller":"traceutil/trace.go:171","msg":"trace[1359147475] transaction","detail":"{read_only:false; response_revision:4883; number_of_response:1; }","duration":"288.86693ms","start":"2026-06-09T22:23:19.42197Z","end":"2026-06-09T22:23:19.710837Z","steps":["trace[1359147475] 'process raft request' (duration: 288.731166ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:19.711524Z","caller":"traceutil/trace.go:171","msg":"trace[1884058031] transaction","detail":"{read_only:false; response_revision:4882; number_of_response:1; }","duration":"379.081904ms","start":"2026-06-09T22:23:19.332413Z","end":"2026-06-09T22:23:19.711495Z","steps":["trace[1884058031] 'process raft request' (duration: 357.863448ms)","trace[1884058031] 'compare' (duration: 20.180111ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:23:19.711649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:19.332389Z","time spent":"379.197057ms","remote":"127.0.0.1:49464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":922,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T22:23:19.711031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.731557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T22:23:19.711856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.681799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206966"} {"level":"info","ts":"2026-06-09T22:23:19.711898Z","caller":"traceutil/trace.go:171","msg":"trace[1101876156] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4883; }","duration":"265.72401ms","start":"2026-06-09T22:23:19.446161Z","end":"2026-06-09T22:23:19.711885Z","steps":["trace[1101876156] 'agreement among raft nodes before linearized reading' (duration: 264.768838ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:19.711933Z","caller":"traceutil/trace.go:171","msg":"trace[107946405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4883; }","duration":"162.627467ms","start":"2026-06-09T22:23:19.549242Z","end":"2026-06-09T22:23:19.711869Z","steps":["trace[107946405] 'agreement among raft nodes before linearized reading' (duration: 161.657145ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:19.711315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.118966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9697"} {"level":"info","ts":"2026-06-09T22:23:19.712143Z","caller":"traceutil/trace.go:171","msg":"trace[570671008] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4883; }","duration":"143.001106ms","start":"2026-06-09T22:23:19.569127Z","end":"2026-06-09T22:23:19.712128Z","steps":["trace[570671008] 'agreement among raft nodes before linearized reading' (duration: 141.968071ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:19.711391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.561314ms","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-06-09T22:23:19.712227Z","caller":"traceutil/trace.go:171","msg":"trace[397516685] 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:4883; }","duration":"424.443874ms","start":"2026-06-09T22:23:19.287756Z","end":"2026-06-09T22:23:19.7122Z","steps":["trace[397516685] 'agreement among raft nodes before linearized reading' (duration: 423.347596ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:19.712307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:19.287752Z","time spent":"424.528556ms","remote":"127.0.0.1:49532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1957,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-06-09T22:23:19.948148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.155668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:7581"} {"level":"info","ts":"2026-06-09T22:23:19.948237Z","caller":"traceutil/trace.go:171","msg":"trace[789656835] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4883; }","duration":"128.317514ms","start":"2026-06-09T22:23:19.819893Z","end":"2026-06-09T22:23:19.948211Z","steps":["trace[789656835] 'range keys from in-memory index tree' (duration: 127.93355ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:21.509557Z","caller":"traceutil/trace.go:171","msg":"trace[1181229951] transaction","detail":"{read_only:false; response_revision:4902; number_of_response:1; }","duration":"367.680497ms","start":"2026-06-09T22:23:21.141835Z","end":"2026-06-09T22:23:21.509515Z","steps":["trace[1181229951] 'process raft request' (duration: 367.436378ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:21.509957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:21.141809Z","time spent":"367.933135ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:23:21.514817Z","caller":"traceutil/trace.go:171","msg":"trace[767776921] linearizableReadLoop","detail":"{readStateIndex:5096; appliedIndex:5094; }","duration":"366.590889ms","start":"2026-06-09T22:23:21.148214Z","end":"2026-06-09T22:23:21.514805Z","steps":["trace[767776921] 'read index received' (duration: 361.183564ms)","trace[767776921] 'applied index is now lower than readState.Index' (duration: 5.406725ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:23:21.514846Z","caller":"traceutil/trace.go:171","msg":"trace[27064411] transaction","detail":"{read_only:false; response_revision:4903; number_of_response:1; }","duration":"371.744956ms","start":"2026-06-09T22:23:21.143088Z","end":"2026-06-09T22:23:21.514833Z","steps":["trace[27064411] 'process raft request' (duration: 371.658073ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:21.515005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.773335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1b0b9b23-5dde-4d94-968a-84750f406e7a\" ","response":"range_response_count:1 size:3935"} {"level":"warn","ts":"2026-06-09T22:23:21.515033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.68027ms","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-06-09T22:23:21.515045Z","caller":"traceutil/trace.go:171","msg":"trace[751606467] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1b0b9b23-5dde-4d94-968a-84750f406e7a; range_end:; response_count:1; response_revision:4903; }","duration":"366.851948ms","start":"2026-06-09T22:23:21.148182Z","end":"2026-06-09T22:23:21.515034Z","steps":["trace[751606467] 'agreement among raft nodes before linearized reading' (duration: 366.711093ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:21.515059Z","caller":"traceutil/trace.go:171","msg":"trace[181530110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4903; }","duration":"356.728581ms","start":"2026-06-09T22:23:21.158323Z","end":"2026-06-09T22:23:21.515052Z","steps":["trace[181530110] 'agreement among raft nodes before linearized reading' (duration: 356.575396ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:21.515074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:21.14817Z","time spent":"366.896419ms","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3957,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1b0b9b23-5dde-4d94-968a-84750f406e7a\" "} {"level":"warn","ts":"2026-06-09T22:23:21.515088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:21.158316Z","time spent":"356.767123ms","remote":"127.0.0.1:49622","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-06-09T22:23:21.515106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.693873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-9jpw5.18b789e4bcd03aed\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-06-09T22:23:21.515168Z","caller":"traceutil/trace.go:171","msg":"trace[753074000] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-9jpw5.18b789e4bcd03aed; range_end:; response_count:1; response_revision:4903; }","duration":"257.807166ms","start":"2026-06-09T22:23:21.257348Z","end":"2026-06-09T22:23:21.515155Z","steps":["trace[753074000] 'agreement among raft nodes before linearized reading' (duration: 257.644651ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:21.515374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:21.143071Z","time spent":"372.270895ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:23:32.009901Z","caller":"traceutil/trace.go:171","msg":"trace[443875387] linearizableReadLoop","detail":"{readStateIndex:5223; appliedIndex:5222; }","duration":"453.247862ms","start":"2026-06-09T22:23:31.55661Z","end":"2026-06-09T22:23:32.009858Z","steps":["trace[443875387] 'read index received' (duration: 453.160189ms)","trace[443875387] 'applied index is now lower than readState.Index' (duration: 86.283µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:23:32.009998Z","caller":"traceutil/trace.go:171","msg":"trace[2115612683] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"468.122932ms","start":"2026-06-09T22:23:31.541841Z","end":"2026-06-09T22:23:32.009964Z","steps":["trace[2115612683] 'process raft request' (duration: 467.852393ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:32.010164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:31.541817Z","time spent":"468.257757ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:23:32.010263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.622565ms","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-06-09T22:23:32.010345Z","caller":"traceutil/trace.go:171","msg":"trace[121009022] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5026; }","duration":"453.74913ms","start":"2026-06-09T22:23:31.556577Z","end":"2026-06-09T22:23:32.010326Z","steps":["trace[121009022] 'agreement among raft nodes before linearized reading' (duration: 453.45885ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:32.010394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:31.556564Z","time spent":"453.821062ms","remote":"127.0.0.1:49622","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-06-09T22:23:32.314783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.67118ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T22:23:32.315073Z","caller":"traceutil/trace.go:171","msg":"trace[1796292958] transaction","detail":"{read_only:false; response_revision:5027; number_of_response:1; }","duration":"755.323823ms","start":"2026-06-09T22:23:31.559688Z","end":"2026-06-09T22:23:32.315012Z","steps":["trace[1796292958] 'process raft request' (duration: 638.344462ms)","trace[1796292958] 'compare' (duration: 116.529035ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:23:32.315187Z","caller":"traceutil/trace.go:171","msg":"trace[1857838507] transaction","detail":"{read_only:false; response_revision:5030; number_of_response:1; }","duration":"206.744568ms","start":"2026-06-09T22:23:32.10842Z","end":"2026-06-09T22:23:32.315165Z","steps":["trace[1857838507] 'process raft request' (duration: 206.593283ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:32.315302Z","caller":"traceutil/trace.go:171","msg":"trace[1896513787] linearizableReadLoop","detail":"{readStateIndex:5225; appliedIndex:5223; }","duration":"305.289143ms","start":"2026-06-09T22:23:32.009991Z","end":"2026-06-09T22:23:32.31528Z","steps":["trace[1896513787] 'read index received' (duration: 188.031172ms)","trace[1896513787] 'applied index is now lower than readState.Index' (duration: 117.255561ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:23:32.315304Z","caller":"traceutil/trace.go:171","msg":"trace[699987745] transaction","detail":"{read_only:false; response_revision:5031; number_of_response:1; }","duration":"150.01511ms","start":"2026-06-09T22:23:32.16527Z","end":"2026-06-09T22:23:32.315285Z","steps":["trace[699987745] 'process raft request' (duration: 149.808442ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:32.31549Z","caller":"traceutil/trace.go:171","msg":"trace[85899378] transaction","detail":"{read_only:false; response_revision:5029; number_of_response:1; }","duration":"300.498675ms","start":"2026-06-09T22:23:32.014977Z","end":"2026-06-09T22:23:32.315476Z","steps":["trace[85899378] 'process raft request' (duration: 299.996718ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:32.315502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.132107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T22:23:32.315588Z","caller":"traceutil/trace.go:171","msg":"trace[998101581] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:5031; }","duration":"671.284331ms","start":"2026-06-09T22:23:31.644285Z","end":"2026-06-09T22:23:32.31557Z","steps":["trace[998101581] 'agreement among raft nodes before linearized reading' (duration: 671.055383ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:32.315646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:31.644263Z","time spent":"671.373505ms","remote":"127.0.0.1:49558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":29,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-06-09T22:23:32.31565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.289301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-06-09T22:23:32.315639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:32.01495Z","time spent":"300.610289ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:23:32.315716Z","caller":"traceutil/trace.go:171","msg":"trace[1207228865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5031; }","duration":"755.394634ms","start":"2026-06-09T22:23:31.560306Z","end":"2026-06-09T22:23:32.315701Z","steps":["trace[1207228865] 'agreement among raft nodes before linearized reading' (duration: 755.112344ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:32.315769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:31.560295Z","time spent":"755.465668ms","remote":"127.0.0.1:49622","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":"info","ts":"2026-06-09T22:23:32.315909Z","caller":"traceutil/trace.go:171","msg":"trace[369445760] transaction","detail":"{read_only:false; response_revision:5028; number_of_response:1; }","duration":"543.478959ms","start":"2026-06-09T22:23:31.771576Z","end":"2026-06-09T22:23:32.315055Z","steps":["trace[369445760] 'process raft request' (duration: 543.311333ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:32.316071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:31.559662Z","time spent":"755.556411ms","remote":"127.0.0.1:49558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11505,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:23:32.316167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:31.771547Z","time spent":"544.3967ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:23:32.530403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.009447ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T22:23:32.530688Z","caller":"traceutil/trace.go:171","msg":"trace[476998320] transaction","detail":"{read_only:false; response_revision:5034; number_of_response:1; }","duration":"209.35609ms","start":"2026-06-09T22:23:32.321313Z","end":"2026-06-09T22:23:32.530669Z","steps":["trace[476998320] 'process raft request' (duration: 209.256546ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:32.53072Z","caller":"traceutil/trace.go:171","msg":"trace[219724351] transaction","detail":"{read_only:false; response_revision:5032; number_of_response:1; }","duration":"209.454504ms","start":"2026-06-09T22:23:32.32124Z","end":"2026-06-09T22:23:32.530695Z","steps":["trace[219724351] 'process raft request' (duration: 103.058254ms)","trace[219724351] 'compare' (duration: 105.854891ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:23:32.530819Z","caller":"traceutil/trace.go:171","msg":"trace[1498852430] transaction","detail":"{read_only:false; response_revision:5033; number_of_response:1; }","duration":"209.507866ms","start":"2026-06-09T22:23:32.321276Z","end":"2026-06-09T22:23:32.530784Z","steps":["trace[1498852430] 'process raft request' (duration: 209.229736ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:32.530948Z","caller":"traceutil/trace.go:171","msg":"trace[935789639] transaction","detail":"{read_only:false; response_revision:5035; number_of_response:1; }","duration":"209.623709ms","start":"2026-06-09T22:23:32.321315Z","end":"2026-06-09T22:23:32.530938Z","steps":["trace[935789639] 'process raft request' (duration: 209.289498ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:32.773856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.01209ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-09T22:23:32.773962Z","caller":"traceutil/trace.go:171","msg":"trace[1143540083] linearizableReadLoop","detail":"{readStateIndex:5233; appliedIndex:5228; }","duration":"304.60282ms","start":"2026-06-09T22:23:32.46934Z","end":"2026-06-09T22:23:32.773942Z","steps":["trace[1143540083] 'read index received' (duration: 23.986612ms)","trace[1143540083] 'applied index is now lower than readState.Index' (duration: 280.614428ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:23:32.774166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.619146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:23:32.774218Z","caller":"traceutil/trace.go:171","msg":"trace[148653881] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5035; }","duration":"222.716929ms","start":"2026-06-09T22:23:32.551488Z","end":"2026-06-09T22:23:32.774204Z","steps":["trace[148653881] 'agreement among raft nodes before linearized reading' (duration: 222.627446ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:32.774241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.580575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T22:23:32.774289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.950382ms","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-06-09T22:23:32.774422Z","caller":"traceutil/trace.go:171","msg":"trace[1604829279] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5035; }","duration":"305.117597ms","start":"2026-06-09T22:23:32.469286Z","end":"2026-06-09T22:23:32.774404Z","steps":["trace[1604829279] 'agreement among raft nodes before linearized reading' (duration: 304.814206ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:32.774504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:32.469265Z","time spent":"305.225401ms","remote":"127.0.0.1:49532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-06-09T22:23:32.774425Z","caller":"traceutil/trace.go:171","msg":"trace[72604129] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:5035; }","duration":"236.71826ms","start":"2026-06-09T22:23:32.537591Z","end":"2026-06-09T22:23:32.774309Z","steps":["trace[72604129] 'agreement among raft nodes before linearized reading' (duration: 236.459711ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:32.774511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.927582ms","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-06-09T22:23:32.775159Z","caller":"traceutil/trace.go:171","msg":"trace[1518107135] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5035; }","duration":"147.642216ms","start":"2026-06-09T22:23:32.627487Z","end":"2026-06-09T22:23:32.775129Z","steps":["trace[1518107135] 'agreement among raft nodes before linearized reading' (duration: 146.61036ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:34.337548Z","caller":"traceutil/trace.go:171","msg":"trace[920772834] transaction","detail":"{read_only:false; response_revision:5036; number_of_response:1; }","duration":"321.451381ms","start":"2026-06-09T22:23:34.016077Z","end":"2026-06-09T22:23:34.337528Z","steps":["trace[920772834] 'process raft request' (duration: 321.291725ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:34.337705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:34.016053Z","time spent":"321.561665ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:23:35.056631Z","caller":"traceutil/trace.go:171","msg":"trace[359964350] transaction","detail":"{read_only:false; response_revision:5037; number_of_response:1; }","duration":"736.652024ms","start":"2026-06-09T22:23:34.319956Z","end":"2026-06-09T22:23:35.056608Z","steps":["trace[359964350] 'process raft request' (duration: 736.473347ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:35.056767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:34.319933Z","time spent":"736.765808ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:23:35.056934Z","caller":"traceutil/trace.go:171","msg":"trace[734647827] transaction","detail":"{read_only:false; response_revision:5038; number_of_response:1; }","duration":"734.934664ms","start":"2026-06-09T22:23:34.321966Z","end":"2026-06-09T22:23:35.056901Z","steps":["trace[734647827] 'process raft request' (duration: 734.598582ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:35.05696Z","caller":"traceutil/trace.go:171","msg":"trace[113970604] linearizableReadLoop","detail":"{readStateIndex:5235; appliedIndex:5233; }","duration":"736.173057ms","start":"2026-06-09T22:23:34.320762Z","end":"2026-06-09T22:23:35.056936Z","steps":["trace[113970604] 'read index received' (duration: 16.678507ms)","trace[113970604] 'applied index is now lower than readState.Index' (duration: 719.49351ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:23:35.057055Z","caller":"traceutil/trace.go:171","msg":"trace[909235300] transaction","detail":"{read_only:false; response_revision:5039; number_of_response:1; }","duration":"734.80026ms","start":"2026-06-09T22:23:34.322235Z","end":"2026-06-09T22:23:35.057035Z","steps":["trace[909235300] 'process raft request' (duration: 734.584312ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:35.05711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:34.321946Z","time spent":"735.0848ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:23:35.057148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:34.322222Z","time spent":"734.881842ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:23:35.057212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.426676ms","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-06-09T22:23:35.057255Z","caller":"traceutil/trace.go:171","msg":"trace[11289068] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5039; }","duration":"736.52495ms","start":"2026-06-09T22:23:34.32072Z","end":"2026-06-09T22:23:35.057245Z","steps":["trace[11289068] 'agreement among raft nodes before linearized reading' (duration: 736.362104ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:35.057292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:34.3207Z","time spent":"736.584471ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-09T22:23:35.206281Z","caller":"traceutil/trace.go:171","msg":"trace[1629137191] transaction","detail":"{read_only:false; response_revision:5040; number_of_response:1; }","duration":"177.520767ms","start":"2026-06-09T22:23:35.028727Z","end":"2026-06-09T22:23:35.206248Z","steps":["trace[1629137191] 'process raft request' (duration: 79.220802ms)","trace[1629137191] 'compare' (duration: 98.13382ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:23:35.206352Z","caller":"traceutil/trace.go:171","msg":"trace[2140382502] linearizableReadLoop","detail":"{readStateIndex:5238; appliedIndex:5237; }","duration":"149.292423ms","start":"2026-06-09T22:23:35.05705Z","end":"2026-06-09T22:23:35.206343Z","steps":["trace[2140382502] 'read index received' (duration: 50.911505ms)","trace[2140382502] 'applied index is now lower than readState.Index' (duration: 98.380068ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:23:35.20629Z","caller":"traceutil/trace.go:171","msg":"trace[1219305511] transaction","detail":"{read_only:false; response_revision:5041; number_of_response:1; }","duration":"144.272156ms","start":"2026-06-09T22:23:35.061994Z","end":"2026-06-09T22:23:35.206266Z","steps":["trace[1219305511] 'process raft request' (duration: 144.218574ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:35.206543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.161853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-09T22:23:35.206584Z","caller":"traceutil/trace.go:171","msg":"trace[1555650751] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5041; }","duration":"234.259536ms","start":"2026-06-09T22:23:34.972313Z","end":"2026-06-09T22:23:35.206573Z","steps":["trace[1555650751] 'agreement among raft nodes before linearized reading' (duration: 234.110421ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:35.206686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.792506ms","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-06-09T22:23:35.206725Z","caller":"traceutil/trace.go:171","msg":"trace[1910780252] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5041; }","duration":"670.867468ms","start":"2026-06-09T22:23:34.535846Z","end":"2026-06-09T22:23:35.206713Z","steps":["trace[1910780252] 'agreement among raft nodes before linearized reading' (duration: 670.689781ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:35.206765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:34.535833Z","time spent":"670.9239ms","remote":"127.0.0.1:49622","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-06-09T22:23:35.20683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.765273ms","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-06-09T22:23:35.206859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.97009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:23:35.206898Z","caller":"traceutil/trace.go:171","msg":"trace[931505482] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:5041; }","duration":"768.912198ms","start":"2026-06-09T22:23:34.437964Z","end":"2026-06-09T22:23:35.206876Z","steps":["trace[931505482] 'agreement among raft nodes before linearized reading' (duration: 768.757603ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:35.206967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.78ms","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-06-09T22:23:35.207001Z","caller":"traceutil/trace.go:171","msg":"trace[1525731921] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5041; }","duration":"669.849912ms","start":"2026-06-09T22:23:34.53714Z","end":"2026-06-09T22:23:35.20699Z","steps":["trace[1525731921] 'agreement among raft nodes before linearized reading' (duration: 669.761969ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:35.207033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:34.537117Z","time spent":"669.905694ms","remote":"127.0.0.1:49622","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-06-09T22:23:35.207021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:34.437942Z","time spent":"769.051203ms","remote":"127.0.0.1:54194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":9,"response size":29,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-06-09T22:23:35.206901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.511321ms","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-06-09T22:23:35.207102Z","caller":"traceutil/trace.go:171","msg":"trace[1729441479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5041; }","duration":"669.740039ms","start":"2026-06-09T22:23:34.537351Z","end":"2026-06-09T22:23:35.207091Z","steps":["trace[1729441479] 'agreement among raft nodes before linearized reading' (duration: 669.427358ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:35.207147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:34.537338Z","time spent":"669.79634ms","remote":"127.0.0.1:49622","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":"info","ts":"2026-06-09T22:23:35.206924Z","caller":"traceutil/trace.go:171","msg":"trace[326056827] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5041; }","duration":"659.076053ms","start":"2026-06-09T22:23:34.547827Z","end":"2026-06-09T22:23:35.206903Z","steps":["trace[326056827] 'agreement among raft nodes before linearized reading' (duration: 658.98076ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:35.207214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:34.547806Z","time spent":"659.394695ms","remote":"127.0.0.1:49380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T22:23:35.207084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.628025ms","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-06-09T22:23:35.208231Z","caller":"traceutil/trace.go:171","msg":"trace[1801944615] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5041; }","duration":"672.822869ms","start":"2026-06-09T22:23:34.535384Z","end":"2026-06-09T22:23:35.208207Z","steps":["trace[1801944615] 'agreement among raft nodes before linearized reading' (duration: 671.524952ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:35.208376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:34.53536Z","time spent":"672.999744ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-09T22:23:35.75581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.070034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-09T22:23:35.755939Z","caller":"traceutil/trace.go:171","msg":"trace[1067503446] linearizableReadLoop","detail":"{readStateIndex:5244; appliedIndex:5243; }","duration":"469.734248ms","start":"2026-06-09T22:23:35.286181Z","end":"2026-06-09T22:23:35.755916Z","steps":["trace[1067503446] 'read index received' (duration: 366.364284ms)","trace[1067503446] 'applied index is now lower than readState.Index' (duration: 103.367274ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:23:35.756101Z","caller":"traceutil/trace.go:171","msg":"trace[94786332] transaction","detail":"{read_only:false; response_revision:5046; number_of_response:1; }","duration":"517.717391ms","start":"2026-06-09T22:23:35.238365Z","end":"2026-06-09T22:23:35.756082Z","steps":["trace[94786332] 'process raft request' (duration: 414.285285ms)","trace[94786332] 'compare' (duration: 102.677409ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:23:35.756193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:35.238345Z","time spent":"517.808574ms","remote":"127.0.0.1:49464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T22:23:35.756382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.321808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T22:23:35.756382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.210584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T22:23:35.756391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.579056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-06-09T22:23:35.756452Z","caller":"traceutil/trace.go:171","msg":"trace[1552962232] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:5046; }","duration":"165.429672ms","start":"2026-06-09T22:23:35.591004Z","end":"2026-06-09T22:23:35.756434Z","steps":["trace[1552962232] 'agreement among raft nodes before linearized reading' (duration: 165.311378ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:35.756457Z","caller":"traceutil/trace.go:171","msg":"trace[704887501] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5046; }","duration":"485.326028ms","start":"2026-06-09T22:23:35.271114Z","end":"2026-06-09T22:23:35.75644Z","steps":["trace[704887501] 'agreement among raft nodes before linearized reading' (duration: 485.198324ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:35.756471Z","caller":"traceutil/trace.go:171","msg":"trace[182196051] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:5046; }","duration":"464.712372ms","start":"2026-06-09T22:23:35.291741Z","end":"2026-06-09T22:23:35.756453Z","steps":["trace[182196051] 'agreement among raft nodes before linearized reading' (duration: 464.437122ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:35.756504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:35.271098Z","time spent":"485.398441ms","remote":"127.0.0.1:49798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-06-09T22:23:35.756516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:35.291717Z","time spent":"464.792055ms","remote":"127.0.0.1:49562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1287,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" "} {"level":"warn","ts":"2026-06-09T22:23:35.756581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.038989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:23:35.756672Z","caller":"traceutil/trace.go:171","msg":"trace[174393200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5046; }","duration":"207.271697ms","start":"2026-06-09T22:23:35.549382Z","end":"2026-06-09T22:23:35.756653Z","steps":["trace[174393200] 'agreement among raft nodes before linearized reading' (duration: 207.041549ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:55.521417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.442132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T22:23:55.521791Z","caller":"traceutil/trace.go:171","msg":"trace[1025024324] transaction","detail":"{read_only:false; response_revision:5195; number_of_response:1; }","duration":"305.132124ms","start":"2026-06-09T22:23:55.216614Z","end":"2026-06-09T22:23:55.521746Z","steps":["trace[1025024324] 'process raft request' (duration: 163.226085ms)","trace[1025024324] 'compare' (duration: 141.244485ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:23:55.521922Z","caller":"traceutil/trace.go:171","msg":"trace[898272240] transaction","detail":"{read_only:false; response_revision:5196; number_of_response:1; }","duration":"304.347096ms","start":"2026-06-09T22:23:55.217497Z","end":"2026-06-09T22:23:55.521844Z","steps":["trace[898272240] 'process raft request' (duration: 304.18759ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:55.521963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:55.216588Z","time spent":"305.277569ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:23:55.5221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:23:55.217491Z","time spent":"304.568544ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:23:55.522161Z","caller":"traceutil/trace.go:171","msg":"trace[1274173754] linearizableReadLoop","detail":"{readStateIndex:5399; appliedIndex:5396; }","duration":"218.093784ms","start":"2026-06-09T22:23:55.304013Z","end":"2026-06-09T22:23:55.522106Z","steps":["trace[1274173754] 'read index received' (duration: 75.945036ms)","trace[1274173754] 'applied index is now lower than readState.Index' (duration: 142.147368ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:23:55.522456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.449316ms","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-06-09T22:23:55.52243Z","caller":"traceutil/trace.go:171","msg":"trace[1602207101] transaction","detail":"{read_only:false; response_revision:5197; number_of_response:1; }","duration":"254.061664ms","start":"2026-06-09T22:23:55.268316Z","end":"2026-06-09T22:23:55.522378Z","steps":["trace[1602207101] 'process raft request' (duration: 253.431471ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:55.522507Z","caller":"traceutil/trace.go:171","msg":"trace[363421032] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5197; }","duration":"218.54155ms","start":"2026-06-09T22:23:55.303952Z","end":"2026-06-09T22:23:55.522493Z","steps":["trace[363421032] 'agreement among raft nodes before linearized reading' (duration: 218.409345ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:55.522944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.134744ms","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-06-09T22:23:55.523006Z","caller":"traceutil/trace.go:171","msg":"trace[1225256828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5197; }","duration":"140.277399ms","start":"2026-06-09T22:23:55.382717Z","end":"2026-06-09T22:23:55.522994Z","steps":["trace[1225256828] 'agreement among raft nodes before linearized reading' (duration: 140.068132ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:55.523033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.03236ms","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-06-09T22:23:55.523173Z","caller":"traceutil/trace.go:171","msg":"trace[1082724240] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5197; }","duration":"140.228128ms","start":"2026-06-09T22:23:55.382925Z","end":"2026-06-09T22:23:55.523153Z","steps":["trace[1082724240] 'agreement among raft nodes before linearized reading' (duration: 139.858925ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:55.523047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.109256ms","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-06-09T22:23:55.523237Z","caller":"traceutil/trace.go:171","msg":"trace[2066743688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5197; }","duration":"139.369485ms","start":"2026-06-09T22:23:55.383851Z","end":"2026-06-09T22:23:55.523221Z","steps":["trace[2066743688] 'agreement among raft nodes before linearized reading' (duration: 139.090685ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:23:55.523504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.5859ms","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-06-09T22:23:55.52356Z","caller":"traceutil/trace.go:171","msg":"trace[892798109] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5197; }","duration":"210.681563ms","start":"2026-06-09T22:23:55.312865Z","end":"2026-06-09T22:23:55.523546Z","steps":["trace[892798109] 'agreement among raft nodes before linearized reading' (duration: 209.866994ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:55.674256Z","caller":"traceutil/trace.go:171","msg":"trace[109651561] transaction","detail":"{read_only:false; response_revision:5199; number_of_response:1; }","duration":"146.009198ms","start":"2026-06-09T22:23:55.528201Z","end":"2026-06-09T22:23:55.67421Z","steps":["trace[109651561] 'process raft request' (duration: 144.157261ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:55.674262Z","caller":"traceutil/trace.go:171","msg":"trace[6993140] transaction","detail":"{read_only:false; response_revision:5200; number_of_response:1; }","duration":"145.907455ms","start":"2026-06-09T22:23:55.52832Z","end":"2026-06-09T22:23:55.674227Z","steps":["trace[6993140] 'process raft request' (duration: 145.798751ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:55.674529Z","caller":"traceutil/trace.go:171","msg":"trace[102301451] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"144.054167ms","start":"2026-06-09T22:23:55.530453Z","end":"2026-06-09T22:23:55.674507Z","steps":["trace[102301451] 'process raft request' (duration: 143.931332ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:23:55.674485Z","caller":"traceutil/trace.go:171","msg":"trace[1271471310] transaction","detail":"{read_only:false; response_revision:5201; number_of_response:1; }","duration":"146.096892ms","start":"2026-06-09T22:23:55.528375Z","end":"2026-06-09T22:23:55.674472Z","steps":["trace[1271471310] 'process raft request' (duration: 145.794411ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:11.89389Z","caller":"traceutil/trace.go:171","msg":"trace[1786140108] linearizableReadLoop","detail":"{readStateIndex:5586; appliedIndex:5585; }","duration":"104.911806ms","start":"2026-06-09T22:24:11.788948Z","end":"2026-06-09T22:24:11.89386Z","steps":["trace[1786140108] 'read index received' (duration: 104.838333ms)","trace[1786140108] 'applied index is now lower than readState.Index' (duration: 72.283µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:24:11.893962Z","caller":"traceutil/trace.go:171","msg":"trace[1536746474] transaction","detail":"{read_only:false; response_revision:5380; number_of_response:1; }","duration":"105.568311ms","start":"2026-06-09T22:24:11.788349Z","end":"2026-06-09T22:24:11.893918Z","steps":["trace[1536746474] 'process raft request' (duration: 105.340623ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:11.894196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.231008ms","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-06-09T22:24:11.894286Z","caller":"traceutil/trace.go:171","msg":"trace[1595185956] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5380; }","duration":"105.388574ms","start":"2026-06-09T22:24:11.788876Z","end":"2026-06-09T22:24:11.894265Z","steps":["trace[1595185956] 'agreement among raft nodes before linearized reading' (duration: 105.121614ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:12.055471Z","caller":"traceutil/trace.go:171","msg":"trace[736468298] transaction","detail":"{read_only:false; response_revision:5381; number_of_response:1; }","duration":"266.381571ms","start":"2026-06-09T22:24:11.789052Z","end":"2026-06-09T22:24:12.055434Z","steps":["trace[736468298] 'process raft request' (duration: 229.957133ms)","trace[736468298] 'compare' (duration: 36.134167ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:24:12.055591Z","caller":"traceutil/trace.go:171","msg":"trace[1336751790] linearizableReadLoop","detail":"{readStateIndex:5587; appliedIndex:5586; }","duration":"161.60583ms","start":"2026-06-09T22:24:11.893964Z","end":"2026-06-09T22:24:12.05557Z","steps":["trace[1336751790] 'read index received' (duration: 125.067008ms)","trace[1336751790] 'applied index is now lower than readState.Index' (duration: 36.537932ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:24:12.055724Z","caller":"traceutil/trace.go:171","msg":"trace[393656208] transaction","detail":"{read_only:false; response_revision:5382; number_of_response:1; }","duration":"157.097172ms","start":"2026-06-09T22:24:11.898618Z","end":"2026-06-09T22:24:12.055715Z","steps":["trace[393656208] 'process raft request' (duration: 156.787591ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:12.068725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.171088ms","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-06-09T22:24:12.068783Z","caller":"traceutil/trace.go:171","msg":"trace[1599054968] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5382; }","duration":"279.268552ms","start":"2026-06-09T22:24:11.7895Z","end":"2026-06-09T22:24:12.068768Z","steps":["trace[1599054968] 'agreement among raft nodes before linearized reading' (duration: 266.183774ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:22.306065Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2862} {"level":"info","ts":"2026-06-09T22:24:22.411642Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2862,"took":"104.427482ms","hash":1913052511,"current-db-size-bytes":52858880,"current-db-size":"53 MB","current-db-size-in-use-bytes":28508160,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-09T22:24:22.411817Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1913052511,"revision":2862,"compact-revision":-1} {"level":"info","ts":"2026-06-09T22:24:22.682998Z","caller":"traceutil/trace.go:171","msg":"trace[216516308] transaction","detail":"{read_only:false; response_revision:5443; number_of_response:1; }","duration":"114.675308ms","start":"2026-06-09T22:24:22.568296Z","end":"2026-06-09T22:24:22.682971Z","steps":["trace[216516308] 'process raft request' (duration: 114.516782ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:23.038999Z","caller":"traceutil/trace.go:171","msg":"trace[347098301] transaction","detail":"{read_only:false; response_revision:5444; number_of_response:1; }","duration":"234.780312ms","start":"2026-06-09T22:24:22.804193Z","end":"2026-06-09T22:24:23.038973Z","steps":["trace[347098301] 'process raft request' (duration: 234.592355ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:23.658923Z","caller":"traceutil/trace.go:171","msg":"trace[740504985] linearizableReadLoop","detail":"{readStateIndex:5656; appliedIndex:5655; }","duration":"108.898385ms","start":"2026-06-09T22:24:23.549993Z","end":"2026-06-09T22:24:23.658891Z","steps":["trace[740504985] 'read index received' (duration: 108.711078ms)","trace[740504985] 'applied index is now lower than readState.Index' (duration: 185.687µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:24:23.659378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.283769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:24:23.659432Z","caller":"traceutil/trace.go:171","msg":"trace[1260970630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5445; }","duration":"109.437235ms","start":"2026-06-09T22:24:23.549981Z","end":"2026-06-09T22:24:23.659419Z","steps":["trace[1260970630] 'agreement among raft nodes before linearized reading' (duration: 109.088112ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:23.659345Z","caller":"traceutil/trace.go:171","msg":"trace[1299906937] transaction","detail":"{read_only:false; response_revision:5445; number_of_response:1; }","duration":"146.390681ms","start":"2026-06-09T22:24:23.512845Z","end":"2026-06-09T22:24:23.659236Z","steps":["trace[1299906937] 'process raft request' (duration: 145.857571ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:23.875283Z","caller":"traceutil/trace.go:171","msg":"trace[76159747] linearizableReadLoop","detail":"{readStateIndex:5658; appliedIndex:5657; }","duration":"150.212396ms","start":"2026-06-09T22:24:23.725048Z","end":"2026-06-09T22:24:23.875261Z","steps":["trace[76159747] 'read index received' (duration: 121.537483ms)","trace[76159747] 'applied index is now lower than readState.Index' (duration: 28.673943ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:24:23.875397Z","caller":"traceutil/trace.go:171","msg":"trace[1261345140] transaction","detail":"{read_only:false; response_revision:5447; number_of_response:1; }","duration":"152.512102ms","start":"2026-06-09T22:24:23.722832Z","end":"2026-06-09T22:24:23.875344Z","steps":["trace[1261345140] 'process raft request' (duration: 123.694444ms)","trace[1261345140] 'compare' (duration: 28.625901ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:24:23.875607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.337144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6157"} {"level":"info","ts":"2026-06-09T22:24:23.875661Z","caller":"traceutil/trace.go:171","msg":"trace[1502939030] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5447; }","duration":"162.423077ms","start":"2026-06-09T22:24:23.713224Z","end":"2026-06-09T22:24:23.875647Z","steps":["trace[1502939030] 'agreement among raft nodes before linearized reading' (duration: 162.201629ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:23.875987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.137547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:24:23.876054Z","caller":"traceutil/trace.go:171","msg":"trace[305067055] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5447; }","duration":"166.22292ms","start":"2026-06-09T22:24:23.709815Z","end":"2026-06-09T22:24:23.876038Z","steps":["trace[305067055] 'agreement among raft nodes before linearized reading' (duration: 166.123827ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:24.117692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.393309ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T22:24:24.117814Z","caller":"traceutil/trace.go:171","msg":"trace[1319755705] transaction","detail":"{read_only:false; response_revision:5449; number_of_response:1; }","duration":"163.968071ms","start":"2026-06-09T22:24:23.953827Z","end":"2026-06-09T22:24:24.117795Z","steps":["trace[1319755705] 'process raft request' (duration: 27.393055ms)","trace[1319755705] 'compare' (duration: 136.285444ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:24:24.507118Z","caller":"traceutil/trace.go:171","msg":"trace[372994485] linearizableReadLoop","detail":"{readStateIndex:5661; appliedIndex:5660; }","duration":"360.090262ms","start":"2026-06-09T22:24:24.146991Z","end":"2026-06-09T22:24:24.507081Z","steps":["trace[372994485] 'read index received' (duration: 321.870586ms)","trace[372994485] 'applied index is now lower than readState.Index' (duration: 38.218556ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:24:24.507425Z","caller":"traceutil/trace.go:171","msg":"trace[339774345] transaction","detail":"{read_only:false; response_revision:5450; number_of_response:1; }","duration":"376.545944ms","start":"2026-06-09T22:24:24.130861Z","end":"2026-06-09T22:24:24.507407Z","steps":["trace[339774345] 'process raft request' (duration: 337.991426ms)","trace[339774345] 'compare' (duration: 38.10047ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:24:24.507573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.213254ms","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-06-09T22:24:24.507565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.543309ms","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-06-09T22:24:24.507583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:24:24.130839Z","time spent":"376.664309ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:24:24.507626Z","caller":"traceutil/trace.go:171","msg":"trace[24141619] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5450; }","duration":"329.322268ms","start":"2026-06-09T22:24:24.178291Z","end":"2026-06-09T22:24:24.507613Z","steps":["trace[24141619] 'agreement among raft nodes before linearized reading' (duration: 329.142911ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:24.507646Z","caller":"traceutil/trace.go:171","msg":"trace[2141989301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5450; }","duration":"360.657913ms","start":"2026-06-09T22:24:24.146968Z","end":"2026-06-09T22:24:24.507626Z","steps":["trace[2141989301] 'agreement among raft nodes before linearized reading' (duration: 360.376733ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:24.507694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.726184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-09T22:24:24.507693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:24:24.146958Z","time spent":"360.726626ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-09T22:24:24.507717Z","caller":"traceutil/trace.go:171","msg":"trace[383288423] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5450; }","duration":"218.783336ms","start":"2026-06-09T22:24:24.288926Z","end":"2026-06-09T22:24:24.50771Z","steps":["trace[383288423] 'agreement among raft nodes before linearized reading' (duration: 218.744864ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:24.507657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:24:24.178271Z","time spent":"329.37951ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-09T22:24:24.829753Z","caller":"traceutil/trace.go:171","msg":"trace[464992159] transaction","detail":"{read_only:false; response_revision:5451; number_of_response:1; }","duration":"318.493438ms","start":"2026-06-09T22:24:24.511225Z","end":"2026-06-09T22:24:24.829718Z","steps":["trace[464992159] 'process raft request' (duration: 301.744225ms)","trace[464992159] 'compare' (duration: 16.286986ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:24:24.82995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:24:24.511209Z","time spent":"318.639473ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:24:24.884542Z","caller":"traceutil/trace.go:171","msg":"trace[813875241] linearizableReadLoop","detail":"{readStateIndex:5663; appliedIndex:5661; }","duration":"334.274655ms","start":"2026-06-09T22:24:24.550247Z","end":"2026-06-09T22:24:24.884521Z","steps":["trace[813875241] 'read index received' (duration: 262.794322ms)","trace[813875241] 'applied index is now lower than readState.Index' (duration: 71.479463ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:24:24.884664Z","caller":"traceutil/trace.go:171","msg":"trace[59663893] transaction","detail":"{read_only:false; response_revision:5452; number_of_response:1; }","duration":"373.080092ms","start":"2026-06-09T22:24:24.511531Z","end":"2026-06-09T22:24:24.884611Z","steps":["trace[59663893] 'process raft request' (duration: 372.816722ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:24.88479Z","caller":"traceutil/trace.go:171","msg":"trace[65638934] transaction","detail":"{read_only:false; response_revision:5453; number_of_response:1; }","duration":"195.935482ms","start":"2026-06-09T22:24:24.688836Z","end":"2026-06-09T22:24:24.884772Z","steps":["trace[65638934] 'process raft request' (duration: 195.624781ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:24.884937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:24:24.511522Z","time spent":"373.26736ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:24:24.88473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.472502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:24:24.885234Z","caller":"traceutil/trace.go:171","msg":"trace[1612682814] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5453; }","duration":"334.976812ms","start":"2026-06-09T22:24:24.550205Z","end":"2026-06-09T22:24:24.885182Z","steps":["trace[1612682814] 'agreement among raft nodes before linearized reading' (duration: 334.4163ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:24.885387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:24:24.550189Z","time spent":"335.142098ms","remote":"127.0.0.1:49380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T22:24:26.217217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.271058ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T22:24:26.217326Z","caller":"traceutil/trace.go:171","msg":"trace[1982066301] linearizableReadLoop","detail":"{readStateIndex:5666; appliedIndex:5665; }","duration":"336.263474ms","start":"2026-06-09T22:24:25.881045Z","end":"2026-06-09T22:24:26.217308Z","steps":["trace[1982066301] 'read index received' (duration: 10.940865ms)","trace[1982066301] 'applied index is now lower than readState.Index' (duration: 325.321349ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:24:26.217401Z","caller":"traceutil/trace.go:171","msg":"trace[471854502] transaction","detail":"{read_only:false; response_revision:5455; number_of_response:1; }","duration":"486.529665ms","start":"2026-06-09T22:24:25.73086Z","end":"2026-06-09T22:24:26.217389Z","steps":["trace[471854502] 'process raft request' (duration: 160.989787ms)","trace[471854502] 'compare' (duration: 325.102051ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:24:26.217449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.392378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:24:26.217524Z","caller":"traceutil/trace.go:171","msg":"trace[218174189] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5455; }","duration":"336.528993ms","start":"2026-06-09T22:24:25.880978Z","end":"2026-06-09T22:24:26.217507Z","steps":["trace[218174189] 'agreement among raft nodes before linearized reading' (duration: 336.408059ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:26.217553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:24:25.880959Z","time spent":"336.588466ms","remote":"127.0.0.1:49602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"warn","ts":"2026-06-09T22:24:26.217466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:24:25.730836Z","time spent":"486.599078ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:24:26.311778Z","caller":"traceutil/trace.go:171","msg":"trace[1774860872] transaction","detail":"{read_only:false; response_revision:5456; number_of_response:1; }","duration":"430.218607ms","start":"2026-06-09T22:24:25.881531Z","end":"2026-06-09T22:24:26.31175Z","steps":["trace[1774860872] 'process raft request' (duration: 430.081492ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:26.311941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:24:25.881509Z","time spent":"430.345741ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:24:26.448323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.621567ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T22:24:26.448457Z","caller":"traceutil/trace.go:171","msg":"trace[2006669626] transaction","detail":"{read_only:false; response_revision:5457; number_of_response:1; }","duration":"257.892479ms","start":"2026-06-09T22:24:26.190529Z","end":"2026-06-09T22:24:26.448421Z","steps":["trace[2006669626] 'process raft request' (duration: 153.061664ms)","trace[2006669626] 'compare' (duration: 104.164819ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:24:26.448683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.719247ms","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-06-09T22:24:26.44881Z","caller":"traceutil/trace.go:171","msg":"trace[665854713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5457; }","duration":"326.845212ms","start":"2026-06-09T22:24:26.121949Z","end":"2026-06-09T22:24:26.448794Z","steps":["trace[665854713] 'agreement among raft nodes before linearized reading' (duration: 326.550951ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:26.448856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:24:26.121892Z","time spent":"326.954987ms","remote":"127.0.0.1:49622","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":"info","ts":"2026-06-09T22:24:26.448443Z","caller":"traceutil/trace.go:171","msg":"trace[2085777446] linearizableReadLoop","detail":"{readStateIndex:5668; appliedIndex:5667; }","duration":"231.068492ms","start":"2026-06-09T22:24:26.217352Z","end":"2026-06-09T22:24:26.44842Z","steps":["trace[2085777446] 'read index received' (duration: 94.445082ms)","trace[2085777446] 'applied index is now lower than readState.Index' (duration: 136.62165ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:24:26.449194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.030792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6157"} {"level":"warn","ts":"2026-06-09T22:24:26.449213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.158422ms","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-06-09T22:24:26.449223Z","caller":"traceutil/trace.go:171","msg":"trace[1061400384] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5457; }","duration":"568.096114ms","start":"2026-06-09T22:24:25.881117Z","end":"2026-06-09T22:24:26.449213Z","steps":["trace[1061400384] 'agreement among raft nodes before linearized reading' (duration: 567.99438ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:26.449247Z","caller":"traceutil/trace.go:171","msg":"trace[1928944399] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5457; }","duration":"551.235036ms","start":"2026-06-09T22:24:25.898003Z","end":"2026-06-09T22:24:26.449239Z","steps":["trace[1928944399] 'agreement among raft nodes before linearized reading' (duration: 551.109591ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:26.44927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:24:25.897988Z","time spent":"551.277367ms","remote":"127.0.0.1:49622","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-06-09T22:24:26.449945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:24:25.881095Z","time spent":"568.817201ms","remote":"127.0.0.1:49602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":6179,"request content":"key:\"/registry/jobs/openstack/keystone-credential-setup\" "} {"level":"warn","ts":"2026-06-09T22:24:27.079846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.387642ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T22:24:27.080388Z","caller":"traceutil/trace.go:171","msg":"trace[386780249] linearizableReadLoop","detail":"{readStateIndex:5670; appliedIndex:5668; }","duration":"568.646293ms","start":"2026-06-09T22:24:26.511719Z","end":"2026-06-09T22:24:27.080366Z","steps":["trace[386780249] 'read index received' (duration: 61.70404ms)","trace[386780249] 'applied index is now lower than readState.Index' (duration: 506.940663ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:24:27.080448Z","caller":"traceutil/trace.go:171","msg":"trace[840997835] transaction","detail":"{read_only:false; response_revision:5458; number_of_response:1; }","duration":"626.522247ms","start":"2026-06-09T22:24:26.453878Z","end":"2026-06-09T22:24:27.080401Z","steps":["trace[840997835] 'process raft request' (duration: 119.453279ms)","trace[840997835] 'compare' (duration: 505.986747ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:24:27.080541Z","caller":"traceutil/trace.go:171","msg":"trace[297509822] transaction","detail":"{read_only:false; response_revision:5459; number_of_response:1; }","duration":"624.5019ms","start":"2026-06-09T22:24:26.455963Z","end":"2026-06-09T22:24:27.080465Z","steps":["trace[297509822] 'process raft request' (duration: 624.016222ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:27.080618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.891362ms","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-06-09T22:24:27.080681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:24:26.453854Z","time spent":"626.700484ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:24:27.080671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:24:26.455935Z","time spent":"624.680197ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:24:27.080691Z","caller":"traceutil/trace.go:171","msg":"trace[1441361857] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5459; }","duration":"569.027497ms","start":"2026-06-09T22:24:26.511646Z","end":"2026-06-09T22:24:27.080673Z","steps":["trace[1441361857] 'agreement among raft nodes before linearized reading' (duration: 568.82221ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:27.080824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:24:26.511618Z","time spent":"569.166173ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-09T22:24:27.816568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.861691ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T22:24:27.816779Z","caller":"traceutil/trace.go:171","msg":"trace[235913584] linearizableReadLoop","detail":"{readStateIndex:5671; appliedIndex:5670; }","duration":"736.325648ms","start":"2026-06-09T22:24:27.080433Z","end":"2026-06-09T22:24:27.816758Z","steps":["trace[235913584] 'read index received' (duration: 294.172156ms)","trace[235913584] 'applied index is now lower than readState.Index' (duration: 442.152152ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:24:27.816885Z","caller":"traceutil/trace.go:171","msg":"trace[1998078337] transaction","detail":"{read_only:false; response_revision:5460; number_of_response:1; }","duration":"926.604645ms","start":"2026-06-09T22:24:26.890264Z","end":"2026-06-09T22:24:27.816869Z","steps":["trace[1998078337] 'process raft request' (duration: 484.330728ms)","trace[1998078337] 'compare' (duration: 441.741167ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:24:27.816975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:24:26.89024Z","time spent":"926.688128ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:24:27.817618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.222305046s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T22:24:27.817654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.266641897s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:24:27.817694Z","caller":"traceutil/trace.go:171","msg":"trace[579327282] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:5461; }","duration":"1.222452882s","start":"2026-06-09T22:24:26.595225Z","end":"2026-06-09T22:24:27.817677Z","steps":["trace[579327282] 'agreement among raft nodes before linearized reading' (duration: 1.221966073s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:27.817702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127606406s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-09T22:24:27.817724Z","caller":"traceutil/trace.go:171","msg":"trace[2076409789] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5461; }","duration":"1.266791693s","start":"2026-06-09T22:24:26.550915Z","end":"2026-06-09T22:24:27.817707Z","steps":["trace[2076409789] 'agreement among raft nodes before linearized reading' (duration: 1.266325385s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:27.817704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.436084ms","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-06-09T22:24:27.817767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:24:26.550881Z","time spent":"1.266878106s","remote":"127.0.0.1:49380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T22:24:27.817702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"984.195719ms","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-06-09T22:24:27.817789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:24:26.595201Z","time spent":"1.222573736s","remote":"127.0.0.1:49478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-06-09T22:24:27.817813Z","caller":"traceutil/trace.go:171","msg":"trace[194510103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5461; }","duration":"984.361984ms","start":"2026-06-09T22:24:26.83344Z","end":"2026-06-09T22:24:27.817801Z","steps":["trace[194510103] 'agreement among raft nodes before linearized reading' (duration: 983.589745ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:27.817873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:24:26.833408Z","time spent":"984.450598ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-09T22:24:27.817765Z","caller":"traceutil/trace.go:171","msg":"trace[824095378] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5461; }","duration":"1.127716989s","start":"2026-06-09T22:24:26.690032Z","end":"2026-06-09T22:24:27.817749Z","steps":["trace[824095378] 'agreement among raft nodes before linearized reading' (duration: 1.12719516s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:27.818111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:24:26.689998Z","time spent":"1.128071113s","remote":"127.0.0.1:49560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-06-09T22:24:27.817814Z","caller":"traceutil/trace.go:171","msg":"trace[2022114485] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5461; }","duration":"928.610411ms","start":"2026-06-09T22:24:26.889182Z","end":"2026-06-09T22:24:27.817792Z","steps":["trace[2022114485] 'agreement among raft nodes before linearized reading' (duration: 927.884844ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:27.818384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:24:26.889155Z","time spent":"929.093249ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-09T22:24:27.818414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.410973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-09T22:24:27.817534Z","caller":"traceutil/trace.go:171","msg":"trace[1088738647] transaction","detail":"{read_only:false; response_revision:5461; number_of_response:1; }","duration":"732.206731ms","start":"2026-06-09T22:24:27.085302Z","end":"2026-06-09T22:24:27.817508Z","steps":["trace[1088738647] 'process raft request' (duration: 731.396411ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:27.818838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:24:27.085283Z","time spent":"733.496821ms","remote":"127.0.0.1:49622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:24:27.819088Z","caller":"traceutil/trace.go:171","msg":"trace[927339240] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:5461; }","duration":"886.048088ms","start":"2026-06-09T22:24:26.932702Z","end":"2026-06-09T22:24:27.81875Z","steps":["trace[927339240] 'agreement among raft nodes before linearized reading' (duration: 884.353763ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:27.819254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:24:26.932685Z","time spent":"886.488815ms","remote":"127.0.0.1:49404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.38\" "} {"level":"warn","ts":"2026-06-09T22:24:27.908661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.488973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:24:27.908737Z","caller":"traceutil/trace.go:171","msg":"trace[903438296] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5462; }","duration":"154.634879ms","start":"2026-06-09T22:24:27.754088Z","end":"2026-06-09T22:24:27.908723Z","steps":["trace[903438296] 'agreement among raft nodes before linearized reading' (duration: 154.494783ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:27.908725Z","caller":"traceutil/trace.go:171","msg":"trace[846817511] transaction","detail":"{read_only:false; response_revision:5462; number_of_response:1; }","duration":"153.652282ms","start":"2026-06-09T22:24:27.755024Z","end":"2026-06-09T22:24:27.908677Z","steps":["trace[846817511] 'process raft request' (duration: 153.355281ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:27.908668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.770866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T22:24:27.908795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.946517ms","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":"info","ts":"2026-06-09T22:24:27.908827Z","caller":"traceutil/trace.go:171","msg":"trace[413161058] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:5462; }","duration":"227.990064ms","start":"2026-06-09T22:24:27.680822Z","end":"2026-06-09T22:24:27.908813Z","steps":["trace[413161058] 'agreement among raft nodes before linearized reading' (duration: 227.763506ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:27.908884Z","caller":"traceutil/trace.go:171","msg":"trace[92730361] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:5462; }","duration":"260.183606ms","start":"2026-06-09T22:24:27.648683Z","end":"2026-06-09T22:24:27.908866Z","steps":["trace[92730361] 'agreement among raft nodes before linearized reading' (duration: 260.02601ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:27.95383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.888453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:24:27.954044Z","caller":"traceutil/trace.go:171","msg":"trace[163494939] transaction","detail":"{read_only:false; response_revision:5464; number_of_response:1; }","duration":"130.904699ms","start":"2026-06-09T22:24:27.823051Z","end":"2026-06-09T22:24:27.953956Z","steps":["trace[163494939] 'process raft request' (duration: 130.586387ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:27.954133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.887196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-09T22:24:27.954191Z","caller":"traceutil/trace.go:171","msg":"trace[1004479777] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5464; }","duration":"132.00715ms","start":"2026-06-09T22:24:27.822174Z","end":"2026-06-09T22:24:27.954181Z","steps":["trace[1004479777] 'agreement among raft nodes before linearized reading' (duration: 131.810523ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:27.954071Z","caller":"traceutil/trace.go:171","msg":"trace[1603485924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5464; }","duration":"134.190233ms","start":"2026-06-09T22:24:27.819864Z","end":"2026-06-09T22:24:27.954055Z","steps":["trace[1603485924] 'agreement among raft nodes before linearized reading' (duration: 133.84138ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:27.953883Z","caller":"traceutil/trace.go:171","msg":"trace[344109088] transaction","detail":"{read_only:false; response_revision:5463; number_of_response:1; }","duration":"131.19921ms","start":"2026-06-09T22:24:27.822657Z","end":"2026-06-09T22:24:27.953856Z","steps":["trace[344109088] 'process raft request' (duration: 130.67152ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:36.845307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.073871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T22:24:36.845471Z","caller":"traceutil/trace.go:171","msg":"trace[866306248] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:5533; }","duration":"108.267029ms","start":"2026-06-09T22:24:36.737155Z","end":"2026-06-09T22:24:36.845422Z","steps":["trace[866306248] 'count revisions from in-memory index tree' (duration: 107.882515ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:40.016279Z","caller":"traceutil/trace.go:171","msg":"trace[1453257848] transaction","detail":"{read_only:false; response_revision:5552; number_of_response:1; }","duration":"125.796376ms","start":"2026-06-09T22:24:39.890451Z","end":"2026-06-09T22:24:40.016247Z","steps":["trace[1453257848] 'process raft request' (duration: 112.64865ms)","trace[1453257848] 'compare' (duration: 12.849525ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:24:40.973601Z","caller":"traceutil/trace.go:171","msg":"trace[1730951112] transaction","detail":"{read_only:false; response_revision:5558; number_of_response:1; }","duration":"116.72807ms","start":"2026-06-09T22:24:40.856849Z","end":"2026-06-09T22:24:40.973578Z","steps":["trace[1730951112] 'process raft request' (duration: 116.546343ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:44.564576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.280644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-06-09T22:24:44.564678Z","caller":"traceutil/trace.go:171","msg":"trace[1297101833] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5582; }","duration":"107.44054ms","start":"2026-06-09T22:24:44.457199Z","end":"2026-06-09T22:24:44.564639Z","steps":["trace[1297101833] 'range keys from in-memory index tree' (duration: 106.998513ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:27:24.191451Z","caller":"traceutil/trace.go:171","msg":"trace[2133167968] transaction","detail":"{read_only:false; response_revision:6689; number_of_response:1; }","duration":"153.968344ms","start":"2026-06-09T22:27:24.037452Z","end":"2026-06-09T22:27:24.19142Z","steps":["trace[2133167968] 'process raft request' (duration: 127.328965ms)","trace[2133167968] 'compare' (duration: 26.317546ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:29:22.311077Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5440} {"level":"info","ts":"2026-06-09T22:29:22.402733Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5440,"took":"90.658727ms","hash":3173256534,"current-db-size-bytes":52858880,"current-db-size":"53 MB","current-db-size-in-use-bytes":18477056,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-09T22:29:22.40285Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3173256534,"revision":5440,"compact-revision":2862} {"level":"warn","ts":"2026-06-09T22:30:34.065666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.072555ms","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-06-09T22:30:34.065738Z","caller":"traceutil/trace.go:171","msg":"trace[929892389] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7738; }","duration":"101.176007ms","start":"2026-06-09T22:30:33.964546Z","end":"2026-06-09T22:30:34.065722Z","steps":["trace[929892389] 'range keys from in-memory index tree' (duration: 100.956404ms)"],"step_count":1}