{"level":"warn","ts":"2026-10-26T19:16:01.162107Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-26T19:16:01.162236Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.113: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.113:2380","--initial-cluster=instance=https://162.253.55.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.113: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-10-26T19:16:01.162359Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-26T19:16:01.16238Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.113:2380"]} {"level":"info","ts":"2026-10-26T19:16:01.162406Z","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-10-26T19:16:01.163099Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.113:2379"]} {"level":"info","ts":"2026-10-26T19:16:01.163177Z","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.113:2380"],"listen-peer-urls":["https://162.253.55.113:2380"],"advertise-client-urls":["https://162.253.55.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.113: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-10-26T19:16:01.166487Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.037584ms"} {"level":"info","ts":"2026-10-26T19:16:01.173361Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3b717bddf98d48e3","cluster-id":"cef0f7c69f0576fc"} {"level":"info","ts":"2026-10-26T19:16:01.173446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 switched to configuration voters=()"} {"level":"info","ts":"2026-10-26T19:16:01.173471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became follower at term 0"} {"level":"info","ts":"2026-10-26T19:16:01.173482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3b717bddf98d48e3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-26T19:16:01.173499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became follower at term 1"} {"level":"info","ts":"2026-10-26T19:16:01.173539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 switched to configuration voters=(4283340913910827235)"} {"level":"warn","ts":"2026-10-26T19:16:01.179477Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-26T19:16:01.181819Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-26T19:16:01.183345Z","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-10-26T19:16:01.18564Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3b717bddf98d48e3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-26T19:16:01.18598Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3b717bddf98d48e3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-26T19:16:01.186099Z","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-10-26T19:16:01.186284Z","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-10-26T19:16:01.186349Z","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-10-26T19:16:01.187664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 switched to configuration voters=(4283340913910827235)"} {"level":"info","ts":"2026-10-26T19:16:01.188068Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cef0f7c69f0576fc","local-member-id":"3b717bddf98d48e3","added-peer-id":"3b717bddf98d48e3","added-peer-peer-urls":["https://162.253.55.113:2380"]} {"level":"info","ts":"2026-10-26T19:16:01.19464Z","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-10-26T19:16:01.194757Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.113:2380"} {"level":"info","ts":"2026-10-26T19:16:01.19485Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.113:2380"} {"level":"info","ts":"2026-10-26T19:16:01.195017Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3b717bddf98d48e3","initial-advertise-peer-urls":["https://162.253.55.113:2380"],"listen-peer-urls":["https://162.253.55.113:2380"],"advertise-client-urls":["https://162.253.55.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-26T19:16:01.195074Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-26T19:16:01.4748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 is starting a new election at term 1"} {"level":"info","ts":"2026-10-26T19:16:01.474966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-26T19:16:01.475016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 received MsgPreVoteResp from 3b717bddf98d48e3 at term 1"} {"level":"info","ts":"2026-10-26T19:16:01.475044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became candidate at term 2"} {"level":"info","ts":"2026-10-26T19:16:01.475059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 received MsgVoteResp from 3b717bddf98d48e3 at term 2"} {"level":"info","ts":"2026-10-26T19:16:01.475075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became leader at term 2"} {"level":"info","ts":"2026-10-26T19:16:01.475093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3b717bddf98d48e3 elected leader 3b717bddf98d48e3 at term 2"} {"level":"info","ts":"2026-10-26T19:16:01.476514Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T19:16:01.476503Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:16:01.476622Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T19:16:01.477073Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-26T19:16:01.477204Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-26T19:16:01.478406Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cef0f7c69f0576fc","local-member-id":"3b717bddf98d48e3","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:16:01.478559Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:16:01.478606Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:16:01.478931Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.113:2379"} {"level":"info","ts":"2026-10-26T19:16:01.480028Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-26T19:16:01.476476Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3b717bddf98d48e3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.113:2379]}","request-path":"/0/members/3b717bddf98d48e3/attributes","cluster-id":"cef0f7c69f0576fc","publish-timeout":"7s"} {"level":"info","ts":"2026-10-26T19:16:56.788692Z","caller":"traceutil/trace.go:171","msg":"trace[1070460060] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"368.029582ms","start":"2026-10-26T19:16:56.420625Z","end":"2026-10-26T19:16:56.788655Z","steps":["trace[1070460060] 'process raft request' (duration: 367.841017ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:16:56.789421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:16:56.420588Z","time spent":"368.206936ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:16:56.79736Z","caller":"traceutil/trace.go:171","msg":"trace[273033885] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"291.019683ms","start":"2026-10-26T19:16:56.506292Z","end":"2026-10-26T19:16:56.797312Z","steps":["trace[273033885] 'process raft request' (duration: 290.950641ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:16:56.797383Z","caller":"traceutil/trace.go:171","msg":"trace[756932198] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"373.055693ms","start":"2026-10-26T19:16:56.424285Z","end":"2026-10-26T19:16:56.79734Z","steps":["trace[756932198] 'process raft request' (duration: 372.765996ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:16:56.797787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:16:56.424173Z","time spent":"373.437293ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:16:57.558978Z","caller":"traceutil/trace.go:171","msg":"trace[2052179043] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"341.295394ms","start":"2026-10-26T19:16:57.217643Z","end":"2026-10-26T19:16:57.558938Z","steps":["trace[2052179043] 'process raft request' (duration: 339.47945ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:16:57.559219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:16:57.217612Z","time spent":"341.481607ms","remote":"127.0.0.1:32934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:17:13.364609Z","caller":"traceutil/trace.go:171","msg":"trace[1356421713] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"172.745153ms","start":"2026-10-26T19:17:13.191831Z","end":"2026-10-26T19:17:13.364576Z","steps":["trace[1356421713] 'process raft request' (duration: 172.556538ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:18:34.330215Z","caller":"traceutil/trace.go:171","msg":"trace[934171794] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"108.603381ms","start":"2026-10-26T19:18:34.221567Z","end":"2026-10-26T19:18:34.33017Z","steps":["trace[934171794] 'process raft request' (duration: 108.406246ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:18:34.563496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.935217ms","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-10-26T19:18:34.563642Z","caller":"traceutil/trace.go:171","msg":"trace[1007274758] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1905; }","duration":"132.161692ms","start":"2026-10-26T19:18:34.431454Z","end":"2026-10-26T19:18:34.563616Z","steps":["trace[1007274758] 'range keys from in-memory index tree' (duration: 131.753553ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:18:34.563494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.402659ms","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-10-26T19:18:34.563734Z","caller":"traceutil/trace.go:171","msg":"trace[1837082936] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1905; }","duration":"153.719327ms","start":"2026-10-26T19:18:34.409981Z","end":"2026-10-26T19:18:34.563701Z","steps":["trace[1837082936] 'range keys from in-memory index tree' (duration: 153.093232ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:18:34.833233Z","caller":"traceutil/trace.go:171","msg":"trace[2039007380] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"172.883013ms","start":"2026-10-26T19:18:34.660322Z","end":"2026-10-26T19:18:34.833205Z","steps":["trace[2039007380] 'process raft request' (duration: 172.488754ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:18:34.836962Z","caller":"traceutil/trace.go:171","msg":"trace[1324786392] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"138.927363ms","start":"2026-10-26T19:18:34.697971Z","end":"2026-10-26T19:18:34.836898Z","steps":["trace[1324786392] 'process raft request' (duration: 138.721208ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:18:49.752253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.760551ms","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-10-26T19:18:49.752359Z","caller":"traceutil/trace.go:171","msg":"trace[1538669105] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2040; }","duration":"124.955775ms","start":"2026-10-26T19:18:49.627381Z","end":"2026-10-26T19:18:49.752337Z","steps":["trace[1538669105] 'range keys from in-memory index tree' (duration: 124.516766ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:19:18.814699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.945175ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:19:18.814799Z","caller":"traceutil/trace.go:171","msg":"trace[353507346] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"193.589934ms","start":"2026-10-26T19:19:18.621193Z","end":"2026-10-26T19:19:18.814783Z","steps":["trace[353507346] 'process raft request' (duration: 68.351282ms)","trace[353507346] 'compare' (duration: 124.814722ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:19:25.048969Z","caller":"traceutil/trace.go:171","msg":"trace[1767427558] linearizableReadLoop","detail":"{readStateIndex:2319; appliedIndex:2318; }","duration":"162.931437ms","start":"2026-10-26T19:19:24.885999Z","end":"2026-10-26T19:19:25.04893Z","steps":["trace[1767427558] 'read index received' (duration: 158.809179ms)","trace[1767427558] 'applied index is now lower than readState.Index' (duration: 4.120418ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:19:25.048989Z","caller":"traceutil/trace.go:171","msg":"trace[1213891842] transaction","detail":"{read_only:false; response_revision:2235; number_of_response:1; }","duration":"166.406929ms","start":"2026-10-26T19:19:24.882539Z","end":"2026-10-26T19:19:25.048946Z","steps":["trace[1213891842] 'process raft request' (duration: 162.333723ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:19:25.049112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.09174ms","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-10-26T19:19:25.049197Z","caller":"traceutil/trace.go:171","msg":"trace[1485088506] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2235; }","duration":"163.216024ms","start":"2026-10-26T19:19:24.885961Z","end":"2026-10-26T19:19:25.049177Z","steps":["trace[1485088506] 'agreement among raft nodes before linearized reading' (duration: 163.013559ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:20:14.364381Z","caller":"traceutil/trace.go:171","msg":"trace[1781429038] transaction","detail":"{read_only:false; response_revision:2676; number_of_response:1; }","duration":"118.757507ms","start":"2026-10-26T19:20:14.245587Z","end":"2026-10-26T19:20:14.364344Z","steps":["trace[1781429038] 'process raft request' (duration: 118.504571ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:20:47.587096Z","caller":"traceutil/trace.go:171","msg":"trace[22867587] transaction","detail":"{read_only:false; response_revision:2874; number_of_response:1; }","duration":"168.613112ms","start":"2026-10-26T19:20:47.418444Z","end":"2026-10-26T19:20:47.587057Z","steps":["trace[22867587] 'process raft request' (duration: 143.754576ms)","trace[22867587] 'compare' (duration: 24.6327ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:20:47.616305Z","caller":"traceutil/trace.go:171","msg":"trace[1833310405] transaction","detail":"{read_only:false; response_revision:2875; number_of_response:1; }","duration":"195.839405ms","start":"2026-10-26T19:20:47.420439Z","end":"2026-10-26T19:20:47.616279Z","steps":["trace[1833310405] 'process raft request' (duration: 195.684402ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:20:47.979632Z","caller":"traceutil/trace.go:171","msg":"trace[1599063657] transaction","detail":"{read_only:false; response_revision:2878; number_of_response:1; }","duration":"150.363595ms","start":"2026-10-26T19:20:47.829238Z","end":"2026-10-26T19:20:47.979602Z","steps":["trace[1599063657] 'process raft request' (duration: 150.195661ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:24:01.457834Z","caller":"traceutil/trace.go:171","msg":"trace[409293223] transaction","detail":"{read_only:false; response_revision:4097; number_of_response:1; }","duration":"100.521384ms","start":"2026-10-26T19:24:01.357283Z","end":"2026-10-26T19:24:01.457805Z","steps":["trace[409293223] 'process raft request' (duration: 98.385352ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:25:29.586727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.226644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:16 size:518904"} {"level":"info","ts":"2026-10-26T19:25:29.58687Z","caller":"traceutil/trace.go:171","msg":"trace[1729183334] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:16; response_revision:5034; }","duration":"128.442459ms","start":"2026-10-26T19:25:29.458401Z","end":"2026-10-26T19:25:29.586843Z","steps":["trace[1729183334] 'range keys from in-memory index tree' (duration: 127.123827ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:25:31.069115Z","caller":"traceutil/trace.go:171","msg":"trace[980388201] linearizableReadLoop","detail":"{readStateIndex:5266; appliedIndex:5265; }","duration":"103.704572ms","start":"2026-10-26T19:25:30.96533Z","end":"2026-10-26T19:25:31.069034Z","steps":["trace[980388201] 'read index received' (duration: 62.928163ms)","trace[980388201] 'applied index is now lower than readState.Index' (duration: 40.774159ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:25:31.069628Z","caller":"traceutil/trace.go:171","msg":"trace[1289499820] transaction","detail":"{read_only:false; response_revision:5072; number_of_response:1; }","duration":"106.772868ms","start":"2026-10-26T19:25:30.962807Z","end":"2026-10-26T19:25:31.06958Z","steps":["trace[1289499820] 'process raft request' (duration: 65.622479ms)","trace[1289499820] 'compare' (duration: 40.115103ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:25:31.069729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.360309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4809"} {"level":"warn","ts":"2026-10-26T19:25:31.069802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.317498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-10-26T19:25:31.069967Z","caller":"traceutil/trace.go:171","msg":"trace[1677454430] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5072; }","duration":"104.446171ms","start":"2026-10-26T19:25:30.965427Z","end":"2026-10-26T19:25:31.069873Z","steps":["trace[1677454430] 'agreement among raft nodes before linearized reading' (duration: 104.135823ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:25:31.069831Z","caller":"traceutil/trace.go:171","msg":"trace[409126722] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5072; }","duration":"104.491522ms","start":"2026-10-26T19:25:30.965314Z","end":"2026-10-26T19:25:31.069806Z","steps":["trace[409126722] 'agreement among raft nodes before linearized reading' (duration: 104.153314ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:25:36.654183Z","caller":"traceutil/trace.go:171","msg":"trace[867883986] transaction","detail":"{read_only:false; response_revision:5300; number_of_response:1; }","duration":"274.840219ms","start":"2026-10-26T19:25:36.379313Z","end":"2026-10-26T19:25:36.654153Z","steps":["trace[867883986] 'process raft request' (duration: 274.603753ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:25:36.70559Z","caller":"traceutil/trace.go:171","msg":"trace[1097899302] transaction","detail":"{read_only:false; response_revision:5301; number_of_response:1; }","duration":"308.70283ms","start":"2026-10-26T19:25:36.396863Z","end":"2026-10-26T19:25:36.705566Z","steps":["trace[1097899302] 'process raft request' (duration: 308.377452ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:25:36.705755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:25:36.396814Z","time spent":"308.856583ms","remote":"127.0.0.1:33020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:25:48.429832Z","caller":"traceutil/trace.go:171","msg":"trace[817124317] transaction","detail":"{read_only:false; response_revision:5507; number_of_response:1; }","duration":"147.111417ms","start":"2026-10-26T19:25:48.282687Z","end":"2026-10-26T19:25:48.429799Z","steps":["trace[817124317] 'process raft request' (duration: 146.165064ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:25:52.471703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.203061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-86cf4f7cb6-4jt8f\" ","response":"range_response_count:1 size:8112"} {"level":"info","ts":"2026-10-26T19:25:52.47179Z","caller":"traceutil/trace.go:171","msg":"trace[1138467059] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-86cf4f7cb6-4jt8f; range_end:; response_count:1; response_revision:5575; }","duration":"185.302824ms","start":"2026-10-26T19:25:52.286469Z","end":"2026-10-26T19:25:52.471772Z","steps":["trace[1138467059] 'range keys from in-memory index tree' (duration: 185.027417ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:26:01.694238Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2952} {"level":"info","ts":"2026-10-26T19:26:01.773399Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2952,"took":"77.601563ms","hash":702742440,"current-db-size-bytes":56406016,"current-db-size":"56 MB","current-db-size-in-use-bytes":31936512,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T19:26:01.773473Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":702742440,"revision":2952,"compact-revision":-1} {"level":"info","ts":"2026-10-26T19:26:02.693455Z","caller":"traceutil/trace.go:171","msg":"trace[340916323] transaction","detail":"{read_only:false; response_revision:5759; number_of_response:1; }","duration":"148.829719ms","start":"2026-10-26T19:26:02.544598Z","end":"2026-10-26T19:26:02.693428Z","steps":["trace[340916323] 'process raft request' (duration: 148.653115ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:26:12.708156Z","caller":"traceutil/trace.go:171","msg":"trace[1494247451] linearizableReadLoop","detail":"{readStateIndex:6143; appliedIndex:6142; }","duration":"109.005073ms","start":"2026-10-26T19:26:12.599124Z","end":"2026-10-26T19:26:12.708129Z","steps":["trace[1494247451] 'read index received' (duration: 108.613693ms)","trace[1494247451] 'applied index is now lower than readState.Index' (duration: 390.18µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:26:12.708251Z","caller":"traceutil/trace.go:171","msg":"trace[151184621] transaction","detail":"{read_only:false; response_revision:5925; number_of_response:1; }","duration":"111.272958ms","start":"2026-10-26T19:26:12.596946Z","end":"2026-10-26T19:26:12.708219Z","steps":["trace[151184621] 'process raft request' (duration: 111.016712ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:26:12.708567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.416563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-10-26T19:26:12.708623Z","caller":"traceutil/trace.go:171","msg":"trace[1472274208] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5925; }","duration":"109.505785ms","start":"2026-10-26T19:26:12.599101Z","end":"2026-10-26T19:26:12.708607Z","steps":["trace[1472274208] 'agreement among raft nodes before linearized reading' (duration: 109.154376ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:26:12.825439Z","caller":"traceutil/trace.go:171","msg":"trace[1029078560] transaction","detail":"{read_only:false; response_revision:5926; number_of_response:1; }","duration":"110.487619ms","start":"2026-10-26T19:26:12.714921Z","end":"2026-10-26T19:26:12.825409Z","steps":["trace[1029078560] 'process raft request' (duration: 71.913443ms)","trace[1029078560] 'compare' (duration: 38.406842ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:26:12.839056Z","caller":"traceutil/trace.go:171","msg":"trace[864943671] transaction","detail":"{read_only:false; response_revision:5927; number_of_response:1; }","duration":"123.273183ms","start":"2026-10-26T19:26:12.715757Z","end":"2026-10-26T19:26:12.839031Z","steps":["trace[864943671] 'process raft request' (duration: 123.006006ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:26:12.839486Z","caller":"traceutil/trace.go:171","msg":"trace[609615564] transaction","detail":"{read_only:false; response_revision:5929; number_of_response:1; }","duration":"123.484347ms","start":"2026-10-26T19:26:12.715982Z","end":"2026-10-26T19:26:12.839467Z","steps":["trace[609615564] 'process raft request' (duration: 123.313783ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:26:12.839487Z","caller":"traceutil/trace.go:171","msg":"trace[1303471784] transaction","detail":"{read_only:false; response_revision:5928; number_of_response:1; }","duration":"123.628331ms","start":"2026-10-26T19:26:12.715822Z","end":"2026-10-26T19:26:12.83945Z","steps":["trace[1303471784] 'process raft request' (duration: 123.138469ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:26:12.839648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.028273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-26T19:26:12.839741Z","caller":"traceutil/trace.go:171","msg":"trace[112987719] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5929; }","duration":"100.167956ms","start":"2026-10-26T19:26:12.739553Z","end":"2026-10-26T19:26:12.839721Z","steps":["trace[112987719] 'agreement among raft nodes before linearized reading' (duration: 99.905939ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:31:01.700853Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5754} {"level":"info","ts":"2026-10-26T19:31:01.795654Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5754,"took":"92.826115ms","hash":3227615285,"current-db-size-bytes":56406016,"current-db-size":"56 MB","current-db-size-in-use-bytes":26423296,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-26T19:31:01.795725Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3227615285,"revision":5754,"compact-revision":2952} {"level":"warn","ts":"2026-10-26T19:32:06.049134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.840591ms","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-10-26T19:32:06.049249Z","caller":"traceutil/trace.go:171","msg":"trace[1985354235] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8588; }","duration":"441.992415ms","start":"2026-10-26T19:32:05.607228Z","end":"2026-10-26T19:32:06.04922Z","steps":["trace[1985354235] 'range keys from in-memory index tree' (duration: 441.650986ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:32:06.049267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.582761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-26T19:32:06.049353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.758862ms","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-10-26T19:32:06.049453Z","caller":"traceutil/trace.go:171","msg":"trace[1431000166] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8588; }","duration":"435.876815ms","start":"2026-10-26T19:32:05.613554Z","end":"2026-10-26T19:32:06.049431Z","steps":["trace[1431000166] 'range keys from in-memory index tree' (duration: 435.640909ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:32:06.049458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.110838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-b2mtj\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-10-26T19:32:06.049511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:32:05.613547Z","time spent":"435.950046ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-26T19:32:06.049531Z","caller":"traceutil/trace.go:171","msg":"trace[351064442] range","detail":"{range_begin:/registry/pods/openstack/keepalived-b2mtj; range_end:; response_count:1; response_revision:8588; }","duration":"263.20527ms","start":"2026-10-26T19:32:05.786295Z","end":"2026-10-26T19:32:06.0495Z","steps":["trace[351064442] 'range keys from in-memory index tree' (duration: 262.978265ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:32:06.049384Z","caller":"traceutil/trace.go:171","msg":"trace[143085989] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8588; }","duration":"458.754205ms","start":"2026-10-26T19:32:05.590603Z","end":"2026-10-26T19:32:06.049357Z","steps":["trace[143085989] 'range keys from in-memory index tree' (duration: 458.323124ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:32:06.049631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:32:05.590583Z","time spent":"459.027741ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-26T19:32:06.049304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:32:05.607214Z","time spent":"442.076036ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-26T19:32:17.181237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.456472ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:32:17.181366Z","caller":"traceutil/trace.go:171","msg":"trace[1090126619] linearizableReadLoop","detail":"{readStateIndex:8957; appliedIndex:8956; }","duration":"142.657441ms","start":"2026-10-26T19:32:17.038684Z","end":"2026-10-26T19:32:17.181342Z","steps":["trace[1090126619] 'read index received' (duration: 29.986494ms)","trace[1090126619] 'applied index is now lower than readState.Index' (duration: 112.668847ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:32:17.18153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.851865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:32:17.181586Z","caller":"traceutil/trace.go:171","msg":"trace[731149985] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8652; }","duration":"142.966199ms","start":"2026-10-26T19:32:17.038604Z","end":"2026-10-26T19:32:17.181571Z","steps":["trace[731149985] 'agreement among raft nodes before linearized reading' (duration: 142.872766ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:32:17.181538Z","caller":"traceutil/trace.go:171","msg":"trace[1228950632] transaction","detail":"{read_only:false; response_revision:8652; number_of_response:1; }","duration":"377.337374ms","start":"2026-10-26T19:32:16.804146Z","end":"2026-10-26T19:32:17.181483Z","steps":["trace[1228950632] 'process raft request' (duration: 264.516103ms)","trace[1228950632] 'compare' (duration: 112.269107ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:32:17.181793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:32:16.804116Z","time spent":"377.537149ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:32:18.478137Z","caller":"traceutil/trace.go:171","msg":"trace[1177331446] transaction","detail":"{read_only:false; response_revision:8656; number_of_response:1; }","duration":"130.454373ms","start":"2026-10-26T19:32:18.347655Z","end":"2026-10-26T19:32:18.47811Z","steps":["trace[1177331446] 'process raft request' (duration: 130.263938ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:32:18.519183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.642218ms","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-10-26T19:32:18.519292Z","caller":"traceutil/trace.go:171","msg":"trace[1585634508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8658; }","duration":"128.839553ms","start":"2026-10-26T19:32:18.39043Z","end":"2026-10-26T19:32:18.519269Z","steps":["trace[1585634508] 'agreement among raft nodes before linearized reading' (duration: 128.505275ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:32:18.886415Z","caller":"traceutil/trace.go:171","msg":"trace[2018452764] transaction","detail":"{read_only:false; response_revision:8660; number_of_response:1; }","duration":"113.709673ms","start":"2026-10-26T19:32:18.772666Z","end":"2026-10-26T19:32:18.886376Z","steps":["trace[2018452764] 'process raft request' (duration: 113.465437ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:32:44.771383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.88843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/rook-ceph/csi-rbdplugin-metrics\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:32:44.771483Z","caller":"traceutil/trace.go:171","msg":"trace[1888985495] range","detail":"{range_begin:/registry/services/specs/rook-ceph/csi-rbdplugin-metrics; range_end:; response_count:0; response_revision:8850; }","duration":"104.047944ms","start":"2026-10-26T19:32:44.667413Z","end":"2026-10-26T19:32:44.771461Z","steps":["trace[1888985495] 'range keys from in-memory index tree' (duration: 103.780007ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:32:46.999708Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023dc.wal"} {"level":"warn","ts":"2026-10-26T19:32:48.248222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.269398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:32:48.248317Z","caller":"traceutil/trace.go:171","msg":"trace[1335356880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8868; }","duration":"209.389841ms","start":"2026-10-26T19:32:48.038905Z","end":"2026-10-26T19:32:48.248295Z","steps":["trace[1335356880] 'range keys from in-memory index tree' (duration: 209.181746ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:32:49.618624Z","caller":"traceutil/trace.go:171","msg":"trace[21779394] transaction","detail":"{read_only:false; response_revision:8878; number_of_response:1; }","duration":"117.176805ms","start":"2026-10-26T19:32:49.501425Z","end":"2026-10-26T19:32:49.618602Z","steps":["trace[21779394] 'process raft request' (duration: 117.036762ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:33:02.387163Z","caller":"traceutil/trace.go:171","msg":"trace[1546439296] linearizableReadLoop","detail":"{readStateIndex:9298; appliedIndex:9297; }","duration":"158.984688ms","start":"2026-10-26T19:33:02.228159Z","end":"2026-10-26T19:33:02.387144Z","steps":["trace[1546439296] 'read index received' (duration: 150.914251ms)","trace[1546439296] 'applied index is now lower than readState.Index' (duration: 8.069817ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:33:02.38733Z","caller":"traceutil/trace.go:171","msg":"trace[2120903729] transaction","detail":"{read_only:false; response_revision:8980; number_of_response:1; }","duration":"164.449181ms","start":"2026-10-26T19:33:02.222872Z","end":"2026-10-26T19:33:02.387321Z","steps":["trace[2120903729] 'process raft request' (duration: 156.034806ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:02.38752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.362027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-10-26T19:33:02.387549Z","caller":"traceutil/trace.go:171","msg":"trace[1865761681] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:8980; }","duration":"159.45158ms","start":"2026-10-26T19:33:02.22809Z","end":"2026-10-26T19:33:02.387541Z","steps":["trace[1865761681] 'agreement among raft nodes before linearized reading' (duration: 159.352047ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:33:02.453661Z","caller":"traceutil/trace.go:171","msg":"trace[1397338913] transaction","detail":"{read_only:false; response_revision:8981; number_of_response:1; }","duration":"179.482339ms","start":"2026-10-26T19:33:02.274158Z","end":"2026-10-26T19:33:02.45364Z","steps":["trace[1397338913] 'process raft request' (duration: 179.244353ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:02.453966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.090529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-8kxv2\" ","response":"range_response_count:1 size:4793"} {"level":"info","ts":"2026-10-26T19:33:02.454018Z","caller":"traceutil/trace.go:171","msg":"trace[426868695] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-8kxv2; range_end:; response_count:1; response_revision:8981; }","duration":"181.211931ms","start":"2026-10-26T19:33:02.272797Z","end":"2026-10-26T19:33:02.454008Z","steps":["trace[426868695] 'agreement among raft nodes before linearized reading' (duration: 181.045867ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:02.454165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.991475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-10-26T19:33:02.454193Z","caller":"traceutil/trace.go:171","msg":"trace[1519030998] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:8981; }","duration":"210.039637ms","start":"2026-10-26T19:33:02.244146Z","end":"2026-10-26T19:33:02.454185Z","steps":["trace[1519030998] 'agreement among raft nodes before linearized reading' (duration: 209.973115ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:33:02.664273Z","caller":"traceutil/trace.go:171","msg":"trace[1975932026] linearizableReadLoop","detail":"{readStateIndex:9300; appliedIndex:9299; }","duration":"193.707467ms","start":"2026-10-26T19:33:02.470531Z","end":"2026-10-26T19:33:02.664238Z","steps":["trace[1975932026] 'read index received' (duration: 193.42364ms)","trace[1975932026] 'applied index is now lower than readState.Index' (duration: 282.717µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:33:02.664581Z","caller":"traceutil/trace.go:171","msg":"trace[978213403] transaction","detail":"{read_only:false; response_revision:8982; number_of_response:1; }","duration":"197.019718ms","start":"2026-10-26T19:33:02.467544Z","end":"2026-10-26T19:33:02.664564Z","steps":["trace[978213403] 'process raft request' (duration: 196.531956ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:02.664935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.379504ms","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":"info","ts":"2026-10-26T19:33:02.664981Z","caller":"traceutil/trace.go:171","msg":"trace[1169967311] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:8982; }","duration":"194.455055ms","start":"2026-10-26T19:33:02.470512Z","end":"2026-10-26T19:33:02.664967Z","steps":["trace[1169967311] 'agreement among raft nodes before linearized reading' (duration: 194.291151ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:02.665236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.881232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-10-26T19:33:02.665329Z","caller":"traceutil/trace.go:171","msg":"trace[517705144] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:8982; }","duration":"138.034385ms","start":"2026-10-26T19:33:02.527273Z","end":"2026-10-26T19:33:02.665307Z","steps":["trace[517705144] 'agreement among raft nodes before linearized reading' (duration: 137.751429ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:02.666082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.476217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-26T19:33:02.666115Z","caller":"traceutil/trace.go:171","msg":"trace[1707898180] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8982; }","duration":"109.56658ms","start":"2026-10-26T19:33:02.556537Z","end":"2026-10-26T19:33:02.666104Z","steps":["trace[1707898180] 'agreement among raft nodes before linearized reading' (duration: 109.418846ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:33:02.862483Z","caller":"traceutil/trace.go:171","msg":"trace[851571552] linearizableReadLoop","detail":"{readStateIndex:9305; appliedIndex:9303; }","duration":"125.809877ms","start":"2026-10-26T19:33:02.736651Z","end":"2026-10-26T19:33:02.862461Z","steps":["trace[851571552] 'read index received' (duration: 125.617192ms)","trace[851571552] 'applied index is now lower than readState.Index' (duration: 191.415µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:33:02.862571Z","caller":"traceutil/trace.go:171","msg":"trace[1956209889] transaction","detail":"{read_only:false; response_revision:8986; number_of_response:1; }","duration":"164.737169ms","start":"2026-10-26T19:33:02.697764Z","end":"2026-10-26T19:33:02.862502Z","steps":["trace[1956209889] 'process raft request' (duration: 164.520513ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:02.862737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.473421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"warn","ts":"2026-10-26T19:33:02.862729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.029731ms","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-10-26T19:33:02.862784Z","caller":"traceutil/trace.go:171","msg":"trace[2075838745] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:8987; }","duration":"184.561263ms","start":"2026-10-26T19:33:02.67821Z","end":"2026-10-26T19:33:02.862771Z","steps":["trace[2075838745] 'agreement among raft nodes before linearized reading' (duration: 184.377819ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:33:02.862812Z","caller":"traceutil/trace.go:171","msg":"trace[572449598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8987; }","duration":"146.163654ms","start":"2026-10-26T19:33:02.716627Z","end":"2026-10-26T19:33:02.862791Z","steps":["trace[572449598] 'agreement among raft nodes before linearized reading' (duration: 145.876497ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:02.863083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.437974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-10-26T19:33:02.863114Z","caller":"traceutil/trace.go:171","msg":"trace[1639358623] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:8987; }","duration":"122.514215ms","start":"2026-10-26T19:33:02.740593Z","end":"2026-10-26T19:33:02.863107Z","steps":["trace[1639358623] 'agreement among raft nodes before linearized reading' (duration: 122.431523ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:33:02.863196Z","caller":"traceutil/trace.go:171","msg":"trace[1105519089] transaction","detail":"{read_only:false; response_revision:8987; number_of_response:1; }","duration":"158.911576ms","start":"2026-10-26T19:33:02.704265Z","end":"2026-10-26T19:33:02.863177Z","steps":["trace[1105519089] 'process raft request' (duration: 158.142717ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:02.863387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.566932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-669d78b7-4jcbw.18e22b3681cd013d\" ","response":"range_response_count:1 size:1129"} {"level":"info","ts":"2026-10-26T19:33:02.863426Z","caller":"traceutil/trace.go:171","msg":"trace[316007514] range","detail":"{range_begin:/registry/events/openstack/barbican-api-669d78b7-4jcbw.18e22b3681cd013d; range_end:; response_count:1; response_revision:8987; }","duration":"105.607343ms","start":"2026-10-26T19:33:02.757809Z","end":"2026-10-26T19:33:02.863416Z","steps":["trace[316007514] 'agreement among raft nodes before linearized reading' (duration: 105.368787ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:02.863432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.49263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:229763"} {"level":"info","ts":"2026-10-26T19:33:02.863501Z","caller":"traceutil/trace.go:171","msg":"trace[16479812] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8987; }","duration":"186.607694ms","start":"2026-10-26T19:33:02.676875Z","end":"2026-10-26T19:33:02.863483Z","steps":["trace[16479812] 'agreement among raft nodes before linearized reading' (duration: 186.099171ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:06.557633Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3b717bddf98d48e3","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T19:34:06.564962Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-26T19:34:06.565211Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-26T19:34:21.466199Z","caller":"traceutil/trace.go:171","msg":"trace[481208540] transaction","detail":"{read_only:false; response_revision:9752; number_of_response:1; }","duration":"118.502525ms","start":"2026-10-26T19:34:21.347667Z","end":"2026-10-26T19:34:21.46617Z","steps":["trace[481208540] 'process raft request' (duration: 118.208698ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:21.477801Z","caller":"traceutil/trace.go:171","msg":"trace[844886256] transaction","detail":"{read_only:false; response_revision:9753; number_of_response:1; }","duration":"129.679427ms","start":"2026-10-26T19:34:21.348095Z","end":"2026-10-26T19:34:21.477775Z","steps":["trace[844886256] 'process raft request' (duration: 129.489132ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:21.478078Z","caller":"traceutil/trace.go:171","msg":"trace[1391489656] transaction","detail":"{read_only:false; response_revision:9754; number_of_response:1; }","duration":"120.225997ms","start":"2026-10-26T19:34:21.357825Z","end":"2026-10-26T19:34:21.478051Z","steps":["trace[1391489656] 'process raft request' (duration: 119.900029ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:36:01.708191Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8018} {"level":"info","ts":"2026-10-26T19:36:01.78372Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8018,"took":"72.287384ms","hash":611643981,"current-db-size-bytes":56406016,"current-db-size":"56 MB","current-db-size-in-use-bytes":28884992,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-26T19:36:01.783784Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":611643981,"revision":8018,"compact-revision":5754} {"level":"info","ts":"2026-10-26T19:40:02.646287Z","caller":"traceutil/trace.go:171","msg":"trace[2122606686] transaction","detail":"{read_only:false; response_revision:12196; number_of_response:1; }","duration":"138.107535ms","start":"2026-10-26T19:40:02.508149Z","end":"2026-10-26T19:40:02.646257Z","steps":["trace[2122606686] 'process raft request' (duration: 137.904961ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:40:02.653678Z","caller":"traceutil/trace.go:171","msg":"trace[779387977] transaction","detail":"{read_only:false; response_revision:12197; number_of_response:1; }","duration":"121.779559ms","start":"2026-10-26T19:40:02.531863Z","end":"2026-10-26T19:40:02.653643Z","steps":["trace[779387977] 'process raft request' (duration: 121.516933ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:40:03.417443Z","caller":"traceutil/trace.go:171","msg":"trace[1626711127] linearizableReadLoop","detail":"{readStateIndex:12620; appliedIndex:12619; }","duration":"106.299762ms","start":"2026-10-26T19:40:03.311109Z","end":"2026-10-26T19:40:03.417409Z","steps":["trace[1626711127] 'read index received' (duration: 106.22725ms)","trace[1626711127] 'applied index is now lower than readState.Index' (duration: 71.042µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:40:03.417604Z","caller":"traceutil/trace.go:171","msg":"trace[575344593] transaction","detail":"{read_only:false; response_revision:12202; number_of_response:1; }","duration":"108.152088ms","start":"2026-10-26T19:40:03.309436Z","end":"2026-10-26T19:40:03.417589Z","steps":["trace[575344593] 'process raft request' (duration: 107.751168ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:40:03.417976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.808045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-10-26T19:40:03.418022Z","caller":"traceutil/trace.go:171","msg":"trace[589012745] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:12202; }","duration":"106.928398ms","start":"2026-10-26T19:40:03.31108Z","end":"2026-10-26T19:40:03.418008Z","steps":["trace[589012745] 'agreement among raft nodes before linearized reading' (duration: 106.700452ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:40:03.422142Z","caller":"traceutil/trace.go:171","msg":"trace[1937011425] transaction","detail":"{read_only:false; response_revision:12203; number_of_response:1; }","duration":"109.4719ms","start":"2026-10-26T19:40:03.312614Z","end":"2026-10-26T19:40:03.422086Z","steps":["trace[1937011425] 'process raft request' (duration: 109.121681ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:41:01.716912Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10445} {"level":"info","ts":"2026-10-26T19:41:01.804396Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10445,"took":"85.291133ms","hash":2927677710,"current-db-size-bytes":56406016,"current-db-size":"56 MB","current-db-size-in-use-bytes":26075136,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-26T19:41:01.804493Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2927677710,"revision":10445,"compact-revision":8018} {"level":"info","ts":"2026-10-26T19:46:01.725809Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12618} {"level":"info","ts":"2026-10-26T19:46:01.816615Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12618,"took":"87.866607ms","hash":1957462751,"current-db-size-bytes":56406016,"current-db-size":"56 MB","current-db-size-in-use-bytes":27987968,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-26T19:46:01.816691Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1957462751,"revision":12618,"compact-revision":10445} {"level":"info","ts":"2026-10-26T19:46:14.31569Z","caller":"traceutil/trace.go:171","msg":"trace[189951433] transaction","detail":"{read_only:false; response_revision:14707; number_of_response:1; }","duration":"148.625735ms","start":"2026-10-26T19:46:14.167037Z","end":"2026-10-26T19:46:14.315662Z","steps":["trace[189951433] 'process raft request' (duration: 148.363539ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:46:14.450378Z","caller":"traceutil/trace.go:171","msg":"trace[1450755656] transaction","detail":"{read_only:false; response_revision:14708; number_of_response:1; }","duration":"279.199641ms","start":"2026-10-26T19:46:14.171149Z","end":"2026-10-26T19:46:14.450349Z","steps":["trace[1450755656] 'process raft request' (duration: 264.581385ms)","trace[1450755656] 'compare' (duration: 14.437121ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:46:32.78049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.948746ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:46:32.780654Z","caller":"traceutil/trace.go:171","msg":"trace[1260722399] transaction","detail":"{read_only:false; response_revision:14815; number_of_response:1; }","duration":"400.658545ms","start":"2026-10-26T19:46:32.37997Z","end":"2026-10-26T19:46:32.780628Z","steps":["trace[1260722399] 'process raft request' (duration: 107.461693ms)","trace[1260722399] 'compare' (duration: 292.71652ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:46:32.780743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:46:32.379867Z","time spent":"400.82743ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:46:32.876635Z","caller":"traceutil/trace.go:171","msg":"trace[978606074] transaction","detail":"{read_only:false; response_revision:14816; number_of_response:1; }","duration":"362.396234ms","start":"2026-10-26T19:46:32.514207Z","end":"2026-10-26T19:46:32.876603Z","steps":["trace[978606074] 'process raft request' (duration: 362.21672ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:46:32.876808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:46:32.514179Z","time spent":"362.540518ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:46:32.877657Z","caller":"traceutil/trace.go:171","msg":"trace[678495220] transaction","detail":"{read_only:false; response_revision:14817; number_of_response:1; }","duration":"361.915413ms","start":"2026-10-26T19:46:32.515704Z","end":"2026-10-26T19:46:32.87762Z","steps":["trace[678495220] 'process raft request' (duration: 361.745849ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:46:32.877832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:46:32.515676Z","time spent":"362.062677ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:46:34.760505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.891932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:46:34.760585Z","caller":"traceutil/trace.go:171","msg":"trace[1774679210] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:14832; }","duration":"126.106207ms","start":"2026-10-26T19:46:34.634462Z","end":"2026-10-26T19:46:34.760568Z","steps":["trace[1774679210] 'count revisions from in-memory index tree' (duration: 125.756169ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:46:43.107685Z","caller":"traceutil/trace.go:171","msg":"trace[1118596528] transaction","detail":"{read_only:false; response_revision:14986; number_of_response:1; }","duration":"195.864514ms","start":"2026-10-26T19:46:42.911789Z","end":"2026-10-26T19:46:43.107653Z","steps":["trace[1118596528] 'process raft request' (duration: 99.869629ms)","trace[1118596528] 'compare' (duration: 95.615216ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:46:43.108275Z","caller":"traceutil/trace.go:171","msg":"trace[1413030085] transaction","detail":"{read_only:false; response_revision:14987; number_of_response:1; }","duration":"195.373592ms","start":"2026-10-26T19:46:42.912869Z","end":"2026-10-26T19:46:43.108243Z","steps":["trace[1413030085] 'process raft request' (duration: 194.729556ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:46:43.108342Z","caller":"traceutil/trace.go:171","msg":"trace[1313652694] transaction","detail":"{read_only:false; response_revision:14988; number_of_response:1; }","duration":"104.468761ms","start":"2026-10-26T19:46:43.003826Z","end":"2026-10-26T19:46:43.108295Z","steps":["trace[1313652694] 'process raft request' (duration: 104.145773ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:48:07.556601Z","caller":"traceutil/trace.go:171","msg":"trace[1558069209] transaction","detail":"{read_only:false; response_revision:15995; number_of_response:1; }","duration":"243.254659ms","start":"2026-10-26T19:48:07.313304Z","end":"2026-10-26T19:48:07.556559Z","steps":["trace[1558069209] 'process raft request' (duration: 242.830178ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:48:07.573345Z","caller":"traceutil/trace.go:171","msg":"trace[209473718] transaction","detail":"{read_only:false; response_revision:15996; number_of_response:1; }","duration":"164.459471ms","start":"2026-10-26T19:48:07.408854Z","end":"2026-10-26T19:48:07.573314Z","steps":["trace[209473718] 'process raft request' (duration: 164.244896ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:48:07.573608Z","caller":"traceutil/trace.go:171","msg":"trace[824682171] transaction","detail":"{read_only:false; response_revision:15997; number_of_response:1; }","duration":"152.571903ms","start":"2026-10-26T19:48:07.420967Z","end":"2026-10-26T19:48:07.573539Z","steps":["trace[824682171] 'process raft request' (duration: 152.300786ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:48:08.712809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.519841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:48:08.712941Z","caller":"traceutil/trace.go:171","msg":"trace[1458903773] linearizableReadLoop","detail":"{readStateIndex:16548; appliedIndex:16546; }","duration":"375.687221ms","start":"2026-10-26T19:48:08.337232Z","end":"2026-10-26T19:48:08.712919Z","steps":["trace[1458903773] 'read index received' (duration: 39.85799ms)","trace[1458903773] 'applied index is now lower than readState.Index' (duration: 335.82778ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:48:08.713035Z","caller":"traceutil/trace.go:171","msg":"trace[1024003413] transaction","detail":"{read_only:false; response_revision:16002; number_of_response:1; }","duration":"398.092345ms","start":"2026-10-26T19:48:08.314926Z","end":"2026-10-26T19:48:08.713019Z","steps":["trace[1024003413] 'process raft request' (duration: 222.252946ms)","trace[1024003413] 'compare' (duration: 175.332857ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:48:08.713103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:48:08.314907Z","time spent":"398.155497ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:48:08.713265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.022999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T19:48:08.713383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.754153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-26T19:48:08.713431Z","caller":"traceutil/trace.go:171","msg":"trace[1589426819] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16002; }","duration":"376.208344ms","start":"2026-10-26T19:48:08.337205Z","end":"2026-10-26T19:48:08.713413Z","steps":["trace[1589426819] 'agreement among raft nodes before linearized reading' (duration: 376.010259ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:48:08.71344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.43537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-10-26T19:48:08.713476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:48:08.33719Z","time spent":"376.275455ms","remote":"127.0.0.1:33060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"info","ts":"2026-10-26T19:48:08.713474Z","caller":"traceutil/trace.go:171","msg":"trace[2141368807] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16002; }","duration":"373.860196ms","start":"2026-10-26T19:48:08.339594Z","end":"2026-10-26T19:48:08.713454Z","steps":["trace[2141368807] 'agreement among raft nodes before linearized reading' (duration: 373.6352ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:48:08.713387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.751323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T19:48:08.714084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:48:08.339481Z","time spent":"374.530342ms","remote":"127.0.0.1:33060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5546,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-10-26T19:48:08.713528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.733053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-10-26T19:48:08.714229Z","caller":"traceutil/trace.go:171","msg":"trace[637775002] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16002; }","duration":"233.597794ms","start":"2026-10-26T19:48:08.480567Z","end":"2026-10-26T19:48:08.714165Z","steps":["trace[637775002] 'agreement among raft nodes before linearized reading' (duration: 232.690812ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:48:08.714317Z","caller":"traceutil/trace.go:171","msg":"trace[1202049285] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16002; }","duration":"374.537962ms","start":"2026-10-26T19:48:08.339764Z","end":"2026-10-26T19:48:08.714302Z","steps":["trace[1202049285] 'agreement among raft nodes before linearized reading' (duration: 373.63204ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:48:08.713545Z","caller":"traceutil/trace.go:171","msg":"trace[646399847] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16002; }","duration":"247.615915ms","start":"2026-10-26T19:48:08.465906Z","end":"2026-10-26T19:48:08.713522Z","steps":["trace[646399847] 'agreement among raft nodes before linearized reading' (duration: 247.351999ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:48:08.714372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:48:08.339758Z","time spent":"374.599974ms","remote":"127.0.0.1:32998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":690,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-10-26T19:48:08.713559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.22204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T19:48:08.714462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.472004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:48:08.714525Z","caller":"traceutil/trace.go:171","msg":"trace[1891715927] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16002; }","duration":"233.171274ms","start":"2026-10-26T19:48:08.481312Z","end":"2026-10-26T19:48:08.714483Z","steps":["trace[1891715927] 'agreement among raft nodes before linearized reading' (duration: 232.223491ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:48:08.713535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.349586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-26T19:48:08.714637Z","caller":"traceutil/trace.go:171","msg":"trace[1048327219] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16002; }","duration":"109.534686ms","start":"2026-10-26T19:48:08.605076Z","end":"2026-10-26T19:48:08.71461Z","steps":["trace[1048327219] 'agreement among raft nodes before linearized reading' (duration: 108.202153ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:48:08.713596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.421217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-26T19:48:08.714772Z","caller":"traceutil/trace.go:171","msg":"trace[1881322298] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16002; }","duration":"231.610675ms","start":"2026-10-26T19:48:08.483145Z","end":"2026-10-26T19:48:08.714756Z","steps":["trace[1881322298] 'agreement among raft nodes before linearized reading' (duration: 230.301633ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:48:08.713607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.830444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:48:08.714909Z","caller":"traceutil/trace.go:171","msg":"trace[617700643] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16002; }","duration":"123.137176ms","start":"2026-10-26T19:48:08.591729Z","end":"2026-10-26T19:48:08.714866Z","steps":["trace[617700643] 'agreement among raft nodes before linearized reading' (duration: 121.862505ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:48:08.713647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.831144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-10-26T19:48:08.715052Z","caller":"traceutil/trace.go:171","msg":"trace[12160641] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16002; }","duration":"123.238469ms","start":"2026-10-26T19:48:08.591794Z","end":"2026-10-26T19:48:08.715033Z","steps":["trace[12160641] 'agreement among raft nodes before linearized reading' (duration: 121.793994ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:48:08.714569Z","caller":"traceutil/trace.go:171","msg":"trace[11056126] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16002; }","duration":"230.292223ms","start":"2026-10-26T19:48:08.484258Z","end":"2026-10-26T19:48:08.71455Z","steps":["trace[11056126] 'agreement among raft nodes before linearized reading' (duration: 228.984261ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:51:01.732303Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14641} {"level":"info","ts":"2026-10-26T19:51:01.82555Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14641,"took":"90.695821ms","hash":2396574003,"current-db-size-bytes":56406016,"current-db-size":"56 MB","current-db-size-in-use-bytes":32518144,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T19:51:01.825628Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2396574003,"revision":14641,"compact-revision":12618} {"level":"info","ts":"2026-10-26T19:54:04.050569Z","caller":"traceutil/trace.go:171","msg":"trace[990310137] transaction","detail":"{read_only:false; response_revision:18651; number_of_response:1; }","duration":"104.840998ms","start":"2026-10-26T19:54:03.9457Z","end":"2026-10-26T19:54:04.050541Z","steps":["trace[990310137] 'process raft request' (duration: 104.320396ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:54:06.551016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.338732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-26T19:54:06.551087Z","caller":"traceutil/trace.go:171","msg":"trace[1501736590] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:18673; }","duration":"126.471186ms","start":"2026-10-26T19:54:06.4246Z","end":"2026-10-26T19:54:06.551071Z","steps":["trace[1501736590] 'count revisions from in-memory index tree' (duration: 126.244921ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:54:06.897463Z","caller":"traceutil/trace.go:171","msg":"trace[1692381152] transaction","detail":"{read_only:false; response_revision:18674; number_of_response:1; }","duration":"326.039295ms","start":"2026-10-26T19:54:06.571397Z","end":"2026-10-26T19:54:06.897436Z","steps":["trace[1692381152] 'process raft request' (duration: 325.82504ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:54:06.897613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:54:06.571373Z","time spent":"326.162348ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:54:06.925688Z","caller":"traceutil/trace.go:171","msg":"trace[846556852] linearizableReadLoop","detail":"{readStateIndex:19310; appliedIndex:19308; }","duration":"247.859118ms","start":"2026-10-26T19:54:06.677803Z","end":"2026-10-26T19:54:06.925662Z","steps":["trace[846556852] 'read index received' (duration: 219.483265ms)","trace[846556852] 'applied index is now lower than readState.Index' (duration: 28.374543ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:54:06.925728Z","caller":"traceutil/trace.go:171","msg":"trace[1415526677] transaction","detail":"{read_only:false; response_revision:18675; number_of_response:1; }","duration":"349.448827ms","start":"2026-10-26T19:54:06.576258Z","end":"2026-10-26T19:54:06.925707Z","steps":["trace[1415526677] 'process raft request' (duration: 349.272663ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:54:06.925917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.095673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-7z6wg\" ","response":"range_response_count:1 size:20902"} {"level":"info","ts":"2026-10-26T19:54:06.925969Z","caller":"traceutil/trace.go:171","msg":"trace[948860487] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-7z6wg; range_end:; response_count:1; response_revision:18675; }","duration":"248.197766ms","start":"2026-10-26T19:54:06.677762Z","end":"2026-10-26T19:54:06.92596Z","steps":["trace[948860487] 'agreement among raft nodes before linearized reading' (duration: 248.001941ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:54:06.926094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:54:06.576233Z","time spent":"349.714193ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:54:06.926159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.13904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-10-26T19:54:06.9262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.937108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-26T19:54:06.926247Z","caller":"traceutil/trace.go:171","msg":"trace[1056389015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18675; }","duration":"222.263373ms","start":"2026-10-26T19:54:06.703963Z","end":"2026-10-26T19:54:06.926226Z","steps":["trace[1056389015] 'agreement among raft nodes before linearized reading' (duration: 221.997857ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:54:06.926258Z","caller":"traceutil/trace.go:171","msg":"trace[405084090] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18675; }","duration":"216.021671ms","start":"2026-10-26T19:54:06.710219Z","end":"2026-10-26T19:54:06.926241Z","steps":["trace[405084090] 'agreement among raft nodes before linearized reading' (duration: 215.850957ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:55:04.442893Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3b717bddf98d48e3","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T19:55:04.44917Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-26T19:55:04.449381Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-26T19:56:01.738694Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17226} {"level":"info","ts":"2026-10-26T19:56:01.848414Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17226,"took":"106.227622ms","hash":603974419,"current-db-size-bytes":56406016,"current-db-size":"56 MB","current-db-size-in-use-bytes":34693120,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-26T19:56:01.84848Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":603974419,"revision":17226,"compact-revision":14641} {"level":"info","ts":"2026-10-26T19:59:40.920782Z","caller":"traceutil/trace.go:171","msg":"trace[1187177486] transaction","detail":"{read_only:false; response_revision:21208; number_of_response:1; }","duration":"118.261754ms","start":"2026-10-26T19:59:40.802489Z","end":"2026-10-26T19:59:40.920751Z","steps":["trace[1187177486] 'process raft request' (duration: 118.076209ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:59:41.080991Z","caller":"traceutil/trace.go:171","msg":"trace[1179695731] transaction","detail":"{read_only:false; response_revision:21209; number_of_response:1; }","duration":"274.726598ms","start":"2026-10-26T19:59:40.806232Z","end":"2026-10-26T19:59:41.080959Z","steps":["trace[1179695731] 'process raft request' (duration: 253.185612ms)","trace[1179695731] 'compare' (duration: 20.995262ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:59:56.75043Z","caller":"traceutil/trace.go:171","msg":"trace[1516931574] transaction","detail":"{read_only:false; response_revision:21288; number_of_response:1; }","duration":"380.364623ms","start":"2026-10-26T19:59:56.370041Z","end":"2026-10-26T19:59:56.750406Z","steps":["trace[1516931574] 'process raft request' (duration: 380.135528ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:59:56.75058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:59:56.370017Z","time spent":"380.485857ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:59:56.790534Z","caller":"traceutil/trace.go:171","msg":"trace[942641959] transaction","detail":"{read_only:false; response_revision:21289; number_of_response:1; }","duration":"379.269597ms","start":"2026-10-26T19:59:56.41124Z","end":"2026-10-26T19:59:56.79051Z","steps":["trace[942641959] 'process raft request' (duration: 379.108843ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:59:56.790681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:59:56.411215Z","time spent":"379.392289ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:59:56.790737Z","caller":"traceutil/trace.go:171","msg":"trace[971919384] transaction","detail":"{read_only:false; response_revision:21290; number_of_response:1; }","duration":"370.939404ms","start":"2026-10-26T19:59:56.419775Z","end":"2026-10-26T19:59:56.790714Z","steps":["trace[971919384] 'process raft request' (duration: 370.694528ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:59:56.790725Z","caller":"traceutil/trace.go:171","msg":"trace[918735528] linearizableReadLoop","detail":"{readStateIndex:22017; appliedIndex:22014; }","duration":"272.289688ms","start":"2026-10-26T19:59:56.518401Z","end":"2026-10-26T19:59:56.79069Z","steps":["trace[918735528] 'read index received' (duration: 231.815452ms)","trace[918735528] 'applied index is now lower than readState.Index' (duration: 40.471496ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:59:56.790839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:59:56.419765Z","time spent":"371.026676ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:59:56.790894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.481033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-26T19:59:56.790941Z","caller":"traceutil/trace.go:171","msg":"trace[443146690] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:21290; }","duration":"272.545805ms","start":"2026-10-26T19:59:56.518378Z","end":"2026-10-26T19:59:56.790924Z","steps":["trace[443146690] 'agreement among raft nodes before linearized reading' (duration: 272.426482ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:59:57.121314Z","caller":"traceutil/trace.go:171","msg":"trace[1466758016] transaction","detail":"{read_only:false; response_revision:21291; number_of_response:1; }","duration":"122.6285ms","start":"2026-10-26T19:59:56.998647Z","end":"2026-10-26T19:59:57.121276Z","steps":["trace[1466758016] 'process raft request' (duration: 122.343853ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:59:57.444333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.881034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T19:59:57.444435Z","caller":"traceutil/trace.go:171","msg":"trace[634133011] linearizableReadLoop","detail":"{readStateIndex:22019; appliedIndex:22018; }","duration":"321.991769ms","start":"2026-10-26T19:59:57.122425Z","end":"2026-10-26T19:59:57.444417Z","steps":["trace[634133011] 'read index received' (duration: 139.926831ms)","trace[634133011] 'applied index is now lower than readState.Index' (duration: 182.063448ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:59:57.44454Z","caller":"traceutil/trace.go:171","msg":"trace[846725988] transaction","detail":"{read_only:false; response_revision:21292; number_of_response:1; }","duration":"355.714331ms","start":"2026-10-26T19:59:57.088814Z","end":"2026-10-26T19:59:57.444529Z","steps":["trace[846725988] 'process raft request' (duration: 173.524209ms)","trace[846725988] 'compare' (duration: 181.741561ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:59:57.444594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:59:57.088788Z","time spent":"355.775763ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:59:57.44468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.009366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-26T19:59:57.444714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.539439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-26T19:59:57.44471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.261906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T19:59:57.444752Z","caller":"traceutil/trace.go:171","msg":"trace[1126618081] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:21292; }","duration":"305.137279ms","start":"2026-10-26T19:59:57.139596Z","end":"2026-10-26T19:59:57.444734Z","steps":["trace[1126618081] 'agreement among raft nodes before linearized reading' (duration: 304.981765ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:59:57.44478Z","caller":"traceutil/trace.go:171","msg":"trace[267596100] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:21292; }","duration":"273.612451ms","start":"2026-10-26T19:59:57.171149Z","end":"2026-10-26T19:59:57.444762Z","steps":["trace[267596100] 'agreement among raft nodes before linearized reading' (duration: 273.479587ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:59:57.444805Z","caller":"traceutil/trace.go:171","msg":"trace[187615318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21292; }","duration":"322.363198ms","start":"2026-10-26T19:59:57.1224Z","end":"2026-10-26T19:59:57.444763Z","steps":["trace[187615318] 'agreement among raft nodes before linearized reading' (duration: 322.230805ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:59:57.444811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:59:57.139576Z","time spent":"305.225162ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-10-26T19:59:57.444792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.530669ms","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":"warn","ts":"2026-10-26T19:59:57.444836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:59:57.122388Z","time spent":"322.43667ms","remote":"127.0.0.1:32854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-26T19:59:57.444869Z","caller":"traceutil/trace.go:171","msg":"trace[351902105] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21292; }","duration":"264.641522ms","start":"2026-10-26T19:59:57.180208Z","end":"2026-10-26T19:59:57.44485Z","steps":["trace[351902105] 'agreement among raft nodes before linearized reading' (duration: 264.415836ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:59:57.575005Z","caller":"traceutil/trace.go:171","msg":"trace[613160979] transaction","detail":"{read_only:false; response_revision:21293; number_of_response:1; }","duration":"125.569102ms","start":"2026-10-26T19:59:57.44941Z","end":"2026-10-26T19:59:57.574979Z","steps":["trace[613160979] 'process raft request' (duration: 125.195183ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:59:58.044102Z","caller":"traceutil/trace.go:171","msg":"trace[578046515] transaction","detail":"{read_only:false; response_revision:21294; number_of_response:1; }","duration":"122.882735ms","start":"2026-10-26T19:59:57.921195Z","end":"2026-10-26T19:59:58.044077Z","steps":["trace[578046515] 'process raft request' (duration: 122.67148ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:59:58.044391Z","caller":"traceutil/trace.go:171","msg":"trace[1434553419] linearizableReadLoop","detail":"{readStateIndex:22021; appliedIndex:22021; }","duration":"114.42905ms","start":"2026-10-26T19:59:57.929918Z","end":"2026-10-26T19:59:58.044347Z","steps":["trace[1434553419] 'read index received' (duration: 114.40889ms)","trace[1434553419] 'applied index is now lower than readState.Index' (duration: 17.37µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:59:58.044646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.702036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-26T19:59:58.044755Z","caller":"traceutil/trace.go:171","msg":"trace[225490700] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21294; }","duration":"114.87332ms","start":"2026-10-26T19:59:57.92986Z","end":"2026-10-26T19:59:58.044733Z","steps":["trace[225490700] 'agreement among raft nodes before linearized reading' (duration: 114.576303ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:59:58.169059Z","caller":"traceutil/trace.go:171","msg":"trace[1344847510] transaction","detail":"{read_only:false; response_revision:21295; number_of_response:1; }","duration":"119.556374ms","start":"2026-10-26T19:59:58.049457Z","end":"2026-10-26T19:59:58.169013Z","steps":["trace[1344847510] 'process raft request' (duration: 84.124281ms)","trace[1344847510] 'compare' (duration: 35.124896ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:01:01.750915Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19732} {"level":"info","ts":"2026-10-26T20:01:01.879228Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19732,"took":"124.468824ms","hash":29686483,"current-db-size-bytes":56406016,"current-db-size":"56 MB","current-db-size-in-use-bytes":30232576,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T20:01:01.879331Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":29686483,"revision":19732,"compact-revision":17226} {"level":"info","ts":"2026-10-26T20:03:54.167624Z","caller":"traceutil/trace.go:171","msg":"trace[985987415] linearizableReadLoop","detail":"{readStateIndex:24627; appliedIndex:24626; }","duration":"129.675672ms","start":"2026-10-26T20:03:54.037921Z","end":"2026-10-26T20:03:54.167597Z","steps":["trace[985987415] 'read index received' (duration: 125.89622ms)","trace[985987415] 'applied index is now lower than readState.Index' (duration: 3.778042ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:03:54.167643Z","caller":"traceutil/trace.go:171","msg":"trace[1091865670] transaction","detail":"{read_only:false; response_revision:23828; number_of_response:1; }","duration":"210.290967ms","start":"2026-10-26T20:03:53.957316Z","end":"2026-10-26T20:03:54.167607Z","steps":["trace[1091865670] 'process raft request' (duration: 206.487854ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:03:54.167756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.816595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:03:54.167788Z","caller":"traceutil/trace.go:171","msg":"trace[2105803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23828; }","duration":"129.861826ms","start":"2026-10-26T20:03:54.037914Z","end":"2026-10-26T20:03:54.167776Z","steps":["trace[2105803] 'agreement among raft nodes before linearized reading' (duration: 129.788684ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:03:54.167859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.558923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12432"} {"level":"info","ts":"2026-10-26T20:03:54.168125Z","caller":"traceutil/trace.go:171","msg":"trace[1882536916] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23827; }","duration":"210.870601ms","start":"2026-10-26T20:03:53.957238Z","end":"2026-10-26T20:03:54.168108Z","steps":["trace[1882536916] 'range keys from in-memory index tree' (duration: 209.913217ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:05:36.453022Z","caller":"traceutil/trace.go:171","msg":"trace[966894726] linearizableReadLoop","detail":"{readStateIndex:25748; appliedIndex:25746; }","duration":"198.868939ms","start":"2026-10-26T20:05:36.25412Z","end":"2026-10-26T20:05:36.452989Z","steps":["trace[966894726] 'read index received' (duration: 55.80348ms)","trace[966894726] 'applied index is now lower than readState.Index' (duration: 143.063929ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:05:36.453249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.121565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:05:36.453777Z","caller":"traceutil/trace.go:171","msg":"trace[1144342662] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:24923; }","duration":"199.32946ms","start":"2026-10-26T20:05:36.254055Z","end":"2026-10-26T20:05:36.453384Z","steps":["trace[1144342662] 'agreement among raft nodes before linearized reading' (duration: 199.107405ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:05:36.454651Z","caller":"traceutil/trace.go:171","msg":"trace[1245751763] transaction","detail":"{read_only:false; response_revision:24923; number_of_response:1; }","duration":"203.048351ms","start":"2026-10-26T20:05:36.250052Z","end":"2026-10-26T20:05:36.4531Z","steps":["trace[1245751763] 'process raft request' (duration: 121.712408ms)","trace[1245751763] 'compare' (duration: 80.78696ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:05:36.82298Z","caller":"traceutil/trace.go:171","msg":"trace[1441751793] transaction","detail":"{read_only:false; response_revision:24924; number_of_response:1; }","duration":"113.701983ms","start":"2026-10-26T20:05:36.709258Z","end":"2026-10-26T20:05:36.82296Z","steps":["trace[1441751793] 'process raft request' (duration: 113.58405ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:05:36.957023Z","caller":"traceutil/trace.go:171","msg":"trace[1012707796] linearizableReadLoop","detail":"{readStateIndex:25750; appliedIndex:25749; }","duration":"130.090712ms","start":"2026-10-26T20:05:36.826893Z","end":"2026-10-26T20:05:36.956984Z","steps":["trace[1012707796] 'read index received' (duration: 54.372696ms)","trace[1012707796] 'applied index is now lower than readState.Index' (duration: 75.716146ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:05:36.957388Z","caller":"traceutil/trace.go:171","msg":"trace[250368018] transaction","detail":"{read_only:false; response_revision:24925; number_of_response:1; }","duration":"162.301828ms","start":"2026-10-26T20:05:36.795052Z","end":"2026-10-26T20:05:36.957353Z","steps":["trace[250368018] 'process raft request' (duration: 86.211333ms)","trace[250368018] 'compare' (duration: 75.445249ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:05:36.957429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.512612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:05:36.957504Z","caller":"traceutil/trace.go:171","msg":"trace[313454273] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24925; }","duration":"130.627495ms","start":"2026-10-26T20:05:36.826857Z","end":"2026-10-26T20:05:36.957484Z","steps":["trace[313454273] 'agreement among raft nodes before linearized reading' (duration: 130.499722ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:06:01.758405Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21987} {"level":"info","ts":"2026-10-26T20:06:01.877786Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21987,"took":"115.659031ms","hash":1567954035,"current-db-size-bytes":57249792,"current-db-size":"57 MB","current-db-size-in-use-bytes":44548096,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-10-26T20:06:01.877858Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1567954035,"revision":21987,"compact-revision":19732} {"level":"warn","ts":"2026-10-26T20:07:28.345916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.633048ms","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-10-26T20:07:28.346063Z","caller":"traceutil/trace.go:171","msg":"trace[83913601] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25950; }","duration":"238.882464ms","start":"2026-10-26T20:07:28.107147Z","end":"2026-10-26T20:07:28.346029Z","steps":["trace[83913601] 'range keys from in-memory index tree' (duration: 238.321311ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:07:28.611402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.068344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:07:28.611493Z","caller":"traceutil/trace.go:171","msg":"trace[1791152639] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:25951; }","duration":"158.30945ms","start":"2026-10-26T20:07:28.45316Z","end":"2026-10-26T20:07:28.61147Z","steps":["trace[1791152639] 'count revisions from in-memory index tree' (duration: 157.86502ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:07:28.778406Z","caller":"traceutil/trace.go:171","msg":"trace[2094272329] transaction","detail":"{read_only:false; response_revision:25952; number_of_response:1; }","duration":"215.512005ms","start":"2026-10-26T20:07:28.562875Z","end":"2026-10-26T20:07:28.778387Z","steps":["trace[2094272329] 'process raft request' (duration: 215.369582ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:07:28.781128Z","caller":"traceutil/trace.go:171","msg":"trace[1852544840] transaction","detail":"{read_only:false; response_revision:25953; number_of_response:1; }","duration":"217.929914ms","start":"2026-10-26T20:07:28.563165Z","end":"2026-10-26T20:07:28.781094Z","steps":["trace[1852544840] 'process raft request' (duration: 217.654658ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:07:28.781118Z","caller":"traceutil/trace.go:171","msg":"trace[1674438502] transaction","detail":"{read_only:false; response_revision:25954; number_of_response:1; }","duration":"164.306696ms","start":"2026-10-26T20:07:28.61678Z","end":"2026-10-26T20:07:28.781087Z","steps":["trace[1674438502] 'process raft request' (duration: 164.220274ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:07:28.781227Z","caller":"traceutil/trace.go:171","msg":"trace[1524896042] linearizableReadLoop","detail":"{readStateIndex:26806; appliedIndex:26803; }","duration":"162.340838ms","start":"2026-10-26T20:07:28.618876Z","end":"2026-10-26T20:07:28.781217Z","steps":["trace[1524896042] 'read index received' (duration: 159.363386ms)","trace[1524896042] 'applied index is now lower than readState.Index' (duration: 2.976632ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:07:28.781308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.41532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:07:28.781389Z","caller":"traceutil/trace.go:171","msg":"trace[1346529604] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:25955; }","duration":"162.526953ms","start":"2026-10-26T20:07:28.618848Z","end":"2026-10-26T20:07:28.781375Z","steps":["trace[1346529604] 'agreement among raft nodes before linearized reading' (duration: 162.41361ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:07:30.98755Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068cd.wal"} {"level":"info","ts":"2026-10-26T20:08:29.828707Z","caller":"traceutil/trace.go:171","msg":"trace[1957265735] transaction","detail":"{read_only:false; response_revision:26503; number_of_response:1; }","duration":"184.54417ms","start":"2026-10-26T20:08:29.644137Z","end":"2026-10-26T20:08:29.828681Z","steps":["trace[1957265735] 'process raft request' (duration: 184.370196ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:08:30.20835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.784196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T20:08:30.208525Z","caller":"traceutil/trace.go:171","msg":"trace[1791174921] linearizableReadLoop","detail":"{readStateIndex:27370; appliedIndex:27368; }","duration":"263.178787ms","start":"2026-10-26T20:08:29.94533Z","end":"2026-10-26T20:08:30.208508Z","steps":["trace[1791174921] 'read index received' (duration: 135.166406ms)","trace[1791174921] 'applied index is now lower than readState.Index' (duration: 128.011351ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:08:30.208865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.519875ms","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-10-26T20:08:30.20877Z","caller":"traceutil/trace.go:171","msg":"trace[1831314250] transaction","detail":"{read_only:false; response_revision:26505; number_of_response:1; }","duration":"308.965644ms","start":"2026-10-26T20:08:29.899765Z","end":"2026-10-26T20:08:30.208731Z","steps":["trace[1831314250] 'process raft request' (duration: 308.685647ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:08:30.208954Z","caller":"traceutil/trace.go:171","msg":"trace[919787834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26505; }","duration":"263.668499ms","start":"2026-10-26T20:08:29.945265Z","end":"2026-10-26T20:08:30.208934Z","steps":["trace[919787834] 'agreement among raft nodes before linearized reading' (duration: 263.32175ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:08:30.208984Z","caller":"traceutil/trace.go:171","msg":"trace[1987240260] transaction","detail":"{read_only:false; response_revision:26506; number_of_response:1; }","duration":"107.258765ms","start":"2026-10-26T20:08:30.101706Z","end":"2026-10-26T20:08:30.208964Z","steps":["trace[1987240260] 'process raft request' (duration: 106.891476ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:08:30.208879Z","caller":"traceutil/trace.go:171","msg":"trace[1620731675] transaction","detail":"{read_only:false; response_revision:26504; number_of_response:1; }","duration":"396.523389ms","start":"2026-10-26T20:08:29.812081Z","end":"2026-10-26T20:08:30.208605Z","steps":["trace[1620731675] 'process raft request' (duration: 268.396555ms)","trace[1620731675] 'compare' (duration: 127.629471ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:08:30.209023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.924713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:08:30.209116Z","caller":"traceutil/trace.go:171","msg":"trace[759066781] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26506; }","duration":"170.052737ms","start":"2026-10-26T20:08:30.039043Z","end":"2026-10-26T20:08:30.209096Z","steps":["trace[759066781] 'agreement among raft nodes before linearized reading' (duration: 169.884652ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:08:30.20913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.652273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:08:30.209249Z","caller":"traceutil/trace.go:171","msg":"trace[1883190246] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:26506; }","duration":"141.842099ms","start":"2026-10-26T20:08:30.067382Z","end":"2026-10-26T20:08:30.209224Z","steps":["trace[1883190246] 'agreement among raft nodes before linearized reading' (duration: 141.625963ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:08:30.209213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:08:29.899739Z","time spent":"309.21521ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:08:30.209417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:08:29.812053Z","time spent":"397.024561ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:08:31.06349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.18795ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-26T20:08:31.063774Z","caller":"traceutil/trace.go:171","msg":"trace[2143773685] linearizableReadLoop","detail":"{readStateIndex:27373; appliedIndex:27372; }","duration":"309.64986ms","start":"2026-10-26T20:08:30.754076Z","end":"2026-10-26T20:08:31.063726Z","steps":["trace[2143773685] 'read index received' (duration: 52.166642ms)","trace[2143773685] 'applied index is now lower than readState.Index' (duration: 257.480508ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:08:31.064134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.042139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-26T20:08:31.064272Z","caller":"traceutil/trace.go:171","msg":"trace[137219983] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26507; }","duration":"310.230344ms","start":"2026-10-26T20:08:30.754026Z","end":"2026-10-26T20:08:31.064256Z","steps":["trace[137219983] 'agreement among raft nodes before linearized reading' (duration: 309.899386ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:08:31.064347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:08:30.754006Z","time spent":"310.326087ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-26T20:08:31.208245Z","caller":"traceutil/trace.go:171","msg":"trace[1384059883] transaction","detail":"{read_only:false; response_revision:26508; number_of_response:1; }","duration":"334.466587ms","start":"2026-10-26T20:08:30.873751Z","end":"2026-10-26T20:08:31.208218Z","steps":["trace[1384059883] 'process raft request' (duration: 334.032136ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:08:31.208414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:08:30.873724Z","time spent":"334.599839ms","remote":"127.0.0.1:47254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419172,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:08:31.209762Z","caller":"traceutil/trace.go:171","msg":"trace[2058344930] transaction","detail":"{read_only:false; response_revision:26509; number_of_response:1; }","duration":"181.091727ms","start":"2026-10-26T20:08:31.028654Z","end":"2026-10-26T20:08:31.209745Z","steps":["trace[2058344930] 'process raft request' (duration: 180.79388ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:08:31.209838Z","caller":"traceutil/trace.go:171","msg":"trace[1261838124] linearizableReadLoop","detail":"{readStateIndex:27377; appliedIndex:27373; }","duration":"145.963171ms","start":"2026-10-26T20:08:31.063849Z","end":"2026-10-26T20:08:31.209812Z","steps":["trace[1261838124] 'read index received' (duration: 143.87853ms)","trace[1261838124] 'applied index is now lower than readState.Index' (duration: 2.083341ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:08:31.209902Z","caller":"traceutil/trace.go:171","msg":"trace[1668970151] transaction","detail":"{read_only:false; response_revision:26510; number_of_response:1; }","duration":"176.981657ms","start":"2026-10-26T20:08:31.032912Z","end":"2026-10-26T20:08:31.209894Z","steps":["trace[1668970151] 'process raft request' (duration: 176.70156ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:08:31.210074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.414257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:08:31.20986Z","caller":"traceutil/trace.go:171","msg":"trace[252673531] transaction","detail":"{read_only:false; response_revision:26511; number_of_response:1; }","duration":"150.926572ms","start":"2026-10-26T20:08:31.058915Z","end":"2026-10-26T20:08:31.209842Z","steps":["trace[252673531] 'process raft request' (duration: 150.747968ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:08:31.210119Z","caller":"traceutil/trace.go:171","msg":"trace[1900167585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26513; }","duration":"170.513549ms","start":"2026-10-26T20:08:31.039594Z","end":"2026-10-26T20:08:31.210107Z","steps":["trace[1900167585] 'agreement among raft nodes before linearized reading' (duration: 170.348285ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:08:31.210103Z","caller":"traceutil/trace.go:171","msg":"trace[1324971552] transaction","detail":"{read_only:false; response_revision:26512; number_of_response:1; }","duration":"141.925793ms","start":"2026-10-26T20:08:31.068133Z","end":"2026-10-26T20:08:31.210059Z","steps":["trace[1324971552] 'process raft request' (duration: 141.598405ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:11:01.765892Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25159} {"level":"info","ts":"2026-10-26T20:11:01.930234Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25159,"took":"161.280812ms","hash":3752848214,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":37462016,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-10-26T20:11:01.930379Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3752848214,"revision":25159,"compact-revision":21987} {"level":"info","ts":"2026-10-26T20:12:50.334539Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3b717bddf98d48e3","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T20:12:50.342834Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-26T20:12:50.342972Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-26T20:16:01.775787Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27969} {"level":"info","ts":"2026-10-26T20:16:01.923823Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27969,"took":"144.36209ms","hash":138074160,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":33325056,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-26T20:16:01.923932Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":138074160,"revision":27969,"compact-revision":25159} {"level":"info","ts":"2026-10-26T20:21:01.785158Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30517} {"level":"info","ts":"2026-10-26T20:21:01.934202Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30517,"took":"144.272702ms","hash":1516176580,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":30621696,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T20:21:01.934296Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1516176580,"revision":30517,"compact-revision":27969} {"level":"info","ts":"2026-10-26T20:26:01.796274Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32772} {"level":"info","ts":"2026-10-26T20:26:01.971982Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32772,"took":"170.865727ms","hash":3361821070,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":30347264,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T20:26:01.972063Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3361821070,"revision":32772,"compact-revision":30517} {"level":"info","ts":"2026-10-26T20:31:01.804327Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35000} {"level":"info","ts":"2026-10-26T20:31:01.938295Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35000,"took":"128.778907ms","hash":1221572779,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":32247808,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T20:31:01.938362Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1221572779,"revision":35000,"compact-revision":32772} {"level":"info","ts":"2026-10-26T20:34:21.886769Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3b717bddf98d48e3","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T20:34:21.89305Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-26T20:34:21.893187Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-26T20:36:01.813592Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37292} {"level":"info","ts":"2026-10-26T20:36:01.958274Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37292,"took":"136.305108ms","hash":3206654830,"current-db-size-bytes":59371520,"current-db-size":"59 MB","current-db-size-in-use-bytes":32833536,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-26T20:36:01.958374Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3206654830,"revision":37292,"compact-revision":35000} {"level":"info","ts":"2026-10-26T20:41:01.820493Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39569} {"level":"info","ts":"2026-10-26T20:41:01.963689Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39569,"took":"138.40311ms","hash":286801536,"current-db-size-bytes":60637184,"current-db-size":"61 MB","current-db-size-in-use-bytes":32088064,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T20:41:01.963803Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":286801536,"revision":39569,"compact-revision":37292} {"level":"info","ts":"2026-10-26T20:46:01.828662Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41802} {"level":"info","ts":"2026-10-26T20:46:01.961302Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41802,"took":"126.263792ms","hash":2440189966,"current-db-size-bytes":60637184,"current-db-size":"61 MB","current-db-size-in-use-bytes":29945856,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T20:46:01.961367Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2440189966,"revision":41802,"compact-revision":39569}