{"level":"warn","ts":"2026-08-14T09:13:02.375963Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-14T09:13:02.376097Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156: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.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156: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-08-14T09:13:02.376183Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-14T09:13:02.376205Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-08-14T09:13:02.376222Z","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-08-14T09:13:02.377001Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-08-14T09:13:02.377087Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156: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-08-14T09:13:02.380218Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.884516ms"} {"level":"info","ts":"2026-08-14T09:13:02.386793Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-08-14T09:13:02.386931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-08-14T09:13:02.386974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-08-14T09:13:02.387023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-14T09:13:02.387101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-08-14T09:13:02.387196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-08-14T09:13:02.392416Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-14T09:13:02.394207Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-14T09:13:02.395706Z","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-08-14T09:13:02.39805Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-14T09:13:02.398325Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-14T09:13:02.398437Z","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-08-14T09:13:02.398624Z","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-08-14T09:13:02.398669Z","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-08-14T09:13:02.400801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-08-14T09:13:02.400967Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-08-14T09:13:02.402452Z","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-08-14T09:13:02.402592Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-08-14T09:13:02.402631Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-08-14T09:13:02.402786Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-14T09:13:02.402862Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-14T09:13:03.087901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-08-14T09:13:03.087961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-14T09:13:03.087981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-08-14T09:13:03.087998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-08-14T09:13:03.088007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-08-14T09:13:03.08802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-08-14T09:13:03.08803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-08-14T09:13:03.089245Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:13:03.090097Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-08-14T09:13:03.090263Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T09:13:03.090335Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T09:13:03.090897Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:13:03.091044Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:13:03.091085Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:13:03.093835Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-08-14T09:13:03.093921Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-14T09:13:03.103008Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-14T09:13:03.103087Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-14T09:13:06.639363Z","caller":"traceutil/trace.go:171","msg":"trace[1302943899] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"133.549574ms","start":"2026-08-14T09:13:06.505785Z","end":"2026-08-14T09:13:06.639335Z","steps":["trace[1302943899] 'process raft request' (duration: 133.507333ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:06.639378Z","caller":"traceutil/trace.go:171","msg":"trace[1779745161] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"137.893504ms","start":"2026-08-14T09:13:06.501437Z","end":"2026-08-14T09:13:06.63933Z","steps":["trace[1779745161] 'process raft request' (duration: 129.574656ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:06.639454Z","caller":"traceutil/trace.go:171","msg":"trace[560024243] linearizableReadLoop","detail":"{readStateIndex:239; appliedIndex:238; }","duration":"134.827373ms","start":"2026-08-14T09:13:06.504605Z","end":"2026-08-14T09:13:06.639432Z","steps":["trace[560024243] 'read index received' (duration: 126.34204ms)","trace[560024243] 'applied index is now lower than readState.Index' (duration: 8.482473ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:13:06.6397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.036739ms","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-08-14T09:13:06.639763Z","caller":"traceutil/trace.go:171","msg":"trace[1951808379] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:234; }","duration":"135.214705ms","start":"2026-08-14T09:13:06.504538Z","end":"2026-08-14T09:13:06.639753Z","steps":["trace[1951808379] 'agreement among raft nodes before linearized reading' (duration: 135.006498ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:06.639781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.630829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:13:06.639917Z","caller":"traceutil/trace.go:171","msg":"trace[1554191284] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:0; response_revision:234; }","duration":"124.824255ms","start":"2026-08-14T09:13:06.515063Z","end":"2026-08-14T09:13:06.639887Z","steps":["trace[1554191284] 'agreement among raft nodes before linearized reading' (duration: 124.573777ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:11.813867Z","caller":"traceutil/trace.go:171","msg":"trace[1734932186] linearizableReadLoop","detail":"{readStateIndex:374; appliedIndex:373; }","duration":"119.475285ms","start":"2026-08-14T09:13:11.694359Z","end":"2026-08-14T09:13:11.813834Z","steps":["trace[1734932186] 'read index received' (duration: 119.251989ms)","trace[1734932186] 'applied index is now lower than readState.Index' (duration: 218.676µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:13:11.813953Z","caller":"traceutil/trace.go:171","msg":"trace[1368868347] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"157.71997ms","start":"2026-08-14T09:13:11.656206Z","end":"2026-08-14T09:13:11.813926Z","steps":["trace[1368868347] 'process raft request' (duration: 157.395361ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:11.814132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.732073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-08-14T09:13:11.814235Z","caller":"traceutil/trace.go:171","msg":"trace[1401285789] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:361; }","duration":"119.882588ms","start":"2026-08-14T09:13:11.694331Z","end":"2026-08-14T09:13:11.814213Z","steps":["trace[1401285789] 'agreement among raft nodes before linearized reading' (duration: 119.681411ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:20.7582Z","caller":"traceutil/trace.go:171","msg":"trace[70193628] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"129.85338ms","start":"2026-08-14T09:13:20.628312Z","end":"2026-08-14T09:13:20.758165Z","steps":["trace[70193628] 'process raft request' (duration: 129.653934ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:20.911776Z","caller":"traceutil/trace.go:171","msg":"trace[1861400763] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"149.056104ms","start":"2026-08-14T09:13:20.762689Z","end":"2026-08-14T09:13:20.911745Z","steps":["trace[1861400763] 'process raft request' (duration: 142.051126ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:21.228126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.72832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-14T09:13:21.228253Z","caller":"traceutil/trace.go:171","msg":"trace[2113954113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:416; }","duration":"121.919726ms","start":"2026-08-14T09:13:21.106311Z","end":"2026-08-14T09:13:21.228231Z","steps":["trace[2113954113] 'range keys from in-memory index tree' (duration: 121.531684ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:21.375922Z","caller":"traceutil/trace.go:171","msg":"trace[402700739] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"142.604932ms","start":"2026-08-14T09:13:21.23329Z","end":"2026-08-14T09:13:21.375895Z","steps":["trace[402700739] 'process raft request' (duration: 142.444277ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:42.590782Z","caller":"traceutil/trace.go:171","msg":"trace[1268210233] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"144.040093ms","start":"2026-08-14T09:13:42.446714Z","end":"2026-08-14T09:13:42.590754Z","steps":["trace[1268210233] 'read index received' (duration: 53.804465ms)","trace[1268210233] 'applied index is now lower than readState.Index' (duration: 90.234658ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:13:42.590846Z","caller":"traceutil/trace.go:171","msg":"trace[591262332] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"158.969296ms","start":"2026-08-14T09:13:42.431844Z","end":"2026-08-14T09:13:42.590814Z","steps":["trace[591262332] 'process raft request' (duration: 68.694796ms)","trace[591262332] 'compare' (duration: 89.957341ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:13:42.590926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.187517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:13:42.590961Z","caller":"traceutil/trace.go:171","msg":"trace[2094304401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"144.276859ms","start":"2026-08-14T09:13:42.446672Z","end":"2026-08-14T09:13:42.590949Z","steps":["trace[2094304401] 'agreement among raft nodes before linearized reading' (duration: 144.191577ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:42.775473Z","caller":"traceutil/trace.go:171","msg":"trace[860761374] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"138.637769ms","start":"2026-08-14T09:13:42.636809Z","end":"2026-08-14T09:13:42.775447Z","steps":["trace[860761374] 'process raft request' (duration: 138.576788ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:42.775937Z","caller":"traceutil/trace.go:171","msg":"trace[419854679] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"183.486031ms","start":"2026-08-14T09:13:42.592419Z","end":"2026-08-14T09:13:42.775905Z","steps":["trace[419854679] 'read index received' (duration: 151.037591ms)","trace[419854679] 'applied index is now lower than readState.Index' (duration: 32.44321ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:13:42.775966Z","caller":"traceutil/trace.go:171","msg":"trace[729696014] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"268.255873ms","start":"2026-08-14T09:13:42.50768Z","end":"2026-08-14T09:13:42.775936Z","steps":["trace[729696014] 'process raft request' (duration: 235.893815ms)","trace[729696014] 'compare' (duration: 31.340608ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:13:42.776086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.652715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:13:42.776129Z","caller":"traceutil/trace.go:171","msg":"trace[1173658584] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"183.750368ms","start":"2026-08-14T09:13:42.592368Z","end":"2026-08-14T09:13:42.776118Z","steps":["trace[1173658584] 'agreement among raft nodes before linearized reading' (duration: 183.665226ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:47.664126Z","caller":"traceutil/trace.go:171","msg":"trace[1435086228] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"147.404408ms","start":"2026-08-14T09:13:47.516386Z","end":"2026-08-14T09:13:47.66379Z","steps":["trace[1435086228] 'process raft request' (duration: 147.166142ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:48.147036Z","caller":"traceutil/trace.go:171","msg":"trace[1531163297] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"181.976217ms","start":"2026-08-14T09:13:47.965037Z","end":"2026-08-14T09:13:48.147013Z","steps":["trace[1531163297] 'read index received' (duration: 181.421412ms)","trace[1531163297] 'applied index is now lower than readState.Index' (duration: 553.615µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:13:48.147168Z","caller":"traceutil/trace.go:171","msg":"trace[859475640] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"287.068706ms","start":"2026-08-14T09:13:47.860011Z","end":"2026-08-14T09:13:48.147079Z","steps":["trace[859475640] 'process raft request' (duration: 286.51506ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:13:48.147217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.161413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-n7kbb\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-14T09:13:48.147316Z","caller":"traceutil/trace.go:171","msg":"trace[326365694] range","detail":"{range_begin:/registry/pods/kube-system/cilium-n7kbb; range_end:; response_count:1; response_revision:636; }","duration":"182.301766ms","start":"2026-08-14T09:13:47.965003Z","end":"2026-08-14T09:13:48.147305Z","steps":["trace[326365694] 'agreement among raft nodes before linearized reading' (duration: 182.098861ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:13:49.165328Z","caller":"traceutil/trace.go:171","msg":"trace[1983200077] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"100.425016ms","start":"2026-08-14T09:13:49.064874Z","end":"2026-08-14T09:13:49.165299Z","steps":["trace[1983200077] 'process raft request' (duration: 100.185938ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:14:37.601701Z","caller":"traceutil/trace.go:171","msg":"trace[130176397] linearizableReadLoop","detail":"{readStateIndex:1255; appliedIndex:1254; }","duration":"156.469443ms","start":"2026-08-14T09:14:37.445198Z","end":"2026-08-14T09:14:37.601668Z","steps":["trace[130176397] 'read index received' (duration: 156.162564ms)","trace[130176397] 'applied index is now lower than readState.Index' (duration: 305.839µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:14:37.60176Z","caller":"traceutil/trace.go:171","msg":"trace[1470866529] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"197.366258ms","start":"2026-08-14T09:14:37.404363Z","end":"2026-08-14T09:14:37.601729Z","steps":["trace[1470866529] 'process raft request' (duration: 197.07366ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:14:37.601847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.622837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:14:37.601883Z","caller":"traceutil/trace.go:171","msg":"trace[1404346312] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1216; }","duration":"156.72689ms","start":"2026-08-14T09:14:37.445145Z","end":"2026-08-14T09:14:37.601872Z","steps":["trace[1404346312] 'agreement among raft nodes before linearized reading' (duration: 156.644648ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:15:24.566901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.655308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:15:24.567031Z","caller":"traceutil/trace.go:171","msg":"trace[500310705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1895; }","duration":"120.843654ms","start":"2026-08-14T09:15:24.446159Z","end":"2026-08-14T09:15:24.567003Z","steps":["trace[500310705] 'range keys from in-memory index tree' (duration: 120.512465ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:15:24.567167Z","caller":"traceutil/trace.go:171","msg":"trace[1865314527] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"118.346313ms","start":"2026-08-14T09:15:24.448799Z","end":"2026-08-14T09:15:24.567146Z","steps":["trace[1865314527] 'process raft request' (duration: 116.533032ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:15:39.520364Z","caller":"traceutil/trace.go:171","msg":"trace[284595050] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"169.703574ms","start":"2026-08-14T09:15:39.350634Z","end":"2026-08-14T09:15:39.520338Z","steps":["trace[284595050] 'process raft request' (duration: 169.521459ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:16:19.915711Z","caller":"traceutil/trace.go:171","msg":"trace[1061320969] transaction","detail":"{read_only:false; response_revision:2244; number_of_response:1; }","duration":"196.552062ms","start":"2026-08-14T09:16:19.719131Z","end":"2026-08-14T09:16:19.915683Z","steps":["trace[1061320969] 'process raft request' (duration: 196.376747ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:16:20.274856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.238472ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:16:20.275138Z","caller":"traceutil/trace.go:171","msg":"trace[881233071] linearizableReadLoop","detail":"{readStateIndex:2331; appliedIndex:2328; }","duration":"460.361525ms","start":"2026-08-14T09:16:19.814752Z","end":"2026-08-14T09:16:20.275113Z","steps":["trace[881233071] 'read index received' (duration: 100.89951ms)","trace[881233071] 'applied index is now lower than readState.Index' (duration: 359.460795ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:16:20.275158Z","caller":"traceutil/trace.go:171","msg":"trace[1937199413] transaction","detail":"{read_only:false; response_revision:2245; number_of_response:1; }","duration":"555.04951ms","start":"2026-08-14T09:16:19.720047Z","end":"2026-08-14T09:16:20.275097Z","steps":["trace[1937199413] 'process raft request' (duration: 299.700677ms)","trace[1937199413] 'compare' (duration: 254.039395ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:16:20.275265Z","caller":"traceutil/trace.go:171","msg":"trace[1730962838] transaction","detail":"{read_only:false; response_revision:2246; number_of_response:1; }","duration":"523.938011ms","start":"2026-08-14T09:16:19.751313Z","end":"2026-08-14T09:16:20.275251Z","steps":["trace[1730962838] 'process raft request' (duration: 523.672904ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:16:20.275369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:16:19.751277Z","time spent":"524.034604ms","remote":"127.0.0.1:57416","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-08-14T09:16:20.275433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:16:19.720007Z","time spent":"555.274736ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:16:20.275621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.866039ms","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-08-14T09:16:20.275694Z","caller":"traceutil/trace.go:171","msg":"trace[124420259] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2246; }","duration":"460.973812ms","start":"2026-08-14T09:16:19.814704Z","end":"2026-08-14T09:16:20.275677Z","steps":["trace[124420259] 'agreement among raft nodes before linearized reading' (duration: 460.830268ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:16:20.275741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:16:19.814686Z","time spent":"461.044265ms","remote":"127.0.0.1:57416","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-08-14T09:17:35.862165Z","caller":"traceutil/trace.go:171","msg":"trace[784652179] transaction","detail":"{read_only:false; response_revision:2854; number_of_response:1; }","duration":"183.943439ms","start":"2026-08-14T09:17:35.678186Z","end":"2026-08-14T09:17:35.86213Z","steps":["trace[784652179] 'process raft request' (duration: 183.695602ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:17:36.853393Z","caller":"traceutil/trace.go:171","msg":"trace[867378176] transaction","detail":"{read_only:false; response_revision:2858; number_of_response:1; }","duration":"218.944704ms","start":"2026-08-14T09:17:36.634419Z","end":"2026-08-14T09:17:36.853364Z","steps":["trace[867378176] 'process raft request' (duration: 173.081777ms)","trace[867378176] 'compare' (duration: 45.691191ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:17:36.853381Z","caller":"traceutil/trace.go:171","msg":"trace[983812800] linearizableReadLoop","detail":"{readStateIndex:2966; appliedIndex:2965; }","duration":"195.273625ms","start":"2026-08-14T09:17:36.65808Z","end":"2026-08-14T09:17:36.853354Z","steps":["trace[983812800] 'read index received' (duration: 149.434618ms)","trace[983812800] 'applied index is now lower than readState.Index' (duration: 45.837687ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:17:36.853745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.644835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-08-14T09:17:36.853743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.558619ms","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-08-14T09:17:36.853817Z","caller":"traceutil/trace.go:171","msg":"trace[1277975296] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2858; }","duration":"195.758488ms","start":"2026-08-14T09:17:36.658043Z","end":"2026-08-14T09:17:36.853802Z","steps":["trace[1277975296] 'agreement among raft nodes before linearized reading' (duration: 195.494241ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:17:36.853828Z","caller":"traceutil/trace.go:171","msg":"trace[1112972870] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2858; }","duration":"190.656982ms","start":"2026-08-14T09:17:36.663153Z","end":"2026-08-14T09:17:36.85381Z","steps":["trace[1112972870] 'agreement among raft nodes before linearized reading' (duration: 190.415305ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:17:36.853985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.076283ms","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-08-14T09:17:36.854027Z","caller":"traceutil/trace.go:171","msg":"trace[516442673] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2858; }","duration":"161.158145ms","start":"2026-08-14T09:17:36.692856Z","end":"2026-08-14T09:17:36.854014Z","steps":["trace[516442673] 'agreement among raft nodes before linearized reading' (duration: 161.070353ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:17:37.132835Z","caller":"traceutil/trace.go:171","msg":"trace[1766795201] linearizableReadLoop","detail":"{readStateIndex:2969; appliedIndex:2966; }","duration":"266.197639ms","start":"2026-08-14T09:17:36.866617Z","end":"2026-08-14T09:17:37.132815Z","steps":["trace[1766795201] 'read index received' (duration: 31.282237ms)","trace[1766795201] 'applied index is now lower than readState.Index' (duration: 234.914592ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:17:37.132916Z","caller":"traceutil/trace.go:171","msg":"trace[2001445560] transaction","detail":"{read_only:false; response_revision:2861; number_of_response:1; }","duration":"273.821328ms","start":"2026-08-14T09:17:36.859086Z","end":"2026-08-14T09:17:37.132907Z","steps":["trace[2001445560] 'process raft request' (duration: 273.702775ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:17:37.132909Z","caller":"traceutil/trace.go:171","msg":"trace[1845875450] transaction","detail":"{read_only:false; response_revision:2860; number_of_response:1; }","duration":"274.162779ms","start":"2026-08-14T09:17:36.858711Z","end":"2026-08-14T09:17:37.132874Z","steps":["trace[1845875450] 'process raft request' (duration: 273.949143ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:17:37.133171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.508269ms","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-08-14T09:17:37.13324Z","caller":"traceutil/trace.go:171","msg":"trace[196989925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2861; }","duration":"266.624131ms","start":"2026-08-14T09:17:36.866583Z","end":"2026-08-14T09:17:37.133207Z","steps":["trace[196989925] 'agreement among raft nodes before linearized reading' (duration: 266.424246ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:17:37.146874Z","caller":"traceutil/trace.go:171","msg":"trace[666654266] transaction","detail":"{read_only:false; response_revision:2862; number_of_response:1; }","duration":"102.940014ms","start":"2026-08-14T09:17:37.043915Z","end":"2026-08-14T09:17:37.146855Z","steps":["trace[666654266] 'process raft request' (duration: 102.80807ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:17:37.147015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.442533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:17:37.147078Z","caller":"traceutil/trace.go:171","msg":"trace[1976374413] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2862; }","duration":"269.547556ms","start":"2026-08-14T09:17:36.877514Z","end":"2026-08-14T09:17:37.147061Z","steps":["trace[1976374413] 'agreement among raft nodes before linearized reading' (duration: 269.427033ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:17:37.347486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.960838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:17:37.347599Z","caller":"traceutil/trace.go:171","msg":"trace[1104815066] linearizableReadLoop","detail":"{readStateIndex:2972; appliedIndex:2971; }","duration":"102.720658ms","start":"2026-08-14T09:17:37.24486Z","end":"2026-08-14T09:17:37.347581Z","steps":["trace[1104815066] 'read index received' (duration: 55.191µs)","trace[1104815066] 'applied index is now lower than readState.Index' (duration: 102.663877ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:17:37.347772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.920794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-14T09:17:37.347816Z","caller":"traceutil/trace.go:171","msg":"trace[443209024] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2864; }","duration":"103.008496ms","start":"2026-08-14T09:17:37.244789Z","end":"2026-08-14T09:17:37.347797Z","steps":["trace[443209024] 'agreement among raft nodes before linearized reading' (duration: 102.840412ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:17:37.347907Z","caller":"traceutil/trace.go:171","msg":"trace[640503576] transaction","detail":"{read_only:false; response_revision:2864; number_of_response:1; }","duration":"198.684302ms","start":"2026-08-14T09:17:37.149186Z","end":"2026-08-14T09:17:37.347871Z","steps":["trace[640503576] 'process raft request' (duration: 89.240681ms)","trace[640503576] 'compare' (duration: 108.824684ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:17:37.570488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.209816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:17:37.570644Z","caller":"traceutil/trace.go:171","msg":"trace[526812209] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2864; }","duration":"123.418272ms","start":"2026-08-14T09:17:37.447175Z","end":"2026-08-14T09:17:37.570593Z","steps":["trace[526812209] 'range keys from in-memory index tree' (duration: 123.112123ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:17:37.570633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.424362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-14T09:17:37.570743Z","caller":"traceutil/trace.go:171","msg":"trace[1076579854] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2864; }","duration":"123.575706ms","start":"2026-08-14T09:17:37.447144Z","end":"2026-08-14T09:17:37.57072Z","steps":["trace[1076579854] 'range keys from in-memory index tree' (duration: 123.194375ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:17:38.080631Z","caller":"traceutil/trace.go:171","msg":"trace[1729431929] transaction","detail":"{read_only:false; response_revision:2865; number_of_response:1; }","duration":"209.503903ms","start":"2026-08-14T09:17:37.871101Z","end":"2026-08-14T09:17:38.080605Z","steps":["trace[1729431929] 'process raft request' (duration: 209.345218ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:21:34.87456Z","caller":"traceutil/trace.go:171","msg":"trace[2139247059] linearizableReadLoop","detail":"{readStateIndex:5040; appliedIndex:5039; }","duration":"121.568907ms","start":"2026-08-14T09:21:34.752969Z","end":"2026-08-14T09:21:34.874538Z","steps":["trace[2139247059] 'read index received' (duration: 93.799745ms)","trace[2139247059] 'applied index is now lower than readState.Index' (duration: 27.768222ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:21:34.874678Z","caller":"traceutil/trace.go:171","msg":"trace[683604347] transaction","detail":"{read_only:false; response_revision:4857; number_of_response:1; }","duration":"162.118625ms","start":"2026-08-14T09:21:34.712529Z","end":"2026-08-14T09:21:34.874648Z","steps":["trace[683604347] 'process raft request' (duration: 134.23933ms)","trace[683604347] 'compare' (duration: 27.643609ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:21:34.875059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.071222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:22 size:526635"} {"level":"info","ts":"2026-08-14T09:21:34.875094Z","caller":"traceutil/trace.go:171","msg":"trace[2031620184] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:22; response_revision:4857; }","duration":"122.153044ms","start":"2026-08-14T09:21:34.752932Z","end":"2026-08-14T09:21:34.875085Z","steps":["trace[2031620184] 'agreement among raft nodes before linearized reading' (duration: 121.734272ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:21:42.543631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.077694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:21:42.543895Z","caller":"traceutil/trace.go:171","msg":"trace[1468971521] transaction","detail":"{read_only:false; response_revision:5101; number_of_response:1; }","duration":"121.782035ms","start":"2026-08-14T09:21:42.422074Z","end":"2026-08-14T09:21:42.543856Z","steps":["trace[1468971521] 'process raft request' (duration: 20.375751ms)","trace[1468971521] 'compare' (duration: 100.908949ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:21:42.543959Z","caller":"traceutil/trace.go:171","msg":"trace[1456463203] transaction","detail":"{read_only:false; response_revision:5102; number_of_response:1; }","duration":"119.452938ms","start":"2026-08-14T09:21:42.424481Z","end":"2026-08-14T09:21:42.543933Z","steps":["trace[1456463203] 'process raft request' (duration: 119.296803ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:21:42.604454Z","caller":"traceutil/trace.go:171","msg":"trace[1164822135] transaction","detail":"{read_only:false; response_revision:5103; number_of_response:1; }","duration":"125.107618ms","start":"2026-08-14T09:21:42.479319Z","end":"2026-08-14T09:21:42.604426Z","steps":["trace[1164822135] 'process raft request' (duration: 124.324676ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:21:42.604982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.843319ms","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-08-14T09:21:42.605093Z","caller":"traceutil/trace.go:171","msg":"trace[1351733054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5103; }","duration":"106.007594ms","start":"2026-08-14T09:21:42.499064Z","end":"2026-08-14T09:21:42.605072Z","steps":["trace[1351733054] 'agreement among raft nodes before linearized reading' (duration: 105.785977ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:21:42.60539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.146081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-dc8d79bf9-dmwdk.18cba18c6068a058\" ","response":"range_response_count:1 size:895"} {"level":"info","ts":"2026-08-14T09:21:42.605442Z","caller":"traceutil/trace.go:171","msg":"trace[319256527] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-dc8d79bf9-dmwdk.18cba18c6068a058; range_end:; response_count:1; response_revision:5103; }","duration":"125.214032ms","start":"2026-08-14T09:21:42.480214Z","end":"2026-08-14T09:21:42.605428Z","steps":["trace[319256527] 'agreement among raft nodes before linearized reading' (duration: 125.072328ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:21:42.605718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.454897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a8f7860d-62d0-4398-93a8-8debc0cdf7e0\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-08-14T09:21:42.605784Z","caller":"traceutil/trace.go:171","msg":"trace[1253393174] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a8f7860d-62d0-4398-93a8-8debc0cdf7e0; range_end:; response_count:1; response_revision:5103; }","duration":"127.55614ms","start":"2026-08-14T09:21:42.478213Z","end":"2026-08-14T09:21:42.605769Z","steps":["trace[1253393174] 'agreement among raft nodes before linearized reading' (duration: 127.386965ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:21:54.087444Z","caller":"traceutil/trace.go:171","msg":"trace[448816428] transaction","detail":"{read_only:false; response_revision:5336; number_of_response:1; }","duration":"141.038717ms","start":"2026-08-14T09:21:53.946377Z","end":"2026-08-14T09:21:54.087416Z","steps":["trace[448816428] 'process raft request' (duration: 140.798041ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:22:02.047067Z","caller":"traceutil/trace.go:171","msg":"trace[838440274] transaction","detail":"{read_only:false; response_revision:5534; number_of_response:1; }","duration":"107.583885ms","start":"2026-08-14T09:22:01.939451Z","end":"2026-08-14T09:22:02.047035Z","steps":["trace[838440274] 'process raft request' (duration: 107.256257ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:22:02.567285Z","caller":"traceutil/trace.go:171","msg":"trace[1740699103] linearizableReadLoop","detail":"{readStateIndex:5741; appliedIndex:5740; }","duration":"121.918894ms","start":"2026-08-14T09:22:02.445339Z","end":"2026-08-14T09:22:02.567258Z","steps":["trace[1740699103] 'read index received' (duration: 76.79071ms)","trace[1740699103] 'applied index is now lower than readState.Index' (duration: 45.127324ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:22:02.567278Z","caller":"traceutil/trace.go:171","msg":"trace[179427881] transaction","detail":"{read_only:false; response_revision:5536; number_of_response:1; }","duration":"136.209652ms","start":"2026-08-14T09:22:02.431021Z","end":"2026-08-14T09:22:02.567231Z","steps":["trace[179427881] 'process raft request' (duration: 91.16223ms)","trace[179427881] 'compare' (duration: 44.898958ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:22:02.567848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.484869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:22:02.567919Z","caller":"traceutil/trace.go:171","msg":"trace[1639080857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5537; }","duration":"122.568481ms","start":"2026-08-14T09:22:02.445332Z","end":"2026-08-14T09:22:02.567901Z","steps":["trace[1639080857] 'agreement among raft nodes before linearized reading' (duration: 122.064137ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:23:03.13344Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3004} {"level":"info","ts":"2026-08-14T09:23:03.219454Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3004,"took":"84.558475ms","hash":1005690570,"current-db-size-bytes":59162624,"current-db-size":"59 MB","current-db-size-in-use-bytes":31801344,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-14T09:23:03.219532Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1005690570,"revision":3004,"compact-revision":-1} {"level":"info","ts":"2026-08-14T09:28:03.139474Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6072} {"level":"info","ts":"2026-08-14T09:28:03.23809Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6072,"took":"95.808487ms","hash":1770231015,"current-db-size-bytes":59162624,"current-db-size":"59 MB","current-db-size-in-use-bytes":22040576,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-08-14T09:28:03.238173Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1770231015,"revision":6072,"compact-revision":3004} {"level":"warn","ts":"2026-08-14T09:28:26.531909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.993885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-14T09:28:26.532017Z","caller":"traceutil/trace.go:171","msg":"trace[1318705263] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:8418; }","duration":"260.139269ms","start":"2026-08-14T09:28:26.271859Z","end":"2026-08-14T09:28:26.531999Z","steps":["trace[1318705263] 'range keys from in-memory index tree' (duration: 259.84164ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:28:27.397552Z","caller":"traceutil/trace.go:171","msg":"trace[257311569] linearizableReadLoop","detail":"{readStateIndex:8723; appliedIndex:8722; }","duration":"100.352489ms","start":"2026-08-14T09:28:27.297171Z","end":"2026-08-14T09:28:27.397523Z","steps":["trace[257311569] 'read index received' (duration: 100.05765ms)","trace[257311569] 'applied index is now lower than readState.Index' (duration: 293.649µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:28:27.397712Z","caller":"traceutil/trace.go:171","msg":"trace[125896525] transaction","detail":"{read_only:false; response_revision:8426; number_of_response:1; }","duration":"185.518663ms","start":"2026-08-14T09:28:27.212171Z","end":"2026-08-14T09:28:27.39769Z","steps":["trace[125896525] 'process raft request' (duration: 185.185303ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:28:27.397776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.585244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:28:27.397827Z","caller":"traceutil/trace.go:171","msg":"trace[354305048] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:8426; }","duration":"100.691507ms","start":"2026-08-14T09:28:27.297118Z","end":"2026-08-14T09:28:27.39781Z","steps":["trace[354305048] 'agreement among raft nodes before linearized reading' (duration: 100.570844ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:28:28.782168Z","caller":"traceutil/trace.go:171","msg":"trace[218213187] linearizableReadLoop","detail":"{readStateIndex:8728; appliedIndex:8727; }","duration":"113.349904ms","start":"2026-08-14T09:28:28.668789Z","end":"2026-08-14T09:28:28.782139Z","steps":["trace[218213187] 'read index received' (duration: 112.49025ms)","trace[218213187] 'applied index is now lower than readState.Index' (duration: 858.264µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:28:28.782212Z","caller":"traceutil/trace.go:171","msg":"trace[1316139921] transaction","detail":"{read_only:false; response_revision:8431; number_of_response:1; }","duration":"151.399211ms","start":"2026-08-14T09:28:28.630782Z","end":"2026-08-14T09:28:28.782181Z","steps":["trace[1316139921] 'process raft request' (duration: 150.546227ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:28:28.782418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.617171ms","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-08-14T09:28:28.782516Z","caller":"traceutil/trace.go:171","msg":"trace[307696982] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8431; }","duration":"113.771525ms","start":"2026-08-14T09:28:28.668727Z","end":"2026-08-14T09:28:28.782499Z","steps":["trace[307696982] 'agreement among raft nodes before linearized reading' (duration: 113.514968ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:28:48.484497Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000228d.wal"} {"level":"info","ts":"2026-08-14T09:28:55.870345Z","caller":"traceutil/trace.go:171","msg":"trace[243297814] linearizableReadLoop","detail":"{readStateIndex:8934; appliedIndex:8933; }","duration":"155.462301ms","start":"2026-08-14T09:28:55.714861Z","end":"2026-08-14T09:28:55.870323Z","steps":["trace[243297814] 'read index received' (duration: 155.119462ms)","trace[243297814] 'applied index is now lower than readState.Index' (duration: 341.859µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:28:55.870833Z","caller":"traceutil/trace.go:171","msg":"trace[1677219190] transaction","detail":"{read_only:false; response_revision:8630; number_of_response:1; }","duration":"196.03ms","start":"2026-08-14T09:28:55.674788Z","end":"2026-08-14T09:28:55.870818Z","steps":["trace[1677219190] 'process raft request' (duration: 195.269378ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:28:55.871302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.175981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T09:28:55.871399Z","caller":"traceutil/trace.go:171","msg":"trace[1822022995] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:8630; }","duration":"123.316865ms","start":"2026-08-14T09:28:55.748061Z","end":"2026-08-14T09:28:55.871378Z","steps":["trace[1822022995] 'agreement among raft nodes before linearized reading' (duration: 123.124209ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:28:55.87176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.892381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-47ddz\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-08-14T09:28:55.871809Z","caller":"traceutil/trace.go:171","msg":"trace[129293806] range","detail":"{range_begin:/registry/pods/openstack/keepalived-47ddz; range_end:; response_count:1; response_revision:8630; }","duration":"156.972133ms","start":"2026-08-14T09:28:55.714824Z","end":"2026-08-14T09:28:55.871796Z","steps":["trace[129293806] 'agreement among raft nodes before linearized reading' (duration: 156.824669ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:28:59.229083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.234258ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:28:59.229191Z","caller":"traceutil/trace.go:171","msg":"trace[457175769] linearizableReadLoop","detail":"{readStateIndex:8953; appliedIndex:8951; }","duration":"284.816192ms","start":"2026-08-14T09:28:58.944359Z","end":"2026-08-14T09:28:59.229175Z","steps":["trace[457175769] 'read index received' (duration: 57.366645ms)","trace[457175769] 'applied index is now lower than readState.Index' (duration: 227.448177ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:28:59.229205Z","caller":"traceutil/trace.go:171","msg":"trace[409372712] transaction","detail":"{read_only:false; response_revision:8648; number_of_response:1; }","duration":"293.129458ms","start":"2026-08-14T09:28:58.936058Z","end":"2026-08-14T09:28:59.229188Z","steps":["trace[409372712] 'process raft request' (duration: 149.737715ms)","trace[409372712] 'compare' (duration: 143.045894ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:28:59.22928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.912374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T09:28:59.229312Z","caller":"traceutil/trace.go:171","msg":"trace[814194757] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:8648; }","duration":"284.972185ms","start":"2026-08-14T09:28:58.944328Z","end":"2026-08-14T09:28:59.2293Z","steps":["trace[814194757] 'agreement among raft nodes before linearized reading' (duration: 284.913644ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:28:59.242725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.74686ms","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-08-14T09:28:59.24281Z","caller":"traceutil/trace.go:171","msg":"trace[1479675373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8649; }","duration":"262.884504ms","start":"2026-08-14T09:28:58.979909Z","end":"2026-08-14T09:28:59.242794Z","steps":["trace[1479675373] 'agreement among raft nodes before linearized reading' (duration: 262.672668ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:28:59.355459Z","caller":"traceutil/trace.go:171","msg":"trace[184923887] transaction","detail":"{read_only:false; response_revision:8650; number_of_response:1; }","duration":"108.212482ms","start":"2026-08-14T09:28:59.247195Z","end":"2026-08-14T09:28:59.355408Z","steps":["trace[184923887] 'process raft request' (duration: 94.011245ms)","trace[184923887] 'compare' (duration: 14.041293ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:29:09.552488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.454861ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:29:09.552658Z","caller":"traceutil/trace.go:171","msg":"trace[1515925707] linearizableReadLoop","detail":"{readStateIndex:9055; appliedIndex:9054; }","duration":"171.475929ms","start":"2026-08-14T09:29:09.381168Z","end":"2026-08-14T09:29:09.552643Z","steps":["trace[1515925707] 'read index received' (duration: 32.061µs)","trace[1515925707] 'applied index is now lower than readState.Index' (duration: 171.442488ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:29:09.552828Z","caller":"traceutil/trace.go:171","msg":"trace[1820412144] transaction","detail":"{read_only:false; response_revision:8745; number_of_response:1; }","duration":"269.159136ms","start":"2026-08-14T09:29:09.283657Z","end":"2026-08-14T09:29:09.552816Z","steps":["trace[1820412144] 'process raft request' (duration: 54.285502ms)","trace[1820412144] 'compare' (duration: 214.220815ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:29:09.553049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.84138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T09:29:09.553158Z","caller":"traceutil/trace.go:171","msg":"trace[11133992] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:8745; }","duration":"172.003394ms","start":"2026-08-14T09:29:09.381133Z","end":"2026-08-14T09:29:09.553136Z","steps":["trace[11133992] 'agreement among raft nodes before linearized reading' (duration: 171.806059ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:09.553214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.550397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:29:09.553338Z","caller":"traceutil/trace.go:171","msg":"trace[978582301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8745; }","duration":"106.755163ms","start":"2026-08-14T09:29:09.446556Z","end":"2026-08-14T09:29:09.553311Z","steps":["trace[978582301] 'agreement among raft nodes before linearized reading' (duration: 106.540707ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:29:09.553099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.016483ms","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-08-14T09:29:09.553438Z","caller":"traceutil/trace.go:171","msg":"trace[713409252] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8745; }","duration":"158.372012ms","start":"2026-08-14T09:29:09.395045Z","end":"2026-08-14T09:29:09.553417Z","steps":["trace[713409252] 'agreement among raft nodes before linearized reading' (duration: 157.91596ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:30:21.556624Z","caller":"traceutil/trace.go:171","msg":"trace[297849990] transaction","detail":"{read_only:false; response_revision:9468; number_of_response:1; }","duration":"107.026084ms","start":"2026-08-14T09:30:21.449567Z","end":"2026-08-14T09:30:21.556593Z","steps":["trace[297849990] 'process raft request' (duration: 50.656489ms)","trace[297849990] 'compare' (duration: 56.093487ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:30:48.424896Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5ac240a6daec2148","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-14T09:30:48.430428Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-14T09:30:48.430532Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-14T09:33:03.170847Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8161} {"level":"info","ts":"2026-08-14T09:33:03.23236Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8161,"took":"59.546687ms","hash":3631036954,"current-db-size-bytes":59162624,"current-db-size":"59 MB","current-db-size-in-use-bytes":29720576,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-14T09:33:03.232408Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3631036954,"revision":8161,"compact-revision":6072} {"level":"info","ts":"2026-08-14T09:38:03.182376Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10762} {"level":"info","ts":"2026-08-14T09:38:03.271675Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10762,"took":"84.405406ms","hash":2414185186,"current-db-size-bytes":59162624,"current-db-size":"59 MB","current-db-size-in-use-bytes":27672576,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-14T09:38:03.271728Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2414185186,"revision":10762,"compact-revision":8161} {"level":"info","ts":"2026-08-14T09:40:03.367641Z","caller":"traceutil/trace.go:171","msg":"trace[695823345] transaction","detail":"{read_only:false; response_revision:13756; number_of_response:1; }","duration":"106.869736ms","start":"2026-08-14T09:40:03.260709Z","end":"2026-08-14T09:40:03.367579Z","steps":["trace[695823345] 'process raft request' (duration: 101.31957ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:40:06.561314Z","caller":"traceutil/trace.go:171","msg":"trace[322538580] transaction","detail":"{read_only:false; response_revision:13772; number_of_response:1; }","duration":"136.551106ms","start":"2026-08-14T09:40:06.424734Z","end":"2026-08-14T09:40:06.561286Z","steps":["trace[322538580] 'process raft request' (duration: 136.410171ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:40:06.561851Z","caller":"traceutil/trace.go:171","msg":"trace[946295445] linearizableReadLoop","detail":"{readStateIndex:14241; appliedIndex:14241; }","duration":"116.889906ms","start":"2026-08-14T09:40:06.444948Z","end":"2026-08-14T09:40:06.561838Z","steps":["trace[946295445] 'read index received' (duration: 116.882896ms)","trace[946295445] 'applied index is now lower than readState.Index' (duration: 6.15µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:40:06.561982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.005009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:40:06.562038Z","caller":"traceutil/trace.go:171","msg":"trace[1189131616] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13772; }","duration":"117.105903ms","start":"2026-08-14T09:40:06.444922Z","end":"2026-08-14T09:40:06.562028Z","steps":["trace[1189131616] 'agreement among raft nodes before linearized reading' (duration: 116.995729ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:40:42.543045Z","caller":"traceutil/trace.go:171","msg":"trace[1265484061] transaction","detail":"{read_only:false; response_revision:13976; number_of_response:1; }","duration":"205.760474ms","start":"2026-08-14T09:40:42.337259Z","end":"2026-08-14T09:40:42.543019Z","steps":["trace[1265484061] 'process raft request' (duration: 205.60533ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:40:42.844408Z","caller":"traceutil/trace.go:171","msg":"trace[1356194498] linearizableReadLoop","detail":"{readStateIndex:14454; appliedIndex:14452; }","duration":"399.440961ms","start":"2026-08-14T09:40:42.44494Z","end":"2026-08-14T09:40:42.844381Z","steps":["trace[1356194498] 'read index received' (duration: 98.014513ms)","trace[1356194498] 'applied index is now lower than readState.Index' (duration: 301.425328ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:40:42.844483Z","caller":"traceutil/trace.go:171","msg":"trace[1264743579] transaction","detail":"{read_only:false; response_revision:13977; number_of_response:1; }","duration":"493.276077ms","start":"2026-08-14T09:40:42.351182Z","end":"2026-08-14T09:40:42.844458Z","steps":["trace[1264743579] 'process raft request' (duration: 492.763442ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:40:42.844577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.622556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T09:40:42.844623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:40:42.351148Z","time spent":"493.402741ms","remote":"127.0.0.1:38954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":415539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:40:42.844651Z","caller":"traceutil/trace.go:171","msg":"trace[758550654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13977; }","duration":"399.743099ms","start":"2026-08-14T09:40:42.44489Z","end":"2026-08-14T09:40:42.844633Z","steps":["trace[758550654] 'agreement among raft nodes before linearized reading' (duration: 399.629006ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:40:42.844702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:40:42.444871Z","time spent":"399.818912ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-14T09:40:42.857317Z","caller":"traceutil/trace.go:171","msg":"trace[1378496986] transaction","detail":"{read_only:false; response_revision:13978; number_of_response:1; }","duration":"161.431419ms","start":"2026-08-14T09:40:42.695862Z","end":"2026-08-14T09:40:42.857293Z","steps":["trace[1378496986] 'process raft request' (duration: 161.12232ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:40:42.857388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.173304ms","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-08-14T09:40:42.857441Z","caller":"traceutil/trace.go:171","msg":"trace[526654560] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13979; }","duration":"117.278818ms","start":"2026-08-14T09:40:42.740147Z","end":"2026-08-14T09:40:42.857426Z","steps":["trace[526654560] 'agreement among raft nodes before linearized reading' (duration: 117.059091ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:40:42.857441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.038389ms","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-08-14T09:40:42.857536Z","caller":"traceutil/trace.go:171","msg":"trace[307853433] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13979; }","duration":"393.179713ms","start":"2026-08-14T09:40:42.464328Z","end":"2026-08-14T09:40:42.857508Z","steps":["trace[307853433] 'agreement among raft nodes before linearized reading' (duration: 392.873843ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:40:42.857586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:40:42.464304Z","time spent":"393.272766ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-14T09:40:51.823365Z","caller":"traceutil/trace.go:171","msg":"trace[2088196554] transaction","detail":"{read_only:false; response_revision:14143; number_of_response:1; }","duration":"152.705148ms","start":"2026-08-14T09:40:51.670633Z","end":"2026-08-14T09:40:51.823338Z","steps":["trace[2088196554] 'process raft request' (duration: 152.513102ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:42:27.561064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.212829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:42:27.561154Z","caller":"traceutil/trace.go:171","msg":"trace[1552522081] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:15210; }","duration":"220.342362ms","start":"2026-08-14T09:42:27.340791Z","end":"2026-08-14T09:42:27.561133Z","steps":["trace[1552522081] 'range keys from in-memory index tree' (duration: 220.139877ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:42:27.561154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.264726ms","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-08-14T09:42:27.56123Z","caller":"traceutil/trace.go:171","msg":"trace[473778596] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15210; }","duration":"150.380059ms","start":"2026-08-14T09:42:27.410832Z","end":"2026-08-14T09:42:27.561212Z","steps":["trace[473778596] 'range keys from in-memory index tree' (duration: 150.116311ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:42:27.561441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.314276ms","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-08-14T09:42:27.561475Z","caller":"traceutil/trace.go:171","msg":"trace[873293634] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15210; }","duration":"150.360428ms","start":"2026-08-14T09:42:27.411104Z","end":"2026-08-14T09:42:27.561465Z","steps":["trace[873293634] 'range keys from in-memory index tree' (duration: 150.209244ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:42:27.561556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.240324ms","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-08-14T09:42:27.561594Z","caller":"traceutil/trace.go:171","msg":"trace[594180346] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15210; }","duration":"152.307836ms","start":"2026-08-14T09:42:27.409273Z","end":"2026-08-14T09:42:27.561581Z","steps":["trace[594180346] 'range keys from in-memory index tree' (duration: 152.131231ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:42:27.688527Z","caller":"traceutil/trace.go:171","msg":"trace[811047993] transaction","detail":"{read_only:false; response_revision:15212; number_of_response:1; }","duration":"123.104793ms","start":"2026-08-14T09:42:27.565397Z","end":"2026-08-14T09:42:27.688502Z","steps":["trace[811047993] 'process raft request' (duration: 122.932678ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:42:27.688527Z","caller":"traceutil/trace.go:171","msg":"trace[1708615104] transaction","detail":"{read_only:false; response_revision:15213; number_of_response:1; }","duration":"122.99949ms","start":"2026-08-14T09:42:27.565504Z","end":"2026-08-14T09:42:27.688503Z","steps":["trace[1708615104] 'process raft request' (duration: 122.925888ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:43:03.189987Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12857} {"level":"info","ts":"2026-08-14T09:43:03.277808Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12857,"took":"85.597431ms","hash":2357157645,"current-db-size-bytes":59162624,"current-db-size":"59 MB","current-db-size-in-use-bytes":30699520,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-14T09:43:03.277887Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2357157645,"revision":12857,"compact-revision":10762} {"level":"info","ts":"2026-08-14T09:48:03.196265Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15428} {"level":"info","ts":"2026-08-14T09:48:03.292925Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15428,"took":"93.993821ms","hash":3450287473,"current-db-size-bytes":59162624,"current-db-size":"59 MB","current-db-size-in-use-bytes":32555008,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-14T09:48:03.292982Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3450287473,"revision":15428,"compact-revision":12857} {"level":"warn","ts":"2026-08-14T09:48:14.09303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.700032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-14T09:48:14.093095Z","caller":"traceutil/trace.go:171","msg":"trace[1719708329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17857; }","duration":"118.813056ms","start":"2026-08-14T09:48:13.974269Z","end":"2026-08-14T09:48:14.093082Z","steps":["trace[1719708329] 'range keys from in-memory index tree' (duration: 118.560068ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:48:14.475692Z","caller":"traceutil/trace.go:171","msg":"trace[882019532] linearizableReadLoop","detail":"{readStateIndex:18460; appliedIndex:18459; }","duration":"137.821995ms","start":"2026-08-14T09:48:14.337851Z","end":"2026-08-14T09:48:14.475673Z","steps":["trace[882019532] 'read index received' (duration: 97.124651ms)","trace[882019532] 'applied index is now lower than readState.Index' (duration: 40.696654ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:48:14.475712Z","caller":"traceutil/trace.go:171","msg":"trace[620257478] transaction","detail":"{read_only:false; response_revision:17859; number_of_response:1; }","duration":"264.693263ms","start":"2026-08-14T09:48:14.210991Z","end":"2026-08-14T09:48:14.475684Z","steps":["trace[620257478] 'process raft request' (duration: 224.01109ms)","trace[620257478] 'compare' (duration: 40.590961ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:48:14.475877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.01041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-7cgsd\" ","response":"range_response_count:1 size:22015"} {"level":"info","ts":"2026-08-14T09:48:14.475916Z","caller":"traceutil/trace.go:171","msg":"trace[1977634358] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-7cgsd; range_end:; response_count:1; response_revision:17859; }","duration":"138.096283ms","start":"2026-08-14T09:48:14.337812Z","end":"2026-08-14T09:48:14.475908Z","steps":["trace[1977634358] 'agreement among raft nodes before linearized reading' (duration: 137.947538ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:48:14.947014Z","caller":"traceutil/trace.go:171","msg":"trace[1565933237] linearizableReadLoop","detail":"{readStateIndex:18461; appliedIndex:18460; }","duration":"374.598972ms","start":"2026-08-14T09:48:14.572393Z","end":"2026-08-14T09:48:14.946992Z","steps":["trace[1565933237] 'read index received' (duration: 368.441905ms)","trace[1565933237] 'applied index is now lower than readState.Index' (duration: 6.156017ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:48:14.947297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.884561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-j7dkf\" ","response":"range_response_count:1 size:9710"} {"level":"info","ts":"2026-08-14T09:48:14.94738Z","caller":"traceutil/trace.go:171","msg":"trace[604115314] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-j7dkf; range_end:; response_count:1; response_revision:17860; }","duration":"375.019245ms","start":"2026-08-14T09:48:14.572342Z","end":"2026-08-14T09:48:14.947361Z","steps":["trace[604115314] 'agreement among raft nodes before linearized reading' (duration: 374.787388ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:48:14.947424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:48:14.57231Z","time spent":"375.105427ms","remote":"127.0.0.1:57344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9732,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-j7dkf\" "} {"level":"info","ts":"2026-08-14T09:48:14.94763Z","caller":"traceutil/trace.go:171","msg":"trace[28358989] transaction","detail":"{read_only:false; response_revision:17860; number_of_response:1; }","duration":"414.581795ms","start":"2026-08-14T09:48:14.533031Z","end":"2026-08-14T09:48:14.947613Z","steps":["trace[28358989] 'process raft request' (duration: 407.852101ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:48:14.947934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:48:14.532994Z","time spent":"414.872723ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:48:14.947751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.078471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-qsmfq\" ","response":"range_response_count:1 size:20661"} {"level":"info","ts":"2026-08-14T09:48:14.948071Z","caller":"traceutil/trace.go:171","msg":"trace[1796033998] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-qsmfq; range_end:; response_count:1; response_revision:17860; }","duration":"268.390129ms","start":"2026-08-14T09:48:14.679662Z","end":"2026-08-14T09:48:14.948052Z","steps":["trace[1796033998] 'agreement among raft nodes before linearized reading' (duration: 268.008439ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:48:14.94819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.223679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-08-14T09:48:14.948235Z","caller":"traceutil/trace.go:171","msg":"trace[2079765517] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17860; }","duration":"290.313131ms","start":"2026-08-14T09:48:14.657909Z","end":"2026-08-14T09:48:14.948222Z","steps":["trace[2079765517] 'agreement among raft nodes before linearized reading' (duration: 289.328213ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:51:21.699984Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5ac240a6daec2148","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-14T09:51:21.70684Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-14T09:51:21.707139Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-14T09:53:03.201551Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17739} {"level":"info","ts":"2026-08-14T09:53:03.298178Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17739,"took":"91.562984ms","hash":1407103632,"current-db-size-bytes":59162624,"current-db-size":"59 MB","current-db-size-in-use-bytes":34480128,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-14T09:53:03.298234Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1407103632,"revision":17739,"compact-revision":15428} {"level":"info","ts":"2026-08-14T09:53:51.010041Z","caller":"traceutil/trace.go:171","msg":"trace[396137191] transaction","detail":"{read_only:false; response_revision:20401; number_of_response:1; }","duration":"147.832481ms","start":"2026-08-14T09:53:50.862175Z","end":"2026-08-14T09:53:51.010007Z","steps":["trace[396137191] 'process raft request' (duration: 147.566153ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:53:51.148093Z","caller":"traceutil/trace.go:171","msg":"trace[1149162160] linearizableReadLoop","detail":"{readStateIndex:21090; appliedIndex:21089; }","duration":"102.774529ms","start":"2026-08-14T09:53:51.045293Z","end":"2026-08-14T09:53:51.148068Z","steps":["trace[1149162160] 'read index received' (duration: 94.83205ms)","trace[1149162160] 'applied index is now lower than readState.Index' (duration: 7.941239ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:53:51.148202Z","caller":"traceutil/trace.go:171","msg":"trace[927755880] transaction","detail":"{read_only:false; response_revision:20402; number_of_response:1; }","duration":"191.071819ms","start":"2026-08-14T09:53:50.9571Z","end":"2026-08-14T09:53:51.148172Z","steps":["trace[927755880] 'process raft request' (duration: 183.091558ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:53:51.148334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.025106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-14T09:53:51.148377Z","caller":"traceutil/trace.go:171","msg":"trace[1580884315] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20402; }","duration":"103.118759ms","start":"2026-08-14T09:53:51.045247Z","end":"2026-08-14T09:53:51.148365Z","steps":["trace[1580884315] 'agreement among raft nodes before linearized reading' (duration: 102.957584ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:53:51.289197Z","caller":"traceutil/trace.go:171","msg":"trace[635971733] linearizableReadLoop","detail":"{readStateIndex:21093; appliedIndex:21091; }","duration":"115.190357ms","start":"2026-08-14T09:53:51.173985Z","end":"2026-08-14T09:53:51.289176Z","steps":["trace[635971733] 'read index received' (duration: 104.382335ms)","trace[635971733] 'applied index is now lower than readState.Index' (duration: 10.807142ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:53:51.28941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.407155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-14T09:53:51.289453Z","caller":"traceutil/trace.go:171","msg":"trace[642668338] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20405; }","duration":"115.496057ms","start":"2026-08-14T09:53:51.173943Z","end":"2026-08-14T09:53:51.28944Z","steps":["trace[642668338] 'agreement among raft nodes before linearized reading' (duration: 115.324972ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:53:51.289753Z","caller":"traceutil/trace.go:171","msg":"trace[2134121209] transaction","detail":"{read_only:false; response_revision:20404; number_of_response:1; }","duration":"137.738008ms","start":"2026-08-14T09:53:51.151988Z","end":"2026-08-14T09:53:51.289726Z","steps":["trace[2134121209] 'process raft request' (duration: 126.35933ms)","trace[2134121209] 'compare' (duration: 10.384579ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:53:51.28967Z","caller":"traceutil/trace.go:171","msg":"trace[1862513998] transaction","detail":"{read_only:false; response_revision:20405; number_of_response:1; }","duration":"137.313546ms","start":"2026-08-14T09:53:51.152303Z","end":"2026-08-14T09:53:51.289616Z","steps":["trace[1862513998] 'process raft request' (duration: 136.820201ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:53:51.449044Z","caller":"traceutil/trace.go:171","msg":"trace[1104169076] transaction","detail":"{read_only:false; response_revision:20406; number_of_response:1; }","duration":"155.712699ms","start":"2026-08-14T09:53:51.293306Z","end":"2026-08-14T09:53:51.449019Z","steps":["trace[1104169076] 'process raft request' (duration: 150.297592ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:53:51.737595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.593449ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-14T09:53:51.737753Z","caller":"traceutil/trace.go:171","msg":"trace[895100987] linearizableReadLoop","detail":"{readStateIndex:21095; appliedIndex:21094; }","duration":"293.138388ms","start":"2026-08-14T09:53:51.444591Z","end":"2026-08-14T09:53:51.737729Z","steps":["trace[895100987] 'read index received' (duration: 7.351113ms)","trace[895100987] 'applied index is now lower than readState.Index' (duration: 285.785635ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:53:51.738068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.451137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T09:53:51.738175Z","caller":"traceutil/trace.go:171","msg":"trace[904940328] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20406; }","duration":"293.56339ms","start":"2026-08-14T09:53:51.444586Z","end":"2026-08-14T09:53:51.73815Z","steps":["trace[904940328] 'agreement among raft nodes before linearized reading' (duration: 293.405615ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:53:51.946428Z","caller":"traceutil/trace.go:171","msg":"trace[288780961] linearizableReadLoop","detail":"{readStateIndex:21097; appliedIndex:21096; }","duration":"138.739127ms","start":"2026-08-14T09:53:51.807671Z","end":"2026-08-14T09:53:51.94641Z","steps":["trace[288780961] 'read index received' (duration: 85.629053ms)","trace[288780961] 'applied index is now lower than readState.Index' (duration: 53.109254ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:53:51.94656Z","caller":"traceutil/trace.go:171","msg":"trace[213780063] transaction","detail":"{read_only:false; response_revision:20408; number_of_response:1; }","duration":"174.008857ms","start":"2026-08-14T09:53:51.772517Z","end":"2026-08-14T09:53:51.946526Z","steps":["trace[213780063] 'process raft request' (duration: 120.833661ms)","trace[213780063] 'compare' (duration: 52.834946ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:53:51.946614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.945153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T09:53:51.946638Z","caller":"traceutil/trace.go:171","msg":"trace[1581745864] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:20408; }","duration":"139.008545ms","start":"2026-08-14T09:53:51.807623Z","end":"2026-08-14T09:53:51.946631Z","steps":["trace[1581745864] 'agreement among raft nodes before linearized reading' (duration: 138.942383ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:57:41.336531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.690164ms","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-08-14T09:57:41.336632Z","caller":"traceutil/trace.go:171","msg":"trace[620719150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22822; }","duration":"260.849249ms","start":"2026-08-14T09:57:41.075762Z","end":"2026-08-14T09:57:41.336611Z","steps":["trace[620719150] 'range keys from in-memory index tree' (duration: 260.485358ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:58:03.208031Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20158} {"level":"info","ts":"2026-08-14T09:58:03.31804Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20158,"took":"106.992083ms","hash":4009258825,"current-db-size-bytes":59162624,"current-db-size":"59 MB","current-db-size-in-use-bytes":42401792,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-08-14T09:58:03.318126Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4009258825,"revision":20158,"compact-revision":17739} {"level":"info","ts":"2026-08-14T10:01:02.300832Z","caller":"traceutil/trace.go:171","msg":"trace[186969500] transaction","detail":"{read_only:false; response_revision:24955; number_of_response:1; }","duration":"113.471134ms","start":"2026-08-14T10:01:02.187342Z","end":"2026-08-14T10:01:02.300813Z","steps":["trace[186969500] 'process raft request' (duration: 113.34569ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:05.976718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.663694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T10:01:05.976803Z","caller":"traceutil/trace.go:171","msg":"trace[495212090] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24992; }","duration":"220.787158ms","start":"2026-08-14T10:01:05.755999Z","end":"2026-08-14T10:01:05.976786Z","steps":["trace[495212090] 'range keys from in-memory index tree' (duration: 220.581832ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:07.065521Z","caller":"traceutil/trace.go:171","msg":"trace[53367601] transaction","detail":"{read_only:false; response_revision:24999; number_of_response:1; }","duration":"112.919197ms","start":"2026-08-14T10:01:06.952546Z","end":"2026-08-14T10:01:07.065465Z","steps":["trace[53367601] 'process raft request' (duration: 112.611498ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:07.911032Z","caller":"traceutil/trace.go:171","msg":"trace[1455101978] transaction","detail":"{read_only:false; response_revision:25006; number_of_response:1; }","duration":"266.616639ms","start":"2026-08-14T10:01:07.644386Z","end":"2026-08-14T10:01:07.911003Z","steps":["trace[1455101978] 'process raft request' (duration: 266.405233ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:07.911917Z","caller":"traceutil/trace.go:171","msg":"trace[1997622579] transaction","detail":"{read_only:false; response_revision:25007; number_of_response:1; }","duration":"206.784064ms","start":"2026-08-14T10:01:07.705092Z","end":"2026-08-14T10:01:07.911876Z","steps":["trace[1997622579] 'process raft request' (duration: 206.473915ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:08.215905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.650052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T10:01:08.215983Z","caller":"traceutil/trace.go:171","msg":"trace[1420014953] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25007; }","duration":"204.751515ms","start":"2026-08-14T10:01:08.011207Z","end":"2026-08-14T10:01:08.215959Z","steps":["trace[1420014953] 'count revisions from in-memory index tree' (duration: 204.504947ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:10.045507Z","caller":"traceutil/trace.go:171","msg":"trace[1180006072] transaction","detail":"{read_only:false; response_revision:25021; number_of_response:1; }","duration":"126.691773ms","start":"2026-08-14T10:01:09.91878Z","end":"2026-08-14T10:01:10.045472Z","steps":["trace[1180006072] 'process raft request' (duration: 126.352744ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:10.125722Z","caller":"traceutil/trace.go:171","msg":"trace[254742254] transaction","detail":"{read_only:false; response_revision:25022; number_of_response:1; }","duration":"203.130698ms","start":"2026-08-14T10:01:09.922563Z","end":"2026-08-14T10:01:10.125694Z","steps":["trace[254742254] 'process raft request' (duration: 201.660406ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:10.89012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.901396ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-14T10:01:10.891052Z","caller":"traceutil/trace.go:171","msg":"trace[1965926560] transaction","detail":"{read_only:false; response_revision:25025; number_of_response:1; }","duration":"226.373589ms","start":"2026-08-14T10:01:10.664631Z","end":"2026-08-14T10:01:10.891004Z","steps":["trace[1965926560] 'process raft request' (duration: 95.491734ms)","trace[1965926560] 'compare' (duration: 129.65076ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:01:10.891288Z","caller":"traceutil/trace.go:171","msg":"trace[220936764] transaction","detail":"{read_only:false; response_revision:25026; number_of_response:1; }","duration":"145.27446ms","start":"2026-08-14T10:01:10.745958Z","end":"2026-08-14T10:01:10.891232Z","steps":["trace[220936764] 'process raft request' (duration: 144.976832ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:11.244908Z","caller":"traceutil/trace.go:171","msg":"trace[392092717] transaction","detail":"{read_only:false; response_revision:25027; number_of_response:1; }","duration":"167.82494ms","start":"2026-08-14T10:01:11.077063Z","end":"2026-08-14T10:01:11.244888Z","steps":["trace[392092717] 'process raft request' (duration: 167.695717ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:11.403861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.272281ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-14T10:01:11.40402Z","caller":"traceutil/trace.go:171","msg":"trace[561730243] linearizableReadLoop","detail":"{readStateIndex:25846; appliedIndex:25844; }","duration":"265.706153ms","start":"2026-08-14T10:01:11.138302Z","end":"2026-08-14T10:01:11.404008Z","steps":["trace[561730243] 'read index received' (duration: 106.517512ms)","trace[561730243] 'applied index is now lower than readState.Index' (duration: 159.188011ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:01:11.404074Z","caller":"traceutil/trace.go:171","msg":"trace[664239406] transaction","detail":"{read_only:false; response_revision:25028; number_of_response:1; }","duration":"298.736016ms","start":"2026-08-14T10:01:11.10533Z","end":"2026-08-14T10:01:11.404066Z","steps":["trace[664239406] 'process raft request' (duration: 180.201417ms)","trace[664239406] 'compare' (duration: 118.079456ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:01:11.404279Z","caller":"traceutil/trace.go:171","msg":"trace[740015127] transaction","detail":"{read_only:false; response_revision:25029; number_of_response:1; }","duration":"195.286911ms","start":"2026-08-14T10:01:11.208985Z","end":"2026-08-14T10:01:11.404272Z","steps":["trace[740015127] 'process raft request' (duration: 194.953042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:11.404351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.034672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T10:01:11.404416Z","caller":"traceutil/trace.go:171","msg":"trace[819768626] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25029; }","duration":"266.134826ms","start":"2026-08-14T10:01:11.138269Z","end":"2026-08-14T10:01:11.404404Z","steps":["trace[819768626] 'agreement among raft nodes before linearized reading' (duration: 266.037832ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:11.772147Z","caller":"traceutil/trace.go:171","msg":"trace[944705342] transaction","detail":"{read_only:false; response_revision:25032; number_of_response:1; }","duration":"337.483955ms","start":"2026-08-14T10:01:11.434619Z","end":"2026-08-14T10:01:11.772103Z","steps":["trace[944705342] 'process raft request' (duration: 337.188506ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:11.772308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:11.434609Z","time spent":"337.617918ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T10:01:11.772317Z","caller":"traceutil/trace.go:171","msg":"trace[189444921] linearizableReadLoop","detail":"{readStateIndex:25850; appliedIndex:25849; }","duration":"336.68461ms","start":"2026-08-14T10:01:11.43533Z","end":"2026-08-14T10:01:11.772014Z","steps":["trace[189444921] 'read index received' (duration: 336.356601ms)","trace[189444921] 'applied index is now lower than readState.Index' (duration: 327.109µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:01:11.772429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.070811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18557"} {"level":"info","ts":"2026-08-14T10:01:11.772538Z","caller":"traceutil/trace.go:171","msg":"trace[853611109] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25032; }","duration":"337.215235ms","start":"2026-08-14T10:01:11.435296Z","end":"2026-08-14T10:01:11.772511Z","steps":["trace[853611109] 'agreement among raft nodes before linearized reading' (duration: 336.910497ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:11.77261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:11.435282Z","time spent":"337.312669ms","remote":"127.0.0.1:57334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18579,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-08-14T10:01:12.392354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.692702ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-14T10:01:12.392614Z","caller":"traceutil/trace.go:171","msg":"trace[360087374] linearizableReadLoop","detail":"{readStateIndex:25851; appliedIndex:25850; }","duration":"620.207287ms","start":"2026-08-14T10:01:11.772384Z","end":"2026-08-14T10:01:12.392591Z","steps":["trace[360087374] 'read index received' (duration: 86.295909ms)","trace[360087374] 'applied index is now lower than readState.Index' (duration: 533.909928ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:01:12.392721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.304561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-14T10:01:12.392712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.34313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T10:01:12.392772Z","caller":"traceutil/trace.go:171","msg":"trace[1697130908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25032; }","duration":"947.375133ms","start":"2026-08-14T10:01:11.445384Z","end":"2026-08-14T10:01:12.392759Z","steps":["trace[1697130908] 'agreement among raft nodes before linearized reading' (duration: 947.276011ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:12.392767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.396593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-14T10:01:12.392804Z","caller":"traceutil/trace.go:171","msg":"trace[648114643] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:25032; }","duration":"897.447053ms","start":"2026-08-14T10:01:11.495339Z","end":"2026-08-14T10:01:12.392786Z","steps":["trace[648114643] 'agreement among raft nodes before linearized reading' (duration: 897.304969ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:12.39281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:11.445377Z","time spent":"947.425205ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-14T10:01:12.392816Z","caller":"traceutil/trace.go:171","msg":"trace[786341770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25032; }","duration":"895.481146ms","start":"2026-08-14T10:01:11.497322Z","end":"2026-08-14T10:01:12.392803Z","steps":["trace[786341770] 'agreement among raft nodes before linearized reading' (duration: 895.318591ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:12.392852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:11.495311Z","time spent":"897.532685ms","remote":"127.0.0.1:59256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-08-14T10:01:12.392872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:11.497304Z","time spent":"895.560388ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-14T10:01:12.392716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.216964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-14T10:01:12.392893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.353001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-14T10:01:12.392955Z","caller":"traceutil/trace.go:171","msg":"trace[1541731216] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:25032; }","duration":"786.493462ms","start":"2026-08-14T10:01:11.606435Z","end":"2026-08-14T10:01:12.392928Z","steps":["trace[1541731216] 'agreement among raft nodes before linearized reading' (duration: 786.208394ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:12.393006Z","caller":"traceutil/trace.go:171","msg":"trace[1822372422] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25032; }","duration":"896.491246ms","start":"2026-08-14T10:01:11.496488Z","end":"2026-08-14T10:01:12.392979Z","steps":["trace[1822372422] 'agreement among raft nodes before linearized reading' (duration: 896.161426ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:12.393022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:11.60642Z","time spent":"786.577825ms","remote":"127.0.0.1:56972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true "} {"level":"warn","ts":"2026-08-14T10:01:12.393055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:11.496482Z","time spent":"896.562097ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-14T10:01:12.393272Z","caller":"traceutil/trace.go:171","msg":"trace[1432715679] transaction","detail":"{read_only:false; response_revision:25033; number_of_response:1; }","duration":"341.015986ms","start":"2026-08-14T10:01:12.052241Z","end":"2026-08-14T10:01:12.393257Z","steps":["trace[1432715679] 'process raft request' (duration: 340.230422ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:12.39338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:12.05221Z","time spent":"341.102238ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T10:01:12.483516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.006961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-14T10:01:12.483612Z","caller":"traceutil/trace.go:171","msg":"trace[406971759] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:25033; }","duration":"500.148625ms","start":"2026-08-14T10:01:11.98344Z","end":"2026-08-14T10:01:12.483588Z","steps":["trace[406971759] 'agreement among raft nodes before linearized reading' (duration: 410.012336ms)","trace[406971759] 'count revisions from in-memory index tree' (duration: 89.976844ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:01:12.483658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:11.983427Z","time spent":"500.219587ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":92,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-08-14T10:01:12.48374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.143466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-14T10:01:12.483807Z","caller":"traceutil/trace.go:171","msg":"trace[1336981340] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25033; }","duration":"352.25628ms","start":"2026-08-14T10:01:12.131532Z","end":"2026-08-14T10:01:12.483788Z","steps":["trace[1336981340] 'agreement among raft nodes before linearized reading' (duration: 261.891594ms)","trace[1336981340] 'range keys from in-memory index tree' (duration: 90.208322ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:01:12.483814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.116245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"warn","ts":"2026-08-14T10:01:12.483842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:12.131512Z","time spent":"352.322921ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-14T10:01:12.483865Z","caller":"traceutil/trace.go:171","msg":"trace[969486521] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25033; }","duration":"697.165267ms","start":"2026-08-14T10:01:11.786682Z","end":"2026-08-14T10:01:12.483847Z","steps":["trace[969486521] 'agreement among raft nodes before linearized reading' (duration: 606.553873ms)","trace[969486521] 'range keys from in-memory index tree' (duration: 90.449169ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:01:12.483869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.682196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-14T10:01:12.483907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:11.786638Z","time spent":"697.261239ms","remote":"127.0.0.1:53704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5674,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-14T10:01:12.483918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.570404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T10:01:12.483949Z","caller":"traceutil/trace.go:171","msg":"trace[1967221889] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:25033; }","duration":"219.80887ms","start":"2026-08-14T10:01:12.264119Z","end":"2026-08-14T10:01:12.483928Z","steps":["trace[1967221889] 'agreement among raft nodes before linearized reading' (duration: 129.322921ms)","trace[1967221889] 'count revisions from in-memory index tree' (duration: 90.375856ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:01:12.483957Z","caller":"traceutil/trace.go:171","msg":"trace[1455846663] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:25033; }","duration":"399.635186ms","start":"2026-08-14T10:01:12.084306Z","end":"2026-08-14T10:01:12.483941Z","steps":["trace[1455846663] 'agreement among raft nodes before linearized reading' (duration: 309.143317ms)","trace[1455846663] 'count revisions from in-memory index tree' (duration: 90.435318ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:01:12.484081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.186942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T10:01:12.484114Z","caller":"traceutil/trace.go:171","msg":"trace[1723757384] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:25033; }","duration":"184.236584ms","start":"2026-08-14T10:01:12.299867Z","end":"2026-08-14T10:01:12.484103Z","steps":["trace[1723757384] 'agreement among raft nodes before linearized reading' (duration: 93.56809ms)","trace[1723757384] 'count revisions from in-memory index tree' (duration: 90.625423ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:01:12.484152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:12.084291Z","time spent":"399.805821ms","remote":"127.0.0.1:56964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true "} {"level":"info","ts":"2026-08-14T10:01:12.643182Z","caller":"traceutil/trace.go:171","msg":"trace[1533024752] linearizableReadLoop","detail":"{readStateIndex:25855; appliedIndex:25852; }","duration":"207.804192ms","start":"2026-08-14T10:01:12.435358Z","end":"2026-08-14T10:01:12.643162Z","steps":["trace[1533024752] 'read index received' (duration: 32.520757ms)","trace[1533024752] 'applied index is now lower than readState.Index' (duration: 175.282005ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:01:12.643287Z","caller":"traceutil/trace.go:171","msg":"trace[1868482802] transaction","detail":"{read_only:false; response_revision:25036; number_of_response:1; }","duration":"216.947817ms","start":"2026-08-14T10:01:12.426272Z","end":"2026-08-14T10:01:12.64322Z","steps":["trace[1868482802] 'process raft request' (duration: 182.901386ms)","trace[1868482802] 'compare' (duration: 33.877466ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:01:12.64346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.090371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-14T10:01:12.643537Z","caller":"traceutil/trace.go:171","msg":"trace[1010799780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25036; }","duration":"208.206515ms","start":"2026-08-14T10:01:12.435313Z","end":"2026-08-14T10:01:12.64352Z","steps":["trace[1010799780] 'agreement among raft nodes before linearized reading' (duration: 207.988188ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:12.71652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.381681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T10:01:12.716592Z","caller":"traceutil/trace.go:171","msg":"trace[439385388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25037; }","duration":"224.497795ms","start":"2026-08-14T10:01:12.492079Z","end":"2026-08-14T10:01:12.716576Z","steps":["trace[439385388] 'agreement among raft nodes before linearized reading' (duration: 224.34829ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:12.71648Z","caller":"traceutil/trace.go:171","msg":"trace[1478767031] transaction","detail":"{read_only:false; response_revision:25037; number_of_response:1; }","duration":"228.519881ms","start":"2026-08-14T10:01:12.487931Z","end":"2026-08-14T10:01:12.716451Z","steps":["trace[1478767031] 'process raft request' (duration: 228.303015ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:12.954039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.582819ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-14T10:01:12.954177Z","caller":"traceutil/trace.go:171","msg":"trace[378995092] transaction","detail":"{read_only:false; response_revision:25038; number_of_response:1; }","duration":"306.219963ms","start":"2026-08-14T10:01:12.647932Z","end":"2026-08-14T10:01:12.954152Z","steps":["trace[378995092] 'process raft request' (duration: 159.430169ms)","trace[378995092] 'compare' (duration: 146.446114ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:01:12.954256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:12.647902Z","time spent":"306.314905ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T10:01:13.427803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.440278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T10:01:13.427863Z","caller":"traceutil/trace.go:171","msg":"trace[1415285661] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:25042; }","duration":"108.575912ms","start":"2026-08-14T10:01:13.319275Z","end":"2026-08-14T10:01:13.427851Z","steps":["trace[1415285661] 'count revisions from in-memory index tree' (duration: 108.301534ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:13.583111Z","caller":"traceutil/trace.go:171","msg":"trace[1209978199] transaction","detail":"{read_only:false; response_revision:25043; number_of_response:1; }","duration":"173.530525ms","start":"2026-08-14T10:01:13.40956Z","end":"2026-08-14T10:01:13.583091Z","steps":["trace[1209978199] 'process raft request' (duration: 173.38463ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:13.721061Z","caller":"traceutil/trace.go:171","msg":"trace[1105626019] transaction","detail":"{read_only:false; response_revision:25044; number_of_response:1; }","duration":"310.193505ms","start":"2026-08-14T10:01:13.410821Z","end":"2026-08-14T10:01:13.721015Z","steps":["trace[1105626019] 'process raft request' (duration: 309.393962ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:13.721236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:13.41081Z","time spent":"310.333441ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T10:01:13.721334Z","caller":"traceutil/trace.go:171","msg":"trace[117831589] transaction","detail":"{read_only:false; response_revision:25046; number_of_response:1; }","duration":"267.224568ms","start":"2026-08-14T10:01:13.45409Z","end":"2026-08-14T10:01:13.721315Z","steps":["trace[117831589] 'process raft request' (duration: 267.024772ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:13.721221Z","caller":"traceutil/trace.go:171","msg":"trace[1889274533] linearizableReadLoop","detail":"{readStateIndex:25864; appliedIndex:25861; }","duration":"276.70434ms","start":"2026-08-14T10:01:13.44449Z","end":"2026-08-14T10:01:13.721194Z","steps":["trace[1889274533] 'read index received' (duration: 138.529205ms)","trace[1889274533] 'applied index is now lower than readState.Index' (duration: 138.174185ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:01:13.721346Z","caller":"traceutil/trace.go:171","msg":"trace[746267382] transaction","detail":"{read_only:false; response_revision:25045; number_of_response:1; }","duration":"285.942837ms","start":"2026-08-14T10:01:13.435359Z","end":"2026-08-14T10:01:13.721302Z","steps":["trace[746267382] 'process raft request' (duration: 285.515594ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:13.72137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.871115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T10:01:13.72146Z","caller":"traceutil/trace.go:171","msg":"trace[46895919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25046; }","duration":"277.02833ms","start":"2026-08-14T10:01:13.444425Z","end":"2026-08-14T10:01:13.721453Z","steps":["trace[46895919] 'agreement among raft nodes before linearized reading' (duration: 276.853015ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:14.624853Z","caller":"traceutil/trace.go:171","msg":"trace[1136716654] transaction","detail":"{read_only:false; response_revision:25048; number_of_response:1; }","duration":"313.268146ms","start":"2026-08-14T10:01:14.311541Z","end":"2026-08-14T10:01:14.62481Z","steps":["trace[1136716654] 'process raft request' (duration: 312.977967ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:14.625126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:14.311519Z","time spent":"313.461381ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T10:01:14.635491Z","caller":"traceutil/trace.go:171","msg":"trace[1571433801] linearizableReadLoop","detail":"{readStateIndex:25868; appliedIndex:25866; }","duration":"190.527375ms","start":"2026-08-14T10:01:14.444927Z","end":"2026-08-14T10:01:14.635455Z","steps":["trace[1571433801] 'read index received' (duration: 179.533918ms)","trace[1571433801] 'applied index is now lower than readState.Index' (duration: 10.992007ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:01:14.635862Z","caller":"traceutil/trace.go:171","msg":"trace[1551871904] transaction","detail":"{read_only:false; response_revision:25049; number_of_response:1; }","duration":"237.464029ms","start":"2026-08-14T10:01:14.398384Z","end":"2026-08-14T10:01:14.635848Z","steps":["trace[1551871904] 'process raft request' (duration: 236.761359ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:14.636175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.235946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T10:01:14.636221Z","caller":"traceutil/trace.go:171","msg":"trace[1487273717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25049; }","duration":"191.351568ms","start":"2026-08-14T10:01:14.444857Z","end":"2026-08-14T10:01:14.636208Z","steps":["trace[1487273717] 'agreement among raft nodes before linearized reading' (duration: 191.262316ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:14.636656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.960898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:215"} {"level":"info","ts":"2026-08-14T10:01:14.636705Z","caller":"traceutil/trace.go:171","msg":"trace[1854887512] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:25049; }","duration":"165.074421ms","start":"2026-08-14T10:01:14.471619Z","end":"2026-08-14T10:01:14.636694Z","steps":["trace[1854887512] 'agreement among raft nodes before linearized reading' (duration: 164.761862ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:14.637326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.692372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-14T10:01:14.637371Z","caller":"traceutil/trace.go:171","msg":"trace[1184953895] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25049; }","duration":"145.792774ms","start":"2026-08-14T10:01:14.491568Z","end":"2026-08-14T10:01:14.637361Z","steps":["trace[1184953895] 'agreement among raft nodes before linearized reading' (duration: 145.691841ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:14.637793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.619729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-14T10:01:14.637837Z","caller":"traceutil/trace.go:171","msg":"trace[632331802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25049; }","duration":"146.725443ms","start":"2026-08-14T10:01:14.491101Z","end":"2026-08-14T10:01:14.637826Z","steps":["trace[632331802] 'agreement among raft nodes before linearized reading' (duration: 146.615939ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:14.746402Z","caller":"traceutil/trace.go:171","msg":"trace[319362533] linearizableReadLoop","detail":"{readStateIndex:25869; appliedIndex:25868; }","duration":"104.01228ms","start":"2026-08-14T10:01:14.642352Z","end":"2026-08-14T10:01:14.746364Z","steps":["trace[319362533] 'read index received' (duration: 103.735502ms)","trace[319362533] 'applied index is now lower than readState.Index' (duration: 274.508µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:01:14.746522Z","caller":"traceutil/trace.go:171","msg":"trace[786619589] transaction","detail":"{read_only:false; response_revision:25050; number_of_response:1; }","duration":"104.261547ms","start":"2026-08-14T10:01:14.64223Z","end":"2026-08-14T10:01:14.746492Z","steps":["trace[786619589] 'process raft request' (duration: 103.813944ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:14.746677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.269658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:215"} {"level":"info","ts":"2026-08-14T10:01:14.746741Z","caller":"traceutil/trace.go:171","msg":"trace[1784368947] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:25050; }","duration":"104.398451ms","start":"2026-08-14T10:01:14.642326Z","end":"2026-08-14T10:01:14.746725Z","steps":["trace[1784368947] 'agreement among raft nodes before linearized reading' (duration: 104.218106ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:14.894245Z","caller":"traceutil/trace.go:171","msg":"trace[941888074] linearizableReadLoop","detail":"{readStateIndex:25871; appliedIndex:25869; }","duration":"147.713649ms","start":"2026-08-14T10:01:14.746497Z","end":"2026-08-14T10:01:14.89421Z","steps":["trace[941888074] 'read index received' (duration: 132.47155ms)","trace[941888074] 'applied index is now lower than readState.Index' (duration: 15.240599ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:01:14.894425Z","caller":"traceutil/trace.go:171","msg":"trace[1190656838] transaction","detail":"{read_only:false; response_revision:25051; number_of_response:1; }","duration":"245.986404ms","start":"2026-08-14T10:01:14.648423Z","end":"2026-08-14T10:01:14.894409Z","steps":["trace[1190656838] 'process raft request' (duration: 230.528939ms)","trace[1190656838] 'compare' (duration: 14.985331ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:01:14.894891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.42451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:217"} {"level":"info","ts":"2026-08-14T10:01:14.894922Z","caller":"traceutil/trace.go:171","msg":"trace[436563220] transaction","detail":"{read_only:false; response_revision:25052; number_of_response:1; }","duration":"242.731219ms","start":"2026-08-14T10:01:14.652154Z","end":"2026-08-14T10:01:14.894885Z","steps":["trace[436563220] 'process raft request' (duration: 241.988728ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:14.895005Z","caller":"traceutil/trace.go:171","msg":"trace[1129165269] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:25052; }","duration":"252.581275ms","start":"2026-08-14T10:01:14.642397Z","end":"2026-08-14T10:01:14.894978Z","steps":["trace[1129165269] 'agreement among raft nodes before linearized reading' (duration: 252.197683ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:14.895124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.810632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-14T10:01:14.895212Z","caller":"traceutil/trace.go:171","msg":"trace[70212630] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25052; }","duration":"174.961366ms","start":"2026-08-14T10:01:14.720228Z","end":"2026-08-14T10:01:14.895189Z","steps":["trace[70212630] 'agreement among raft nodes before linearized reading' (duration: 174.664707ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:15.18777Z","caller":"traceutil/trace.go:171","msg":"trace[1509071618] linearizableReadLoop","detail":"{readStateIndex:25872; appliedIndex:25871; }","duration":"228.436638ms","start":"2026-08-14T10:01:14.959311Z","end":"2026-08-14T10:01:15.187748Z","steps":["trace[1509071618] 'read index received' (duration: 228.418608ms)","trace[1509071618] 'applied index is now lower than readState.Index' (duration: 17.09µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:01:15.188073Z","caller":"traceutil/trace.go:171","msg":"trace[908078417] transaction","detail":"{read_only:false; response_revision:25053; number_of_response:1; }","duration":"288.423519ms","start":"2026-08-14T10:01:14.899619Z","end":"2026-08-14T10:01:15.188042Z","steps":["trace[908078417] 'process raft request' (duration: 287.990326ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:15.1882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.868871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-14T10:01:15.188264Z","caller":"traceutil/trace.go:171","msg":"trace[1877549429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25053; }","duration":"229.027405ms","start":"2026-08-14T10:01:14.95922Z","end":"2026-08-14T10:01:15.188247Z","steps":["trace[1877549429] 'agreement among raft nodes before linearized reading' (duration: 228.793029ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:15.20043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.811261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-14T10:01:15.200494Z","caller":"traceutil/trace.go:171","msg":"trace[1373645250] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:25055; }","duration":"141.921114ms","start":"2026-08-14T10:01:15.058561Z","end":"2026-08-14T10:01:15.200482Z","steps":["trace[1373645250] 'agreement among raft nodes before linearized reading' (duration: 141.79448ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:15.200489Z","caller":"traceutil/trace.go:171","msg":"trace[1095219080] transaction","detail":"{read_only:false; response_revision:25054; number_of_response:1; }","duration":"237.324455ms","start":"2026-08-14T10:01:14.963133Z","end":"2026-08-14T10:01:15.200458Z","steps":["trace[1095219080] 'process raft request' (duration: 237.081627ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:15.200522Z","caller":"traceutil/trace.go:171","msg":"trace[1808251083] transaction","detail":"{read_only:false; response_revision:25055; number_of_response:1; }","duration":"227.825691ms","start":"2026-08-14T10:01:14.972672Z","end":"2026-08-14T10:01:15.200497Z","steps":["trace[1808251083] 'process raft request' (duration: 227.647586ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:15.39933Z","caller":"traceutil/trace.go:171","msg":"trace[1868132413] transaction","detail":"{read_only:false; response_revision:25057; number_of_response:1; }","duration":"133.80896ms","start":"2026-08-14T10:01:15.265489Z","end":"2026-08-14T10:01:15.399298Z","steps":["trace[1868132413] 'process raft request' (duration: 131.919625ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:15.70521Z","caller":"traceutil/trace.go:171","msg":"trace[1086295221] transaction","detail":"{read_only:false; response_revision:25058; number_of_response:1; }","duration":"116.101209ms","start":"2026-08-14T10:01:15.589076Z","end":"2026-08-14T10:01:15.705178Z","steps":["trace[1086295221] 'process raft request' (duration: 115.874972ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:16.011597Z","caller":"traceutil/trace.go:171","msg":"trace[293722497] transaction","detail":"{read_only:false; response_revision:25059; number_of_response:1; }","duration":"283.723313ms","start":"2026-08-14T10:01:15.727801Z","end":"2026-08-14T10:01:16.011524Z","steps":["trace[293722497] 'process raft request' (duration: 283.390323ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:16.225226Z","caller":"traceutil/trace.go:171","msg":"trace[1436028068] linearizableReadLoop","detail":"{readStateIndex:25879; appliedIndex:25877; }","duration":"412.443935ms","start":"2026-08-14T10:01:15.812755Z","end":"2026-08-14T10:01:16.225199Z","steps":["trace[1436028068] 'read index received' (duration: 198.523126ms)","trace[1436028068] 'applied index is now lower than readState.Index' (duration: 213.919239ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:01:16.225355Z","caller":"traceutil/trace.go:171","msg":"trace[1133373324] transaction","detail":"{read_only:false; response_revision:25060; number_of_response:1; }","duration":"497.501998ms","start":"2026-08-14T10:01:15.72784Z","end":"2026-08-14T10:01:16.225342Z","steps":["trace[1133373324] 'process raft request' (duration: 439.456074ms)","trace[1133373324] 'compare' (duration: 57.518339ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:01:16.225437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.528433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T10:01:16.225518Z","caller":"traceutil/trace.go:171","msg":"trace[530426505] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:25060; }","duration":"106.653136ms","start":"2026-08-14T10:01:16.118846Z","end":"2026-08-14T10:01:16.225499Z","steps":["trace[530426505] 'agreement among raft nodes before linearized reading' (duration: 106.519062ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:16.225717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.963532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-14T10:01:16.225769Z","caller":"traceutil/trace.go:171","msg":"trace[603120579] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25060; }","duration":"413.049113ms","start":"2026-08-14T10:01:15.812709Z","end":"2026-08-14T10:01:16.225758Z","steps":["trace[603120579] 'agreement among raft nodes before linearized reading' (duration: 412.797345ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:16.225801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:15.812692Z","time spent":"413.102785ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-14T10:01:16.225466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:15.727817Z","time spent":"497.5695ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T10:01:16.504496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.574317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-14T10:01:16.504626Z","caller":"traceutil/trace.go:171","msg":"trace[1331970892] linearizableReadLoop","detail":"{readStateIndex:25880; appliedIndex:25879; }","duration":"121.490473ms","start":"2026-08-14T10:01:16.383114Z","end":"2026-08-14T10:01:16.504604Z","steps":["trace[1331970892] 'read index received' (duration: 39.181µs)","trace[1331970892] 'applied index is now lower than readState.Index' (duration: 121.449502ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:01:16.504801Z","caller":"traceutil/trace.go:171","msg":"trace[2098423238] transaction","detail":"{read_only:false; response_revision:25061; number_of_response:1; }","duration":"274.078535ms","start":"2026-08-14T10:01:16.230653Z","end":"2026-08-14T10:01:16.504731Z","steps":["trace[2098423238] 'process raft request' (duration: 110.161708ms)","trace[2098423238] 'compare' (duration: 163.274969ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:01:16.505059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.943476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-14T10:01:16.505119Z","caller":"traceutil/trace.go:171","msg":"trace[666267606] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:25061; }","duration":"122.037619ms","start":"2026-08-14T10:01:16.383066Z","end":"2026-08-14T10:01:16.505104Z","steps":["trace[666267606] 'agreement among raft nodes before linearized reading' (duration: 121.665628ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:16.810887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.580396ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-08-14T10:01:16.811466Z","caller":"traceutil/trace.go:171","msg":"trace[1942072055] transaction","detail":"{read_only:false; response_revision:25063; number_of_response:1; }","duration":"166.858223ms","start":"2026-08-14T10:01:16.644574Z","end":"2026-08-14T10:01:16.811433Z","steps":["trace[1942072055] 'process raft request' (duration: 166.522064ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:16.811497Z","caller":"traceutil/trace.go:171","msg":"trace[2029816376] transaction","detail":"{read_only:false; response_revision:25062; number_of_response:1; }","duration":"176.938144ms","start":"2026-08-14T10:01:16.634428Z","end":"2026-08-14T10:01:16.811366Z","steps":["trace[2029816376] 'process raft request' (duration: 176.559462ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:16.811907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:16.507Z","time spent":"304.116041ms","remote":"127.0.0.1:57204","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-14T10:01:17.105237Z","caller":"traceutil/trace.go:171","msg":"trace[213996901] transaction","detail":"{read_only:false; response_revision:25064; number_of_response:1; }","duration":"291.779706ms","start":"2026-08-14T10:01:16.813424Z","end":"2026-08-14T10:01:17.105203Z","steps":["trace[213996901] 'process raft request' (duration: 207.00443ms)","trace[213996901] 'compare' (duration: 83.687324ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:01:17.105814Z","caller":"traceutil/trace.go:171","msg":"trace[1932156645] linearizableReadLoop","detail":"{readStateIndex:25885; appliedIndex:25883; }","duration":"205.131237ms","start":"2026-08-14T10:01:16.900603Z","end":"2026-08-14T10:01:17.105735Z","steps":["trace[1932156645] 'read index received' (duration: 119.836946ms)","trace[1932156645] 'applied index is now lower than readState.Index' (duration: 85.293311ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:01:17.105906Z","caller":"traceutil/trace.go:171","msg":"trace[715920179] transaction","detail":"{read_only:false; response_revision:25066; number_of_response:1; }","duration":"204.859218ms","start":"2026-08-14T10:01:16.901033Z","end":"2026-08-14T10:01:17.105893Z","steps":["trace[715920179] 'process raft request' (duration: 204.636702ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:17.106049Z","caller":"traceutil/trace.go:171","msg":"trace[534815528] transaction","detail":"{read_only:false; response_revision:25065; number_of_response:1; }","duration":"288.058918ms","start":"2026-08-14T10:01:16.81798Z","end":"2026-08-14T10:01:17.106039Z","steps":["trace[534815528] 'process raft request' (duration: 287.569694ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:17.106198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.511378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-14T10:01:17.106243Z","caller":"traceutil/trace.go:171","msg":"trace[625153786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25066; }","duration":"205.635351ms","start":"2026-08-14T10:01:16.900596Z","end":"2026-08-14T10:01:17.106231Z","steps":["trace[625153786] 'agreement among raft nodes before linearized reading' (duration: 205.418225ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:17.372987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.1777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-14T10:01:17.373043Z","caller":"traceutil/trace.go:171","msg":"trace[443208615] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:25067; }","duration":"168.269662ms","start":"2026-08-14T10:01:17.204761Z","end":"2026-08-14T10:01:17.373031Z","steps":["trace[443208615] 'range keys from in-memory index tree' (duration: 168.049547ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:17.373371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.511802ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-14T10:01:17.373422Z","caller":"traceutil/trace.go:171","msg":"trace[1980886711] transaction","detail":"{read_only:false; response_revision:25068; number_of_response:1; }","duration":"168.427297ms","start":"2026-08-14T10:01:17.204984Z","end":"2026-08-14T10:01:17.373412Z","steps":["trace[1980886711] 'process raft request' (duration: 10.829603ms)","trace[1980886711] 'compare' (duration: 157.134411ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:01:17.395081Z","caller":"traceutil/trace.go:171","msg":"trace[865641588] linearizableReadLoop","detail":"{readStateIndex:25890; appliedIndex:25888; }","duration":"151.944452ms","start":"2026-08-14T10:01:17.24312Z","end":"2026-08-14T10:01:17.395064Z","steps":["trace[865641588] 'read index received' (duration: 151.796338ms)","trace[865641588] 'applied index is now lower than readState.Index' (duration: 147.294µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:01:17.395137Z","caller":"traceutil/trace.go:171","msg":"trace[2000323575] transaction","detail":"{read_only:false; response_revision:25069; number_of_response:1; }","duration":"189.597658ms","start":"2026-08-14T10:01:17.205494Z","end":"2026-08-14T10:01:17.395092Z","steps":["trace[2000323575] 'process raft request' (duration: 189.431203ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:17.395198Z","caller":"traceutil/trace.go:171","msg":"trace[704971729] transaction","detail":"{read_only:false; response_revision:25070; number_of_response:1; }","duration":"188.415924ms","start":"2026-08-14T10:01:17.20677Z","end":"2026-08-14T10:01:17.395186Z","steps":["trace[704971729] 'process raft request' (duration: 188.256069ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:17.395565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.419205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-14T10:01:17.395638Z","caller":"traceutil/trace.go:171","msg":"trace[1230631887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25070; }","duration":"152.544029ms","start":"2026-08-14T10:01:17.243072Z","end":"2026-08-14T10:01:17.395616Z","steps":["trace[1230631887] 'agreement among raft nodes before linearized reading' (duration: 152.185819ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:17.396612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.424329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T10:01:17.396662Z","caller":"traceutil/trace.go:171","msg":"trace[247393712] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:25070; }","duration":"133.529061ms","start":"2026-08-14T10:01:17.26312Z","end":"2026-08-14T10:01:17.39665Z","steps":["trace[247393712] 'agreement among raft nodes before linearized reading' (duration: 132.842951ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:17.567276Z","caller":"traceutil/trace.go:171","msg":"trace[1599493121] transaction","detail":"{read_only:false; response_revision:25071; number_of_response:1; }","duration":"168.85075ms","start":"2026-08-14T10:01:17.398398Z","end":"2026-08-14T10:01:17.567249Z","steps":["trace[1599493121] 'process raft request' (duration: 117.480739ms)","trace[1599493121] 'compare' (duration: 51.026291ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:01:17.567502Z","caller":"traceutil/trace.go:171","msg":"trace[1758678963] transaction","detail":"{read_only:false; response_revision:25072; number_of_response:1; }","duration":"163.604509ms","start":"2026-08-14T10:01:17.40386Z","end":"2026-08-14T10:01:17.567465Z","steps":["trace[1758678963] 'process raft request' (duration: 163.31401ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:19.072442Z","caller":"traceutil/trace.go:171","msg":"trace[767690997] transaction","detail":"{read_only:false; response_revision:25077; number_of_response:1; }","duration":"255.702036ms","start":"2026-08-14T10:01:18.816715Z","end":"2026-08-14T10:01:19.072417Z","steps":["trace[767690997] 'process raft request' (duration: 255.552772ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:19.305986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.110058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-14T10:01:19.306083Z","caller":"traceutil/trace.go:171","msg":"trace[920397200] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25077; }","duration":"194.247982ms","start":"2026-08-14T10:01:19.111812Z","end":"2026-08-14T10:01:19.30606Z","steps":["trace[920397200] 'range keys from in-memory index tree' (duration: 193.940303ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:19.306321Z","caller":"traceutil/trace.go:171","msg":"trace[1321472933] transaction","detail":"{read_only:false; response_revision:25078; number_of_response:1; }","duration":"193.220143ms","start":"2026-08-14T10:01:19.113073Z","end":"2026-08-14T10:01:19.306293Z","steps":["trace[1321472933] 'process raft request' (duration: 191.232206ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:19.307046Z","caller":"traceutil/trace.go:171","msg":"trace[1348361784] transaction","detail":"{read_only:false; response_revision:25079; number_of_response:1; }","duration":"125.790988ms","start":"2026-08-14T10:01:19.181241Z","end":"2026-08-14T10:01:19.307032Z","steps":["trace[1348361784] 'process raft request' (duration: 125.656214ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:29.282487Z","caller":"traceutil/trace.go:171","msg":"trace[1322574327] linearizableReadLoop","detail":"{readStateIndex:26030; appliedIndex:26029; }","duration":"115.053398ms","start":"2026-08-14T10:01:29.167379Z","end":"2026-08-14T10:01:29.282433Z","steps":["trace[1322574327] 'read index received' (duration: 112.707171ms)","trace[1322574327] 'applied index is now lower than readState.Index' (duration: 2.344617ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:01:29.282657Z","caller":"traceutil/trace.go:171","msg":"trace[729180526] transaction","detail":"{read_only:false; response_revision:25208; number_of_response:1; }","duration":"125.419036ms","start":"2026-08-14T10:01:29.157223Z","end":"2026-08-14T10:01:29.282642Z","steps":["trace[729180526] 'process raft request' (duration: 122.896324ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:29.282956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.226328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-767d07d6-ccad-43eb-aef2-35385873f8ec\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-08-14T10:01:29.28307Z","caller":"traceutil/trace.go:171","msg":"trace[2047988515] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-767d07d6-ccad-43eb-aef2-35385873f8ec; range_end:; response_count:1; response_revision:25208; }","duration":"111.379002ms","start":"2026-08-14T10:01:29.17166Z","end":"2026-08-14T10:01:29.283039Z","steps":["trace[2047988515] 'agreement among raft nodes before linearized reading' (duration: 111.035681ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:29.283514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.864092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-767d07d6-ccad-43eb-aef2-35385873f8ec\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-08-14T10:01:29.283592Z","caller":"traceutil/trace.go:171","msg":"trace[2102842062] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-767d07d6-ccad-43eb-aef2-35385873f8ec; range_end:; response_count:1; response_revision:25208; }","duration":"116.238533ms","start":"2026-08-14T10:01:29.167329Z","end":"2026-08-14T10:01:29.283567Z","steps":["trace[2102842062] 'agreement among raft nodes before linearized reading' (duration: 115.714038ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:29.638326Z","caller":"traceutil/trace.go:171","msg":"trace[1640041577] transaction","detail":"{read_only:false; response_revision:25209; number_of_response:1; }","duration":"348.321615ms","start":"2026-08-14T10:01:29.289876Z","end":"2026-08-14T10:01:29.638197Z","steps":["trace[1640041577] 'process raft request' (duration: 325.883859ms)","trace[1640041577] 'compare' (duration: 22.20358ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:01:29.638585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:29.289838Z","time spent":"348.604483ms","remote":"127.0.0.1:57344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3746,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T10:01:29.926658Z","caller":"traceutil/trace.go:171","msg":"trace[153394989] transaction","detail":"{read_only:false; response_revision:25211; number_of_response:1; }","duration":"576.523436ms","start":"2026-08-14T10:01:29.35011Z","end":"2026-08-14T10:01:29.926633Z","steps":["trace[153394989] 'process raft request' (duration: 576.436834ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:29.926691Z","caller":"traceutil/trace.go:171","msg":"trace[763695952] transaction","detail":"{read_only:false; response_revision:25210; number_of_response:1; }","duration":"585.421304ms","start":"2026-08-14T10:01:29.341232Z","end":"2026-08-14T10:01:29.926653Z","steps":["trace[763695952] 'process raft request' (duration: 585.143606ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:29.926811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:29.350074Z","time spent":"576.65707ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T10:01:29.926858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:29.341203Z","time spent":"585.567498ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T10:01:29.92703Z","caller":"traceutil/trace.go:171","msg":"trace[1203987470] transaction","detail":"{read_only:false; response_revision:25212; number_of_response:1; }","duration":"495.556812ms","start":"2026-08-14T10:01:29.431449Z","end":"2026-08-14T10:01:29.927006Z","steps":["trace[1203987470] 'process raft request' (duration: 495.14142ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:29.927181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:29.431425Z","time spent":"495.661214ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T10:01:29.927223Z","caller":"traceutil/trace.go:171","msg":"trace[1784528875] linearizableReadLoop","detail":"{readStateIndex:26033; appliedIndex:26030; }","duration":"569.875056ms","start":"2026-08-14T10:01:29.357309Z","end":"2026-08-14T10:01:29.927184Z","steps":["trace[1784528875] 'read index received' (duration: 258.408002ms)","trace[1784528875] 'applied index is now lower than readState.Index' (duration: 311.465794ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:01:29.92728Z","caller":"traceutil/trace.go:171","msg":"trace[945566602] transaction","detail":"{read_only:false; response_revision:25214; number_of_response:1; }","duration":"317.166149ms","start":"2026-08-14T10:01:29.610104Z","end":"2026-08-14T10:01:29.92727Z","steps":["trace[945566602] 'process raft request' (duration: 316.971682ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:29.92733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:29.610085Z","time spent":"317.22355ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T10:01:29.927326Z","caller":"traceutil/trace.go:171","msg":"trace[1076689399] transaction","detail":"{read_only:false; response_revision:25213; number_of_response:1; }","duration":"494.981816ms","start":"2026-08-14T10:01:29.432303Z","end":"2026-08-14T10:01:29.927285Z","steps":["trace[1076689399] 'process raft request' (duration: 494.600124ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:29.927474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.161435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-14T10:01:29.92752Z","caller":"traceutil/trace.go:171","msg":"trace[2130740489] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25214; }","duration":"570.249797ms","start":"2026-08-14T10:01:29.357258Z","end":"2026-08-14T10:01:29.927508Z","steps":["trace[2130740489] 'agreement among raft nodes before linearized reading' (duration: 570.085603ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:29.927554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:29.357234Z","time spent":"570.313068ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-14T10:01:29.927608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:29.432273Z","time spent":"495.169882ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T10:01:29.962609Z","caller":"traceutil/trace.go:171","msg":"trace[493033232] transaction","detail":"{read_only:false; response_revision:25215; number_of_response:1; }","duration":"317.686492ms","start":"2026-08-14T10:01:29.644895Z","end":"2026-08-14T10:01:29.962581Z","steps":["trace[493033232] 'process raft request' (duration: 317.543278ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:29.96278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:29.64487Z","time spent":"317.811896ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T10:01:29.962902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.830768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-14T10:01:29.962939Z","caller":"traceutil/trace.go:171","msg":"trace[564411695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25216; }","duration":"600.89612ms","start":"2026-08-14T10:01:29.362032Z","end":"2026-08-14T10:01:29.962928Z","steps":["trace[564411695] 'agreement among raft nodes before linearized reading' (duration: 600.741266ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:29.962962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:29.362022Z","time spent":"600.934491ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-14T10:01:29.963075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.350074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-14T10:01:29.963097Z","caller":"traceutil/trace.go:171","msg":"trace[877067428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25216; }","duration":"333.416246ms","start":"2026-08-14T10:01:29.629673Z","end":"2026-08-14T10:01:29.963089Z","steps":["trace[877067428] 'agreement among raft nodes before linearized reading' (duration: 333.341043ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:29.963159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:29.629619Z","time spent":"333.535639ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-14T10:01:29.963218Z","caller":"traceutil/trace.go:171","msg":"trace[2037584403] transaction","detail":"{read_only:false; response_revision:25216; number_of_response:1; }","duration":"209.141571ms","start":"2026-08-14T10:01:29.754066Z","end":"2026-08-14T10:01:29.963207Z","steps":["trace[2037584403] 'process raft request' (duration: 208.484822ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:29.963292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.576148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T10:01:29.963311Z","caller":"traceutil/trace.go:171","msg":"trace[694908244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25216; }","duration":"516.64808ms","start":"2026-08-14T10:01:29.446658Z","end":"2026-08-14T10:01:29.963306Z","steps":["trace[694908244] 'agreement among raft nodes before linearized reading' (duration: 516.611069ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:29.963327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:29.446631Z","time spent":"516.692641ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T10:01:29.963383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.082192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-14T10:01:29.963456Z","caller":"traceutil/trace.go:171","msg":"trace[1286206563] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25216; }","duration":"517.198645ms","start":"2026-08-14T10:01:29.446237Z","end":"2026-08-14T10:01:29.963436Z","steps":["trace[1286206563] 'agreement among raft nodes before linearized reading' (duration: 517.023831ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:29.963494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:29.446214Z","time spent":"517.271788ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-14T10:01:29.963562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.428026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-14T10:01:29.963633Z","caller":"traceutil/trace.go:171","msg":"trace[25164739] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:25216; }","duration":"592.557321ms","start":"2026-08-14T10:01:29.371059Z","end":"2026-08-14T10:01:29.963617Z","steps":["trace[25164739] 'agreement among raft nodes before linearized reading' (duration: 592.341363ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:29.963676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:29.37104Z","time spent":"592.626112ms","remote":"127.0.0.1:57386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":80,"response size":30,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-08-14T10:01:30.33588Z","caller":"traceutil/trace.go:171","msg":"trace[1474611526] linearizableReadLoop","detail":"{readStateIndex:26039; appliedIndex:26038; }","duration":"370.663689ms","start":"2026-08-14T10:01:29.965193Z","end":"2026-08-14T10:01:30.335857Z","steps":["trace[1474611526] 'read index received' (duration: 337.088911ms)","trace[1474611526] 'applied index is now lower than readState.Index' (duration: 33.573608ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:01:30.336271Z","caller":"traceutil/trace.go:171","msg":"trace[591022357] transaction","detail":"{read_only:false; response_revision:25217; number_of_response:1; }","duration":"404.007012ms","start":"2026-08-14T10:01:29.932236Z","end":"2026-08-14T10:01:30.336243Z","steps":["trace[591022357] 'process raft request' (duration: 370.030492ms)","trace[591022357] 'compare' (duration: 33.107875ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:01:30.336538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.32247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T10:01:30.336602Z","caller":"traceutil/trace.go:171","msg":"trace[15557900] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25217; }","duration":"371.438653ms","start":"2026-08-14T10:01:29.965146Z","end":"2026-08-14T10:01:30.336585Z","steps":["trace[15557900] 'agreement among raft nodes before linearized reading' (duration: 371.281869ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:30.336613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:29.93221Z","time spent":"404.313961ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T10:01:30.336644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:29.965126Z","time spent":"371.509845ms","remote":"127.0.0.1:57180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T10:01:30.662535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.257915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-14T10:01:30.662872Z","caller":"traceutil/trace.go:171","msg":"trace[883797310] linearizableReadLoop","detail":"{readStateIndex:26044; appliedIndex:26039; }","duration":"326.401154ms","start":"2026-08-14T10:01:30.336451Z","end":"2026-08-14T10:01:30.662852Z","steps":["trace[883797310] 'read index received' (duration: 185.756238ms)","trace[883797310] 'applied index is now lower than readState.Index' (duration: 140.644056ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:01:30.66304Z","caller":"traceutil/trace.go:171","msg":"trace[26747566] transaction","detail":"{read_only:false; response_revision:25222; number_of_response:1; }","duration":"387.045313ms","start":"2026-08-14T10:01:30.275958Z","end":"2026-08-14T10:01:30.663003Z","steps":["trace[26747566] 'process raft request' (duration: 386.850197ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:30.663148Z","caller":"traceutil/trace.go:171","msg":"trace[737695720] transaction","detail":"{read_only:false; response_revision:25221; number_of_response:1; }","duration":"609.025104ms","start":"2026-08-14T10:01:30.05409Z","end":"2026-08-14T10:01:30.663116Z","steps":["trace[737695720] 'process raft request' (duration: 608.675584ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:30.663143Z","caller":"traceutil/trace.go:171","msg":"trace[1442199313] transaction","detail":"{read_only:false; response_revision:25220; number_of_response:1; }","duration":"686.973093ms","start":"2026-08-14T10:01:29.976133Z","end":"2026-08-14T10:01:30.663106Z","steps":["trace[1442199313] 'process raft request' (duration: 686.597922ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:30.663203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.573368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-14T10:01:30.663212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:30.275932Z","time spent":"387.192826ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T10:01:30.663234Z","caller":"traceutil/trace.go:171","msg":"trace[1761446510] transaction","detail":"{read_only:false; response_revision:25218; number_of_response:1; }","duration":"691.263947ms","start":"2026-08-14T10:01:29.971955Z","end":"2026-08-14T10:01:30.663219Z","steps":["trace[1761446510] 'process raft request' (duration: 550.23328ms)","trace[1761446510] 'compare' (duration: 139.827443ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:01:30.66322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.965466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T10:01:30.663246Z","caller":"traceutil/trace.go:171","msg":"trace[720661873] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:25222; }","duration":"456.61723ms","start":"2026-08-14T10:01:30.206614Z","end":"2026-08-14T10:01:30.663231Z","steps":["trace[720661873] 'agreement among raft nodes before linearized reading' (duration: 456.546897ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:30.663296Z","caller":"traceutil/trace.go:171","msg":"trace[20143001] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25222; }","duration":"149.059979ms","start":"2026-08-14T10:01:30.514221Z","end":"2026-08-14T10:01:30.663281Z","steps":["trace[20143001] 'agreement among raft nodes before linearized reading' (duration: 148.882524ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:30.663299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:30.206556Z","time spent":"456.732712ms","remote":"127.0.0.1:38992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":28,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true "} {"level":"warn","ts":"2026-08-14T10:01:30.663315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:30.054072Z","time spent":"609.164969ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T10:01:30.663318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:29.976117Z","time spent":"687.112656ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T10:01:30.663346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.731296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-767d07d6-ccad-43eb-aef2-35385873f8ec\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-08-14T10:01:30.663435Z","caller":"traceutil/trace.go:171","msg":"trace[1464634867] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-767d07d6-ccad-43eb-aef2-35385873f8ec; range_end:; response_count:1; response_revision:25222; }","duration":"375.819479ms","start":"2026-08-14T10:01:30.287566Z","end":"2026-08-14T10:01:30.663386Z","steps":["trace[1464634867] 'agreement among raft nodes before linearized reading' (duration: 375.620963ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:30.663119Z","caller":"traceutil/trace.go:171","msg":"trace[1220092630] transaction","detail":"{read_only:false; response_revision:25219; number_of_response:1; }","duration":"686.983773ms","start":"2026-08-14T10:01:29.976055Z","end":"2026-08-14T10:01:30.663038Z","steps":["trace[1220092630] 'process raft request' (duration: 686.591192ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:30.663445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:29.971935Z","time spent":"691.37155ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T10:01:30.663471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:30.28755Z","time spent":"375.911481ms","remote":"127.0.0.1:57344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3786,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-767d07d6-ccad-43eb-aef2-35385873f8ec\" "} {"level":"warn","ts":"2026-08-14T10:01:30.66357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:01:29.976044Z","time spent":"687.422435ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T10:01:30.854338Z","caller":"traceutil/trace.go:171","msg":"trace[1312705672] linearizableReadLoop","detail":"{readStateIndex:26045; appliedIndex:26044; }","duration":"182.664548ms","start":"2026-08-14T10:01:30.671647Z","end":"2026-08-14T10:01:30.854311Z","steps":["trace[1312705672] 'read index received' (duration: 154.243799ms)","trace[1312705672] 'applied index is now lower than readState.Index' (duration: 28.419579ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:01:30.854487Z","caller":"traceutil/trace.go:171","msg":"trace[1549342506] transaction","detail":"{read_only:false; response_revision:25223; number_of_response:1; }","duration":"185.138661ms","start":"2026-08-14T10:01:30.669334Z","end":"2026-08-14T10:01:30.854473Z","steps":["trace[1549342506] 'process raft request' (duration: 156.629078ms)","trace[1549342506] 'compare' (duration: 28.216423ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:01:30.854939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.270696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T10:01:30.855066Z","caller":"traceutil/trace.go:171","msg":"trace[1582293046] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25223; }","duration":"183.442901ms","start":"2026-08-14T10:01:30.671603Z","end":"2026-08-14T10:01:30.855046Z","steps":["trace[1582293046] 'agreement among raft nodes before linearized reading' (duration: 183.242085ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:31.409557Z","caller":"traceutil/trace.go:171","msg":"trace[1006524968] transaction","detail":"{read_only:false; response_revision:25224; number_of_response:1; }","duration":"121.842005ms","start":"2026-08-14T10:01:31.287691Z","end":"2026-08-14T10:01:31.409533Z","steps":["trace[1006524968] 'process raft request' (duration: 121.585868ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:36.112528Z","caller":"traceutil/trace.go:171","msg":"trace[2110149841] transaction","detail":"{read_only:false; response_revision:25273; number_of_response:1; }","duration":"158.927253ms","start":"2026-08-14T10:01:35.953582Z","end":"2026-08-14T10:01:36.112509Z","steps":["trace[2110149841] 'process raft request' (duration: 158.884492ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:36.112561Z","caller":"traceutil/trace.go:171","msg":"trace[896654477] transaction","detail":"{read_only:false; response_revision:25270; number_of_response:1; }","duration":"169.319112ms","start":"2026-08-14T10:01:35.943201Z","end":"2026-08-14T10:01:36.112521Z","steps":["trace[896654477] 'process raft request' (duration: 169.029684ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:36.112541Z","caller":"traceutil/trace.go:171","msg":"trace[672215527] transaction","detail":"{read_only:false; response_revision:25271; number_of_response:1; }","duration":"162.663421ms","start":"2026-08-14T10:01:35.949854Z","end":"2026-08-14T10:01:36.112518Z","steps":["trace[672215527] 'process raft request' (duration: 162.539137ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:36.112541Z","caller":"traceutil/trace.go:171","msg":"trace[1113449692] transaction","detail":"{read_only:false; response_revision:25272; number_of_response:1; }","duration":"160.996393ms","start":"2026-08-14T10:01:35.95152Z","end":"2026-08-14T10:01:36.112516Z","steps":["trace[1113449692] 'process raft request' (duration: 160.911841ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:36.122808Z","caller":"traceutil/trace.go:171","msg":"trace[1178558129] transaction","detail":"{read_only:false; response_revision:25274; number_of_response:1; }","duration":"133.794339ms","start":"2026-08-14T10:01:35.989001Z","end":"2026-08-14T10:01:36.122795Z","steps":["trace[1178558129] 'process raft request' (duration: 133.669445ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:38.284253Z","caller":"traceutil/trace.go:171","msg":"trace[845105284] transaction","detail":"{read_only:false; response_revision:25293; number_of_response:1; }","duration":"166.42309ms","start":"2026-08-14T10:01:38.117806Z","end":"2026-08-14T10:01:38.284229Z","steps":["trace[845105284] 'process raft request' (duration: 166.240985ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:38.284589Z","caller":"traceutil/trace.go:171","msg":"trace[517333450] linearizableReadLoop","detail":"{readStateIndex:26120; appliedIndex:26115; }","duration":"127.899488ms","start":"2026-08-14T10:01:38.156672Z","end":"2026-08-14T10:01:38.284571Z","steps":["trace[517333450] 'read index received' (duration: 37.990116ms)","trace[517333450] 'applied index is now lower than readState.Index' (duration: 89.908752ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:01:38.284582Z","caller":"traceutil/trace.go:171","msg":"trace[1942857790] transaction","detail":"{read_only:false; response_revision:25294; number_of_response:1; }","duration":"166.695408ms","start":"2026-08-14T10:01:38.117847Z","end":"2026-08-14T10:01:38.284542Z","steps":["trace[1942857790] 'process raft request' (duration: 166.336627ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:38.284703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.006442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T10:01:38.284683Z","caller":"traceutil/trace.go:171","msg":"trace[2035801396] transaction","detail":"{read_only:false; response_revision:25295; number_of_response:1; }","duration":"166.533553ms","start":"2026-08-14T10:01:38.118124Z","end":"2026-08-14T10:01:38.284657Z","steps":["trace[2035801396] 'process raft request' (duration: 166.294706ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:38.284731Z","caller":"traceutil/trace.go:171","msg":"trace[1969737487] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25296; }","duration":"128.078804ms","start":"2026-08-14T10:01:38.156641Z","end":"2026-08-14T10:01:38.28472Z","steps":["trace[1969737487] 'agreement among raft nodes before linearized reading' (duration: 128.001601ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:38.284732Z","caller":"traceutil/trace.go:171","msg":"trace[1006778401] transaction","detail":"{read_only:false; response_revision:25296; number_of_response:1; }","duration":"158.225773ms","start":"2026-08-14T10:01:38.126478Z","end":"2026-08-14T10:01:38.284704Z","steps":["trace[1006778401] 'process raft request' (duration: 158.045198ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:40.995125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.611029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-14T10:01:40.995266Z","caller":"traceutil/trace.go:171","msg":"trace[498804939] transaction","detail":"{read_only:false; response_revision:25319; number_of_response:1; }","duration":"263.554031ms","start":"2026-08-14T10:01:40.731681Z","end":"2026-08-14T10:01:40.995235Z","steps":["trace[498804939] 'process raft request' (duration: 126.714065ms)","trace[498804939] 'compare' (duration: 136.252309ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:01:41.02715Z","caller":"traceutil/trace.go:171","msg":"trace[921283960] transaction","detail":"{read_only:false; response_revision:25320; number_of_response:1; }","duration":"294.665548ms","start":"2026-08-14T10:01:40.732464Z","end":"2026-08-14T10:01:41.027129Z","steps":["trace[921283960] 'process raft request' (duration: 294.508214ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:41.027442Z","caller":"traceutil/trace.go:171","msg":"trace[1438470244] transaction","detail":"{read_only:false; response_revision:25321; number_of_response:1; }","duration":"293.909397ms","start":"2026-08-14T10:01:40.733523Z","end":"2026-08-14T10:01:41.027432Z","steps":["trace[1438470244] 'process raft request' (duration: 293.564377ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:41.027556Z","caller":"traceutil/trace.go:171","msg":"trace[2080175406] linearizableReadLoop","detail":"{readStateIndex:26146; appliedIndex:26143; }","duration":"156.188335ms","start":"2026-08-14T10:01:40.871348Z","end":"2026-08-14T10:01:41.027536Z","steps":["trace[2080175406] 'read index received' (duration: 155.605158ms)","trace[2080175406] 'applied index is now lower than readState.Index' (duration: 582.107µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:01:41.027792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.38718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T10:01:41.027834Z","caller":"traceutil/trace.go:171","msg":"trace[398146074] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25322; }","duration":"156.509534ms","start":"2026-08-14T10:01:40.871307Z","end":"2026-08-14T10:01:41.027817Z","steps":["trace[398146074] 'agreement among raft nodes before linearized reading' (duration: 156.392431ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:41.027842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.767692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-14T10:01:41.027875Z","caller":"traceutil/trace.go:171","msg":"trace[1085960676] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25322; }","duration":"130.875394ms","start":"2026-08-14T10:01:40.896991Z","end":"2026-08-14T10:01:41.027866Z","steps":["trace[1085960676] 'agreement among raft nodes before linearized reading' (duration: 130.72616ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:41.027604Z","caller":"traceutil/trace.go:171","msg":"trace[556107455] transaction","detail":"{read_only:false; response_revision:25322; number_of_response:1; }","duration":"284.351682ms","start":"2026-08-14T10:01:40.743245Z","end":"2026-08-14T10:01:41.027597Z","steps":["trace[556107455] 'process raft request' (duration: 284.085924ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:41.151876Z","caller":"traceutil/trace.go:171","msg":"trace[1101864360] linearizableReadLoop","detail":"{readStateIndex:26147; appliedIndex:26146; }","duration":"118.562519ms","start":"2026-08-14T10:01:41.033293Z","end":"2026-08-14T10:01:41.151855Z","steps":["trace[1101864360] 'read index received' (duration: 99.777118ms)","trace[1101864360] 'applied index is now lower than readState.Index' (duration: 18.784441ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:01:41.151929Z","caller":"traceutil/trace.go:171","msg":"trace[2105813619] transaction","detail":"{read_only:false; response_revision:25323; number_of_response:1; }","duration":"119.99905ms","start":"2026-08-14T10:01:41.0319Z","end":"2026-08-14T10:01:41.151899Z","steps":["trace[2105813619] 'process raft request' (duration: 101.24751ms)","trace[2105813619] 'compare' (duration: 18.564275ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:01:41.152008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.700603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T10:01:41.152044Z","caller":"traceutil/trace.go:171","msg":"trace[1714753146] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25323; }","duration":"118.773555ms","start":"2026-08-14T10:01:41.033259Z","end":"2026-08-14T10:01:41.152032Z","steps":["trace[1714753146] 'agreement among raft nodes before linearized reading' (duration: 118.698653ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:01:41.152111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.63418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T10:01:41.152212Z","caller":"traceutil/trace.go:171","msg":"trace[993264414] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:25323; }","duration":"110.769354ms","start":"2026-08-14T10:01:41.041417Z","end":"2026-08-14T10:01:41.152186Z","steps":["trace[993264414] 'agreement among raft nodes before linearized reading' (duration: 110.62193ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:01:42.378283Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000662d.wal"} {"level":"info","ts":"2026-08-14T10:02:00.548589Z","caller":"traceutil/trace.go:171","msg":"trace[528536016] linearizableReadLoop","detail":"{readStateIndex:26311; appliedIndex:26309; }","duration":"103.284239ms","start":"2026-08-14T10:02:00.44528Z","end":"2026-08-14T10:02:00.548564Z","steps":["trace[528536016] 'read index received' (duration: 103.031992ms)","trace[528536016] 'applied index is now lower than readState.Index' (duration: 251.427µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:02:00.548711Z","caller":"traceutil/trace.go:171","msg":"trace[633956950] transaction","detail":"{read_only:false; response_revision:25482; number_of_response:1; }","duration":"172.450142ms","start":"2026-08-14T10:02:00.376225Z","end":"2026-08-14T10:02:00.548675Z","steps":["trace[633956950] 'process raft request' (duration: 172.290348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:02:00.548801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.490685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T10:02:00.548847Z","caller":"traceutil/trace.go:171","msg":"trace[1311624917] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25482; }","duration":"103.581027ms","start":"2026-08-14T10:02:00.445255Z","end":"2026-08-14T10:02:00.548836Z","steps":["trace[1311624917] 'agreement among raft nodes before linearized reading' (duration: 103.443323ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:02:00.548856Z","caller":"traceutil/trace.go:171","msg":"trace[1533542784] transaction","detail":"{read_only:false; response_revision:25481; number_of_response:1; }","duration":"174.125651ms","start":"2026-08-14T10:02:00.374589Z","end":"2026-08-14T10:02:00.548714Z","steps":["trace[1533542784] 'process raft request' (duration: 173.780042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:02:00.736871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.678201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-14T10:02:00.736997Z","caller":"traceutil/trace.go:171","msg":"trace[1417362588] transaction","detail":"{read_only:false; response_revision:25483; number_of_response:1; }","duration":"183.40933ms","start":"2026-08-14T10:02:00.553565Z","end":"2026-08-14T10:02:00.736974Z","steps":["trace[1417362588] 'process raft request' (duration: 45.515402ms)","trace[1417362588] 'compare' (duration: 137.438224ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:02:01.337187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.985088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-14T10:02:01.33729Z","caller":"traceutil/trace.go:171","msg":"trace[18311099] linearizableReadLoop","detail":"{readStateIndex:26315; appliedIndex:26314; }","duration":"217.221685ms","start":"2026-08-14T10:02:01.120053Z","end":"2026-08-14T10:02:01.337275Z","steps":["trace[18311099] 'read index received' (duration: 42.397202ms)","trace[18311099] 'applied index is now lower than readState.Index' (duration: 174.823303ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:02:01.337338Z","caller":"traceutil/trace.go:171","msg":"trace[1830691893] transaction","detail":"{read_only:false; response_revision:25486; number_of_response:1; }","duration":"238.367765ms","start":"2026-08-14T10:02:01.098931Z","end":"2026-08-14T10:02:01.337299Z","steps":["trace[1830691893] 'process raft request' (duration: 132.176212ms)","trace[1830691893] 'compare' (duration: 105.839053ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:02:01.337475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.421081ms","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-08-14T10:02:01.337503Z","caller":"traceutil/trace.go:171","msg":"trace[1647079221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25486; }","duration":"217.493203ms","start":"2026-08-14T10:02:01.120001Z","end":"2026-08-14T10:02:01.337494Z","steps":["trace[1647079221] 'agreement among raft nodes before linearized reading' (duration: 217.354059ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:02:01.33759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.325084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T10:02:01.337706Z","caller":"traceutil/trace.go:171","msg":"trace[373977514] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25486; }","duration":"134.513619ms","start":"2026-08-14T10:02:01.203166Z","end":"2026-08-14T10:02:01.33768Z","steps":["trace[373977514] 'agreement among raft nodes before linearized reading' (duration: 134.284233ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:02:01.337684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.474122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-14T10:02:01.337802Z","caller":"traceutil/trace.go:171","msg":"trace[2052509705] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25486; }","duration":"217.658358ms","start":"2026-08-14T10:02:01.120114Z","end":"2026-08-14T10:02:01.337772Z","steps":["trace[2052509705] 'agreement among raft nodes before linearized reading' (duration: 217.287687ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:02:01.522654Z","caller":"traceutil/trace.go:171","msg":"trace[1980929906] transaction","detail":"{read_only:false; response_revision:25487; number_of_response:1; }","duration":"180.734193ms","start":"2026-08-14T10:02:01.341893Z","end":"2026-08-14T10:02:01.522627Z","steps":["trace[1980929906] 'process raft request' (duration: 180.571219ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:02:01.523093Z","caller":"traceutil/trace.go:171","msg":"trace[1371818953] linearizableReadLoop","detail":"{readStateIndex:26316; appliedIndex:26316; }","duration":"180.69035ms","start":"2026-08-14T10:02:01.342392Z","end":"2026-08-14T10:02:01.523082Z","steps":["trace[1371818953] 'read index received' (duration: 180.68618ms)","trace[1371818953] 'applied index is now lower than readState.Index' (duration: 3.38µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:02:01.523181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.784303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T10:02:01.523218Z","caller":"traceutil/trace.go:171","msg":"trace[169118226] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25487; }","duration":"180.854525ms","start":"2026-08-14T10:02:01.342352Z","end":"2026-08-14T10:02:01.523207Z","steps":["trace[169118226] 'agreement among raft nodes before linearized reading' (duration: 180.777022ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:02:01.624234Z","caller":"traceutil/trace.go:171","msg":"trace[1649737814] transaction","detail":"{read_only:false; response_revision:25488; number_of_response:1; }","duration":"280.106418ms","start":"2026-08-14T10:02:01.34409Z","end":"2026-08-14T10:02:01.624196Z","steps":["trace[1649737814] 'process raft request' (duration: 265.464186ms)","trace[1649737814] 'compare' (duration: 13.955563ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:02:01.624262Z","caller":"traceutil/trace.go:171","msg":"trace[1026830176] transaction","detail":"{read_only:false; response_revision:25489; number_of_response:1; }","duration":"279.582804ms","start":"2026-08-14T10:02:01.344653Z","end":"2026-08-14T10:02:01.624236Z","steps":["trace[1026830176] 'process raft request' (duration: 279.44728ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:02:02.743554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.966505ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-14T10:02:02.743819Z","caller":"traceutil/trace.go:171","msg":"trace[1044611403] transaction","detail":"{read_only:false; response_revision:25494; number_of_response:1; }","duration":"189.765463ms","start":"2026-08-14T10:02:02.554023Z","end":"2026-08-14T10:02:02.743789Z","steps":["trace[1044611403] 'process raft request' (duration: 74.471938ms)","trace[1044611403] 'compare' (duration: 114.823071ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:02:02.744386Z","caller":"traceutil/trace.go:171","msg":"trace[870352677] transaction","detail":"{read_only:false; response_revision:25495; number_of_response:1; }","duration":"188.626049ms","start":"2026-08-14T10:02:02.555746Z","end":"2026-08-14T10:02:02.744372Z","steps":["trace[870352677] 'process raft request' (duration: 187.911099ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:02:02.910632Z","caller":"traceutil/trace.go:171","msg":"trace[841920954] transaction","detail":"{read_only:false; response_revision:25496; number_of_response:1; }","duration":"162.743014ms","start":"2026-08-14T10:02:02.74787Z","end":"2026-08-14T10:02:02.910613Z","steps":["trace[841920954] 'process raft request' (duration: 138.854095ms)","trace[841920954] 'compare' (duration: 23.301442ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:02:03.648667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.247395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T10:02:03.648771Z","caller":"traceutil/trace.go:171","msg":"trace[2099845995] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25499; }","duration":"140.417319ms","start":"2026-08-14T10:02:03.508331Z","end":"2026-08-14T10:02:03.648749Z","steps":["trace[2099845995] 'range keys from in-memory index tree' (duration: 140.164242ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:02:03.648993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.443932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-14T10:02:03.649064Z","caller":"traceutil/trace.go:171","msg":"trace[829255698] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25499; }","duration":"121.642598ms","start":"2026-08-14T10:02:03.527397Z","end":"2026-08-14T10:02:03.64904Z","steps":["trace[829255698] 'range keys from in-memory index tree' (duration: 121.174515ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:02:03.793406Z","caller":"traceutil/trace.go:171","msg":"trace[1759938576] transaction","detail":"{read_only:false; response_revision:25500; number_of_response:1; }","duration":"140.013908ms","start":"2026-08-14T10:02:03.653361Z","end":"2026-08-14T10:02:03.793375Z","steps":["trace[1759938576] 'process raft request' (duration: 139.819862ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:02:04.065054Z","caller":"traceutil/trace.go:171","msg":"trace[1951985946] transaction","detail":"{read_only:false; response_revision:25501; number_of_response:1; }","duration":"411.245951ms","start":"2026-08-14T10:02:03.653785Z","end":"2026-08-14T10:02:04.065031Z","steps":["trace[1951985946] 'process raft request' (duration: 317.443095ms)","trace[1951985946] 'compare' (duration: 93.513907ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:02:04.065219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:02:03.653767Z","time spent":"411.346664ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T10:02:04.065555Z","caller":"traceutil/trace.go:171","msg":"trace[1745945674] transaction","detail":"{read_only:false; response_revision:25502; number_of_response:1; }","duration":"410.042836ms","start":"2026-08-14T10:02:03.655454Z","end":"2026-08-14T10:02:04.065497Z","steps":["trace[1745945674] 'process raft request' (duration: 409.541422ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:02:04.065708Z","caller":"traceutil/trace.go:171","msg":"trace[550234637] linearizableReadLoop","detail":"{readStateIndex:26331; appliedIndex:26329; }","duration":"411.147769ms","start":"2026-08-14T10:02:03.654536Z","end":"2026-08-14T10:02:04.065684Z","steps":["trace[550234637] 'read index received' (duration: 138.625479ms)","trace[550234637] 'applied index is now lower than readState.Index' (duration: 272.52127ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:02:04.065759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:02:03.655424Z","time spent":"410.254723ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T10:02:04.065912Z","caller":"traceutil/trace.go:171","msg":"trace[1719179205] transaction","detail":"{read_only:false; response_revision:25503; number_of_response:1; }","duration":"344.681191ms","start":"2026-08-14T10:02:03.721219Z","end":"2026-08-14T10:02:04.0659Z","steps":["trace[1719179205] 'process raft request' (duration: 343.906059ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:02:04.066023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:02:03.721188Z","time spent":"344.760053ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T10:02:04.066157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.628472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T10:02:04.066187Z","caller":"traceutil/trace.go:171","msg":"trace[60001832] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25503; }","duration":"411.680234ms","start":"2026-08-14T10:02:03.654496Z","end":"2026-08-14T10:02:04.066177Z","steps":["trace[60001832] 'agreement among raft nodes before linearized reading' (duration: 411.625622ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:02:04.066215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:02:03.654482Z","time spent":"411.726285ms","remote":"127.0.0.1:57346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-08-14T10:02:04.662227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.979578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-14T10:02:04.662379Z","caller":"traceutil/trace.go:171","msg":"trace[733569776] linearizableReadLoop","detail":"{readStateIndex:26336; appliedIndex:26334; }","duration":"160.286853ms","start":"2026-08-14T10:02:04.50208Z","end":"2026-08-14T10:02:04.662367Z","steps":["trace[733569776] 'read index received' (duration: 12.086018ms)","trace[733569776] 'applied index is now lower than readState.Index' (duration: 148.200184ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:02:04.662441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.365065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-14T10:02:04.662462Z","caller":"traceutil/trace.go:171","msg":"trace[775029424] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:25506; }","duration":"160.415376ms","start":"2026-08-14T10:02:04.502037Z","end":"2026-08-14T10:02:04.662452Z","steps":["trace[775029424] 'agreement among raft nodes before linearized reading' (duration: 160.365164ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:02:04.662452Z","caller":"traceutil/trace.go:171","msg":"trace[2107626369] transaction","detail":"{read_only:false; response_revision:25505; number_of_response:1; }","duration":"195.645693ms","start":"2026-08-14T10:02:04.466786Z","end":"2026-08-14T10:02:04.662431Z","steps":["trace[2107626369] 'process raft request' (duration: 47.367057ms)","trace[2107626369] 'compare' (duration: 147.868315ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:02:04.662638Z","caller":"traceutil/trace.go:171","msg":"trace[373561255] transaction","detail":"{read_only:false; response_revision:25506; number_of_response:1; }","duration":"195.754746ms","start":"2026-08-14T10:02:04.466876Z","end":"2026-08-14T10:02:04.66263Z","steps":["trace[373561255] 'process raft request' (duration: 195.445287ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:02:09.375792Z","caller":"traceutil/trace.go:171","msg":"trace[1974343096] transaction","detail":"{read_only:false; response_revision:25541; number_of_response:1; }","duration":"143.494628ms","start":"2026-08-14T10:02:09.232272Z","end":"2026-08-14T10:02:09.375766Z","steps":["trace[1974343096] 'process raft request' (duration: 143.157979ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:02:15.074119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.076469ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-14T10:02:15.074339Z","caller":"traceutil/trace.go:171","msg":"trace[335896258] transaction","detail":"{read_only:false; response_revision:25592; number_of_response:1; }","duration":"227.959034ms","start":"2026-08-14T10:02:14.846364Z","end":"2026-08-14T10:02:15.074323Z","steps":["trace[335896258] 'process raft request' (duration: 227.867161ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:02:15.074541Z","caller":"traceutil/trace.go:171","msg":"trace[2107182361] transaction","detail":"{read_only:false; response_revision:25591; number_of_response:1; }","duration":"375.992215ms","start":"2026-08-14T10:02:14.698495Z","end":"2026-08-14T10:02:15.074488Z","steps":["trace[2107182361] 'process raft request' (duration: 97.452961ms)","trace[2107182361] 'compare' (duration: 277.874304ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:02:15.075083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:02:14.698474Z","time spent":"376.162ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T10:02:15.148306Z","caller":"traceutil/trace.go:171","msg":"trace[961703894] transaction","detail":"{read_only:false; response_revision:25593; number_of_response:1; }","duration":"296.061988ms","start":"2026-08-14T10:02:14.852215Z","end":"2026-08-14T10:02:15.148277Z","steps":["trace[961703894] 'process raft request' (duration: 295.910354ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:02:15.224486Z","caller":"traceutil/trace.go:171","msg":"trace[1193769666] transaction","detail":"{read_only:false; response_revision:25594; number_of_response:1; }","duration":"272.288992ms","start":"2026-08-14T10:02:14.952173Z","end":"2026-08-14T10:02:15.224462Z","steps":["trace[1193769666] 'process raft request' (duration: 270.387107ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:02:15.224626Z","caller":"traceutil/trace.go:171","msg":"trace[967479632] linearizableReadLoop","detail":"{readStateIndex:26426; appliedIndex:26424; }","duration":"267.050351ms","start":"2026-08-14T10:02:14.957551Z","end":"2026-08-14T10:02:15.224601Z","steps":["trace[967479632] 'read index received' (duration: 190.545965ms)","trace[967479632] 'applied index is now lower than readState.Index' (duration: 76.502696ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:02:15.224672Z","caller":"traceutil/trace.go:171","msg":"trace[13711814] transaction","detail":"{read_only:false; response_revision:25595; number_of_response:1; }","duration":"183.749729ms","start":"2026-08-14T10:02:15.040897Z","end":"2026-08-14T10:02:15.224647Z","steps":["trace[13711814] 'process raft request' (duration: 183.477931ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:02:15.224904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.336169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-14T10:02:15.224945Z","caller":"traceutil/trace.go:171","msg":"trace[1382095281] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25595; }","duration":"267.417142ms","start":"2026-08-14T10:02:14.957517Z","end":"2026-08-14T10:02:15.224934Z","steps":["trace[1382095281] 'agreement among raft nodes before linearized reading' (duration: 267.225386ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:02:15.529827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.771278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T10:02:15.52991Z","caller":"traceutil/trace.go:171","msg":"trace[2038393737] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:25596; }","duration":"116.959663ms","start":"2026-08-14T10:02:15.412931Z","end":"2026-08-14T10:02:15.529891Z","steps":["trace[2038393737] 'count revisions from in-memory index tree' (duration: 116.647154ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:02:15.530191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.794351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-14T10:02:15.530251Z","caller":"traceutil/trace.go:171","msg":"trace[1666405826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25596; }","duration":"130.905044ms","start":"2026-08-14T10:02:15.399333Z","end":"2026-08-14T10:02:15.530238Z","steps":["trace[1666405826] 'range keys from in-memory index tree' (duration: 130.625917ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:02:15.532489Z","caller":"traceutil/trace.go:171","msg":"trace[929889378] transaction","detail":"{read_only:false; response_revision:25597; number_of_response:1; }","duration":"100.286053ms","start":"2026-08-14T10:02:15.432178Z","end":"2026-08-14T10:02:15.532464Z","steps":["trace[929889378] 'process raft request' (duration: 100.047996ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:02:15.751679Z","caller":"traceutil/trace.go:171","msg":"trace[1369026271] transaction","detail":"{read_only:false; response_revision:25598; number_of_response:1; }","duration":"217.646737ms","start":"2026-08-14T10:02:15.533996Z","end":"2026-08-14T10:02:15.751643Z","steps":["trace[1369026271] 'process raft request' (duration: 154.079434ms)","trace[1369026271] 'compare' (duration: 63.377727ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:02:25.903945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.140688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T10:02:25.90403Z","caller":"traceutil/trace.go:171","msg":"trace[284631574] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:25668; }","duration":"152.276711ms","start":"2026-08-14T10:02:25.751734Z","end":"2026-08-14T10:02:25.904011Z","steps":["trace[284631574] 'count revisions from in-memory index tree' (duration: 152.064095ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:02:26.217805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.71019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-14T10:02:26.21791Z","caller":"traceutil/trace.go:171","msg":"trace[255558062] linearizableReadLoop","detail":"{readStateIndex:26504; appliedIndex:26503; }","duration":"157.393129ms","start":"2026-08-14T10:02:26.0605Z","end":"2026-08-14T10:02:26.217893Z","steps":["trace[255558062] 'read index received' (duration: 35.505334ms)","trace[255558062] 'applied index is now lower than readState.Index' (duration: 121.886405ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:02:26.217983Z","caller":"traceutil/trace.go:171","msg":"trace[683907295] transaction","detail":"{read_only:false; response_revision:25670; number_of_response:1; }","duration":"305.735317ms","start":"2026-08-14T10:02:25.912236Z","end":"2026-08-14T10:02:26.217971Z","steps":["trace[683907295] 'process raft request' (duration: 183.77207ms)","trace[683907295] 'compare' (duration: 121.36382ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:02:26.218055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:02:25.912213Z","time spent":"305.80933ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T10:02:26.218264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.727919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-14T10:02:26.218358Z","caller":"traceutil/trace.go:171","msg":"trace[1186022084] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:25670; }","duration":"157.883524ms","start":"2026-08-14T10:02:26.060446Z","end":"2026-08-14T10:02:26.218329Z","steps":["trace[1186022084] 'agreement among raft nodes before linearized reading' (duration: 157.720039ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:02:26.525941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.151359ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-14T10:02:26.526042Z","caller":"traceutil/trace.go:171","msg":"trace[267184681] linearizableReadLoop","detail":"{readStateIndex:26505; appliedIndex:26504; }","duration":"308.088066ms","start":"2026-08-14T10:02:26.217936Z","end":"2026-08-14T10:02:26.526024Z","steps":["trace[267184681] 'read index received' (duration: 147.729281ms)","trace[267184681] 'applied index is now lower than readState.Index' (duration: 160.357745ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:02:26.526108Z","caller":"traceutil/trace.go:171","msg":"trace[1511618492] transaction","detail":"{read_only:false; response_revision:25671; number_of_response:1; }","duration":"375.535111ms","start":"2026-08-14T10:02:26.150534Z","end":"2026-08-14T10:02:26.526069Z","steps":["trace[1511618492] 'process raft request' (duration: 215.178937ms)","trace[1511618492] 'compare' (duration: 159.731446ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:02:26.526297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:02:26.150512Z","time spent":"375.683925ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T10:02:26.526309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.668937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-14T10:02:26.526367Z","caller":"traceutil/trace.go:171","msg":"trace[112689583] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25671; }","duration":"356.766559ms","start":"2026-08-14T10:02:26.169585Z","end":"2026-08-14T10:02:26.526352Z","steps":["trace[112689583] 'agreement among raft nodes before linearized reading' (duration: 356.626165ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:02:26.526414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:02:26.169563Z","time spent":"356.842551ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-14T10:02:26.526477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.279521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-14T10:02:26.526537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.939998ms","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-08-14T10:02:26.526543Z","caller":"traceutil/trace.go:171","msg":"trace[2120800825] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:25671; }","duration":"377.390104ms","start":"2026-08-14T10:02:26.149136Z","end":"2026-08-14T10:02:26.526526Z","steps":["trace[2120800825] 'agreement among raft nodes before linearized reading' (duration: 377.28001ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:02:26.526578Z","caller":"traceutil/trace.go:171","msg":"trace[1205982111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25671; }","duration":"345.3248ms","start":"2026-08-14T10:02:26.181242Z","end":"2026-08-14T10:02:26.526567Z","steps":["trace[1205982111] 'agreement among raft nodes before linearized reading' (duration: 344.854686ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:02:26.526587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:02:26.149113Z","time spent":"377.462707ms","remote":"127.0.0.1:59350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-08-14T10:02:26.526612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:02:26.181222Z","time spent":"345.379671ms","remote":"127.0.0.1:57416","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-08-14T10:02:26.537576Z","caller":"traceutil/trace.go:171","msg":"trace[2036798515] transaction","detail":"{read_only:false; response_revision:25672; number_of_response:1; }","duration":"102.615469ms","start":"2026-08-14T10:02:26.434927Z","end":"2026-08-14T10:02:26.537543Z","steps":["trace[2036798515] 'process raft request' (duration: 102.422264ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:02:26.819073Z","caller":"traceutil/trace.go:171","msg":"trace[138700505] transaction","detail":"{read_only:false; response_revision:25673; number_of_response:1; }","duration":"315.662454ms","start":"2026-08-14T10:02:26.503384Z","end":"2026-08-14T10:02:26.819046Z","steps":["trace[138700505] 'process raft request' (duration: 315.463129ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:02:26.819227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:02:26.503354Z","time spent":"315.790988ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T10:02:26.819482Z","caller":"traceutil/trace.go:171","msg":"trace[1486168875] linearizableReadLoop","detail":"{readStateIndex:26507; appliedIndex:26505; }","duration":"293.369302ms","start":"2026-08-14T10:02:26.526074Z","end":"2026-08-14T10:02:26.819443Z","steps":["trace[1486168875] 'read index received' (duration: 11.273485ms)","trace[1486168875] 'applied index is now lower than readState.Index' (duration: 282.094666ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T10:02:26.819584Z","caller":"traceutil/trace.go:171","msg":"trace[1366241455] transaction","detail":"{read_only:false; response_revision:25674; number_of_response:1; }","duration":"289.107938ms","start":"2026-08-14T10:02:26.530463Z","end":"2026-08-14T10:02:26.819571Z","steps":["trace[1366241455] 'process raft request' (duration: 288.542412ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:02:26.819617Z","caller":"traceutil/trace.go:171","msg":"trace[318478821] transaction","detail":"{read_only:false; response_revision:25675; number_of_response:1; }","duration":"289.078517ms","start":"2026-08-14T10:02:26.530522Z","end":"2026-08-14T10:02:26.8196Z","steps":["trace[318478821] 'process raft request' (duration: 288.780769ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:02:26.819868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.500133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-14T10:02:26.819907Z","caller":"traceutil/trace.go:171","msg":"trace[2025638564] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:25675; }","duration":"433.553804ms","start":"2026-08-14T10:02:26.386344Z","end":"2026-08-14T10:02:26.819898Z","steps":["trace[2025638564] 'agreement among raft nodes before linearized reading' (duration: 433.40717ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:02:26.819902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.976219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-14T10:02:26.819951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:02:26.38632Z","time spent":"433.621136ms","remote":"127.0.0.1:57204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":162,"request content":"key:\"/registry/masterleases/199.204.45.156\" "} {"level":"info","ts":"2026-08-14T10:02:26.819978Z","caller":"traceutil/trace.go:171","msg":"trace[991182479] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:25675; }","duration":"466.098292ms","start":"2026-08-14T10:02:26.353861Z","end":"2026-08-14T10:02:26.81996Z","steps":["trace[991182479] 'agreement among raft nodes before linearized reading' (duration: 465.944898ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:02:26.820033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:02:26.353847Z","time spent":"466.175375ms","remote":"127.0.0.1:53122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":23,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true "} {"level":"info","ts":"2026-08-14T10:02:26.887426Z","caller":"traceutil/trace.go:171","msg":"trace[94826392] transaction","detail":"{read_only:false; response_revision:25676; number_of_response:1; }","duration":"103.547976ms","start":"2026-08-14T10:02:26.783848Z","end":"2026-08-14T10:02:26.887396Z","steps":["trace[94826392] 'process raft request' (duration: 53.937616ms)","trace[94826392] 'compare' (duration: 49.182348ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:02:26.88785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.451002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T10:02:26.887938Z","caller":"traceutil/trace.go:171","msg":"trace[698211168] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:25676; }","duration":"303.613477ms","start":"2026-08-14T10:02:26.584304Z","end":"2026-08-14T10:02:26.887917Z","steps":["trace[698211168] 'agreement among raft nodes before linearized reading' (duration: 303.446511ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:02:26.887997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:02:26.584273Z","time spent":"303.7081ms","remote":"127.0.0.1:36240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-14T10:03:03.215216Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23044} {"level":"info","ts":"2026-08-14T10:03:03.359721Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23044,"took":"141.412638ms","hash":1272201833,"current-db-size-bytes":59162624,"current-db-size":"59 MB","current-db-size-in-use-bytes":37740544,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-08-14T10:03:03.359772Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1272201833,"revision":23044,"compact-revision":20158} {"level":"info","ts":"2026-08-14T10:08:03.22174Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26120} {"level":"info","ts":"2026-08-14T10:08:03.373914Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26120,"took":"148.392318ms","hash":552501773,"current-db-size-bytes":59162624,"current-db-size":"59 MB","current-db-size-in-use-bytes":35270656,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-14T10:08:03.373975Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":552501773,"revision":26120,"compact-revision":23044} {"level":"info","ts":"2026-08-14T10:08:34.884632Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5ac240a6daec2148","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-14T10:08:34.890657Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-14T10:08:34.890772Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-14T10:13:03.235Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28849} {"level":"info","ts":"2026-08-14T10:13:03.375762Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28849,"took":"137.513485ms","hash":2244285850,"current-db-size-bytes":59162624,"current-db-size":"59 MB","current-db-size-in-use-bytes":30724096,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-14T10:13:03.375837Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2244285850,"revision":28849,"compact-revision":26120} {"level":"info","ts":"2026-08-14T10:14:48.868167Z","caller":"traceutil/trace.go:171","msg":"trace[460042415] transaction","detail":"{read_only:false; response_revision:31871; number_of_response:1; }","duration":"429.651157ms","start":"2026-08-14T10:14:48.438477Z","end":"2026-08-14T10:14:48.868128Z","steps":["trace[460042415] 'process raft request' (duration: 429.435811ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:14:48.868352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:14:48.438451Z","time spent":"429.808272ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T10:14:48.868931Z","caller":"traceutil/trace.go:171","msg":"trace[1403215593] linearizableReadLoop","detail":"{readStateIndex:32875; appliedIndex:32875; }","duration":"430.032128ms","start":"2026-08-14T10:14:48.438884Z","end":"2026-08-14T10:14:48.868916Z","steps":["trace[1403215593] 'read index received' (duration: 430.024168ms)","trace[1403215593] 'applied index is now lower than readState.Index' (duration: 6.22µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T10:14:48.869141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.250745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-14T10:14:48.86917Z","caller":"traceutil/trace.go:171","msg":"trace[2129015236] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:31871; }","duration":"430.296025ms","start":"2026-08-14T10:14:48.438866Z","end":"2026-08-14T10:14:48.869162Z","steps":["trace[2129015236] 'agreement among raft nodes before linearized reading' (duration: 430.1013ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:14:48.869191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:14:48.438857Z","time spent":"430.328626ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-14T10:14:48.869823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.549536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-14T10:14:48.869889Z","caller":"traceutil/trace.go:171","msg":"trace[376437657] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:31872; }","duration":"429.637018ms","start":"2026-08-14T10:14:48.440237Z","end":"2026-08-14T10:14:48.869874Z","steps":["trace[376437657] 'agreement among raft nodes before linearized reading' (duration: 429.510514ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:14:48.869928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:14:48.440231Z","time spent":"429.687289ms","remote":"127.0.0.1:57512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-08-14T10:14:48.870212Z","caller":"traceutil/trace.go:171","msg":"trace[97432133] transaction","detail":"{read_only:false; response_revision:31872; number_of_response:1; }","duration":"427.263179ms","start":"2026-08-14T10:14:48.442933Z","end":"2026-08-14T10:14:48.870196Z","steps":["trace[97432133] 'process raft request' (duration: 426.665962ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:14:48.870295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T10:14:48.442921Z","time spent":"427.319561ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T10:14:48.871129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.581077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-14T10:14:48.871191Z","caller":"traceutil/trace.go:171","msg":"trace[1911340115] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:31872; }","duration":"265.66366ms","start":"2026-08-14T10:14:48.60551Z","end":"2026-08-14T10:14:48.871174Z","steps":["trace[1911340115] 'agreement among raft nodes before linearized reading' (duration: 265.519196ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T10:14:48.871401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.151692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-14T10:14:48.871432Z","caller":"traceutil/trace.go:171","msg":"trace[872028451] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:31872; }","duration":"276.226944ms","start":"2026-08-14T10:14:48.595197Z","end":"2026-08-14T10:14:48.871424Z","steps":["trace[872028451] 'agreement among raft nodes before linearized reading' (duration: 276.169362ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T10:18:03.241207Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31067} {"level":"info","ts":"2026-08-14T10:18:03.373234Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31067,"took":"128.435023ms","hash":3472978529,"current-db-size-bytes":59162624,"current-db-size":"59 MB","current-db-size-in-use-bytes":30740480,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-14T10:18:03.373299Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3472978529,"revision":31067,"compact-revision":28849} {"level":"info","ts":"2026-08-14T10:23:03.246454Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33332} {"level":"info","ts":"2026-08-14T10:23:03.401895Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33332,"took":"151.717146ms","hash":874963027,"current-db-size-bytes":60203008,"current-db-size":"60 MB","current-db-size-in-use-bytes":31686656,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-14T10:23:03.401969Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":874963027,"revision":33332,"compact-revision":31067} {"level":"info","ts":"2026-08-14T10:28:03.256319Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35624} {"level":"info","ts":"2026-08-14T10:28:03.406703Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35624,"took":"145.65758ms","hash":1821068202,"current-db-size-bytes":60203008,"current-db-size":"60 MB","current-db-size-in-use-bytes":32354304,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-14T10:28:03.406773Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1821068202,"revision":35624,"compact-revision":33332} {"level":"info","ts":"2026-08-14T10:30:03.994636Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5ac240a6daec2148","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-14T10:30:03.998668Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-08-14T10:30:03.998792Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-08-14T10:33:03.262742Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37856} {"level":"info","ts":"2026-08-14T10:33:03.38633Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37856,"took":"119.263489ms","hash":2612841485,"current-db-size-bytes":60203008,"current-db-size":"60 MB","current-db-size-in-use-bytes":31817728,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-14T10:33:03.386408Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2612841485,"revision":37856,"compact-revision":35624}