{"level":"warn","ts":"2026-08-26T00:20:52.011958Z","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-26T00:20:52.012075Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.165: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://162.253.55.165:2380","--initial-cluster=instance=https://162.253.55.165:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.165:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.165: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-26T00:20:52.01216Z","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-26T00:20:52.012178Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.165:2380"]} {"level":"info","ts":"2026-08-26T00:20:52.012201Z","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-26T00:20:52.013017Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.165:2379"]} {"level":"info","ts":"2026-08-26T00:20:52.013105Z","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://162.253.55.165:2380"],"listen-peer-urls":["https://162.253.55.165:2380"],"advertise-client-urls":["https://162.253.55.165:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.165:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.165: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-26T00:20:52.018353Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.969515ms"} {"level":"info","ts":"2026-08-26T00:20:52.029004Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e9d471e5f5fb9faf","cluster-id":"fb4c33c289aeb291"} {"level":"info","ts":"2026-08-26T00:20:52.029092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf switched to configuration voters=()"} {"level":"info","ts":"2026-08-26T00:20:52.029112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf became follower at term 0"} {"level":"info","ts":"2026-08-26T00:20:52.029132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e9d471e5f5fb9faf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-26T00:20:52.029142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf became follower at term 1"} {"level":"info","ts":"2026-08-26T00:20:52.029221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf switched to configuration voters=(16849217338388225967)"} {"level":"warn","ts":"2026-08-26T00:20:52.038549Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-26T00:20:52.040886Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-26T00:20:52.043436Z","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-26T00:20:52.047219Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e9d471e5f5fb9faf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-26T00:20:52.04745Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e9d471e5f5fb9faf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-26T00:20:52.047635Z","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-26T00:20:52.04779Z","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-26T00:20:52.047832Z","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-26T00:20:52.048831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf switched to configuration voters=(16849217338388225967)"} {"level":"info","ts":"2026-08-26T00:20:52.049364Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb4c33c289aeb291","local-member-id":"e9d471e5f5fb9faf","added-peer-id":"e9d471e5f5fb9faf","added-peer-peer-urls":["https://162.253.55.165:2380"]} {"level":"info","ts":"2026-08-26T00:20:52.052254Z","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-26T00:20:52.052531Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e9d471e5f5fb9faf","initial-advertise-peer-urls":["https://162.253.55.165:2380"],"listen-peer-urls":["https://162.253.55.165:2380"],"advertise-client-urls":["https://162.253.55.165:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.165:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-26T00:20:52.052576Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-26T00:20:52.052657Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.165:2380"} {"level":"info","ts":"2026-08-26T00:20:52.052675Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.165:2380"} {"level":"info","ts":"2026-08-26T00:20:52.230892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf is starting a new election at term 1"} {"level":"info","ts":"2026-08-26T00:20:52.231001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf became pre-candidate at term 1"} {"level":"info","ts":"2026-08-26T00:20:52.231048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf received MsgPreVoteResp from e9d471e5f5fb9faf at term 1"} {"level":"info","ts":"2026-08-26T00:20:52.231069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf became candidate at term 2"} {"level":"info","ts":"2026-08-26T00:20:52.231086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf received MsgVoteResp from e9d471e5f5fb9faf at term 2"} {"level":"info","ts":"2026-08-26T00:20:52.231109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf became leader at term 2"} {"level":"info","ts":"2026-08-26T00:20:52.231129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e9d471e5f5fb9faf elected leader e9d471e5f5fb9faf at term 2"} {"level":"info","ts":"2026-08-26T00:20:52.231988Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:20:52.232901Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e9d471e5f5fb9faf","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.165:2379]}","request-path":"/0/members/e9d471e5f5fb9faf/attributes","cluster-id":"fb4c33c289aeb291","publish-timeout":"7s"} {"level":"info","ts":"2026-08-26T00:20:52.23292Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-26T00:20:52.232961Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-26T00:20:52.233181Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb4c33c289aeb291","local-member-id":"e9d471e5f5fb9faf","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:20:52.23329Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:20:52.23333Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:20:52.233317Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-26T00:20:52.233383Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-26T00:20:52.235533Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-26T00:20:52.236951Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.165:2379"} {"level":"info","ts":"2026-08-26T00:21:10.158084Z","caller":"traceutil/trace.go:171","msg":"trace[1324800966] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"168.066833ms","start":"2026-08-26T00:21:09.989973Z","end":"2026-08-26T00:21:10.15804Z","steps":["trace[1324800966] 'process raft request' (duration: 167.840267ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:21:10.409418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.429076ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:21:10.40963Z","caller":"traceutil/trace.go:171","msg":"trace[704455519] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"246.512678ms","start":"2026-08-26T00:21:10.163101Z","end":"2026-08-26T00:21:10.409614Z","steps":["trace[704455519] 'process raft request' (duration: 246.450986ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:21:10.409748Z","caller":"traceutil/trace.go:171","msg":"trace[1951741709] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"247.038892ms","start":"2026-08-26T00:21:10.162665Z","end":"2026-08-26T00:21:10.409703Z","steps":["trace[1951741709] 'process raft request' (duration: 128.745473ms)","trace[1951741709] 'compare' (duration: 117.313593ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:21:30.831717Z","caller":"traceutil/trace.go:171","msg":"trace[601253988] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"134.176811ms","start":"2026-08-26T00:21:30.697514Z","end":"2026-08-26T00:21:30.831691Z","steps":["trace[601253988] 'process raft request' (duration: 134.034968ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:21:30.83648Z","caller":"traceutil/trace.go:171","msg":"trace[1432818727] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"120.208571ms","start":"2026-08-26T00:21:30.716244Z","end":"2026-08-26T00:21:30.836452Z","steps":["trace[1432818727] 'process raft request' (duration: 119.903832ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:21:30.836582Z","caller":"traceutil/trace.go:171","msg":"trace[748017788] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"119.80845ms","start":"2026-08-26T00:21:30.716753Z","end":"2026-08-26T00:21:30.836562Z","steps":["trace[748017788] 'process raft request' (duration: 119.477351ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:21:30.836664Z","caller":"traceutil/trace.go:171","msg":"trace[1481459402] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"133.359508ms","start":"2026-08-26T00:21:30.703298Z","end":"2026-08-26T00:21:30.836657Z","steps":["trace[1481459402] 'process raft request' (duration: 132.704621ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:21:30.836688Z","caller":"traceutil/trace.go:171","msg":"trace[107042416] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"117.500947ms","start":"2026-08-26T00:21:30.719182Z","end":"2026-08-26T00:21:30.836683Z","steps":["trace[107042416] 'process raft request' (duration: 117.066615ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:21:30.836737Z","caller":"traceutil/trace.go:171","msg":"trace[1965128817] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"133.146764ms","start":"2026-08-26T00:21:30.703581Z","end":"2026-08-26T00:21:30.836728Z","steps":["trace[1965128817] 'process raft request' (duration: 132.499796ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:21:30.836758Z","caller":"traceutil/trace.go:171","msg":"trace[246892141] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"114.023113ms","start":"2026-08-26T00:21:30.722729Z","end":"2026-08-26T00:21:30.836752Z","steps":["trace[246892141] 'process raft request' (duration: 113.543811ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:21:30.836804Z","caller":"traceutil/trace.go:171","msg":"trace[1540367403] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"112.099919ms","start":"2026-08-26T00:21:30.724701Z","end":"2026-08-26T00:21:30.836801Z","steps":["trace[1540367403] 'process raft request' (duration: 111.608916ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:21:30.836894Z","caller":"traceutil/trace.go:171","msg":"trace[892870114] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"122.467843ms","start":"2026-08-26T00:21:30.714422Z","end":"2026-08-26T00:21:30.83689Z","steps":["trace[892870114] 'process raft request' (duration: 121.683262ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:21:30.83694Z","caller":"traceutil/trace.go:171","msg":"trace[813127794] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"122.466162ms","start":"2026-08-26T00:21:30.714469Z","end":"2026-08-26T00:21:30.836935Z","steps":["trace[813127794] 'process raft request' (duration: 121.65935ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:21:36.728847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.893756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-08-26T00:21:36.72893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.43137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-26T00:21:36.728959Z","caller":"traceutil/trace.go:171","msg":"trace[1997460115] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:586; }","duration":"194.128453ms","start":"2026-08-26T00:21:36.534807Z","end":"2026-08-26T00:21:36.728936Z","steps":["trace[1997460115] 'range keys from in-memory index tree' (duration: 193.428133ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:21:36.729019Z","caller":"traceutil/trace.go:171","msg":"trace[1438133886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:586; }","duration":"201.570384ms","start":"2026-08-26T00:21:36.527427Z","end":"2026-08-26T00:21:36.728997Z","steps":["trace[1438133886] 'range keys from in-memory index tree' (duration: 201.225014ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:21:36.756228Z","caller":"traceutil/trace.go:171","msg":"trace[1350937153] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"139.281599ms","start":"2026-08-26T00:21:36.616911Z","end":"2026-08-26T00:21:36.756193Z","steps":["trace[1350937153] 'process raft request' (duration: 137.776268ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:21:37.080222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.238943ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:21:37.080335Z","caller":"traceutil/trace.go:171","msg":"trace[146018758] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"265.913536ms","start":"2026-08-26T00:21:36.814399Z","end":"2026-08-26T00:21:37.080312Z","steps":["trace[146018758] 'process raft request' (duration: 142.511028ms)","trace[146018758] 'compare' (duration: 123.085139ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:21:37.729273Z","caller":"traceutil/trace.go:171","msg":"trace[1393681695] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"244.272417ms","start":"2026-08-26T00:21:37.484952Z","end":"2026-08-26T00:21:37.729224Z","steps":["trace[1393681695] 'process raft request' (duration: 242.727535ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:23:03.309003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.801147ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:23:03.30923Z","caller":"traceutil/trace.go:171","msg":"trace[1496288990] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"178.237909ms","start":"2026-08-26T00:23:03.130948Z","end":"2026-08-26T00:23:03.309186Z","steps":["trace[1496288990] 'process raft request' (duration: 24.117982ms)","trace[1496288990] 'compare' (duration: 153.616903ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:23:03.704914Z","caller":"traceutil/trace.go:171","msg":"trace[856142854] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"391.005207ms","start":"2026-08-26T00:23:03.313827Z","end":"2026-08-26T00:23:03.704832Z","steps":["trace[856142854] 'process raft request' (duration: 390.854734ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:23:03.705064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:23:03.313803Z","time spent":"391.174403ms","remote":"127.0.0.1:36490","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-26T00:23:03.794554Z","caller":"traceutil/trace.go:171","msg":"trace[313152745] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"478.237776ms","start":"2026-08-26T00:23:03.316272Z","end":"2026-08-26T00:23:03.79451Z","steps":["trace[313152745] 'process raft request' (duration: 430.298088ms)","trace[313152745] 'compare' (duration: 47.629659ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:23:03.794648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.27024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:23:03.794723Z","caller":"traceutil/trace.go:171","msg":"trace[110709030] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1800; }","duration":"401.397023ms","start":"2026-08-26T00:23:03.393299Z","end":"2026-08-26T00:23:03.794696Z","steps":["trace[110709030] 'agreement among raft nodes before linearized reading' (duration: 401.28326ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:23:03.794774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:23:03.393272Z","time spent":"401.491517ms","remote":"127.0.0.1:36628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:23:03.794768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:23:03.316254Z","time spent":"478.398241ms","remote":"127.0.0.1:36490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:23:03.794444Z","caller":"traceutil/trace.go:171","msg":"trace[751150728] linearizableReadLoop","detail":"{readStateIndex:1870; appliedIndex:1868; }","duration":"401.035894ms","start":"2026-08-26T00:23:03.393369Z","end":"2026-08-26T00:23:03.794405Z","steps":["trace[751150728] 'read index received' (duration: 311.369159ms)","trace[751150728] 'applied index is now lower than readState.Index' (duration: 89.664995ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:23:03.795743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.130718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-26T00:23:03.795747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.87966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-26T00:23:03.795784Z","caller":"traceutil/trace.go:171","msg":"trace[1663619973] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1800; }","duration":"233.224361ms","start":"2026-08-26T00:23:03.562551Z","end":"2026-08-26T00:23:03.795775Z","steps":["trace[1663619973] 'agreement among raft nodes before linearized reading' (duration: 232.981955ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:23:03.795795Z","caller":"traceutil/trace.go:171","msg":"trace[1902076836] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1800; }","duration":"341.000724ms","start":"2026-08-26T00:23:03.45478Z","end":"2026-08-26T00:23:03.795781Z","steps":["trace[1902076836] 'agreement among raft nodes before linearized reading' (duration: 340.727606ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:23:03.795847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:23:03.454745Z","time spent":"341.091486ms","remote":"127.0.0.1:36490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-26T00:23:04.071354Z","caller":"traceutil/trace.go:171","msg":"trace[783824851] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"176.132461ms","start":"2026-08-26T00:23:03.895193Z","end":"2026-08-26T00:23:04.071325Z","steps":["trace[783824851] 'process raft request' (duration: 175.00061ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:23:04.071616Z","caller":"traceutil/trace.go:171","msg":"trace[1558856289] transaction","detail":"{read_only:false; response_revision:1804; number_of_response:1; }","duration":"158.033584ms","start":"2026-08-26T00:23:03.91357Z","end":"2026-08-26T00:23:04.071603Z","steps":["trace[1558856289] 'process raft request' (duration: 157.688204ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:23:50.498707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.644304ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:23:50.498972Z","caller":"traceutil/trace.go:171","msg":"trace[723002286] linearizableReadLoop","detail":"{readStateIndex:2189; appliedIndex:2188; }","duration":"152.376338ms","start":"2026-08-26T00:23:50.346552Z","end":"2026-08-26T00:23:50.498928Z","steps":["trace[723002286] 'read index received' (duration: 43.422356ms)","trace[723002286] 'applied index is now lower than readState.Index' (duration: 108.951452ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:23:50.498993Z","caller":"traceutil/trace.go:171","msg":"trace[2065161060] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"216.111596ms","start":"2026-08-26T00:23:50.282843Z","end":"2026-08-26T00:23:50.498954Z","steps":["trace[2065161060] 'process raft request' (duration: 107.118603ms)","trace[2065161060] 'compare' (duration: 108.329085ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:23:50.499244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.680687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-26T00:23:50.499312Z","caller":"traceutil/trace.go:171","msg":"trace[458581136] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2110; }","duration":"152.80783ms","start":"2026-08-26T00:23:50.346487Z","end":"2026-08-26T00:23:50.499295Z","steps":["trace[458581136] 'agreement among raft nodes before linearized reading' (duration: 152.575984ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:23:55.722622Z","caller":"traceutil/trace.go:171","msg":"trace[1368055463] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"136.349274ms","start":"2026-08-26T00:23:55.586243Z","end":"2026-08-26T00:23:55.722592Z","steps":["trace[1368055463] 'process raft request' (duration: 136.099507ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:25:23.662394Z","caller":"traceutil/trace.go:171","msg":"trace[1165362032] linearizableReadLoop","detail":"{readStateIndex:2911; appliedIndex:2910; }","duration":"101.371603ms","start":"2026-08-26T00:25:23.56098Z","end":"2026-08-26T00:25:23.662352Z","steps":["trace[1165362032] 'read index received' (duration: 100.935462ms)","trace[1165362032] 'applied index is now lower than readState.Index' (duration: 434.531µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:25:23.662476Z","caller":"traceutil/trace.go:171","msg":"trace[221849852] transaction","detail":"{read_only:false; response_revision:2804; number_of_response:1; }","duration":"113.589303ms","start":"2026-08-26T00:25:23.548847Z","end":"2026-08-26T00:25:23.662436Z","steps":["trace[221849852] 'process raft request' (duration: 112.998497ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:25:23.662716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.690792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-26T00:25:23.66277Z","caller":"traceutil/trace.go:171","msg":"trace[888090239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2804; }","duration":"101.801794ms","start":"2026-08-26T00:25:23.560953Z","end":"2026-08-26T00:25:23.662755Z","steps":["trace[888090239] 'agreement among raft nodes before linearized reading' (duration: 101.558448ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:28:00.192327Z","caller":"traceutil/trace.go:171","msg":"trace[1864452029] transaction","detail":"{read_only:false; response_revision:3831; number_of_response:1; }","duration":"166.06199ms","start":"2026-08-26T00:28:00.026227Z","end":"2026-08-26T00:28:00.192289Z","steps":["trace[1864452029] 'process raft request' (duration: 165.825564ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:26.204162Z","caller":"traceutil/trace.go:171","msg":"trace[1260809271] transaction","detail":"{read_only:false; response_revision:4862; number_of_response:1; }","duration":"106.440617ms","start":"2026-08-26T00:29:26.097698Z","end":"2026-08-26T00:29:26.204139Z","steps":["trace[1260809271] 'process raft request' (duration: 106.331214ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:26.2042Z","caller":"traceutil/trace.go:171","msg":"trace[273938200] transaction","detail":"{read_only:false; response_revision:4861; number_of_response:1; }","duration":"106.474278ms","start":"2026-08-26T00:29:26.097666Z","end":"2026-08-26T00:29:26.20414Z","steps":["trace[273938200] 'process raft request' (duration: 103.452476ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:26.204339Z","caller":"traceutil/trace.go:171","msg":"trace[526600499] transaction","detail":"{read_only:false; response_revision:4863; number_of_response:1; }","duration":"106.482278ms","start":"2026-08-26T00:29:26.097802Z","end":"2026-08-26T00:29:26.204284Z","steps":["trace[526600499] 'process raft request' (duration: 106.270813ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:26.204712Z","caller":"traceutil/trace.go:171","msg":"trace[1324459613] transaction","detail":"{read_only:false; response_revision:4864; number_of_response:1; }","duration":"105.887332ms","start":"2026-08-26T00:29:26.098765Z","end":"2026-08-26T00:29:26.204652Z","steps":["trace[1324459613] 'process raft request' (duration: 105.339098ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:26.204958Z","caller":"traceutil/trace.go:171","msg":"trace[626166565] linearizableReadLoop","detail":"{readStateIndex:5044; appliedIndex:5042; }","duration":"107.250448ms","start":"2026-08-26T00:29:26.097674Z","end":"2026-08-26T00:29:26.204924Z","steps":["trace[626166565] 'read index received' (duration: 6.524187ms)","trace[626166565] 'applied index is now lower than readState.Index' (duration: 100.724401ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:29:26.205014Z","caller":"traceutil/trace.go:171","msg":"trace[172692186] transaction","detail":"{read_only:false; response_revision:4865; number_of_response:1; }","duration":"106.091707ms","start":"2026-08-26T00:29:26.098881Z","end":"2026-08-26T00:29:26.204973Z","steps":["trace[172692186] 'process raft request' (duration: 105.692096ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:26.205242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.539937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-67055d43-772e-4a23-811c-d7b88be543ee\" ","response":"range_response_count:1 size:4065"} {"level":"info","ts":"2026-08-26T00:29:26.205352Z","caller":"traceutil/trace.go:171","msg":"trace[1176148314] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-67055d43-772e-4a23-811c-d7b88be543ee; range_end:; response_count:1; response_revision:4865; }","duration":"107.695001ms","start":"2026-08-26T00:29:26.097619Z","end":"2026-08-26T00:29:26.205314Z","steps":["trace[1176148314] 'agreement among raft nodes before linearized reading' (duration: 107.386423ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:26.206042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.232256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"warn","ts":"2026-08-26T00:29:26.20625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.072105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:29:26.206313Z","caller":"traceutil/trace.go:171","msg":"trace[1285114387] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:4866; }","duration":"103.208898ms","start":"2026-08-26T00:29:26.103087Z","end":"2026-08-26T00:29:26.206296Z","steps":["trace[1285114387] 'agreement among raft nodes before linearized reading' (duration: 103.058885ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:26.20608Z","caller":"traceutil/trace.go:171","msg":"trace[1957859649] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4866; }","duration":"108.319678ms","start":"2026-08-26T00:29:26.097751Z","end":"2026-08-26T00:29:26.206071Z","steps":["trace[1957859649] 'agreement among raft nodes before linearized reading' (duration: 108.162965ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:26.206617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.528649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-26T00:29:26.20669Z","caller":"traceutil/trace.go:171","msg":"trace[2105858348] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4866; }","duration":"106.621922ms","start":"2026-08-26T00:29:26.100044Z","end":"2026-08-26T00:29:26.206666Z","steps":["trace[2105858348] 'agreement among raft nodes before linearized reading' (duration: 106.203181ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:31.393959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.172703ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-26T00:29:31.394222Z","caller":"traceutil/trace.go:171","msg":"trace[1674092829] linearizableReadLoop","detail":"{readStateIndex:5240; appliedIndex:5239; }","duration":"206.937923ms","start":"2026-08-26T00:29:31.187177Z","end":"2026-08-26T00:29:31.394115Z","steps":["trace[1674092829] 'read index received' (duration: 7.655967ms)","trace[1674092829] 'applied index is now lower than readState.Index' (duration: 199.280126ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:29:31.394615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.308995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/storage-loki-0.18cf33781f73b298\" ","response":"range_response_count:1 size:943"} {"level":"info","ts":"2026-08-26T00:29:31.394663Z","caller":"traceutil/trace.go:171","msg":"trace[775033654] range","detail":"{range_begin:/registry/events/monitoring/storage-loki-0.18cf33781f73b298; range_end:; response_count:1; response_revision:5050; }","duration":"175.378158ms","start":"2026-08-26T00:29:31.219268Z","end":"2026-08-26T00:29:31.394646Z","steps":["trace[775033654] 'agreement among raft nodes before linearized reading' (duration: 175.11889ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:31.395161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.9431ms","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-26T00:29:31.395289Z","caller":"traceutil/trace.go:171","msg":"trace[1506056253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5050; }","duration":"208.136974ms","start":"2026-08-26T00:29:31.187121Z","end":"2026-08-26T00:29:31.395258Z","steps":["trace[1506056253] 'agreement among raft nodes before linearized reading' (duration: 207.161988ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:43.312686Z","caller":"traceutil/trace.go:171","msg":"trace[1048504780] transaction","detail":"{read_only:false; response_revision:5281; number_of_response:1; }","duration":"107.882959ms","start":"2026-08-26T00:29:43.204768Z","end":"2026-08-26T00:29:43.312651Z","steps":["trace[1048504780] 'process raft request' (duration: 92.431091ms)","trace[1048504780] 'compare' (duration: 15.058258ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:29:43.312654Z","caller":"traceutil/trace.go:171","msg":"trace[1800267154] linearizableReadLoop","detail":"{readStateIndex:5479; appliedIndex:5477; }","duration":"101.719612ms","start":"2026-08-26T00:29:43.210852Z","end":"2026-08-26T00:29:43.312571Z","steps":["trace[1800267154] 'read index received' (duration: 19.059576ms)","trace[1800267154] 'applied index is now lower than readState.Index' (duration: 82.657705ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:29:43.31282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.949807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/memcached-metrics\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:29:43.31288Z","caller":"traceutil/trace.go:171","msg":"trace[492652947] range","detail":"{range_begin:/registry/services/specs/openstack/memcached-metrics; range_end:; response_count:0; response_revision:5281; }","duration":"102.067972ms","start":"2026-08-26T00:29:43.210797Z","end":"2026-08-26T00:29:43.312865Z","steps":["trace[492652947] 'agreement among raft nodes before linearized reading' (duration: 101.944149ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:43.482987Z","caller":"traceutil/trace.go:171","msg":"trace[1954537454] transaction","detail":"{read_only:false; response_revision:5284; number_of_response:1; }","duration":"125.451063ms","start":"2026-08-26T00:29:43.357501Z","end":"2026-08-26T00:29:43.482952Z","steps":["trace[1954537454] 'process raft request' (duration: 125.156785ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:43.525622Z","caller":"traceutil/trace.go:171","msg":"trace[2069076299] transaction","detail":"{read_only:false; response_revision:5286; number_of_response:1; }","duration":"164.325385ms","start":"2026-08-26T00:29:43.361269Z","end":"2026-08-26T00:29:43.525594Z","steps":["trace[2069076299] 'process raft request' (duration: 164.218763ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:43.525624Z","caller":"traceutil/trace.go:171","msg":"trace[129977474] transaction","detail":"{read_only:false; response_revision:5285; number_of_response:1; }","duration":"164.651983ms","start":"2026-08-26T00:29:43.360936Z","end":"2026-08-26T00:29:43.525588Z","steps":["trace[129977474] 'process raft request' (duration: 164.349635ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:43.525838Z","caller":"traceutil/trace.go:171","msg":"trace[1651100829] transaction","detail":"{read_only:false; response_revision:5287; number_of_response:1; }","duration":"163.839713ms","start":"2026-08-26T00:29:43.361985Z","end":"2026-08-26T00:29:43.525825Z","steps":["trace[1651100829] 'process raft request' (duration: 163.561155ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:43.820526Z","caller":"traceutil/trace.go:171","msg":"trace[740679259] transaction","detail":"{read_only:false; response_revision:5295; number_of_response:1; }","duration":"100.990392ms","start":"2026-08-26T00:29:43.719502Z","end":"2026-08-26T00:29:43.820492Z","steps":["trace[740679259] 'process raft request' (duration: 70.620811ms)","trace[740679259] 'compare' (duration: 29.801085ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:29:52.274836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.311637ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:29:52.274999Z","caller":"traceutil/trace.go:171","msg":"trace[1138174537] linearizableReadLoop","detail":"{readStateIndex:5669; appliedIndex:5668; }","duration":"199.108016ms","start":"2026-08-26T00:29:52.075869Z","end":"2026-08-26T00:29:52.274977Z","steps":["trace[1138174537] 'read index received' (duration: 35.368757ms)","trace[1138174537] 'applied index is now lower than readState.Index' (duration: 163.737879ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:29:52.275186Z","caller":"traceutil/trace.go:171","msg":"trace[1064481100] transaction","detail":"{read_only:false; response_revision:5467; number_of_response:1; }","duration":"234.818752ms","start":"2026-08-26T00:29:52.040319Z","end":"2026-08-26T00:29:52.275138Z","steps":["trace[1064481100] 'process raft request' (duration: 71.056443ms)","trace[1064481100] 'compare' (duration: 163.066951ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:29:52.275238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.372013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-26T00:29:52.275283Z","caller":"traceutil/trace.go:171","msg":"trace[242725338] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5467; }","duration":"199.461646ms","start":"2026-08-26T00:29:52.07581Z","end":"2026-08-26T00:29:52.275272Z","steps":["trace[242725338] 'agreement among raft nodes before linearized reading' (duration: 199.27718ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:52.275344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.845323ms","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-26T00:29:52.27544Z","caller":"traceutil/trace.go:171","msg":"trace[655740878] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5467; }","duration":"193.960756ms","start":"2026-08-26T00:29:52.081458Z","end":"2026-08-26T00:29:52.275418Z","steps":["trace[655740878] 'agreement among raft nodes before linearized reading' (duration: 193.70257ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:52.517811Z","caller":"traceutil/trace.go:171","msg":"trace[1417990481] transaction","detail":"{read_only:false; response_revision:5468; number_of_response:1; }","duration":"238.120481ms","start":"2026-08-26T00:29:52.279654Z","end":"2026-08-26T00:29:52.517775Z","steps":["trace[1417990481] 'process raft request' (duration: 237.896545ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:52.653313Z","caller":"traceutil/trace.go:171","msg":"trace[1293903272] linearizableReadLoop","detail":"{readStateIndex:5671; appliedIndex:5669; }","duration":"370.684817ms","start":"2026-08-26T00:29:52.282588Z","end":"2026-08-26T00:29:52.653273Z","steps":["trace[1293903272] 'read index received' (duration: 234.947784ms)","trace[1293903272] 'applied index is now lower than readState.Index' (duration: 135.735373ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:29:52.653534Z","caller":"traceutil/trace.go:171","msg":"trace[1320836183] transaction","detail":"{read_only:false; response_revision:5469; number_of_response:1; }","duration":"372.523657ms","start":"2026-08-26T00:29:52.280981Z","end":"2026-08-26T00:29:52.653505Z","steps":["trace[1320836183] 'process raft request' (duration: 298.664558ms)","trace[1320836183] 'compare' (duration: 73.459298ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:29:52.653693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:29:52.28096Z","time spent":"372.651781ms","remote":"127.0.0.1:36490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:29:52.653932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.168965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-pushgateway-7b8659c68b-kppj6\" ","response":"range_response_count:1 size:4040"} {"level":"warn","ts":"2026-08-26T00:29:52.653928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.286944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-26T00:29:52.654035Z","caller":"traceutil/trace.go:171","msg":"trace[1272642317] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-pushgateway-7b8659c68b-kppj6; range_end:; response_count:1; response_revision:5469; }","duration":"366.319029ms","start":"2026-08-26T00:29:52.287696Z","end":"2026-08-26T00:29:52.654015Z","steps":["trace[1272642317] 'agreement among raft nodes before linearized reading' (duration: 365.97454ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:52.654093Z","caller":"traceutil/trace.go:171","msg":"trace[1023818542] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5469; }","duration":"371.50398ms","start":"2026-08-26T00:29:52.282559Z","end":"2026-08-26T00:29:52.654063Z","steps":["trace[1023818542] 'agreement among raft nodes before linearized reading' (duration: 371.023206ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:29:52.654057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.436289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-26T00:29:52.654245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:29:52.282545Z","time spent":"371.681884ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-26T00:29:52.654251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:29:52.287678Z","time spent":"366.508764ms","remote":"127.0.0.1:36420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":4064,"request content":"key:\"/registry/pods/monitoring/prometheus-pushgateway-7b8659c68b-kppj6\" "} {"level":"info","ts":"2026-08-26T00:29:52.65428Z","caller":"traceutil/trace.go:171","msg":"trace[116296253] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5469; }","duration":"211.711606ms","start":"2026-08-26T00:29:52.442517Z","end":"2026-08-26T00:29:52.654229Z","steps":["trace[116296253] 'agreement among raft nodes before linearized reading' (duration: 211.278224ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:30:42.065182Z","caller":"traceutil/trace.go:171","msg":"trace[318906488] linearizableReadLoop","detail":"{readStateIndex:6160; appliedIndex:6159; }","duration":"150.333746ms","start":"2026-08-26T00:30:41.914758Z","end":"2026-08-26T00:30:42.065092Z","steps":["trace[318906488] 'read index received' (duration: 150.08287ms)","trace[318906488] 'applied index is now lower than readState.Index' (duration: 249.826µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:30:42.065243Z","caller":"traceutil/trace.go:171","msg":"trace[293729999] transaction","detail":"{read_only:false; response_revision:5946; number_of_response:1; }","duration":"212.853339ms","start":"2026-08-26T00:30:41.852335Z","end":"2026-08-26T00:30:42.065188Z","steps":["trace[293729999] 'process raft request' (duration: 212.567221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:30:42.065523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.745168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-26T00:30:42.065662Z","caller":"traceutil/trace.go:171","msg":"trace[1567728410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5946; }","duration":"150.936052ms","start":"2026-08-26T00:30:41.914696Z","end":"2026-08-26T00:30:42.065632Z","steps":["trace[1567728410] 'agreement among raft nodes before linearized reading' (duration: 150.623553ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:30:42.065554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.12254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zj2qm\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-26T00:30:42.065828Z","caller":"traceutil/trace.go:171","msg":"trace[1822945299] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zj2qm; range_end:; response_count:1; response_revision:5946; }","duration":"130.433608ms","start":"2026-08-26T00:30:41.935371Z","end":"2026-08-26T00:30:42.065805Z","steps":["trace[1822945299] 'agreement among raft nodes before linearized reading' (duration: 129.990966ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:30:52.501117Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2962} {"level":"info","ts":"2026-08-26T00:30:52.577661Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2962,"took":"75.2008ms","hash":1629065492,"current-db-size-bytes":58667008,"current-db-size":"59 MB","current-db-size-in-use-bytes":33624064,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-26T00:30:52.577971Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1629065492,"revision":2962,"compact-revision":-1} {"level":"info","ts":"2026-08-26T00:35:50.068946Z","caller":"traceutil/trace.go:171","msg":"trace[486672137] linearizableReadLoop","detail":"{readStateIndex:8531; appliedIndex:8530; }","duration":"127.363528ms","start":"2026-08-26T00:35:49.941558Z","end":"2026-08-26T00:35:50.068921Z","steps":["trace[486672137] 'read index received' (duration: 127.227683ms)","trace[486672137] 'applied index is now lower than readState.Index' (duration: 134.365µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:35:50.069003Z","caller":"traceutil/trace.go:171","msg":"trace[158247886] transaction","detail":"{read_only:false; response_revision:8244; number_of_response:1; }","duration":"338.880719ms","start":"2026-08-26T00:35:49.730074Z","end":"2026-08-26T00:35:50.068955Z","steps":["trace[158247886] 'process raft request' (duration: 338.690014ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:35:50.069196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.599004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-26T00:35:50.069231Z","caller":"traceutil/trace.go:171","msg":"trace[1903107175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8244; }","duration":"123.671356ms","start":"2026-08-26T00:35:49.945552Z","end":"2026-08-26T00:35:50.069223Z","steps":["trace[1903107175] 'agreement among raft nodes before linearized reading' (duration: 123.523073ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:35:50.069254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:35:49.730046Z","time spent":"339.082135ms","remote":"127.0.0.1:36490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:35:50.06931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.710117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-26T00:35:50.069436Z","caller":"traceutil/trace.go:171","msg":"trace[1891163294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8244; }","duration":"127.938123ms","start":"2026-08-26T00:35:49.941473Z","end":"2026-08-26T00:35:50.069411Z","steps":["trace[1891163294] 'agreement among raft nodes before linearized reading' (duration: 127.566423ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:35:52.509051Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6032} {"level":"info","ts":"2026-08-26T00:35:52.61343Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6032,"took":"102.322352ms","hash":3550824732,"current-db-size-bytes":58667008,"current-db-size":"59 MB","current-db-size-in-use-bytes":23822336,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-08-26T00:35:52.613529Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3550824732,"revision":6032,"compact-revision":2962} {"level":"info","ts":"2026-08-26T00:36:02.287924Z","caller":"traceutil/trace.go:171","msg":"trace[472378205] transaction","detail":"{read_only:false; response_revision:8309; number_of_response:1; }","duration":"171.251459ms","start":"2026-08-26T00:36:02.116638Z","end":"2026-08-26T00:36:02.287889Z","steps":["trace[472378205] 'process raft request' (duration: 170.625362ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:36:02.394994Z","caller":"traceutil/trace.go:171","msg":"trace[1644182859] transaction","detail":"{read_only:false; response_revision:8310; number_of_response:1; }","duration":"103.092802ms","start":"2026-08-26T00:36:02.291866Z","end":"2026-08-26T00:36:02.394958Z","steps":["trace[1644182859] 'process raft request' (duration: 91.293229ms)","trace[1644182859] 'compare' (duration: 11.65041ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:36:02.404961Z","caller":"traceutil/trace.go:171","msg":"trace[1079774263] transaction","detail":"{read_only:false; response_revision:8311; number_of_response:1; }","duration":"112.321406ms","start":"2026-08-26T00:36:02.29261Z","end":"2026-08-26T00:36:02.404932Z","steps":["trace[1079774263] 'process raft request' (duration: 112.1037ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:36:02.404956Z","caller":"traceutil/trace.go:171","msg":"trace[1945666296] linearizableReadLoop","detail":"{readStateIndex:8603; appliedIndex:8601; }","duration":"111.994747ms","start":"2026-08-26T00:36:02.292936Z","end":"2026-08-26T00:36:02.40493Z","steps":["trace[1945666296] 'read index received' (duration: 90.179789ms)","trace[1945666296] 'applied index is now lower than readState.Index' (duration: 21.813748ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:36:02.405584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.633094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223809"} {"level":"info","ts":"2026-08-26T00:36:02.405827Z","caller":"traceutil/trace.go:171","msg":"trace[475384702] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8312; }","duration":"112.801749ms","start":"2026-08-26T00:36:02.292846Z","end":"2026-08-26T00:36:02.405647Z","steps":["trace[475384702] 'agreement among raft nodes before linearized reading' (duration: 112.159621ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:36:04.53555Z","caller":"traceutil/trace.go:171","msg":"trace[1163472029] linearizableReadLoop","detail":"{readStateIndex:8612; appliedIndex:8611; }","duration":"109.802596ms","start":"2026-08-26T00:36:04.425716Z","end":"2026-08-26T00:36:04.535518Z","steps":["trace[1163472029] 'read index received' (duration: 109.59508ms)","trace[1163472029] 'applied index is now lower than readState.Index' (duration: 206.246µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:36:04.535713Z","caller":"traceutil/trace.go:171","msg":"trace[1057712732] transaction","detail":"{read_only:false; response_revision:8320; number_of_response:1; }","duration":"125.210179ms","start":"2026-08-26T00:36:04.410481Z","end":"2026-08-26T00:36:04.535691Z","steps":["trace[1057712732] 'process raft request' (duration: 124.852458ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:36:04.536003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.518024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zj2qm\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-26T00:36:04.536117Z","caller":"traceutil/trace.go:171","msg":"trace[2035826911] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zj2qm; range_end:; response_count:1; response_revision:8320; }","duration":"122.671459ms","start":"2026-08-26T00:36:04.413419Z","end":"2026-08-26T00:36:04.536091Z","steps":["trace[2035826911] 'agreement among raft nodes before linearized reading' (duration: 122.413622ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:36:04.555793Z","caller":"traceutil/trace.go:171","msg":"trace[2071222150] transaction","detail":"{read_only:false; response_revision:8321; number_of_response:1; }","duration":"124.608302ms","start":"2026-08-26T00:36:04.431149Z","end":"2026-08-26T00:36:04.555757Z","steps":["trace[2071222150] 'process raft request' (duration: 123.980345ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:36:04.874558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.859798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:36:04.874643Z","caller":"traceutil/trace.go:171","msg":"trace[631315040] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:8323; }","duration":"194.004793ms","start":"2026-08-26T00:36:04.680615Z","end":"2026-08-26T00:36:04.87462Z","steps":["trace[631315040] 'count revisions from in-memory index tree' (duration: 193.375185ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:36:32.606247Z","caller":"traceutil/trace.go:171","msg":"trace[1890248861] linearizableReadLoop","detail":"{readStateIndex:8825; appliedIndex:8824; }","duration":"131.438743ms","start":"2026-08-26T00:36:32.47478Z","end":"2026-08-26T00:36:32.606219Z","steps":["trace[1890248861] 'read index received' (duration: 131.197136ms)","trace[1890248861] 'applied index is now lower than readState.Index' (duration: 240.707µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:36:32.606383Z","caller":"traceutil/trace.go:171","msg":"trace[1141392647] transaction","detail":"{read_only:false; response_revision:8525; number_of_response:1; }","duration":"142.664639ms","start":"2026-08-26T00:36:32.463706Z","end":"2026-08-26T00:36:32.60637Z","steps":["trace[1141392647] 'process raft request' (duration: 142.341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:36:32.606532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.72672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:36:32.60662Z","caller":"traceutil/trace.go:171","msg":"trace[1264154593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8525; }","duration":"131.852944ms","start":"2026-08-26T00:36:32.474743Z","end":"2026-08-26T00:36:32.606596Z","steps":["trace[1264154593] 'agreement among raft nodes before linearized reading' (duration: 131.69174ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:36:32.845339Z","caller":"traceutil/trace.go:171","msg":"trace[1061397684] transaction","detail":"{read_only:false; response_revision:8527; number_of_response:1; }","duration":"212.371258ms","start":"2026-08-26T00:36:32.632917Z","end":"2026-08-26T00:36:32.845289Z","steps":["trace[1061397684] 'process raft request' (duration: 207.525296ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:36:39.186531Z","caller":"traceutil/trace.go:171","msg":"trace[16590556] linearizableReadLoop","detail":"{readStateIndex:8862; appliedIndex:8861; }","duration":"207.797754ms","start":"2026-08-26T00:36:38.978703Z","end":"2026-08-26T00:36:39.1865Z","steps":["trace[16590556] 'read index received' (duration: 196.107755ms)","trace[16590556] 'applied index is now lower than readState.Index' (duration: 11.688559ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:36:39.186795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.063571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:36:39.186859Z","caller":"traceutil/trace.go:171","msg":"trace[1345255951] transaction","detail":"{read_only:false; response_revision:8561; number_of_response:1; }","duration":"259.627387ms","start":"2026-08-26T00:36:38.927188Z","end":"2026-08-26T00:36:39.186815Z","steps":["trace[1345255951] 'process raft request' (duration: 247.683551ms)","trace[1345255951] 'compare' (duration: 11.176705ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:36:39.186913Z","caller":"traceutil/trace.go:171","msg":"trace[533623578] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:8561; }","duration":"208.244595ms","start":"2026-08-26T00:36:38.978644Z","end":"2026-08-26T00:36:39.186889Z","steps":["trace[533623578] 'agreement among raft nodes before linearized reading' (duration: 208.067341ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:36:46.888874Z","caller":"traceutil/trace.go:171","msg":"trace[765250627] linearizableReadLoop","detail":"{readStateIndex:8948; appliedIndex:8947; }","duration":"155.171869ms","start":"2026-08-26T00:36:46.733668Z","end":"2026-08-26T00:36:46.88884Z","steps":["trace[765250627] 'read index received' (duration: 154.901791ms)","trace[765250627] 'applied index is now lower than readState.Index' (duration: 268.518µs)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:36:46.88916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.473337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-26T00:36:46.889225Z","caller":"traceutil/trace.go:171","msg":"trace[84611702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8642; }","duration":"155.59088ms","start":"2026-08-26T00:36:46.733624Z","end":"2026-08-26T00:36:46.889215Z","steps":["trace[84611702] 'agreement among raft nodes before linearized reading' (duration: 155.347394ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:36:46.88929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.900945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-26T00:36:46.889359Z","caller":"traceutil/trace.go:171","msg":"trace[45705619] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8642; }","duration":"132.07197ms","start":"2026-08-26T00:36:46.75726Z","end":"2026-08-26T00:36:46.889332Z","steps":["trace[45705619] 'agreement among raft nodes before linearized reading' (duration: 131.817153ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:36:46.889492Z","caller":"traceutil/trace.go:171","msg":"trace[1181178623] transaction","detail":"{read_only:false; response_revision:8642; number_of_response:1; }","duration":"198.581733ms","start":"2026-08-26T00:36:46.69034Z","end":"2026-08-26T00:36:46.888922Z","steps":["trace[1181178623] 'process raft request' (duration: 198.270884ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:37:05.178403Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000239a.wal"} {"level":"info","ts":"2026-08-26T00:37:52.934478Z","caller":"traceutil/trace.go:171","msg":"trace[1504235369] transaction","detail":"{read_only:false; response_revision:9329; number_of_response:1; }","duration":"107.719355ms","start":"2026-08-26T00:37:52.826728Z","end":"2026-08-26T00:37:52.934447Z","steps":["trace[1504235369] 'process raft request' (duration: 107.52727ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:38:05.562463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.026877ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:38:05.562584Z","caller":"traceutil/trace.go:171","msg":"trace[1873773816] linearizableReadLoop","detail":"{readStateIndex:9725; appliedIndex:9724; }","duration":"189.204157ms","start":"2026-08-26T00:38:05.373363Z","end":"2026-08-26T00:38:05.562567Z","steps":["trace[1873773816] 'read index received' (duration: 41.885821ms)","trace[1873773816] 'applied index is now lower than readState.Index' (duration: 147.317206ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:38:05.56264Z","caller":"traceutil/trace.go:171","msg":"trace[148471609] transaction","detail":"{read_only:false; response_revision:9399; number_of_response:1; }","duration":"243.776255ms","start":"2026-08-26T00:38:05.318813Z","end":"2026-08-26T00:38:05.562589Z","steps":["trace[148471609] 'process raft request' (duration: 96.50013ms)","trace[148471609] 'compare' (duration: 146.71653ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:38:05.562749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.410863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-26T00:38:05.562776Z","caller":"traceutil/trace.go:171","msg":"trace[903623243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9399; }","duration":"189.496914ms","start":"2026-08-26T00:38:05.37327Z","end":"2026-08-26T00:38:05.562767Z","steps":["trace[903623243] 'agreement among raft nodes before linearized reading' (duration: 189.358211ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:38:05.573494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.46452ms","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-26T00:38:05.573588Z","caller":"traceutil/trace.go:171","msg":"trace[1509360130] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9400; }","duration":"180.608342ms","start":"2026-08-26T00:38:05.392955Z","end":"2026-08-26T00:38:05.573563Z","steps":["trace[1509360130] 'agreement among raft nodes before linearized reading' (duration: 180.382896ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:38:40.81535Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e9d471e5f5fb9faf","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-26T00:38:40.827331Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-26T00:38:40.827451Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-26T00:40:22.835448Z","caller":"traceutil/trace.go:171","msg":"trace[2110736265] linearizableReadLoop","detail":"{readStateIndex:10800; appliedIndex:10799; }","duration":"195.318262ms","start":"2026-08-26T00:40:22.640089Z","end":"2026-08-26T00:40:22.835407Z","steps":["trace[2110736265] 'read index received' (duration: 10.534487ms)","trace[2110736265] 'applied index is now lower than readState.Index' (duration: 184.782035ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:40:22.835529Z","caller":"traceutil/trace.go:171","msg":"trace[710019002] transaction","detail":"{read_only:false; response_revision:10439; number_of_response:1; }","duration":"245.501512ms","start":"2026-08-26T00:40:22.589975Z","end":"2026-08-26T00:40:22.835476Z","steps":["trace[710019002] 'process raft request' (duration: 245.177343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:40:22.836005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.707914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zj2qm\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-26T00:40:22.836057Z","caller":"traceutil/trace.go:171","msg":"trace[1001767075] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zj2qm; range_end:; response_count:1; response_revision:10439; }","duration":"196.025073ms","start":"2026-08-26T00:40:22.640016Z","end":"2026-08-26T00:40:22.836042Z","steps":["trace[1001767075] 'agreement among raft nodes before linearized reading' (duration: 195.597551ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:40:22.836071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.604697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-26T00:40:22.836166Z","caller":"traceutil/trace.go:171","msg":"trace[758241666] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:10439; }","duration":"171.768132ms","start":"2026-08-26T00:40:22.664376Z","end":"2026-08-26T00:40:22.836144Z","steps":["trace[758241666] 'agreement among raft nodes before linearized reading' (duration: 171.312989ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:40:52.517016Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8261} {"level":"info","ts":"2026-08-26T00:40:52.605206Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8261,"took":"84.919429ms","hash":1531087737,"current-db-size-bytes":58667008,"current-db-size":"59 MB","current-db-size-in-use-bytes":26427392,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-26T00:40:52.605294Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1531087737,"revision":8261,"compact-revision":6032} {"level":"info","ts":"2026-08-26T00:41:12.838668Z","caller":"traceutil/trace.go:171","msg":"trace[1268444837] linearizableReadLoop","detail":"{readStateIndex:11232; appliedIndex:11232; }","duration":"183.16599ms","start":"2026-08-26T00:41:12.655483Z","end":"2026-08-26T00:41:12.838649Z","steps":["trace[1268444837] 'read index received' (duration: 183.15766ms)","trace[1268444837] 'applied index is now lower than readState.Index' (duration: 6.96µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:41:12.838673Z","caller":"traceutil/trace.go:171","msg":"trace[579935396] transaction","detail":"{read_only:false; response_revision:10860; number_of_response:1; }","duration":"185.549695ms","start":"2026-08-26T00:41:12.653106Z","end":"2026-08-26T00:41:12.838656Z","steps":["trace[579935396] 'process raft request' (duration: 185.37613ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:41:12.839908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.360972ms","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-26T00:41:12.840215Z","caller":"traceutil/trace.go:171","msg":"trace[1339477070] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:10860; }","duration":"184.794114ms","start":"2026-08-26T00:41:12.655389Z","end":"2026-08-26T00:41:12.840183Z","steps":["trace[1339477070] 'agreement among raft nodes before linearized reading' (duration: 183.369695ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:41:14.419993Z","caller":"traceutil/trace.go:171","msg":"trace[64894509] linearizableReadLoop","detail":"{readStateIndex:11239; appliedIndex:11238; }","duration":"364.354127ms","start":"2026-08-26T00:41:14.055619Z","end":"2026-08-26T00:41:14.419973Z","steps":["trace[64894509] 'read index received' (duration: 364.199462ms)","trace[64894509] 'applied index is now lower than readState.Index' (duration: 153.515µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:41:14.420074Z","caller":"traceutil/trace.go:171","msg":"trace[1188651848] transaction","detail":"{read_only:false; response_revision:10867; number_of_response:1; }","duration":"485.07323ms","start":"2026-08-26T00:41:13.934961Z","end":"2026-08-26T00:41:14.420034Z","steps":["trace[1188651848] 'process raft request' (duration: 484.876885ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:41:14.420142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.49265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:41:14.420233Z","caller":"traceutil/trace.go:171","msg":"trace[557308871] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:10867; }","duration":"364.603723ms","start":"2026-08-26T00:41:14.055611Z","end":"2026-08-26T00:41:14.420215Z","steps":["trace[557308871] 'agreement among raft nodes before linearized reading' (duration: 364.447889ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:41:14.420261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:41:13.934937Z","time spent":"485.223584ms","remote":"127.0.0.1:36490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:41:14.420286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:41:14.055563Z","time spent":"364.713267ms","remote":"127.0.0.1:38848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true "} {"level":"info","ts":"2026-08-26T00:41:47.197094Z","caller":"traceutil/trace.go:171","msg":"trace[1117603562] transaction","detail":"{read_only:false; response_revision:11235; number_of_response:1; }","duration":"164.004619ms","start":"2026-08-26T00:41:47.03304Z","end":"2026-08-26T00:41:47.197045Z","steps":["trace[1117603562] 'process raft request' (duration: 163.881156ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:41:47.197201Z","caller":"traceutil/trace.go:171","msg":"trace[671547308] transaction","detail":"{read_only:false; response_revision:11234; number_of_response:1; }","duration":"164.232686ms","start":"2026-08-26T00:41:47.032916Z","end":"2026-08-26T00:41:47.197148Z","steps":["trace[671547308] 'process raft request' (duration: 141.966801ms)","trace[671547308] 'compare' (duration: 21.875905ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:41:47.414573Z","caller":"traceutil/trace.go:171","msg":"trace[1177914510] linearizableReadLoop","detail":"{readStateIndex:11617; appliedIndex:11616; }","duration":"159.546209ms","start":"2026-08-26T00:41:47.254961Z","end":"2026-08-26T00:41:47.414507Z","steps":["trace[1177914510] 'read index received' (duration: 118.40918ms)","trace[1177914510] 'applied index is now lower than readState.Index' (duration: 41.135169ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:41:47.414754Z","caller":"traceutil/trace.go:171","msg":"trace[1193151056] transaction","detail":"{read_only:false; response_revision:11236; number_of_response:1; }","duration":"212.213571ms","start":"2026-08-26T00:41:47.202509Z","end":"2026-08-26T00:41:47.414722Z","steps":["trace[1193151056] 'process raft request' (duration: 170.957868ms)","trace[1193151056] 'compare' (duration: 40.792909ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:41:47.41493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.775735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:41:47.415007Z","caller":"traceutil/trace.go:171","msg":"trace[423761242] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11236; }","duration":"159.920118ms","start":"2026-08-26T00:41:47.255073Z","end":"2026-08-26T00:41:47.414994Z","steps":["trace[423761242] 'agreement among raft nodes before linearized reading' (duration: 159.752714ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:41:47.415125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.395117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"warn","ts":"2026-08-26T00:41:47.415148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.734688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-26T00:41:47.415242Z","caller":"traceutil/trace.go:171","msg":"trace[841878093] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11236; }","duration":"151.593311ms","start":"2026-08-26T00:41:47.263624Z","end":"2026-08-26T00:41:47.415217Z","steps":["trace[841878093] 'agreement among raft nodes before linearized reading' (duration: 151.209762ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:41:47.415258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.427464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:41:47.415263Z","caller":"traceutil/trace.go:171","msg":"trace[2138097873] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11236; }","duration":"125.916374ms","start":"2026-08-26T00:41:47.289317Z","end":"2026-08-26T00:41:47.415233Z","steps":["trace[2138097873] 'agreement among raft nodes before linearized reading' (duration: 125.593225ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:41:47.415299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.279181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-26T00:41:47.415344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.33079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-08-26T00:41:47.415415Z","caller":"traceutil/trace.go:171","msg":"trace[1412756756] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11236; }","duration":"159.416705ms","start":"2026-08-26T00:41:47.255951Z","end":"2026-08-26T00:41:47.415367Z","steps":["trace[1412756756] 'agreement among raft nodes before linearized reading' (duration: 159.275891ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:41:47.415481Z","caller":"traceutil/trace.go:171","msg":"trace[2132517410] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11236; }","duration":"160.551756ms","start":"2026-08-26T00:41:47.254893Z","end":"2026-08-26T00:41:47.415444Z","steps":["trace[2132517410] 'agreement among raft nodes before linearized reading' (duration: 159.852577ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:41:47.415148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.909148ms","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-26T00:41:47.415634Z","caller":"traceutil/trace.go:171","msg":"trace[2003705597] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11236; }","duration":"121.427521ms","start":"2026-08-26T00:41:47.294124Z","end":"2026-08-26T00:41:47.415552Z","steps":["trace[2003705597] 'agreement among raft nodes before linearized reading' (duration: 120.764674ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:41:47.415302Z","caller":"traceutil/trace.go:171","msg":"trace[1923086853] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11236; }","duration":"159.512417ms","start":"2026-08-26T00:41:47.255776Z","end":"2026-08-26T00:41:47.415288Z","steps":["trace[1923086853] 'agreement among raft nodes before linearized reading' (duration: 159.452765ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:41:47.415191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.182708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:41:47.415896Z","caller":"traceutil/trace.go:171","msg":"trace[147370382] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11236; }","duration":"159.927028ms","start":"2026-08-26T00:41:47.255954Z","end":"2026-08-26T00:41:47.415881Z","steps":["trace[147370382] 'agreement among raft nodes before linearized reading' (duration: 159.190128ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:45:52.5243Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10742} {"level":"info","ts":"2026-08-26T00:45:52.606011Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10742,"took":"79.423552ms","hash":4256403820,"current-db-size-bytes":58667008,"current-db-size":"59 MB","current-db-size-in-use-bytes":27127808,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-26T00:45:52.606092Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4256403820,"revision":10742,"compact-revision":8261} {"level":"warn","ts":"2026-08-26T00:48:11.657872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.538877ms","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-26T00:48:11.658009Z","caller":"traceutil/trace.go:171","msg":"trace[605040655] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13823; }","duration":"113.756792ms","start":"2026-08-26T00:48:11.544226Z","end":"2026-08-26T00:48:11.657983Z","steps":["trace[605040655] 'range keys from in-memory index tree' (duration: 113.278809ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:48:21.794224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.818657ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-26T00:48:21.794412Z","caller":"traceutil/trace.go:171","msg":"trace[511293024] linearizableReadLoop","detail":"{readStateIndex:14352; appliedIndex:14351; }","duration":"254.175357ms","start":"2026-08-26T00:48:21.540195Z","end":"2026-08-26T00:48:21.79437Z","steps":["trace[511293024] 'read index received' (duration: 17.210886ms)","trace[511293024] 'applied index is now lower than readState.Index' (duration: 236.962341ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:48:21.794635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.428374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-26T00:48:21.794785Z","caller":"traceutil/trace.go:171","msg":"trace[978021143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13877; }","duration":"254.639019ms","start":"2026-08-26T00:48:21.540119Z","end":"2026-08-26T00:48:21.794758Z","steps":["trace[978021143] 'agreement among raft nodes before linearized reading' (duration: 254.346122ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:48:21.794783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.501096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-26T00:48:21.79485Z","caller":"traceutil/trace.go:171","msg":"trace[647700435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13877; }","duration":"254.636469ms","start":"2026-08-26T00:48:21.540192Z","end":"2026-08-26T00:48:21.794829Z","steps":["trace[647700435] 'agreement among raft nodes before linearized reading' (duration: 254.322961ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:48:21.795115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.677578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:16 size:151635"} {"level":"info","ts":"2026-08-26T00:48:21.79522Z","caller":"traceutil/trace.go:171","msg":"trace[898349843] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:16; response_revision:13877; }","duration":"172.847983ms","start":"2026-08-26T00:48:21.622347Z","end":"2026-08-26T00:48:21.795195Z","steps":["trace[898349843] 'agreement among raft nodes before linearized reading' (duration: 172.35388ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:50:04.764586Z","caller":"traceutil/trace.go:171","msg":"trace[1150230736] transaction","detail":"{read_only:false; response_revision:14971; number_of_response:1; }","duration":"150.857113ms","start":"2026-08-26T00:50:04.613686Z","end":"2026-08-26T00:50:04.764543Z","steps":["trace[1150230736] 'process raft request' (duration: 134.331433ms)","trace[1150230736] 'compare' (duration: 16.14668ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:50:05.049188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.656719ms","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-26T00:50:05.049361Z","caller":"traceutil/trace.go:171","msg":"trace[175437609] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:14971; }","duration":"135.911414ms","start":"2026-08-26T00:50:04.913429Z","end":"2026-08-26T00:50:05.04934Z","steps":["trace[175437609] 'range keys from in-memory index tree' (duration: 135.556205ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:50:52.533567Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12812} {"level":"info","ts":"2026-08-26T00:50:52.615182Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12812,"took":"77.852762ms","hash":3667490554,"current-db-size-bytes":58667008,"current-db-size":"59 MB","current-db-size-in-use-bytes":29855744,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-26T00:50:52.61526Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3667490554,"revision":12812,"compact-revision":10742} {"level":"info","ts":"2026-08-26T00:55:42.347227Z","caller":"traceutil/trace.go:171","msg":"trace[1305620254] transaction","detail":"{read_only:false; response_revision:17498; number_of_response:1; }","duration":"134.449698ms","start":"2026-08-26T00:55:42.212739Z","end":"2026-08-26T00:55:42.347188Z","steps":["trace[1305620254] 'process raft request' (duration: 132.892425ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:55:47.837805Z","caller":"traceutil/trace.go:171","msg":"trace[1660888551] transaction","detail":"{read_only:false; response_revision:17531; number_of_response:1; }","duration":"130.359166ms","start":"2026-08-26T00:55:47.707397Z","end":"2026-08-26T00:55:47.837756Z","steps":["trace[1660888551] 'process raft request' (duration: 129.876413ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:55:48.718318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.7686ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-26T00:55:48.71849Z","caller":"traceutil/trace.go:171","msg":"trace[170450635] linearizableReadLoop","detail":"{readStateIndex:18129; appliedIndex:18128; }","duration":"339.562216ms","start":"2026-08-26T00:55:48.378903Z","end":"2026-08-26T00:55:48.718465Z","steps":["trace[170450635] 'read index received' (duration: 65.891782ms)","trace[170450635] 'applied index is now lower than readState.Index' (duration: 273.668074ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:55:48.718534Z","caller":"traceutil/trace.go:171","msg":"trace[250652269] transaction","detail":"{read_only:false; response_revision:17535; number_of_response:1; }","duration":"349.340983ms","start":"2026-08-26T00:55:48.369131Z","end":"2026-08-26T00:55:48.718472Z","steps":["trace[250652269] 'process raft request' (duration: 75.69817ms)","trace[250652269] 'compare' (duration: 272.4151ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:55:48.718723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.388253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-26T00:55:48.718753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:55:48.369097Z","time spent":"349.543458ms","remote":"127.0.0.1:36490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:55:48.718774Z","caller":"traceutil/trace.go:171","msg":"trace[1259639955] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:17535; }","duration":"167.497057ms","start":"2026-08-26T00:55:48.551262Z","end":"2026-08-26T00:55:48.718759Z","steps":["trace[1259639955] 'agreement among raft nodes before linearized reading' (duration: 167.357192ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:55:48.718822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.888455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-08-26T00:55:48.718887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.858744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-08-26T00:55:48.718891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.072268ms","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-26T00:55:48.718942Z","caller":"traceutil/trace.go:171","msg":"trace[61588945] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17535; }","duration":"232.971347ms","start":"2026-08-26T00:55:48.485955Z","end":"2026-08-26T00:55:48.718926Z","steps":["trace[61588945] 'agreement among raft nodes before linearized reading' (duration: 232.71431ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:55:48.718936Z","caller":"traceutil/trace.go:171","msg":"trace[1832196093] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17535; }","duration":"340.052959ms","start":"2026-08-26T00:55:48.378853Z","end":"2026-08-26T00:55:48.718906Z","steps":["trace[1832196093] 'agreement among raft nodes before linearized reading' (duration: 339.705719ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:55:48.718888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.642087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-26T00:55:48.718891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.248995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-26T00:55:48.718996Z","caller":"traceutil/trace.go:171","msg":"trace[390510216] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17535; }","duration":"223.211722ms","start":"2026-08-26T00:55:48.495754Z","end":"2026-08-26T00:55:48.718966Z","steps":["trace[390510216] 'agreement among raft nodes before linearized reading' (duration: 222.911743ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:55:48.719005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:55:48.378831Z","time spent":"340.164173ms","remote":"127.0.0.1:36490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-26T00:55:48.719091Z","caller":"traceutil/trace.go:171","msg":"trace[410941066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17535; }","duration":"243.874113ms","start":"2026-08-26T00:55:48.475129Z","end":"2026-08-26T00:55:48.719003Z","steps":["trace[410941066] 'agreement among raft nodes before linearized reading' (duration: 243.555155ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:55:48.719099Z","caller":"traceutil/trace.go:171","msg":"trace[1651338175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17535; }","duration":"222.486512ms","start":"2026-08-26T00:55:48.496576Z","end":"2026-08-26T00:55:48.719063Z","steps":["trace[1651338175] 'agreement among raft nodes before linearized reading' (duration: 222.04777ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:55:48.718937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.981557ms","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-26T00:55:48.719368Z","caller":"traceutil/trace.go:171","msg":"trace[1945164816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17535; }","duration":"222.43607ms","start":"2026-08-26T00:55:48.496901Z","end":"2026-08-26T00:55:48.719337Z","steps":["trace[1945164816] 'agreement among raft nodes before linearized reading' (duration: 221.780133ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:55:49.006334Z","caller":"traceutil/trace.go:171","msg":"trace[1542336363] transaction","detail":"{read_only:false; response_revision:17537; number_of_response:1; }","duration":"280.51278ms","start":"2026-08-26T00:55:48.725796Z","end":"2026-08-26T00:55:49.006309Z","steps":["trace[1542336363] 'process raft request' (duration: 280.251992ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:55:49.006707Z","caller":"traceutil/trace.go:171","msg":"trace[1524245766] transaction","detail":"{read_only:false; response_revision:17538; number_of_response:1; }","duration":"280.726806ms","start":"2026-08-26T00:55:48.725944Z","end":"2026-08-26T00:55:49.00667Z","steps":["trace[1524245766] 'process raft request' (duration: 280.314544ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:55:49.006967Z","caller":"traceutil/trace.go:171","msg":"trace[548859674] transaction","detail":"{read_only:false; response_revision:17539; number_of_response:1; }","duration":"280.780247ms","start":"2026-08-26T00:55:48.726176Z","end":"2026-08-26T00:55:49.006956Z","steps":["trace[548859674] 'process raft request' (duration: 280.391576ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:55:49.007056Z","caller":"traceutil/trace.go:171","msg":"trace[340577507] transaction","detail":"{read_only:false; response_revision:17540; number_of_response:1; }","duration":"280.808208ms","start":"2026-08-26T00:55:48.726238Z","end":"2026-08-26T00:55:49.007046Z","steps":["trace[340577507] 'process raft request' (duration: 280.48752ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:55:52.541036Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15273} {"level":"info","ts":"2026-08-26T00:55:52.641547Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15273,"took":"96.297931ms","hash":1408653045,"current-db-size-bytes":58667008,"current-db-size":"59 MB","current-db-size-in-use-bytes":31367168,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-26T00:55:52.641782Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1408653045,"revision":15273,"compact-revision":12812} {"level":"info","ts":"2026-08-26T00:59:31.147773Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e9d471e5f5fb9faf","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-26T00:59:31.153805Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-26T00:59:31.153911Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-26T01:00:52.547248Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17563} {"level":"info","ts":"2026-08-26T01:00:52.651021Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17563,"took":"99.321344ms","hash":1249807021,"current-db-size-bytes":58667008,"current-db-size":"59 MB","current-db-size-in-use-bytes":34541568,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-26T01:00:52.651089Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1249807021,"revision":17563,"compact-revision":15273} {"level":"info","ts":"2026-08-26T01:01:37.23411Z","caller":"traceutil/trace.go:171","msg":"trace[1791322864] transaction","detail":"{read_only:false; response_revision:20291; number_of_response:1; }","duration":"160.057498ms","start":"2026-08-26T01:01:37.074024Z","end":"2026-08-26T01:01:37.234081Z","steps":["trace[1791322864] 'process raft request' (duration: 159.876444ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T01:01:37.27145Z","caller":"traceutil/trace.go:171","msg":"trace[545483696] linearizableReadLoop","detail":"{readStateIndex:20979; appliedIndex:20976; }","duration":"120.775828ms","start":"2026-08-26T01:01:37.150643Z","end":"2026-08-26T01:01:37.271418Z","steps":["trace[545483696] 'read index received' (duration: 83.37086ms)","trace[545483696] 'applied index is now lower than readState.Index' (duration: 37.403518ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T01:01:37.271498Z","caller":"traceutil/trace.go:171","msg":"trace[1377250429] transaction","detail":"{read_only:false; response_revision:20293; number_of_response:1; }","duration":"197.076316ms","start":"2026-08-26T01:01:37.074388Z","end":"2026-08-26T01:01:37.271465Z","steps":["trace[1377250429] 'process raft request' (duration: 196.978563ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T01:01:37.271498Z","caller":"traceutil/trace.go:171","msg":"trace[1562688867] transaction","detail":"{read_only:false; response_revision:20292; number_of_response:1; }","duration":"197.048684ms","start":"2026-08-26T01:01:37.0744Z","end":"2026-08-26T01:01:37.271449Z","steps":["trace[1562688867] 'process raft request' (duration: 196.791368ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T01:01:37.271737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.066776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-26T01:01:37.27179Z","caller":"traceutil/trace.go:171","msg":"trace[333917886] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20293; }","duration":"121.177939ms","start":"2026-08-26T01:01:37.150596Z","end":"2026-08-26T01:01:37.271774Z","steps":["trace[333917886] 'agreement among raft nodes before linearized reading' (duration: 120.978685ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T01:01:37.642684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.412815ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-26T01:01:37.643185Z","caller":"traceutil/trace.go:171","msg":"trace[1863783208] linearizableReadLoop","detail":"{readStateIndex:20980; appliedIndex:20979; }","duration":"168.866418ms","start":"2026-08-26T01:01:37.474278Z","end":"2026-08-26T01:01:37.643145Z","steps":["trace[1863783208] 'read index received' (duration: 63.331µs)","trace[1863783208] 'applied index is now lower than readState.Index' (duration: 168.798837ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T01:01:37.643343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.048552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-26T01:01:37.643395Z","caller":"traceutil/trace.go:171","msg":"trace[292975208] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20295; }","duration":"169.157786ms","start":"2026-08-26T01:01:37.474217Z","end":"2026-08-26T01:01:37.643375Z","steps":["trace[292975208] 'agreement among raft nodes before linearized reading' (duration: 169.047613ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T01:01:37.643358Z","caller":"traceutil/trace.go:171","msg":"trace[677900882] transaction","detail":"{read_only:false; response_revision:20294; number_of_response:1; }","duration":"366.90971ms","start":"2026-08-26T01:01:37.276391Z","end":"2026-08-26T01:01:37.6433Z","steps":["trace[677900882] 'process raft request' (duration: 168.758194ms)","trace[677900882] 'compare' (duration: 197.067126ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T01:01:37.643575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T01:01:37.276373Z","time spent":"367.101435ms","remote":"127.0.0.1:36490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T01:01:37.86193Z","caller":"traceutil/trace.go:171","msg":"trace[1707545162] transaction","detail":"{read_only:false; response_revision:20296; number_of_response:1; }","duration":"251.837687ms","start":"2026-08-26T01:01:37.610053Z","end":"2026-08-26T01:01:37.861891Z","steps":["trace[1707545162] 'process raft request' (duration: 239.45086ms)","trace[1707545162] 'compare' (duration: 12.216362ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T01:01:38.099362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.633943ms","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-26T01:01:38.099498Z","caller":"traceutil/trace.go:171","msg":"trace[962445462] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20296; }","duration":"117.822288ms","start":"2026-08-26T01:01:37.98164Z","end":"2026-08-26T01:01:38.099462Z","steps":["trace[962445462] 'range keys from in-memory index tree' (duration: 117.250241ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T01:01:38.226806Z","caller":"traceutil/trace.go:171","msg":"trace[1651635579] transaction","detail":"{read_only:false; response_revision:20297; number_of_response:1; }","duration":"122.466664ms","start":"2026-08-26T01:01:38.104299Z","end":"2026-08-26T01:01:38.226766Z","steps":["trace[1651635579] 'process raft request' (duration: 122.268898ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T01:01:38.451306Z","caller":"traceutil/trace.go:171","msg":"trace[1858975999] transaction","detail":"{read_only:false; response_revision:20298; number_of_response:1; }","duration":"211.918389ms","start":"2026-08-26T01:01:38.23933Z","end":"2026-08-26T01:01:38.451249Z","steps":["trace[1858975999] 'process raft request' (duration: 178.780788ms)","trace[1858975999] 'compare' (duration: 32.139704ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T01:01:38.970758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.857247ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-26T01:01:38.970911Z","caller":"traceutil/trace.go:171","msg":"trace[1133447424] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:20299; }","duration":"220.025812ms","start":"2026-08-26T01:01:38.750862Z","end":"2026-08-26T01:01:38.970888Z","steps":["trace[1133447424] 'range keys from in-memory index tree' (duration: 219.835316ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T01:01:38.971154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.437133ms","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-26T01:01:38.971238Z","caller":"traceutil/trace.go:171","msg":"trace[1124654044] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20299; }","duration":"173.580396ms","start":"2026-08-26T01:01:38.797638Z","end":"2026-08-26T01:01:38.971219Z","steps":["trace[1124654044] 'range keys from in-memory index tree' (duration: 173.178855ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T01:01:39.366001Z","caller":"traceutil/trace.go:171","msg":"trace[1387501575] transaction","detail":"{read_only:false; response_revision:20301; number_of_response:1; }","duration":"121.986261ms","start":"2026-08-26T01:01:39.243961Z","end":"2026-08-26T01:01:39.365947Z","steps":["trace[1387501575] 'process raft request' (duration: 121.631152ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T01:01:39.659184Z","caller":"traceutil/trace.go:171","msg":"trace[671953640] transaction","detail":"{read_only:false; response_revision:20302; number_of_response:1; }","duration":"287.391324ms","start":"2026-08-26T01:01:39.371752Z","end":"2026-08-26T01:01:39.659143Z","steps":["trace[671953640] 'process raft request' (duration: 188.373939ms)","trace[671953640] 'compare' (duration: 98.416349ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T01:01:39.659247Z","caller":"traceutil/trace.go:171","msg":"trace[1419479735] linearizableReadLoop","detail":"{readStateIndex:20989; appliedIndex:20987; }","duration":"184.455862ms","start":"2026-08-26T01:01:39.474773Z","end":"2026-08-26T01:01:39.659229Z","steps":["trace[1419479735] 'read index received' (duration: 85.375974ms)","trace[1419479735] 'applied index is now lower than readState.Index' (duration: 99.077628ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T01:01:39.659343Z","caller":"traceutil/trace.go:171","msg":"trace[291049815] transaction","detail":"{read_only:false; response_revision:20303; number_of_response:1; }","duration":"287.048024ms","start":"2026-08-26T01:01:39.37228Z","end":"2026-08-26T01:01:39.659328Z","steps":["trace[291049815] 'process raft request' (duration: 286.594732ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T01:01:39.659447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.657798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-26T01:01:39.659533Z","caller":"traceutil/trace.go:171","msg":"trace[1583358022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20304; }","duration":"184.793321ms","start":"2026-08-26T01:01:39.474719Z","end":"2026-08-26T01:01:39.659512Z","steps":["trace[1583358022] 'agreement among raft nodes before linearized reading' (duration: 184.659006ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T01:05:35.693304Z","caller":"traceutil/trace.go:171","msg":"trace[1479789717] linearizableReadLoop","detail":"{readStateIndex:23542; appliedIndex:23541; }","duration":"233.040315ms","start":"2026-08-26T01:05:35.46023Z","end":"2026-08-26T01:05:35.693271Z","steps":["trace[1479789717] 'read index received' (duration: 232.942602ms)","trace[1479789717] 'applied index is now lower than readState.Index' (duration: 95.923µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T01:05:35.693665Z","caller":"traceutil/trace.go:171","msg":"trace[1520888882] transaction","detail":"{read_only:false; response_revision:22783; number_of_response:1; }","duration":"325.268455ms","start":"2026-08-26T01:05:35.36834Z","end":"2026-08-26T01:05:35.693609Z","steps":["trace[1520888882] 'process raft request' (duration: 324.759631ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T01:05:35.693937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T01:05:35.368303Z","time spent":"325.498353ms","remote":"127.0.0.1:36490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T01:05:35.693696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.424375ms","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":"info","ts":"2026-08-26T01:05:35.694198Z","caller":"traceutil/trace.go:171","msg":"trace[899702906] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:22783; }","duration":"233.94563ms","start":"2026-08-26T01:05:35.46017Z","end":"2026-08-26T01:05:35.694116Z","steps":["trace[899702906] 'agreement among raft nodes before linearized reading' (duration: 233.380105ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T01:05:35.762232Z","caller":"traceutil/trace.go:171","msg":"trace[2791707] transaction","detail":"{read_only:false; response_revision:22784; number_of_response:1; }","duration":"273.026323ms","start":"2026-08-26T01:05:35.489142Z","end":"2026-08-26T01:05:35.762168Z","steps":["trace[2791707] 'process raft request' (duration: 272.755505ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T01:05:35.762331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.33873ms","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-26T01:05:35.762441Z","caller":"traceutil/trace.go:171","msg":"trace[123723291] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22784; }","duration":"166.505494ms","start":"2026-08-26T01:05:35.59591Z","end":"2026-08-26T01:05:35.762415Z","steps":["trace[123723291] 'agreement among raft nodes before linearized reading' (duration: 166.191045ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T01:05:52.55418Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20064} {"level":"info","ts":"2026-08-26T01:05:52.675814Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20064,"took":"117.096628ms","hash":2149140527,"current-db-size-bytes":58667008,"current-db-size":"59 MB","current-db-size-in-use-bytes":42819584,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-26T01:05:52.675986Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2149140527,"revision":20064,"compact-revision":17563} {"level":"info","ts":"2026-08-26T01:10:52.561332Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22964} {"level":"info","ts":"2026-08-26T01:10:52.702078Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22964,"took":"134.88506ms","hash":477447627,"current-db-size-bytes":58667008,"current-db-size":"59 MB","current-db-size-in-use-bytes":33103872,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-26T01:10:52.702159Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":477447627,"revision":22964,"compact-revision":20064} {"level":"info","ts":"2026-08-26T01:15:52.567086Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25457} {"level":"info","ts":"2026-08-26T01:15:52.705766Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25457,"took":"133.983743ms","hash":2019977995,"current-db-size-bytes":58667008,"current-db-size":"59 MB","current-db-size-in-use-bytes":29573120,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-26T01:15:52.705866Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2019977995,"revision":25457,"compact-revision":22964}