{"level":"warn","ts":"2026-08-06T01:02:57.318972Z","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-06T01:02:57.319095Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.81:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.81:2380","--initial-cluster=instance=https://199.19.213.81:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.81:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.81: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-06T01:02:57.319182Z","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-06T01:02:57.319197Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.81:2380"]} {"level":"info","ts":"2026-08-06T01:02:57.319228Z","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-06T01:02:57.320149Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.81:2379"]} {"level":"info","ts":"2026-08-06T01:02:57.320261Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.81:2380"],"listen-peer-urls":["https://199.19.213.81:2380"],"advertise-client-urls":["https://199.19.213.81:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.81:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.81: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-06T01:02:57.32519Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.655793ms"} {"level":"info","ts":"2026-08-06T01:02:57.331678Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1627b0b72e417489","cluster-id":"d42e5eae8a1b814c"} {"level":"info","ts":"2026-08-06T01:02:57.331802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 switched to configuration voters=()"} {"level":"info","ts":"2026-08-06T01:02:57.331832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 became follower at term 0"} {"level":"info","ts":"2026-08-06T01:02:57.331851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1627b0b72e417489 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-06T01:02:57.331869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 became follower at term 1"} {"level":"info","ts":"2026-08-06T01:02:57.331916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 switched to configuration voters=(1596438893727675529)"} {"level":"warn","ts":"2026-08-06T01:02:57.341506Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-06T01:02:57.344798Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-06T01:02:57.348891Z","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-06T01:02:57.351216Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1627b0b72e417489","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-06T01:02:57.351419Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1627b0b72e417489","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-06T01:02:57.351533Z","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-06T01:02:57.351717Z","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-06T01:02:57.351751Z","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-06T01:02:57.353833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 switched to configuration voters=(1596438893727675529)"} {"level":"info","ts":"2026-08-06T01:02:57.354118Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d42e5eae8a1b814c","local-member-id":"1627b0b72e417489","added-peer-id":"1627b0b72e417489","added-peer-peer-urls":["https://199.19.213.81:2380"]} {"level":"info","ts":"2026-08-06T01:02:57.357082Z","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-06T01:02:57.357174Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.81:2380"} {"level":"info","ts":"2026-08-06T01:02:57.357221Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.81:2380"} {"level":"info","ts":"2026-08-06T01:02:57.357577Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1627b0b72e417489","initial-advertise-peer-urls":["https://199.19.213.81:2380"],"listen-peer-urls":["https://199.19.213.81:2380"],"advertise-client-urls":["https://199.19.213.81:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.81:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-06T01:02:57.357679Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-06T01:02:58.132772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 is starting a new election at term 1"} {"level":"info","ts":"2026-08-06T01:02:58.132857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-06T01:02:58.132896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 received MsgPreVoteResp from 1627b0b72e417489 at term 1"} {"level":"info","ts":"2026-08-06T01:02:58.132917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 became candidate at term 2"} {"level":"info","ts":"2026-08-06T01:02:58.13293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 received MsgVoteResp from 1627b0b72e417489 at term 2"} {"level":"info","ts":"2026-08-06T01:02:58.132951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 became leader at term 2"} {"level":"info","ts":"2026-08-06T01:02:58.132965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1627b0b72e417489 elected leader 1627b0b72e417489 at term 2"} {"level":"info","ts":"2026-08-06T01:02:58.134324Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1627b0b72e417489","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.81:2379]}","request-path":"/0/members/1627b0b72e417489/attributes","cluster-id":"d42e5eae8a1b814c","publish-timeout":"7s"} {"level":"info","ts":"2026-08-06T01:02:58.134371Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-06T01:02:58.134405Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-06T01:02:58.134464Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T01:02:58.134677Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-06T01:02:58.134759Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-06T01:02:58.13553Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d42e5eae8a1b814c","local-member-id":"1627b0b72e417489","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T01:02:58.135654Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T01:02:58.135684Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T01:02:58.137692Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-06T01:02:58.138171Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.81:2379"} {"level":"info","ts":"2026-08-06T01:02:59.585587Z","caller":"traceutil/trace.go:171","msg":"trace[355286603] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"116.490738ms","start":"2026-08-06T01:02:59.469053Z","end":"2026-08-06T01:02:59.585544Z","steps":["trace[355286603] 'process raft request' (duration: 116.20165ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:59.585641Z","caller":"traceutil/trace.go:171","msg":"trace[1112603924] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"116.500708ms","start":"2026-08-06T01:02:59.469081Z","end":"2026-08-06T01:02:59.585582Z","steps":["trace[1112603924] 'process raft request' (duration: 116.329763ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:59.585823Z","caller":"traceutil/trace.go:171","msg":"trace[236225349] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"116.623181ms","start":"2026-08-06T01:02:59.46917Z","end":"2026-08-06T01:02:59.585793Z","steps":["trace[236225349] 'process raft request' (duration: 116.314342ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:59.585954Z","caller":"traceutil/trace.go:171","msg":"trace[1460364800] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"116.788346ms","start":"2026-08-06T01:02:59.469156Z","end":"2026-08-06T01:02:59.585944Z","steps":["trace[1460364800] 'process raft request' (duration: 116.293482ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:59.586031Z","caller":"traceutil/trace.go:171","msg":"trace[1862574580] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"104.375112ms","start":"2026-08-06T01:02:59.481648Z","end":"2026-08-06T01:02:59.586023Z","steps":["trace[1862574580] 'process raft request' (duration: 104.008921ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:59.586032Z","caller":"traceutil/trace.go:171","msg":"trace[1222398176] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"116.294372ms","start":"2026-08-06T01:02:59.469714Z","end":"2026-08-06T01:02:59.586009Z","steps":["trace[1222398176] 'process raft request' (duration: 115.820459ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:59.586097Z","caller":"traceutil/trace.go:171","msg":"trace[1660585232] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"116.118127ms","start":"2026-08-06T01:02:59.46997Z","end":"2026-08-06T01:02:59.586088Z","steps":["trace[1660585232] 'process raft request' (duration: 115.606703ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:02:59.586052Z","caller":"traceutil/trace.go:171","msg":"trace[1740178381] transaction","detail":"{read_only:false; number_of_response:0; response_revision:11; }","duration":"114.686027ms","start":"2026-08-06T01:02:59.471324Z","end":"2026-08-06T01:02:59.58601Z","steps":["trace[1740178381] 'process raft request' (duration: 114.284696ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:03:11.039897Z","caller":"traceutil/trace.go:171","msg":"trace[1733408539] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"100.662666ms","start":"2026-08-06T01:03:10.939204Z","end":"2026-08-06T01:03:11.039867Z","steps":["trace[1733408539] 'process raft request' (duration: 100.456841ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:03:36.498979Z","caller":"traceutil/trace.go:171","msg":"trace[1573444635] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"191.731507ms","start":"2026-08-06T01:03:36.307214Z","end":"2026-08-06T01:03:36.498945Z","steps":["trace[1573444635] 'process raft request' (duration: 187.513516ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:03:36.499173Z","caller":"traceutil/trace.go:171","msg":"trace[1627969366] transaction","detail":"{read_only:false; number_of_response:0; response_revision:552; }","duration":"191.409439ms","start":"2026-08-06T01:03:36.307754Z","end":"2026-08-06T01:03:36.499164Z","steps":["trace[1627969366] 'process raft request' (duration: 191.280335ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:03:36.499176Z","caller":"traceutil/trace.go:171","msg":"trace[1277456004] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"190.088451ms","start":"2026-08-06T01:03:36.309057Z","end":"2026-08-06T01:03:36.499146Z","steps":["trace[1277456004] 'process raft request' (duration: 190.014009ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:03:36.499519Z","caller":"traceutil/trace.go:171","msg":"trace[1457738169] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"191.671156ms","start":"2026-08-06T01:03:36.307605Z","end":"2026-08-06T01:03:36.499276Z","steps":["trace[1457738169] 'process raft request' (duration: 191.276465ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:03:36.504861Z","caller":"traceutil/trace.go:171","msg":"trace[661959584] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"186.958462ms","start":"2026-08-06T01:03:36.317871Z","end":"2026-08-06T01:03:36.50483Z","steps":["trace[661959584] 'process raft request' (duration: 186.550141ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:03:36.504989Z","caller":"traceutil/trace.go:171","msg":"trace[1848242178] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"175.002679ms","start":"2026-08-06T01:03:36.32996Z","end":"2026-08-06T01:03:36.504962Z","steps":["trace[1848242178] 'process raft request' (duration: 174.716221ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:03:36.505201Z","caller":"traceutil/trace.go:171","msg":"trace[1505075211] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"166.955879ms","start":"2026-08-06T01:03:36.338203Z","end":"2026-08-06T01:03:36.505159Z","steps":["trace[1505075211] 'process raft request' (duration: 166.738533ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:03:36.505445Z","caller":"traceutil/trace.go:171","msg":"trace[105253292] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"162.76498ms","start":"2026-08-06T01:03:36.342664Z","end":"2026-08-06T01:03:36.505429Z","steps":["trace[105253292] 'process raft request' (duration: 162.443301ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:03:42.809881Z","caller":"traceutil/trace.go:171","msg":"trace[1838780620] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"118.473446ms","start":"2026-08-06T01:03:42.69137Z","end":"2026-08-06T01:03:42.809843Z","steps":["trace[1838780620] 'process raft request' (duration: 73.720007ms)","trace[1838780620] 'compare' (duration: 44.511492ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:03:43.328996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.444294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2mn8m\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-08-06T01:03:43.329101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.512905ms","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-06T01:03:43.329165Z","caller":"traceutil/trace.go:171","msg":"trace[1046769840] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2mn8m; range_end:; response_count:1; response_revision:639; }","duration":"188.699851ms","start":"2026-08-06T01:03:43.140441Z","end":"2026-08-06T01:03:43.329141Z","steps":["trace[1046769840] 'range keys from in-memory index tree' (duration: 188.175127ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:03:43.329224Z","caller":"traceutil/trace.go:171","msg":"trace[1194473599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:639; }","duration":"133.728771ms","start":"2026-08-06T01:03:43.195467Z","end":"2026-08-06T01:03:43.329196Z","steps":["trace[1194473599] 'range keys from in-memory index tree' (duration: 133.256138ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:04:25.53384Z","caller":"traceutil/trace.go:171","msg":"trace[698735519] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"123.207054ms","start":"2026-08-06T01:04:25.4106Z","end":"2026-08-06T01:04:25.533807Z","steps":["trace[698735519] 'process raft request' (duration: 123.007159ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:05:13.898036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.529391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-332886b0-bc70-4da9-ada0-fa148b4212ba\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-08-06T01:05:13.898198Z","caller":"traceutil/trace.go:171","msg":"trace[423760654] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-332886b0-bc70-4da9-ada0-fa148b4212ba; range_end:; response_count:1; response_revision:1863; }","duration":"244.707736ms","start":"2026-08-06T01:05:13.653461Z","end":"2026-08-06T01:05:13.898169Z","steps":["trace[423760654] 'range keys from in-memory index tree' (duration: 244.200742ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:05:58.722684Z","caller":"traceutil/trace.go:171","msg":"trace[1934784483] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"134.956473ms","start":"2026-08-06T01:05:58.587689Z","end":"2026-08-06T01:05:58.722645Z","steps":["trace[1934784483] 'process raft request' (duration: 103.300251ms)","trace[1934784483] 'compare' (duration: 31.351783ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:07:26.859629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.305015ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-08-06T01:07:27.311237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.989784ms","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-06T01:07:27.311292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.9854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-06T01:07:27.31133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.380012ms","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-06T01:07:27.311356Z","caller":"traceutil/trace.go:171","msg":"trace[660272308] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2836; }","duration":"120.15754ms","start":"2026-08-06T01:07:27.19117Z","end":"2026-08-06T01:07:27.311328Z","steps":["trace[660272308] 'range keys from in-memory index tree' (duration: 119.770668ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:27.311394Z","caller":"traceutil/trace.go:171","msg":"trace[115600201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2836; }","duration":"177.139365ms","start":"2026-08-06T01:07:27.134232Z","end":"2026-08-06T01:07:27.311371Z","steps":["trace[115600201] 'range keys from in-memory index tree' (duration: 176.783694ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:27.311422Z","caller":"traceutil/trace.go:171","msg":"trace[57657772] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2836; }","duration":"129.522156ms","start":"2026-08-06T01:07:27.181876Z","end":"2026-08-06T01:07:27.311398Z","steps":["trace[57657772] 'range keys from in-memory index tree' (duration: 129.083013ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:10:03.644946Z","caller":"traceutil/trace.go:171","msg":"trace[1482252379] transaction","detail":"{read_only:false; response_revision:3861; number_of_response:1; }","duration":"233.784655ms","start":"2026-08-06T01:10:03.411136Z","end":"2026-08-06T01:10:03.64492Z","steps":["trace[1482252379] 'process raft request' (duration: 233.337173ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:11:26.957854Z","caller":"traceutil/trace.go:171","msg":"trace[118275073] linearizableReadLoop","detail":"{readStateIndex:5106; appliedIndex:5105; }","duration":"106.949756ms","start":"2026-08-06T01:11:26.850874Z","end":"2026-08-06T01:11:26.957824Z","steps":["trace[118275073] 'read index received' (duration: 106.718909ms)","trace[118275073] 'applied index is now lower than readState.Index' (duration: 229.287µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:11:26.958032Z","caller":"traceutil/trace.go:171","msg":"trace[3996406] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"114.803009ms","start":"2026-08-06T01:11:26.843181Z","end":"2026-08-06T01:11:26.957984Z","steps":["trace[3996406] 'process raft request' (duration: 114.430989ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:11:26.958148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.226333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:7581"} {"level":"info","ts":"2026-08-06T01:11:26.958373Z","caller":"traceutil/trace.go:171","msg":"trace[1441475006] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4918; }","duration":"107.521083ms","start":"2026-08-06T01:11:26.850823Z","end":"2026-08-06T01:11:26.958344Z","steps":["trace[1441475006] 'agreement among raft nodes before linearized reading' (duration: 107.132392ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:11:31.114584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.633618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:11:31.114734Z","caller":"traceutil/trace.go:171","msg":"trace[1523275065] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:5049; }","duration":"215.822845ms","start":"2026-08-06T01:11:30.898883Z","end":"2026-08-06T01:11:31.114706Z","steps":["trace[1523275065] 'range keys from in-memory index tree' (duration: 215.539765ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:11:31.115218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.223843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T01:11:31.11538Z","caller":"traceutil/trace.go:171","msg":"trace[2089607384] transaction","detail":"{read_only:false; response_revision:5050; number_of_response:1; }","duration":"144.511188ms","start":"2026-08-06T01:11:30.970836Z","end":"2026-08-06T01:11:31.115347Z","steps":["trace[2089607384] 'process raft request' (duration: 14.540393ms)","trace[2089607384] 'compare' (duration: 129.083829ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:11:31.115396Z","caller":"traceutil/trace.go:171","msg":"trace[1019916401] linearizableReadLoop","detail":"{readStateIndex:5240; appliedIndex:5239; }","duration":"113.694592ms","start":"2026-08-06T01:11:31.001668Z","end":"2026-08-06T01:11:31.115363Z","steps":["trace[1019916401] 'read index received' (duration: 37.481µs)","trace[1019916401] 'applied index is now lower than readState.Index' (duration: 113.654871ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:11:31.115585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.894137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-945392c7-9f7d-4461-bf9a-0a18a001ca29\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-08-06T01:11:31.115675Z","caller":"traceutil/trace.go:171","msg":"trace[108111853] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-945392c7-9f7d-4461-bf9a-0a18a001ca29; range_end:; response_count:1; response_revision:5050; }","duration":"114.029961ms","start":"2026-08-06T01:11:31.001625Z","end":"2026-08-06T01:11:31.115655Z","steps":["trace[108111853] 'agreement among raft nodes before linearized reading' (duration: 113.796705ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:11:31.505065Z","caller":"traceutil/trace.go:171","msg":"trace[826671872] transaction","detail":"{read_only:false; response_revision:5058; number_of_response:1; }","duration":"163.761916ms","start":"2026-08-06T01:11:31.341276Z","end":"2026-08-06T01:11:31.505038Z","steps":["trace[826671872] 'process raft request' (duration: 163.688624ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:11:31.505104Z","caller":"traceutil/trace.go:171","msg":"trace[879026191] transaction","detail":"{read_only:false; response_revision:5057; number_of_response:1; }","duration":"205.930354ms","start":"2026-08-06T01:11:31.299124Z","end":"2026-08-06T01:11:31.505054Z","steps":["trace[879026191] 'process raft request' (duration: 205.648886ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:11:44.432071Z","caller":"traceutil/trace.go:171","msg":"trace[557040796] transaction","detail":"{read_only:false; response_revision:5326; number_of_response:1; }","duration":"218.416828ms","start":"2026-08-06T01:11:44.213629Z","end":"2026-08-06T01:11:44.432046Z","steps":["trace[557040796] 'process raft request' (duration: 218.196512ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:11:44.696777Z","caller":"traceutil/trace.go:171","msg":"trace[1665379287] linearizableReadLoop","detail":"{readStateIndex:5526; appliedIndex:5525; }","duration":"123.433498ms","start":"2026-08-06T01:11:44.573319Z","end":"2026-08-06T01:11:44.696753Z","steps":["trace[1665379287] 'read index received' (duration: 123.227142ms)","trace[1665379287] 'applied index is now lower than readState.Index' (duration: 205.486µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:11:44.696816Z","caller":"traceutil/trace.go:171","msg":"trace[783614336] transaction","detail":"{read_only:false; response_revision:5327; number_of_response:1; }","duration":"260.154184ms","start":"2026-08-06T01:11:44.436638Z","end":"2026-08-06T01:11:44.696792Z","steps":["trace[783614336] 'process raft request' (duration: 259.970209ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:11:44.697243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.901441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-06T01:11:44.697288Z","caller":"traceutil/trace.go:171","msg":"trace[834589375] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:5327; }","duration":"124.004264ms","start":"2026-08-06T01:11:44.573274Z","end":"2026-08-06T01:11:44.697278Z","steps":["trace[834589375] 'agreement among raft nodes before linearized reading' (duration: 123.593432ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:11:51.97189Z","caller":"traceutil/trace.go:171","msg":"trace[1207041464] transaction","detail":"{read_only:false; response_revision:5506; number_of_response:1; }","duration":"147.642217ms","start":"2026-08-06T01:11:51.824225Z","end":"2026-08-06T01:11:51.971868Z","steps":["trace[1207041464] 'process raft request' (duration: 147.451091ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:11:52.091954Z","caller":"traceutil/trace.go:171","msg":"trace[678291779] transaction","detail":"{read_only:false; response_revision:5508; number_of_response:1; }","duration":"114.33963ms","start":"2026-08-06T01:11:51.977589Z","end":"2026-08-06T01:11:52.091928Z","steps":["trace[678291779] 'process raft request' (duration: 114.111144ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:11:52.092172Z","caller":"traceutil/trace.go:171","msg":"trace[1459528031] transaction","detail":"{read_only:false; response_revision:5509; number_of_response:1; }","duration":"109.399839ms","start":"2026-08-06T01:11:51.982733Z","end":"2026-08-06T01:11:52.092133Z","steps":["trace[1459528031] 'process raft request' (duration: 109.129932ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:11:52.319054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.803009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:11:52.319181Z","caller":"traceutil/trace.go:171","msg":"trace[1500885210] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:5510; }","duration":"124.019635ms","start":"2026-08-06T01:11:52.195132Z","end":"2026-08-06T01:11:52.319152Z","steps":["trace[1500885210] 'count revisions from in-memory index tree' (duration: 123.695886ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:12:58.169763Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3008} {"level":"info","ts":"2026-08-06T01:12:58.258918Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3008,"took":"87.532257ms","hash":1575227064,"current-db-size-bytes":58597376,"current-db-size":"59 MB","current-db-size-in-use-bytes":31723520,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-06T01:12:58.258997Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1575227064,"revision":3008,"compact-revision":-1} {"level":"info","ts":"2026-08-06T01:17:46.986464Z","caller":"traceutil/trace.go:171","msg":"trace[1737415492] transaction","detail":"{read_only:false; response_revision:8245; number_of_response:1; }","duration":"177.100396ms","start":"2026-08-06T01:17:46.80933Z","end":"2026-08-06T01:17:46.98643Z","steps":["trace[1737415492] 'process raft request' (duration: 176.835388ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:17:47.22215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.979778ms","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-06T01:17:47.222351Z","caller":"traceutil/trace.go:171","msg":"trace[1500204947] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8245; }","duration":"139.282797ms","start":"2026-08-06T01:17:47.083039Z","end":"2026-08-06T01:17:47.222322Z","steps":["trace[1500204947] 'range keys from in-memory index tree' (duration: 138.663859ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:17:58.176583Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6089} {"level":"info","ts":"2026-08-06T01:17:58.347533Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6089,"took":"168.979682ms","hash":2356695738,"current-db-size-bytes":58597376,"current-db-size":"59 MB","current-db-size-in-use-bytes":22863872,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-06T01:17:58.347613Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2356695738,"revision":6089,"compact-revision":3008} {"level":"info","ts":"2026-08-06T01:17:58.832304Z","caller":"traceutil/trace.go:171","msg":"trace[1105163935] linearizableReadLoop","detail":"{readStateIndex:8600; appliedIndex:8598; }","duration":"153.259504ms","start":"2026-08-06T01:17:58.679018Z","end":"2026-08-06T01:17:58.832277Z","steps":["trace[1105163935] 'read index received' (duration: 129.753933ms)","trace[1105163935] 'applied index is now lower than readState.Index' (duration: 23.504311ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:17:58.832399Z","caller":"traceutil/trace.go:171","msg":"trace[2081388822] transaction","detail":"{read_only:false; response_revision:8307; number_of_response:1; }","duration":"170.744672ms","start":"2026-08-06T01:17:58.661602Z","end":"2026-08-06T01:17:58.832346Z","steps":["trace[2081388822] 'process raft request' (duration: 147.205861ms)","trace[2081388822] 'compare' (duration: 23.248883ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:17:58.832426Z","caller":"traceutil/trace.go:171","msg":"trace[1147343172] transaction","detail":"{read_only:false; response_revision:8308; number_of_response:1; }","duration":"170.036183ms","start":"2026-08-06T01:17:58.662314Z","end":"2026-08-06T01:17:58.83235Z","steps":["trace[1147343172] 'process raft request' (duration: 169.908159ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:17:58.832497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.424169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T01:17:58.832549Z","caller":"traceutil/trace.go:171","msg":"trace[1291986354] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8308; }","duration":"153.556103ms","start":"2026-08-06T01:17:58.678978Z","end":"2026-08-06T01:17:58.832534Z","steps":["trace[1291986354] 'agreement among raft nodes before linearized reading' (duration: 153.427459ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:17:59.275777Z","caller":"traceutil/trace.go:171","msg":"trace[1303334673] linearizableReadLoop","detail":"{readStateIndex:8602; appliedIndex:8601; }","duration":"172.34777ms","start":"2026-08-06T01:17:59.103396Z","end":"2026-08-06T01:17:59.275744Z","steps":["trace[1303334673] 'read index received' (duration: 138.160024ms)","trace[1303334673] 'applied index is now lower than readState.Index' (duration: 34.186656ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:17:59.276012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.602046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:17:59.276113Z","caller":"traceutil/trace.go:171","msg":"trace[17712578] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8310; }","duration":"172.75117ms","start":"2026-08-06T01:17:59.103337Z","end":"2026-08-06T01:17:59.276088Z","steps":["trace[17712578] 'agreement among raft nodes before linearized reading' (duration: 172.585226ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:17:59.276151Z","caller":"traceutil/trace.go:171","msg":"trace[1709344566] transaction","detail":"{read_only:false; response_revision:8310; number_of_response:1; }","duration":"238.337262ms","start":"2026-08-06T01:17:59.03775Z","end":"2026-08-06T01:17:59.276088Z","steps":["trace[1709344566] 'process raft request' (duration: 203.849758ms)","trace[1709344566] 'compare' (duration: 34.035012ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:17:59.276477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.806322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ksz4g\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-08-06T01:17:59.276679Z","caller":"traceutil/trace.go:171","msg":"trace[1666803086] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ksz4g; range_end:; response_count:1; response_revision:8310; }","duration":"139.056209ms","start":"2026-08-06T01:17:59.137586Z","end":"2026-08-06T01:17:59.276643Z","steps":["trace[1666803086] 'agreement among raft nodes before linearized reading' (duration: 138.585346ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:18:27.389151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.14883ms","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-06T01:18:27.389244Z","caller":"traceutil/trace.go:171","msg":"trace[1016863616] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8510; }","duration":"407.291474ms","start":"2026-08-06T01:18:26.981931Z","end":"2026-08-06T01:18:27.389222Z","steps":["trace[1016863616] 'range keys from in-memory index tree' (duration: 407.012736ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:18:27.389287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:18:26.98192Z","time spent":"407.357606ms","remote":"127.0.0.1:32780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-06T01:18:27.389363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.56026ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T01:18:27.389549Z","caller":"traceutil/trace.go:171","msg":"trace[427188140] linearizableReadLoop","detail":"{readStateIndex:8812; appliedIndex:8811; }","duration":"286.995191ms","start":"2026-08-06T01:18:27.102508Z","end":"2026-08-06T01:18:27.389503Z","steps":["trace[427188140] 'read index received' (duration: 111.093481ms)","trace[427188140] 'applied index is now lower than readState.Index' (duration: 175.89998ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:18:27.389628Z","caller":"traceutil/trace.go:171","msg":"trace[1648840295] transaction","detail":"{read_only:false; response_revision:8511; number_of_response:1; }","duration":"406.79847ms","start":"2026-08-06T01:18:26.982803Z","end":"2026-08-06T01:18:27.389601Z","steps":["trace[1648840295] 'process raft request' (duration: 230.870689ms)","trace[1648840295] 'compare' (duration: 174.82595ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:18:27.389658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.147915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:18:27.389708Z","caller":"traceutil/trace.go:171","msg":"trace[189769632] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8511; }","duration":"287.229427ms","start":"2026-08-06T01:18:27.102466Z","end":"2026-08-06T01:18:27.389695Z","steps":["trace[189769632] 'agreement among raft nodes before linearized reading' (duration: 287.151045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:18:27.389767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:18:26.982773Z","time spent":"406.924054ms","remote":"127.0.0.1:32780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:18:27.401832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.379026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T01:18:27.401899Z","caller":"traceutil/trace.go:171","msg":"trace[140442053] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:8512; }","duration":"263.50044ms","start":"2026-08-06T01:18:27.138382Z","end":"2026-08-06T01:18:27.401883Z","steps":["trace[140442053] 'agreement among raft nodes before linearized reading' (duration: 263.311574ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:18:40.176106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.448742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-08-06T01:18:40.176629Z","caller":"traceutil/trace.go:171","msg":"trace[711849163] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:8622; }","duration":"146.036048ms","start":"2026-08-06T01:18:40.030568Z","end":"2026-08-06T01:18:40.176604Z","steps":["trace[711849163] 'range keys from in-memory index tree' (duration: 145.181784ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:18:40.967424Z","caller":"traceutil/trace.go:171","msg":"trace[142687503] transaction","detail":"{read_only:false; response_revision:8625; number_of_response:1; }","duration":"215.709676ms","start":"2026-08-06T01:18:40.751689Z","end":"2026-08-06T01:18:40.967399Z","steps":["trace[142687503] 'process raft request' (duration: 160.82664ms)","trace[142687503] 'compare' (duration: 54.115354ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:18:40.96771Z","caller":"traceutil/trace.go:171","msg":"trace[730845888] linearizableReadLoop","detail":"{readStateIndex:8932; appliedIndex:8929; }","duration":"118.899653ms","start":"2026-08-06T01:18:40.848778Z","end":"2026-08-06T01:18:40.967677Z","steps":["trace[730845888] 'read index received' (duration: 63.661497ms)","trace[730845888] 'applied index is now lower than readState.Index' (duration: 55.236846ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:18:40.96774Z","caller":"traceutil/trace.go:171","msg":"trace[939330435] transaction","detail":"{read_only:false; response_revision:8627; number_of_response:1; }","duration":"199.832383ms","start":"2026-08-06T01:18:40.767871Z","end":"2026-08-06T01:18:40.967704Z","steps":["trace[939330435] 'process raft request' (duration: 199.708329ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:18:40.967798Z","caller":"traceutil/trace.go:171","msg":"trace[1912954509] transaction","detail":"{read_only:false; response_revision:8626; number_of_response:1; }","duration":"210.715224ms","start":"2026-08-06T01:18:40.75706Z","end":"2026-08-06T01:18:40.967775Z","steps":["trace[1912954509] 'process raft request' (duration: 210.265291ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:18:40.968063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.254972ms","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-06T01:18:40.968113Z","caller":"traceutil/trace.go:171","msg":"trace[183597062] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8627; }","duration":"119.378337ms","start":"2026-08-06T01:18:40.848722Z","end":"2026-08-06T01:18:40.968101Z","steps":["trace[183597062] 'agreement among raft nodes before linearized reading' (duration: 119.154219ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:18:40.968161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.903972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T01:18:40.968203Z","caller":"traceutil/trace.go:171","msg":"trace[191446416] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:8627; }","duration":"107.025605ms","start":"2026-08-06T01:18:40.861167Z","end":"2026-08-06T01:18:40.968193Z","steps":["trace[191446416] 'agreement among raft nodes before linearized reading' (duration: 106.942523ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:18:41.549965Z","caller":"traceutil/trace.go:171","msg":"trace[1812003357] transaction","detail":"{read_only:false; response_revision:8630; number_of_response:1; }","duration":"100.293412ms","start":"2026-08-06T01:18:41.449621Z","end":"2026-08-06T01:18:41.549914Z","steps":["trace[1812003357] 'process raft request' (duration: 62.891555ms)","trace[1812003357] 'compare' (duration: 37.124459ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:19:29.395668Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024da.wal"} {"level":"info","ts":"2026-08-06T01:20:36.214824Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1627b0b72e417489","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-06T01:20:36.222277Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-06T01:20:36.222805Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-06T01:21:46.699526Z","caller":"traceutil/trace.go:171","msg":"trace[1372729193] transaction","detail":"{read_only:false; response_revision:10206; number_of_response:1; }","duration":"100.709862ms","start":"2026-08-06T01:21:46.59879Z","end":"2026-08-06T01:21:46.6995Z","steps":["trace[1372729193] 'process raft request' (duration: 100.536557ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:22:58.181532Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8302} {"level":"info","ts":"2026-08-06T01:22:58.261014Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8302,"took":"77.479704ms","hash":979579611,"current-db-size-bytes":58597376,"current-db-size":"59 MB","current-db-size-in-use-bytes":26492928,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-06T01:22:58.261082Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":979579611,"revision":8302,"compact-revision":6089} {"level":"info","ts":"2026-08-06T01:23:32.31064Z","caller":"traceutil/trace.go:171","msg":"trace[68620335] transaction","detail":"{read_only:false; response_revision:11211; number_of_response:1; }","duration":"152.132749ms","start":"2026-08-06T01:23:32.158481Z","end":"2026-08-06T01:23:32.310614Z","steps":["trace[68620335] 'process raft request' (duration: 151.830091ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:23:32.318244Z","caller":"traceutil/trace.go:171","msg":"trace[45589190] transaction","detail":"{read_only:false; response_revision:11212; number_of_response:1; }","duration":"144.163743ms","start":"2026-08-06T01:23:32.174049Z","end":"2026-08-06T01:23:32.318213Z","steps":["trace[45589190] 'process raft request' (duration: 143.956037ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:23:32.440679Z","caller":"traceutil/trace.go:171","msg":"trace[917815069] transaction","detail":"{read_only:false; response_revision:11213; number_of_response:1; }","duration":"118.165567ms","start":"2026-08-06T01:23:32.322433Z","end":"2026-08-06T01:23:32.440598Z","steps":["trace[917815069] 'process raft request' (duration: 112.939118ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:27:58.188333Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10839} {"level":"info","ts":"2026-08-06T01:27:58.265909Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10839,"took":"75.00921ms","hash":1672969702,"current-db-size-bytes":58597376,"current-db-size":"59 MB","current-db-size-in-use-bytes":28291072,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-06T01:27:58.265976Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1672969702,"revision":10839,"compact-revision":8302} {"level":"info","ts":"2026-08-06T01:29:41.791272Z","caller":"traceutil/trace.go:171","msg":"trace[839961750] linearizableReadLoop","detail":"{readStateIndex:14179; appliedIndex:14178; }","duration":"106.995097ms","start":"2026-08-06T01:29:41.684243Z","end":"2026-08-06T01:29:41.791239Z","steps":["trace[839961750] 'read index received' (duration: 106.75469ms)","trace[839961750] 'applied index is now lower than readState.Index' (duration: 239.237µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:29:41.791361Z","caller":"traceutil/trace.go:171","msg":"trace[573238469] transaction","detail":"{read_only:false; response_revision:13709; number_of_response:1; }","duration":"108.315746ms","start":"2026-08-06T01:29:41.683019Z","end":"2026-08-06T01:29:41.791334Z","steps":["trace[573238469] 'process raft request' (duration: 108.049788ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:29:41.791684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.414889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-08-06T01:29:41.791841Z","caller":"traceutil/trace.go:171","msg":"trace[889503690] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:13709; }","duration":"107.552853ms","start":"2026-08-06T01:29:41.684208Z","end":"2026-08-06T01:29:41.791761Z","steps":["trace[889503690] 'agreement among raft nodes before linearized reading' (duration: 107.174312ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:29:56.578959Z","caller":"traceutil/trace.go:171","msg":"trace[1394141584] transaction","detail":"{read_only:false; response_revision:13800; number_of_response:1; }","duration":"240.012563ms","start":"2026-08-06T01:29:56.338921Z","end":"2026-08-06T01:29:56.578934Z","steps":["trace[1394141584] 'process raft request' (duration: 239.873889ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:29:56.579035Z","caller":"traceutil/trace.go:171","msg":"trace[37700031] linearizableReadLoop","detail":"{readStateIndex:14273; appliedIndex:14273; }","duration":"236.125333ms","start":"2026-08-06T01:29:56.342879Z","end":"2026-08-06T01:29:56.579004Z","steps":["trace[37700031] 'read index received' (duration: 236.107512ms)","trace[37700031] 'applied index is now lower than readState.Index' (duration: 15.451µs)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:29:56.579308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.401412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-06T01:29:56.579355Z","caller":"traceutil/trace.go:171","msg":"trace[2113137736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13800; }","duration":"236.479664ms","start":"2026-08-06T01:29:56.342862Z","end":"2026-08-06T01:29:56.579342Z","steps":["trace[2113137736] 'agreement among raft nodes before linearized reading' (duration: 236.278139ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:29:56.76285Z","caller":"traceutil/trace.go:171","msg":"trace[490250215] transaction","detail":"{read_only:false; response_revision:13801; number_of_response:1; }","duration":"177.748475ms","start":"2026-08-06T01:29:56.585067Z","end":"2026-08-06T01:29:56.762815Z","steps":["trace[490250215] 'process raft request' (duration: 177.617822ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:29:56.76298Z","caller":"traceutil/trace.go:171","msg":"trace[278302472] linearizableReadLoop","detail":"{readStateIndex:14274; appliedIndex:14273; }","duration":"183.819247ms","start":"2026-08-06T01:29:56.579107Z","end":"2026-08-06T01:29:56.762927Z","steps":["trace[278302472] 'read index received' (duration: 165.58777ms)","trace[278302472] 'applied index is now lower than readState.Index' (duration: 18.224447ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:29:56.763128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.915894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-dwqcr\" ","response":"range_response_count:1 size:12360"} {"level":"info","ts":"2026-08-06T01:29:56.763187Z","caller":"traceutil/trace.go:171","msg":"trace[336340646] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-dwqcr; range_end:; response_count:1; response_revision:13801; }","duration":"306.022437ms","start":"2026-08-06T01:29:56.457148Z","end":"2026-08-06T01:29:56.76317Z","steps":["trace[336340646] 'agreement among raft nodes before linearized reading' (duration: 305.828222ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:29:56.76319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.178364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-06T01:29:56.763237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:29:56.457128Z","time spent":"306.09961ms","remote":"127.0.0.1:60940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":12383,"request content":"key:\"/registry/pods/openstack/openvswitch-dwqcr\" "} {"level":"info","ts":"2026-08-06T01:29:56.763252Z","caller":"traceutil/trace.go:171","msg":"trace[197775706] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:13801; }","duration":"416.262427ms","start":"2026-08-06T01:29:56.346975Z","end":"2026-08-06T01:29:56.763237Z","steps":["trace[197775706] 'agreement among raft nodes before linearized reading' (duration: 416.109563ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:29:56.763257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.119438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-06T01:29:56.763287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:29:56.346967Z","time spent":"416.313818ms","remote":"127.0.0.1:60922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":64,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-08-06T01:29:56.763297Z","caller":"traceutil/trace.go:171","msg":"trace[1858293870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13801; }","duration":"419.2101ms","start":"2026-08-06T01:29:56.344075Z","end":"2026-08-06T01:29:56.763285Z","steps":["trace[1858293870] 'agreement among raft nodes before linearized reading' (duration: 418.992814ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:29:56.763341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:29:56.344059Z","time spent":"419.273392ms","remote":"127.0.0.1:32780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-06T01:30:06.15137Z","caller":"traceutil/trace.go:171","msg":"trace[2025757273] transaction","detail":"{read_only:false; response_revision:13965; number_of_response:1; }","duration":"230.3907ms","start":"2026-08-06T01:30:05.920958Z","end":"2026-08-06T01:30:06.151349Z","steps":["trace[2025757273] 'process raft request' (duration: 230.237776ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:30:06.333186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.629607ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T01:30:06.333369Z","caller":"traceutil/trace.go:171","msg":"trace[272854540] linearizableReadLoop","detail":"{readStateIndex:14442; appliedIndex:14441; }","duration":"252.55655ms","start":"2026-08-06T01:30:06.08079Z","end":"2026-08-06T01:30:06.333347Z","steps":["trace[272854540] 'read index received' (duration: 70.548023ms)","trace[272854540] 'applied index is now lower than readState.Index' (duration: 182.006297ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:30:06.333498Z","caller":"traceutil/trace.go:171","msg":"trace[1393444837] transaction","detail":"{read_only:false; response_revision:13966; number_of_response:1; }","duration":"295.227901ms","start":"2026-08-06T01:30:06.038224Z","end":"2026-08-06T01:30:06.333452Z","steps":["trace[1393444837] 'process raft request' (duration: 145.188342ms)","trace[1393444837] 'compare' (duration: 149.126903ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:30:06.333716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.303449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"warn","ts":"2026-08-06T01:30:06.333735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.91357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/data-ovn-ovsdb-nb-0.18c9132b79bdd0b2\" ","response":"range_response_count:1 size:956"} {"level":"info","ts":"2026-08-06T01:30:06.333826Z","caller":"traceutil/trace.go:171","msg":"trace[2128346070] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:13966; }","duration":"149.465173ms","start":"2026-08-06T01:30:06.184331Z","end":"2026-08-06T01:30:06.333797Z","steps":["trace[2128346070] 'agreement among raft nodes before linearized reading' (duration: 149.151864ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:30:06.333844Z","caller":"traceutil/trace.go:171","msg":"trace[481406790] range","detail":"{range_begin:/registry/events/openstack/data-ovn-ovsdb-nb-0.18c9132b79bdd0b2; range_end:; response_count:1; response_revision:13966; }","duration":"253.030874ms","start":"2026-08-06T01:30:06.080784Z","end":"2026-08-06T01:30:06.333815Z","steps":["trace[481406790] 'agreement among raft nodes before linearized reading' (duration: 252.667503ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:31:29.817066Z","caller":"traceutil/trace.go:171","msg":"trace[1199694522] transaction","detail":"{read_only:false; response_revision:14968; number_of_response:1; }","duration":"103.003201ms","start":"2026-08-06T01:31:29.714029Z","end":"2026-08-06T01:31:29.817032Z","steps":["trace[1199694522] 'process raft request' (duration: 102.675682ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:31:30.152889Z","caller":"traceutil/trace.go:171","msg":"trace[193611175] linearizableReadLoop","detail":"{readStateIndex:15476; appliedIndex:15474; }","duration":"168.286022ms","start":"2026-08-06T01:31:29.98458Z","end":"2026-08-06T01:31:30.152866Z","steps":["trace[193611175] 'read index received' (duration: 161.100499ms)","trace[193611175] 'applied index is now lower than readState.Index' (duration: 7.184673ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:31:30.152888Z","caller":"traceutil/trace.go:171","msg":"trace[1321300879] transaction","detail":"{read_only:false; response_revision:14969; number_of_response:1; }","duration":"264.192863ms","start":"2026-08-06T01:31:29.888664Z","end":"2026-08-06T01:31:30.152857Z","steps":["trace[1321300879] 'process raft request' (duration: 257.02291ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:31:30.152925Z","caller":"traceutil/trace.go:171","msg":"trace[832520098] transaction","detail":"{read_only:false; response_revision:14970; number_of_response:1; }","duration":"194.073544ms","start":"2026-08-06T01:31:29.958824Z","end":"2026-08-06T01:31:30.152897Z","steps":["trace[832520098] 'process raft request' (duration: 193.949691ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:31:30.153071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.479728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-wg4jz\" ","response":"range_response_count:1 size:9798"} {"level":"info","ts":"2026-08-06T01:31:30.153098Z","caller":"traceutil/trace.go:171","msg":"trace[477964839] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-wg4jz; range_end:; response_count:1; response_revision:14970; }","duration":"168.554261ms","start":"2026-08-06T01:31:29.984536Z","end":"2026-08-06T01:31:30.15309Z","steps":["trace[477964839] 'agreement among raft nodes before linearized reading' (duration: 168.415767ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:32:58.196844Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13015} {"level":"info","ts":"2026-08-06T01:32:58.276446Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13015,"took":"77.341553ms","hash":2470318151,"current-db-size-bytes":58597376,"current-db-size":"59 MB","current-db-size-in-use-bytes":31154176,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-06T01:32:58.276531Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2470318151,"revision":13015,"compact-revision":10839} {"level":"info","ts":"2026-08-06T01:37:58.203458Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15522} {"level":"info","ts":"2026-08-06T01:37:58.305643Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15522,"took":"97.494535ms","hash":28822400,"current-db-size-bytes":58597376,"current-db-size":"59 MB","current-db-size-in-use-bytes":33558528,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-06T01:37:58.305704Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":28822400,"revision":15522,"compact-revision":13015} {"level":"info","ts":"2026-08-06T01:38:25.511802Z","caller":"traceutil/trace.go:171","msg":"trace[1154796543] transaction","detail":"{read_only:false; response_revision:18275; number_of_response:1; }","duration":"130.688177ms","start":"2026-08-06T01:38:25.38109Z","end":"2026-08-06T01:38:25.511778Z","steps":["trace[1154796543] 'process raft request' (duration: 110.371301ms)","trace[1154796543] 'compare' (duration: 20.052949ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:38:40.317834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.08499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T01:38:40.317897Z","caller":"traceutil/trace.go:171","msg":"trace[3714123] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:18402; }","duration":"105.169112ms","start":"2026-08-06T01:38:40.212714Z","end":"2026-08-06T01:38:40.317883Z","steps":["trace[3714123] 'agreement among raft nodes before linearized reading' (duration: 105.070369ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:38:40.318035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.471921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T01:38:40.318168Z","caller":"traceutil/trace.go:171","msg":"trace[568768259] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:18402; }","duration":"105.636086ms","start":"2026-08-06T01:38:40.212524Z","end":"2026-08-06T01:38:40.31816Z","steps":["trace[568768259] 'agreement among raft nodes before linearized reading' (duration: 105.494982ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:38:40.318259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.675596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T01:38:40.318431Z","caller":"traceutil/trace.go:171","msg":"trace[715430005] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:18402; }","duration":"106.886263ms","start":"2026-08-06T01:38:40.211485Z","end":"2026-08-06T01:38:40.318372Z","steps":["trace[715430005] 'agreement among raft nodes before linearized reading' (duration: 106.663846ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:40:50.871289Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1627b0b72e417489","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-06T01:40:50.877565Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-06T01:40:50.877798Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-08-06T01:42:15.874014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.996938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-06T01:42:15.874167Z","caller":"traceutil/trace.go:171","msg":"trace[2139308358] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:19908; }","duration":"123.277636ms","start":"2026-08-06T01:42:15.750868Z","end":"2026-08-06T01:42:15.874146Z","steps":["trace[2139308358] 'count revisions from in-memory index tree' (duration: 122.896056ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:42:26.765343Z","caller":"traceutil/trace.go:171","msg":"trace[1335730911] transaction","detail":"{read_only:false; response_revision:19964; number_of_response:1; }","duration":"149.221145ms","start":"2026-08-06T01:42:26.616085Z","end":"2026-08-06T01:42:26.765306Z","steps":["trace[1335730911] 'process raft request' (duration: 144.571223ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:42:26.956729Z","caller":"traceutil/trace.go:171","msg":"trace[1750524822] transaction","detail":"{read_only:false; response_revision:19966; number_of_response:1; }","duration":"185.764495ms","start":"2026-08-06T01:42:26.770926Z","end":"2026-08-06T01:42:26.95669Z","steps":["trace[1750524822] 'process raft request' (duration: 166.630571ms)","trace[1750524822] 'compare' (duration: 18.817605ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:42:27.215429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.483611ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-06T01:42:27.215815Z","caller":"traceutil/trace.go:171","msg":"trace[171017354] transaction","detail":"{read_only:false; response_revision:19967; number_of_response:1; }","duration":"363.563843ms","start":"2026-08-06T01:42:26.852196Z","end":"2026-08-06T01:42:27.21576Z","steps":["trace[171017354] 'process raft request' (duration: 212.67116ms)","trace[171017354] 'compare' (duration: 150.353507ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:42:27.216126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:42:26.852152Z","time spent":"363.737857ms","remote":"127.0.0.1:32780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:42:27.642806Z","caller":"traceutil/trace.go:171","msg":"trace[1414496145] linearizableReadLoop","detail":"{readStateIndex:20642; appliedIndex:20641; }","duration":"172.356324ms","start":"2026-08-06T01:42:27.470415Z","end":"2026-08-06T01:42:27.642771Z","steps":["trace[1414496145] 'read index received' (duration: 172.163928ms)","trace[1414496145] 'applied index is now lower than readState.Index' (duration: 191.266µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:42:27.643009Z","caller":"traceutil/trace.go:171","msg":"trace[2129996799] transaction","detail":"{read_only:false; response_revision:19970; number_of_response:1; }","duration":"174.371011ms","start":"2026-08-06T01:42:27.468595Z","end":"2026-08-06T01:42:27.642966Z","steps":["trace[2129996799] 'process raft request' (duration: 173.96779ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:42:27.643269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.833707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-06T01:42:27.64332Z","caller":"traceutil/trace.go:171","msg":"trace[1623875229] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:19970; }","duration":"172.93795ms","start":"2026-08-06T01:42:27.470368Z","end":"2026-08-06T01:42:27.643306Z","steps":["trace[1623875229] 'agreement among raft nodes before linearized reading' (duration: 172.519188ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:42:27.896787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.883102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-06T01:42:27.896907Z","caller":"traceutil/trace.go:171","msg":"trace[1821308873] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19970; }","duration":"171.056247ms","start":"2026-08-06T01:42:27.725818Z","end":"2026-08-06T01:42:27.896875Z","steps":["trace[1821308873] 'range keys from in-memory index tree' (duration: 170.649295ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:42:58.212534Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18071} {"level":"info","ts":"2026-08-06T01:42:58.341805Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18071,"took":"126.53578ms","hash":3678896514,"current-db-size-bytes":58597376,"current-db-size":"59 MB","current-db-size-in-use-bytes":30269440,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-06T01:42:58.341867Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3678896514,"revision":18071,"compact-revision":15522} {"level":"info","ts":"2026-08-06T01:46:07.051709Z","caller":"traceutil/trace.go:171","msg":"trace[224807284] transaction","detail":"{read_only:false; response_revision:22336; number_of_response:1; }","duration":"289.558627ms","start":"2026-08-06T01:46:06.762123Z","end":"2026-08-06T01:46:07.051681Z","steps":["trace[224807284] 'process raft request' (duration: 289.391972ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:47:58.219157Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20362} {"level":"info","ts":"2026-08-06T01:47:58.326894Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20362,"took":"103.886645ms","hash":3086496860,"current-db-size-bytes":58597376,"current-db-size":"59 MB","current-db-size-in-use-bytes":43192320,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-06T01:47:58.326948Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3086496860,"revision":20362,"compact-revision":18071} {"level":"info","ts":"2026-08-06T01:52:58.226153Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23446} {"level":"info","ts":"2026-08-06T01:52:58.356331Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23446,"took":"126.872636ms","hash":763727588,"current-db-size-bytes":58597376,"current-db-size":"59 MB","current-db-size-in-use-bytes":30363648,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-06T01:52:58.356404Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":763727588,"revision":23446,"compact-revision":20362} {"level":"warn","ts":"2026-08-06T01:55:45.47619Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"74899fd4984874b9","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-08-06T01:55:45.562336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.984643169s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"}