{"level":"warn","ts":"2026-08-14T23:14:55.243397Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-14T23:14:55.243487Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.210:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.210:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-14T23:14:55.243557Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-14T23:14:55.243564Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-08-14T23:14:55.243583Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-14T23:14:55.244179Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-08-14T23:14:55.244247Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.210:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-14T23:14:55.247713Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.246261ms"} {"level":"info","ts":"2026-08-14T23:14:55.252605Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-08-14T23:14:55.252679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-08-14T23:14:55.252696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-08-14T23:14:55.252708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-14T23:14:55.252719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-08-14T23:14:55.252754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-08-14T23:14:55.262162Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-14T23:14:55.264383Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-14T23:14:55.265946Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-14T23:14:55.269293Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-14T23:14:55.269471Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-14T23:14:55.269835Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-14T23:14:55.269937Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-14T23:14:55.269958Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-14T23:14:55.270486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-08-14T23:14:55.270627Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-08-14T23:14:55.276662Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-14T23:14:55.276853Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-08-14T23:14:55.276906Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-14T23:14:55.276911Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-08-14T23:14:55.276943Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-14T23:14:55.753313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-08-14T23:14:55.753423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-14T23:14:55.753463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-08-14T23:14:55.753485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-08-14T23:14:55.753497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-08-14T23:14:55.753513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-08-14T23:14:55.753533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-08-14T23:14:55.754666Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-08-14T23:14:55.7548Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T23:14:55.754899Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T23:14:55.754919Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T23:14:55.755239Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-14T23:14:55.755389Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-14T23:14:55.756224Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T23:14:55.756445Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T23:14:55.756521Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T23:14:55.758039Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"info","ts":"2026-08-14T23:14:55.75989Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-14T23:15:21.462724Z","caller":"traceutil/trace.go:171","msg":"trace[470198811] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"106.597431ms","start":"2026-08-14T23:15:21.35609Z","end":"2026-08-14T23:15:21.462688Z","steps":["trace[470198811] 'process raft request' (duration: 106.429337ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:15:38.289613Z","caller":"traceutil/trace.go:171","msg":"trace[633541855] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"115.012416ms","start":"2026-08-14T23:15:38.17457Z","end":"2026-08-14T23:15:38.289582Z","steps":["trace[633541855] 'process raft request' (duration: 114.389524ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:15:38.508076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.629565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-14T23:15:38.508213Z","caller":"traceutil/trace.go:171","msg":"trace[2098413410] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"114.501876ms","start":"2026-08-14T23:15:38.393693Z","end":"2026-08-14T23:15:38.508195Z","steps":["trace[2098413410] 'read index received' (duration: 41.81µs)","trace[2098413410] 'applied index is now lower than readState.Index' (duration: 114.458556ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:15:38.508241Z","caller":"traceutil/trace.go:171","msg":"trace[2087115319] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"165.357066ms","start":"2026-08-14T23:15:38.342842Z","end":"2026-08-14T23:15:38.508199Z","steps":["trace[2087115319] 'process raft request' (duration: 28.047968ms)","trace[2087115319] 'compare' (duration: 136.482583ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:15:38.50839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.68872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-14T23:15:38.508476Z","caller":"traceutil/trace.go:171","msg":"trace[1859443493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:591; }","duration":"114.778432ms","start":"2026-08-14T23:15:38.393686Z","end":"2026-08-14T23:15:38.508464Z","steps":["trace[1859443493] 'agreement among raft nodes before linearized reading' (duration: 114.560568ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:15:39.09581Z","caller":"traceutil/trace.go:171","msg":"trace[1292965598] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"337.944356ms","start":"2026-08-14T23:15:38.757789Z","end":"2026-08-14T23:15:39.095734Z","steps":["trace[1292965598] 'read index received' (duration: 337.790573ms)","trace[1292965598] 'applied index is now lower than readState.Index' (duration: 151.942µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:15:39.095959Z","caller":"traceutil/trace.go:171","msg":"trace[149849124] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"420.281438ms","start":"2026-08-14T23:15:38.675621Z","end":"2026-08-14T23:15:39.095903Z","steps":["trace[149849124] 'process raft request' (duration: 417.918784ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:15:39.096072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.265882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T23:15:39.096123Z","caller":"traceutil/trace.go:171","msg":"trace[318438112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"338.335473ms","start":"2026-08-14T23:15:38.757773Z","end":"2026-08-14T23:15:39.096109Z","steps":["trace[318438112] 'agreement among raft nodes before linearized reading' (duration: 338.17111ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:15:39.096214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:15:38.757706Z","time spent":"338.486036ms","remote":"127.0.0.1:45874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-14T23:15:39.096431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:15:38.675593Z","time spent":"420.477151ms","remote":"127.0.0.1:46074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T23:15:39.109993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.111731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-14T23:15:39.110086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.682766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-14T23:15:39.110125Z","caller":"traceutil/trace.go:171","msg":"trace[1642256346] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:594; }","duration":"303.275875ms","start":"2026-08-14T23:15:38.806823Z","end":"2026-08-14T23:15:39.110099Z","steps":["trace[1642256346] 'agreement among raft nodes before linearized reading' (duration: 303.088341ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:15:39.110158Z","caller":"traceutil/trace.go:171","msg":"trace[821113905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:594; }","duration":"208.815769ms","start":"2026-08-14T23:15:38.901323Z","end":"2026-08-14T23:15:39.110138Z","steps":["trace[821113905] 'agreement among raft nodes before linearized reading' (duration: 208.580194ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:15:39.110178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:15:38.806812Z","time spent":"303.355756ms","remote":"127.0.0.1:45916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.210\" "} {"level":"info","ts":"2026-08-14T23:15:39.110366Z","caller":"traceutil/trace.go:171","msg":"trace[1269312845] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"112.919477ms","start":"2026-08-14T23:15:38.997235Z","end":"2026-08-14T23:15:39.110155Z","steps":["trace[1269312845] 'process raft request' (duration: 112.038731ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:17:11.008143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.106778ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T23:17:11.008442Z","caller":"traceutil/trace.go:171","msg":"trace[237213258] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"132.531594ms","start":"2026-08-14T23:17:10.875885Z","end":"2026-08-14T23:17:11.008417Z","steps":["trace[237213258] 'process raft request' (duration: 132.421381ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:17:11.008523Z","caller":"traceutil/trace.go:171","msg":"trace[1582435546] linearizableReadLoop","detail":"{readStateIndex:1884; appliedIndex:1883; }","duration":"163.430795ms","start":"2026-08-14T23:17:10.845052Z","end":"2026-08-14T23:17:11.008482Z","steps":["trace[1582435546] 'read index received' (duration: 54.834898ms)","trace[1582435546] 'applied index is now lower than readState.Index' (duration: 108.593457ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:17:11.008619Z","caller":"traceutil/trace.go:171","msg":"trace[604661764] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"198.008306ms","start":"2026-08-14T23:17:10.810509Z","end":"2026-08-14T23:17:11.008517Z","steps":["trace[604661764] 'process raft request' (duration: 89.387489ms)","trace[604661764] 'compare' (duration: 107.829483ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:17:11.008851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.767621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-14T23:17:11.008901Z","caller":"traceutil/trace.go:171","msg":"trace[1496065300] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1817; }","duration":"163.895823ms","start":"2026-08-14T23:17:10.844992Z","end":"2026-08-14T23:17:11.008888Z","steps":["trace[1496065300] 'agreement among raft nodes before linearized reading' (duration: 163.614808ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:17:11.19153Z","caller":"traceutil/trace.go:171","msg":"trace[296874285] linearizableReadLoop","detail":"{readStateIndex:1886; appliedIndex:1885; }","duration":"161.012449ms","start":"2026-08-14T23:17:11.030489Z","end":"2026-08-14T23:17:11.191502Z","steps":["trace[296874285] 'read index received' (duration: 126.624321ms)","trace[296874285] 'applied index is now lower than readState.Index' (duration: 34.386598ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:17:11.191574Z","caller":"traceutil/trace.go:171","msg":"trace[240208344] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"177.559094ms","start":"2026-08-14T23:17:11.013973Z","end":"2026-08-14T23:17:11.191532Z","steps":["trace[240208344] 'process raft request' (duration: 143.114635ms)","trace[240208344] 'compare' (duration: 33.658134ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:17:11.191772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.263833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-14T23:17:11.191849Z","caller":"traceutil/trace.go:171","msg":"trace[1367034585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1818; }","duration":"161.371765ms","start":"2026-08-14T23:17:11.030456Z","end":"2026-08-14T23:17:11.191827Z","steps":["trace[1367034585] 'agreement among raft nodes before linearized reading' (duration: 161.155761ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:17:11.326179Z","caller":"traceutil/trace.go:171","msg":"trace[1657539999] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"125.956681ms","start":"2026-08-14T23:17:11.20019Z","end":"2026-08-14T23:17:11.326147Z","steps":["trace[1657539999] 'process raft request' (duration: 125.694456ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:17:11.326333Z","caller":"traceutil/trace.go:171","msg":"trace[1545638759] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"126.862378ms","start":"2026-08-14T23:17:11.199415Z","end":"2026-08-14T23:17:11.326278Z","steps":["trace[1545638759] 'process raft request' (duration: 117.409049ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:18:50.386626Z","caller":"traceutil/trace.go:171","msg":"trace[918161036] transaction","detail":"{read_only:false; response_revision:2586; number_of_response:1; }","duration":"100.951106ms","start":"2026-08-14T23:18:50.285658Z","end":"2026-08-14T23:18:50.386609Z","steps":["trace[918161036] 'process raft request' (duration: 100.842314ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:19:24.471668Z","caller":"traceutil/trace.go:171","msg":"trace[1546613076] transaction","detail":"{read_only:false; response_revision:2791; number_of_response:1; }","duration":"171.997178ms","start":"2026-08-14T23:19:24.299631Z","end":"2026-08-14T23:19:24.471628Z","steps":["trace[1546613076] 'process raft request' (duration: 164.694128ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:19:24.78929Z","caller":"traceutil/trace.go:171","msg":"trace[1129705476] transaction","detail":"{read_only:false; response_revision:2792; number_of_response:1; }","duration":"124.138009ms","start":"2026-08-14T23:19:24.665108Z","end":"2026-08-14T23:19:24.789246Z","steps":["trace[1129705476] 'process raft request' (duration: 123.934434ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:23:11.0789Z","caller":"traceutil/trace.go:171","msg":"trace[154684785] linearizableReadLoop","detail":"{readStateIndex:4851; appliedIndex:4850; }","duration":"142.22925ms","start":"2026-08-14T23:23:10.936644Z","end":"2026-08-14T23:23:11.078874Z","steps":["trace[154684785] 'read index received' (duration: 67.675788ms)","trace[154684785] 'applied index is now lower than readState.Index' (duration: 74.552422ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:23:11.078953Z","caller":"traceutil/trace.go:171","msg":"trace[1724040463] transaction","detail":"{read_only:false; response_revision:4673; number_of_response:1; }","duration":"142.344152ms","start":"2026-08-14T23:23:10.936577Z","end":"2026-08-14T23:23:11.078921Z","steps":["trace[1724040463] 'process raft request' (duration: 67.789679ms)","trace[1724040463] 'compare' (duration: 74.37839ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:23:11.07917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.940435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-08-14T23:23:11.079212Z","caller":"traceutil/trace.go:171","msg":"trace[427689888] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4673; }","duration":"136.050927ms","start":"2026-08-14T23:23:10.943149Z","end":"2026-08-14T23:23:11.0792Z","steps":["trace[427689888] 'agreement among raft nodes before linearized reading' (duration: 135.883674ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:23:11.079207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.528085ms","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-08-14T23:23:11.079291Z","caller":"traceutil/trace.go:171","msg":"trace[1217033850] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4673; }","duration":"142.668478ms","start":"2026-08-14T23:23:10.936602Z","end":"2026-08-14T23:23:11.07927Z","steps":["trace[1217033850] 'agreement among raft nodes before linearized reading' (duration: 142.390983ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:23:11.079448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.704104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-08-14T23:23:11.07952Z","caller":"traceutil/trace.go:171","msg":"trace[1397225544] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4673; }","duration":"112.826536ms","start":"2026-08-14T23:23:10.966672Z","end":"2026-08-14T23:23:11.079499Z","steps":["trace[1397225544] 'agreement among raft nodes before linearized reading' (duration: 112.688784ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:23:19.110969Z","caller":"traceutil/trace.go:171","msg":"trace[1927383732] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"130.967166ms","start":"2026-08-14T23:23:18.979973Z","end":"2026-08-14T23:23:19.110941Z","steps":["trace[1927383732] 'process raft request' (duration: 130.751932ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:23:19.292036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.04951ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-14T23:23:19.292154Z","caller":"traceutil/trace.go:171","msg":"trace[1419827115] linearizableReadLoop","detail":"{readStateIndex:5100; appliedIndex:5099; }","duration":"166.692724ms","start":"2026-08-14T23:23:19.125441Z","end":"2026-08-14T23:23:19.292134Z","steps":["trace[1419827115] 'read index received' (duration: 36.41156ms)","trace[1419827115] 'applied index is now lower than readState.Index' (duration: 130.279664ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:23:19.292238Z","caller":"traceutil/trace.go:171","msg":"trace[1881203123] transaction","detail":"{read_only:false; response_revision:4915; number_of_response:1; }","duration":"173.806842ms","start":"2026-08-14T23:23:19.118396Z","end":"2026-08-14T23:23:19.292203Z","steps":["trace[1881203123] 'process raft request' (duration: 43.514608ms)","trace[1881203123] 'compare' (duration: 129.908707ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:23:19.292393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.476619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-14T23:23:19.292437Z","caller":"traceutil/trace.go:171","msg":"trace[1151628324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4915; }","duration":"145.54797ms","start":"2026-08-14T23:23:19.146877Z","end":"2026-08-14T23:23:19.292425Z","steps":["trace[1151628324] 'agreement among raft nodes before linearized reading' (duration: 145.360157ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:23:19.292429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.956058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-14T23:23:19.292513Z","caller":"traceutil/trace.go:171","msg":"trace[1434448157] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4915; }","duration":"167.109471ms","start":"2026-08-14T23:23:19.125386Z","end":"2026-08-14T23:23:19.292495Z","steps":["trace[1434448157] 'agreement among raft nodes before linearized reading' (duration: 166.813526ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:23:19.427893Z","caller":"traceutil/trace.go:171","msg":"trace[1971661507] linearizableReadLoop","detail":"{readStateIndex:5103; appliedIndex:5100; }","duration":"103.10776ms","start":"2026-08-14T23:23:19.324763Z","end":"2026-08-14T23:23:19.427871Z","steps":["trace[1971661507] 'read index received' (duration: 54.134572ms)","trace[1971661507] 'applied index is now lower than readState.Index' (duration: 48.972098ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:23:19.427939Z","caller":"traceutil/trace.go:171","msg":"trace[643989389] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"131.146528ms","start":"2026-08-14T23:23:19.29676Z","end":"2026-08-14T23:23:19.427907Z","steps":["trace[643989389] 'process raft request' (duration: 130.918994ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:23:19.42799Z","caller":"traceutil/trace.go:171","msg":"trace[433981122] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"131.183149ms","start":"2026-08-14T23:23:19.296766Z","end":"2026-08-14T23:23:19.427949Z","steps":["trace[433981122] 'process raft request' (duration: 131.052517ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:23:19.428196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.418035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wdq9g\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-14T23:23:19.428258Z","caller":"traceutil/trace.go:171","msg":"trace[292702120] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wdq9g; range_end:; response_count:1; response_revision:4918; }","duration":"103.528778ms","start":"2026-08-14T23:23:19.324715Z","end":"2026-08-14T23:23:19.428244Z","steps":["trace[292702120] 'agreement among raft nodes before linearized reading' (duration: 103.295854ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:23:23.253265Z","caller":"traceutil/trace.go:171","msg":"trace[85308611] linearizableReadLoop","detail":"{readStateIndex:5219; appliedIndex:5218; }","duration":"172.756664ms","start":"2026-08-14T23:23:23.080481Z","end":"2026-08-14T23:23:23.253238Z","steps":["trace[85308611] 'read index received' (duration: 102.466549ms)","trace[85308611] 'applied index is now lower than readState.Index' (duration: 70.288815ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:23:23.253478Z","caller":"traceutil/trace.go:171","msg":"trace[1223209849] transaction","detail":"{read_only:false; response_revision:5029; number_of_response:1; }","duration":"173.747991ms","start":"2026-08-14T23:23:23.079715Z","end":"2026-08-14T23:23:23.253463Z","steps":["trace[1223209849] 'process raft request' (duration: 103.229322ms)","trace[1223209849] 'compare' (duration: 70.159963ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:23:23.253715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.208112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-08-14T23:23:23.253803Z","caller":"traceutil/trace.go:171","msg":"trace[190636748] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:5029; }","duration":"173.307904ms","start":"2026-08-14T23:23:23.080478Z","end":"2026-08-14T23:23:23.253786Z","steps":["trace[190636748] 'agreement among raft nodes before linearized reading' (duration: 173.08973ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:23:23.253938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.043627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-08-14T23:23:23.25409Z","caller":"traceutil/trace.go:171","msg":"trace[1462028830] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5029; }","duration":"136.23398ms","start":"2026-08-14T23:23:23.117805Z","end":"2026-08-14T23:23:23.254039Z","steps":["trace[1462028830] 'agreement among raft nodes before linearized reading' (duration: 135.788672ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:23:32.455059Z","caller":"traceutil/trace.go:171","msg":"trace[1671778083] transaction","detail":"{read_only:false; response_revision:5206; number_of_response:1; }","duration":"118.155534ms","start":"2026-08-14T23:23:32.336863Z","end":"2026-08-14T23:23:32.455019Z","steps":["trace[1671778083] 'process raft request' (duration: 117.879219ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:23:40.82777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.547519ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T23:23:40.828121Z","caller":"traceutil/trace.go:171","msg":"trace[1988029581] transaction","detail":"{read_only:false; response_revision:5386; number_of_response:1; }","duration":"319.199419ms","start":"2026-08-14T23:23:40.508897Z","end":"2026-08-14T23:23:40.828097Z","steps":["trace[1988029581] 'process raft request' (duration: 114.220292ms)","trace[1988029581] 'compare' (duration: 204.404566ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:23:40.828131Z","caller":"traceutil/trace.go:171","msg":"trace[710013892] linearizableReadLoop","detail":"{readStateIndex:5587; appliedIndex:5585; }","duration":"317.629731ms","start":"2026-08-14T23:23:40.510471Z","end":"2026-08-14T23:23:40.828101Z","steps":["trace[710013892] 'read index received' (duration: 79.53899ms)","trace[710013892] 'applied index is now lower than readState.Index' (duration: 238.08827ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:23:40.828197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:23:40.508869Z","time spent":"319.295932ms","remote":"127.0.0.1:46144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T23:23:40.828392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.906305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a8452b37-4927-4b0f-bee0-2a297c499311\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-14T23:23:40.828437Z","caller":"traceutil/trace.go:171","msg":"trace[1491315439] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a8452b37-4927-4b0f-bee0-2a297c499311; range_end:; response_count:1; response_revision:5387; }","duration":"318.007898ms","start":"2026-08-14T23:23:40.510417Z","end":"2026-08-14T23:23:40.828425Z","steps":["trace[1491315439] 'agreement among raft nodes before linearized reading' (duration: 317.804304ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:23:40.828476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:23:40.510393Z","time spent":"318.074158ms","remote":"127.0.0.1:46074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a8452b37-4927-4b0f-bee0-2a297c499311\" "} {"level":"info","ts":"2026-08-14T23:24:31.898434Z","caller":"traceutil/trace.go:171","msg":"trace[1899291396] linearizableReadLoop","detail":"{readStateIndex:6089; appliedIndex:6088; }","duration":"100.770575ms","start":"2026-08-14T23:24:31.797633Z","end":"2026-08-14T23:24:31.898404Z","steps":["trace[1899291396] 'read index received' (duration: 11.448128ms)","trace[1899291396] 'applied index is now lower than readState.Index' (duration: 89.321727ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:24:31.898589Z","caller":"traceutil/trace.go:171","msg":"trace[1950963326] transaction","detail":"{read_only:false; response_revision:5876; number_of_response:1; }","duration":"104.048418ms","start":"2026-08-14T23:24:31.794492Z","end":"2026-08-14T23:24:31.89854Z","steps":["trace[1950963326] 'process raft request' (duration: 14.576188ms)","trace[1950963326] 'compare' (duration: 89.125403ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:24:31.898885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.227674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-14T23:24:31.89899Z","caller":"traceutil/trace.go:171","msg":"trace[579101389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5877; }","duration":"101.381827ms","start":"2026-08-14T23:24:31.797589Z","end":"2026-08-14T23:24:31.898971Z","steps":["trace[579101389] 'agreement among raft nodes before linearized reading' (duration: 101.048251ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:24:46.976104Z","caller":"traceutil/trace.go:171","msg":"trace[370298906] linearizableReadLoop","detail":"{readStateIndex:6207; appliedIndex:6205; }","duration":"129.933387ms","start":"2026-08-14T23:24:46.846138Z","end":"2026-08-14T23:24:46.976071Z","steps":["trace[370298906] 'read index received' (duration: 47.204705ms)","trace[370298906] 'applied index is now lower than readState.Index' (duration: 82.727762ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:24:46.976294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.1313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T23:24:46.976253Z","caller":"traceutil/trace.go:171","msg":"trace[869946415] transaction","detail":"{read_only:false; response_revision:5991; number_of_response:1; }","duration":"154.025504ms","start":"2026-08-14T23:24:46.822154Z","end":"2026-08-14T23:24:46.97618Z","steps":["trace[869946415] 'process raft request' (duration: 149.731011ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:24:46.976412Z","caller":"traceutil/trace.go:171","msg":"trace[2083025849] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:5991; }","duration":"130.337295ms","start":"2026-08-14T23:24:46.846047Z","end":"2026-08-14T23:24:46.976384Z","steps":["trace[2083025849] 'agreement among raft nodes before linearized reading' (duration: 130.155591ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:24:55.786642Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2960} {"level":"info","ts":"2026-08-14T23:24:55.860031Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2960,"took":"71.068058ms","hash":1362236909,"current-db-size-bytes":58576896,"current-db-size":"59 MB","current-db-size-in-use-bytes":34127872,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-14T23:24:55.86012Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1362236909,"revision":2960,"compact-revision":-1} {"level":"warn","ts":"2026-08-14T23:29:39.551476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.64487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-14T23:29:39.551534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.924095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-14T23:29:39.551578Z","caller":"traceutil/trace.go:171","msg":"trace[263174109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8171; }","duration":"112.802392ms","start":"2026-08-14T23:29:39.438755Z","end":"2026-08-14T23:29:39.551558Z","steps":["trace[263174109] 'range keys from in-memory index tree' (duration: 112.392654ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:29:39.551633Z","caller":"traceutil/trace.go:171","msg":"trace[242985681] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8171; }","duration":"112.078388ms","start":"2026-08-14T23:29:39.43953Z","end":"2026-08-14T23:29:39.551608Z","steps":["trace[242985681] 'range keys from in-memory index tree' (duration: 111.711691ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:29:52.154936Z","caller":"traceutil/trace.go:171","msg":"trace[1127470420] linearizableReadLoop","detail":"{readStateIndex:8530; appliedIndex:8529; }","duration":"144.69482ms","start":"2026-08-14T23:29:52.010208Z","end":"2026-08-14T23:29:52.154903Z","steps":["trace[1127470420] 'read index received' (duration: 144.419335ms)","trace[1127470420] 'applied index is now lower than readState.Index' (duration: 273.875µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:29:52.155053Z","caller":"traceutil/trace.go:171","msg":"trace[911439889] transaction","detail":"{read_only:false; response_revision:8241; number_of_response:1; }","duration":"159.737668ms","start":"2026-08-14T23:29:51.995276Z","end":"2026-08-14T23:29:52.155013Z","steps":["trace[911439889] 'process raft request' (duration: 159.440082ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:29:52.155118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.893764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-14T23:29:52.155156Z","caller":"traceutil/trace.go:171","msg":"trace[778970189] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8241; }","duration":"144.987966ms","start":"2026-08-14T23:29:52.010158Z","end":"2026-08-14T23:29:52.155145Z","steps":["trace[778970189] 'agreement among raft nodes before linearized reading' (duration: 144.899444ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:29:55.792446Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6061} {"level":"info","ts":"2026-08-14T23:29:55.884328Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6061,"took":"89.894315ms","hash":603256104,"current-db-size-bytes":58576896,"current-db-size":"59 MB","current-db-size-in-use-bytes":23375872,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-14T23:29:55.8844Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":603256104,"revision":6061,"compact-revision":2960} {"level":"warn","ts":"2026-08-14T23:30:32.212048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.80252ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T23:30:32.212162Z","caller":"traceutil/trace.go:171","msg":"trace[1791857863] linearizableReadLoop","detail":"{readStateIndex:8855; appliedIndex:8854; }","duration":"243.066755ms","start":"2026-08-14T23:30:31.969077Z","end":"2026-08-14T23:30:32.212144Z","steps":["trace[1791857863] 'read index received' (duration: 40.019551ms)","trace[1791857863] 'applied index is now lower than readState.Index' (duration: 203.046094ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:30:32.212365Z","caller":"traceutil/trace.go:171","msg":"trace[1907774264] transaction","detail":"{read_only:false; response_revision:8552; number_of_response:1; }","duration":"247.264315ms","start":"2026-08-14T23:30:31.965078Z","end":"2026-08-14T23:30:32.212342Z","steps":["trace[1907774264] 'process raft request' (duration: 44.070558ms)","trace[1907774264] 'compare' (duration: 202.700638ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:30:32.212496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.412592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228451"} {"level":"info","ts":"2026-08-14T23:30:32.212535Z","caller":"traceutil/trace.go:171","msg":"trace[555853903] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8552; }","duration":"243.454403ms","start":"2026-08-14T23:30:31.96907Z","end":"2026-08-14T23:30:32.212525Z","steps":["trace[555853903] 'agreement among raft nodes before linearized reading' (duration: 243.120486ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:30:32.812904Z","caller":"traceutil/trace.go:171","msg":"trace[543224119] transaction","detail":"{read_only:false; response_revision:8557; number_of_response:1; }","duration":"109.937552ms","start":"2026-08-14T23:30:32.70294Z","end":"2026-08-14T23:30:32.812877Z","steps":["trace[543224119] 'process raft request' (duration: 109.770139ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:30:32.851167Z","caller":"traceutil/trace.go:171","msg":"trace[1592093115] transaction","detail":"{read_only:false; response_revision:8558; number_of_response:1; }","duration":"135.400086ms","start":"2026-08-14T23:30:32.715748Z","end":"2026-08-14T23:30:32.851148Z","steps":["trace[1592093115] 'process raft request' (duration: 135.196312ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:30:33.556021Z","caller":"traceutil/trace.go:171","msg":"trace[754797438] transaction","detail":"{read_only:false; response_revision:8559; number_of_response:1; }","duration":"141.220367ms","start":"2026-08-14T23:30:33.414774Z","end":"2026-08-14T23:30:33.555995Z","steps":["trace[754797438] 'process raft request' (duration: 141.046134ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:30:33.927362Z","caller":"traceutil/trace.go:171","msg":"trace[2056350091] transaction","detail":"{read_only:false; response_revision:8561; number_of_response:1; }","duration":"148.020846ms","start":"2026-08-14T23:30:33.779301Z","end":"2026-08-14T23:30:33.927322Z","steps":["trace[2056350091] 'process raft request' (duration: 147.69402ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:30:33.959375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.346133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-14T23:30:33.959437Z","caller":"traceutil/trace.go:171","msg":"trace[2142924433] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8561; }","duration":"107.442025ms","start":"2026-08-14T23:30:33.851981Z","end":"2026-08-14T23:30:33.959423Z","steps":["trace[2142924433] 'agreement among raft nodes before linearized reading' (duration: 107.264761ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:30:33.959999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.328826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-14T23:30:33.960026Z","caller":"traceutil/trace.go:171","msg":"trace[976197024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8561; }","duration":"103.356237ms","start":"2026-08-14T23:30:33.856663Z","end":"2026-08-14T23:30:33.960019Z","steps":["trace[976197024] 'agreement among raft nodes before linearized reading' (duration: 103.299546ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:31:16.556631Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000243b.wal"} {"level":"info","ts":"2026-08-14T23:32:36.682778Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1cac722af8b2b8a6","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-14T23:32:36.688362Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-14T23:32:36.688487Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-14T23:34:55.799116Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8268} {"level":"info","ts":"2026-08-14T23:34:55.876306Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8268,"took":"75.170017ms","hash":1315240063,"current-db-size-bytes":58576896,"current-db-size":"59 MB","current-db-size-in-use-bytes":26443776,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-14T23:34:55.876383Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1315240063,"revision":8268,"compact-revision":6061} {"level":"info","ts":"2026-08-14T23:35:27.60927Z","caller":"traceutil/trace.go:171","msg":"trace[1169310347] linearizableReadLoop","detail":"{readStateIndex:11505; appliedIndex:11504; }","duration":"200.320734ms","start":"2026-08-14T23:35:27.408927Z","end":"2026-08-14T23:35:27.609248Z","steps":["trace[1169310347] 'read index received' (duration: 200.245612ms)","trace[1169310347] 'applied index is now lower than readState.Index' (duration: 73.672µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:35:27.60944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.494066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T23:35:27.609404Z","caller":"traceutil/trace.go:171","msg":"trace[1134043836] transaction","detail":"{read_only:false; response_revision:11128; number_of_response:1; }","duration":"229.755541ms","start":"2026-08-14T23:35:27.379575Z","end":"2026-08-14T23:35:27.609331Z","steps":["trace[1134043836] 'process raft request' (duration: 229.512557ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:35:27.609491Z","caller":"traceutil/trace.go:171","msg":"trace[379103599] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:11128; }","duration":"200.592589ms","start":"2026-08-14T23:35:27.408883Z","end":"2026-08-14T23:35:27.609475Z","steps":["trace[379103599] 'agreement among raft nodes before linearized reading' (duration: 200.495247ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:35:27.617361Z","caller":"traceutil/trace.go:171","msg":"trace[1545794373] transaction","detail":"{read_only:false; response_revision:11129; number_of_response:1; }","duration":"127.154023ms","start":"2026-08-14T23:35:27.490178Z","end":"2026-08-14T23:35:27.617332Z","steps":["trace[1545794373] 'process raft request' (duration: 126.99307ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:35:27.61772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.039902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-08-14T23:35:27.617802Z","caller":"traceutil/trace.go:171","msg":"trace[1590094909] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11131; }","duration":"206.170995ms","start":"2026-08-14T23:35:27.411613Z","end":"2026-08-14T23:35:27.617784Z","steps":["trace[1590094909] 'agreement among raft nodes before linearized reading' (duration: 205.963721ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:35:27.617852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.125525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T23:35:27.61791Z","caller":"traceutil/trace.go:171","msg":"trace[594596245] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11131; }","duration":"205.215377ms","start":"2026-08-14T23:35:27.41268Z","end":"2026-08-14T23:35:27.617896Z","steps":["trace[594596245] 'agreement among raft nodes before linearized reading' (duration: 205.119595ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:35:27.618102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.458872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-08-14T23:35:27.618185Z","caller":"traceutil/trace.go:171","msg":"trace[1664035471] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11131; }","duration":"205.540433ms","start":"2026-08-14T23:35:27.412633Z","end":"2026-08-14T23:35:27.618174Z","steps":["trace[1664035471] 'agreement among raft nodes before linearized reading' (duration: 205.37035ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:35:27.61831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.613619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T23:35:27.61837Z","caller":"traceutil/trace.go:171","msg":"trace[399702101] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11131; }","duration":"200.69392ms","start":"2026-08-14T23:35:27.417661Z","end":"2026-08-14T23:35:27.618355Z","steps":["trace[399702101] 'agreement among raft nodes before linearized reading' (duration: 200.601279ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:35:27.618424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.395738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T23:35:27.618483Z","caller":"traceutil/trace.go:171","msg":"trace[1644147247] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11131; }","duration":"206.495821ms","start":"2026-08-14T23:35:27.411974Z","end":"2026-08-14T23:35:27.61847Z","steps":["trace[1644147247] 'agreement among raft nodes before linearized reading' (duration: 206.398609ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:35:27.61785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.911251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"warn","ts":"2026-08-14T23:35:27.6186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.20081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T23:35:27.618636Z","caller":"traceutil/trace.go:171","msg":"trace[1708092071] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11131; }","duration":"201.287451ms","start":"2026-08-14T23:35:27.417339Z","end":"2026-08-14T23:35:27.618627Z","steps":["trace[1708092071] 'agreement among raft nodes before linearized reading' (duration: 201.238781ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:35:27.618635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.012241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T23:35:27.618646Z","caller":"traceutil/trace.go:171","msg":"trace[1055766195] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11131; }","duration":"195.678276ms","start":"2026-08-14T23:35:27.4229Z","end":"2026-08-14T23:35:27.618579Z","steps":["trace[1055766195] 'agreement among raft nodes before linearized reading' (duration: 194.809199ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:35:27.618696Z","caller":"traceutil/trace.go:171","msg":"trace[1029363221] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11131; }","duration":"205.093183ms","start":"2026-08-14T23:35:27.413586Z","end":"2026-08-14T23:35:27.618679Z","steps":["trace[1029363221] 'agreement among raft nodes before linearized reading' (duration: 205.008702ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:35:27.618826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.111388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T23:35:27.618813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.914499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T23:35:27.618934Z","caller":"traceutil/trace.go:171","msg":"trace[708948651] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11131; }","duration":"201.254851ms","start":"2026-08-14T23:35:27.417654Z","end":"2026-08-14T23:35:27.618909Z","steps":["trace[708948651] 'agreement among raft nodes before linearized reading' (duration: 200.991406ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:35:27.619102Z","caller":"traceutil/trace.go:171","msg":"trace[1313166607] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11131; }","duration":"206.546531ms","start":"2026-08-14T23:35:27.412377Z","end":"2026-08-14T23:35:27.618924Z","steps":["trace[1313166607] 'agreement among raft nodes before linearized reading' (duration: 205.761226ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:35:27.619171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.46906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T23:35:27.619261Z","caller":"traceutil/trace.go:171","msg":"trace[208821882] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11131; }","duration":"206.773516ms","start":"2026-08-14T23:35:27.412468Z","end":"2026-08-14T23:35:27.619242Z","steps":["trace[208821882] 'agreement among raft nodes before linearized reading' (duration: 205.810218ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:35:27.726407Z","caller":"traceutil/trace.go:171","msg":"trace[1015711522] transaction","detail":"{read_only:false; response_revision:11133; number_of_response:1; }","duration":"102.410583ms","start":"2026-08-14T23:35:27.623969Z","end":"2026-08-14T23:35:27.72638Z","steps":["trace[1015711522] 'process raft request' (duration: 102.197979ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:35:27.727009Z","caller":"traceutil/trace.go:171","msg":"trace[90634457] transaction","detail":"{read_only:false; response_revision:11134; number_of_response:1; }","duration":"102.222941ms","start":"2026-08-14T23:35:27.624766Z","end":"2026-08-14T23:35:27.726989Z","steps":["trace[90634457] 'process raft request' (duration: 101.563757ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:35:27.727108Z","caller":"traceutil/trace.go:171","msg":"trace[13259804] transaction","detail":"{read_only:false; response_revision:11136; number_of_response:1; }","duration":"102.081398ms","start":"2026-08-14T23:35:27.625005Z","end":"2026-08-14T23:35:27.727087Z","steps":["trace[13259804] 'process raft request' (duration: 102.019357ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:35:27.727158Z","caller":"traceutil/trace.go:171","msg":"trace[1463627527] transaction","detail":"{read_only:false; response_revision:11135; number_of_response:1; }","duration":"102.109739ms","start":"2026-08-14T23:35:27.624973Z","end":"2026-08-14T23:35:27.727083Z","steps":["trace[1463627527] 'process raft request' (duration: 101.931036ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:39:55.805782Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10922} {"level":"info","ts":"2026-08-14T23:39:55.886233Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10922,"took":"77.958473ms","hash":3809527716,"current-db-size-bytes":58576896,"current-db-size":"59 MB","current-db-size-in-use-bytes":27529216,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-14T23:39:55.886334Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3809527716,"revision":10922,"compact-revision":8268} {"level":"info","ts":"2026-08-14T23:41:47.690361Z","caller":"traceutil/trace.go:171","msg":"trace[1993554870] transaction","detail":"{read_only:false; response_revision:13697; number_of_response:1; }","duration":"175.831443ms","start":"2026-08-14T23:41:47.5145Z","end":"2026-08-14T23:41:47.690331Z","steps":["trace[1993554870] 'process raft request' (duration: 131.807046ms)","trace[1993554870] 'compare' (duration: 43.839584ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:41:57.940142Z","caller":"traceutil/trace.go:171","msg":"trace[892538844] linearizableReadLoop","detail":"{readStateIndex:14334; appliedIndex:14333; }","duration":"181.123014ms","start":"2026-08-14T23:41:57.758993Z","end":"2026-08-14T23:41:57.940117Z","steps":["trace[892538844] 'read index received' (duration: 181.074223ms)","trace[892538844] 'applied index is now lower than readState.Index' (duration: 47.731µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:41:57.940274Z","caller":"traceutil/trace.go:171","msg":"trace[259835569] transaction","detail":"{read_only:false; response_revision:13864; number_of_response:1; }","duration":"286.390225ms","start":"2026-08-14T23:41:57.653873Z","end":"2026-08-14T23:41:57.940263Z","steps":["trace[259835569] 'process raft request' (duration: 286.066128ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:41:57.940583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.576793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-14T23:41:57.940674Z","caller":"traceutil/trace.go:171","msg":"trace[1618410181] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13864; }","duration":"181.713845ms","start":"2026-08-14T23:41:57.758941Z","end":"2026-08-14T23:41:57.940655Z","steps":["trace[1618410181] 'agreement among raft nodes before linearized reading' (duration: 181.512651ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:41:57.947463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.96035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-14T23:41:57.947544Z","caller":"traceutil/trace.go:171","msg":"trace[1983213395] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13865; }","duration":"181.217585ms","start":"2026-08-14T23:41:57.766307Z","end":"2026-08-14T23:41:57.947525Z","steps":["trace[1983213395] 'agreement among raft nodes before linearized reading' (duration: 180.897229ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:44:55.812052Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12972} {"level":"info","ts":"2026-08-14T23:44:55.884196Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12972,"took":"69.883119ms","hash":631029219,"current-db-size-bytes":58576896,"current-db-size":"59 MB","current-db-size-in-use-bytes":31416320,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-14T23:44:55.884256Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":631029219,"revision":12972,"compact-revision":10922} {"level":"info","ts":"2026-08-14T23:49:15.145347Z","caller":"traceutil/trace.go:171","msg":"trace[547867410] transaction","detail":"{read_only:false; response_revision:17523; number_of_response:1; }","duration":"180.996173ms","start":"2026-08-14T23:49:14.964312Z","end":"2026-08-14T23:49:15.145308Z","steps":["trace[547867410] 'process raft request' (duration: 180.786879ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:49:15.166675Z","caller":"traceutil/trace.go:171","msg":"trace[1601964045] transaction","detail":"{read_only:false; response_revision:17524; number_of_response:1; }","duration":"189.949633ms","start":"2026-08-14T23:49:14.97669Z","end":"2026-08-14T23:49:15.166639Z","steps":["trace[1601964045] 'process raft request' (duration: 189.651827ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:49:22.457889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.306141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" ","response":"range_response_count:1 size:3225"} {"level":"info","ts":"2026-08-14T23:49:22.457979Z","caller":"traceutil/trace.go:171","msg":"trace[2004382703] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat; range_end:; response_count:1; response_revision:17580; }","duration":"209.458844ms","start":"2026-08-14T23:49:22.248489Z","end":"2026-08-14T23:49:22.457948Z","steps":["trace[2004382703] 'range keys from in-memory index tree' (duration: 209.043637ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:49:22.458008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.923879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-phhbz\" ","response":"range_response_count:1 size:22126"} {"level":"warn","ts":"2026-08-14T23:49:22.458068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.061813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-14T23:49:22.458169Z","caller":"traceutil/trace.go:171","msg":"trace[1294910661] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-phhbz; range_end:; response_count:1; response_revision:17580; }","duration":"243.146124ms","start":"2026-08-14T23:49:22.214992Z","end":"2026-08-14T23:49:22.458138Z","steps":["trace[1294910661] 'range keys from in-memory index tree' (duration: 242.721225ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:49:22.458262Z","caller":"traceutil/trace.go:171","msg":"trace[1316540448] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17580; }","duration":"271.308328ms","start":"2026-08-14T23:49:22.186936Z","end":"2026-08-14T23:49:22.458245Z","steps":["trace[1316540448] 'range keys from in-memory index tree' (duration: 270.917171ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:49:22.49048Z","caller":"traceutil/trace.go:171","msg":"trace[2146607227] linearizableReadLoop","detail":"{readStateIndex:18169; appliedIndex:18168; }","duration":"166.463828ms","start":"2026-08-14T23:49:22.32399Z","end":"2026-08-14T23:49:22.490453Z","steps":["trace[2146607227] 'read index received' (duration: 166.365736ms)","trace[2146607227] 'applied index is now lower than readState.Index' (duration: 96.852µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:49:22.490587Z","caller":"traceutil/trace.go:171","msg":"trace[854107905] transaction","detail":"{read_only:false; response_revision:17581; number_of_response:1; }","duration":"227.849753ms","start":"2026-08-14T23:49:22.262706Z","end":"2026-08-14T23:49:22.490556Z","steps":["trace[854107905] 'process raft request' (duration: 227.495736ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:49:22.490675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.673473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-14T23:49:22.490752Z","caller":"traceutil/trace.go:171","msg":"trace[534022418] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17581; }","duration":"166.757915ms","start":"2026-08-14T23:49:22.323954Z","end":"2026-08-14T23:49:22.490712Z","steps":["trace[534022418] 'agreement among raft nodes before linearized reading' (duration: 166.612711ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:49:22.671915Z","caller":"traceutil/trace.go:171","msg":"trace[1496629989] transaction","detail":"{read_only:false; response_revision:17582; number_of_response:1; }","duration":"209.688399ms","start":"2026-08-14T23:49:22.462193Z","end":"2026-08-14T23:49:22.671881Z","steps":["trace[1496629989] 'process raft request' (duration: 161.326921ms)","trace[1496629989] 'compare' (duration: 48.034562ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:49:22.672002Z","caller":"traceutil/trace.go:171","msg":"trace[1851078893] linearizableReadLoop","detail":"{readStateIndex:18170; appliedIndex:18169; }","duration":"181.36634ms","start":"2026-08-14T23:49:22.49061Z","end":"2026-08-14T23:49:22.671977Z","steps":["trace[1851078893] 'read index received' (duration: 132.872551ms)","trace[1851078893] 'applied index is now lower than readState.Index' (duration: 48.491769ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:49:22.672353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.83206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-08-14T23:49:22.672393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.021343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-14T23:49:22.672393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.284538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-14T23:49:22.67247Z","caller":"traceutil/trace.go:171","msg":"trace[1212292315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17582; }","duration":"348.380739ms","start":"2026-08-14T23:49:22.324072Z","end":"2026-08-14T23:49:22.672453Z","steps":["trace[1212292315] 'agreement among raft nodes before linearized reading' (duration: 348.157155ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:49:22.672471Z","caller":"traceutil/trace.go:171","msg":"trace[1306946054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17582; }","duration":"348.165666ms","start":"2026-08-14T23:49:22.324282Z","end":"2026-08-14T23:49:22.672448Z","steps":["trace[1306946054] 'agreement among raft nodes before linearized reading' (duration: 347.902601ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:49:22.67253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:49:22.324069Z","time spent":"348.449332ms","remote":"127.0.0.1:46144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T23:49:22.672531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:49:22.324234Z","time spent":"348.286408ms","remote":"127.0.0.1:46144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-14T23:49:22.672438Z","caller":"traceutil/trace.go:171","msg":"trace[92268057] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17582; }","duration":"348.002833ms","start":"2026-08-14T23:49:22.324416Z","end":"2026-08-14T23:49:22.672419Z","steps":["trace[92268057] 'agreement among raft nodes before linearized reading' (duration: 347.694927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:49:22.67259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:49:22.324383Z","time spent":"348.193307ms","remote":"127.0.0.1:46144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-14T23:49:22.672631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.648246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-14T23:49:22.672763Z","caller":"traceutil/trace.go:171","msg":"trace[456923273] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17582; }","duration":"347.161037ms","start":"2026-08-14T23:49:22.325581Z","end":"2026-08-14T23:49:22.672742Z","steps":["trace[456923273] 'agreement among raft nodes before linearized reading' (duration: 346.522624ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:49:22.672784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.794275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:942627"} {"level":"info","ts":"2026-08-14T23:49:22.672933Z","caller":"traceutil/trace.go:171","msg":"trace[159356616] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:17582; }","duration":"205.970939ms","start":"2026-08-14T23:49:22.466944Z","end":"2026-08-14T23:49:22.672915Z","steps":["trace[159356616] 'agreement among raft nodes before linearized reading' (duration: 205.133202ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:49:22.672949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:49:22.32556Z","time spent":"347.267489ms","remote":"127.0.0.1:46144","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-08-14T23:49:22.672412Z","caller":"traceutil/trace.go:171","msg":"trace[1441654710] transaction","detail":"{read_only:false; response_revision:17583; number_of_response:1; }","duration":"174.845737ms","start":"2026-08-14T23:49:22.497444Z","end":"2026-08-14T23:49:22.67229Z","steps":["trace[1441654710] 'process raft request' (duration: 174.360358ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:49:22.672517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.014295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-heat-default-user\" ","response":"range_response_count:1 size:1145"} {"level":"info","ts":"2026-08-14T23:49:22.675146Z","caller":"traceutil/trace.go:171","msg":"trace[440451806] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-heat-default-user; range_end:; response_count:1; response_revision:17582; }","duration":"212.804848ms","start":"2026-08-14T23:49:22.462325Z","end":"2026-08-14T23:49:22.67513Z","steps":["trace[440451806] 'agreement among raft nodes before linearized reading' (duration: 209.772391ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:49:55.818089Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15492} {"level":"info","ts":"2026-08-14T23:49:55.91664Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15492,"took":"95.591137ms","hash":3615097268,"current-db-size-bytes":58576896,"current-db-size":"59 MB","current-db-size-in-use-bytes":33734656,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-14T23:49:55.91669Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3615097268,"revision":15492,"compact-revision":12972} {"level":"info","ts":"2026-08-14T23:52:53.630931Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1cac722af8b2b8a6","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-14T23:52:53.6392Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-14T23:52:53.63933Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-14T23:54:24.075743Z","caller":"traceutil/trace.go:171","msg":"trace[1415858229] transaction","detail":"{read_only:false; response_revision:19933; number_of_response:1; }","duration":"223.548528ms","start":"2026-08-14T23:54:23.852172Z","end":"2026-08-14T23:54:24.07572Z","steps":["trace[1415858229] 'process raft request' (duration: 223.442246ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:54:24.075827Z","caller":"traceutil/trace.go:171","msg":"trace[89137079] transaction","detail":"{read_only:false; response_revision:19932; number_of_response:1; }","duration":"250.52814ms","start":"2026-08-14T23:54:23.825278Z","end":"2026-08-14T23:54:24.075806Z","steps":["trace[89137079] 'process raft request' (duration: 221.067731ms)","trace[89137079] 'compare' (duration: 28.96895ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:54:24.475849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.03063ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-14T23:54:24.475972Z","caller":"traceutil/trace.go:171","msg":"trace[1603974253] linearizableReadLoop","detail":"{readStateIndex:20598; appliedIndex:20597; }","duration":"298.047814ms","start":"2026-08-14T23:54:24.177907Z","end":"2026-08-14T23:54:24.475955Z","steps":["trace[1603974253] 'read index received' (duration: 126.85129ms)","trace[1603974253] 'applied index is now lower than readState.Index' (duration: 171.195114ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:54:24.476208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.274658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-08-14T23:54:24.476266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.613734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-14T23:54:24.476316Z","caller":"traceutil/trace.go:171","msg":"trace[1409620681] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19933; }","duration":"298.41126ms","start":"2026-08-14T23:54:24.177879Z","end":"2026-08-14T23:54:24.47629Z","steps":["trace[1409620681] 'agreement among raft nodes before linearized reading' (duration: 298.140855ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:54:24.476331Z","caller":"traceutil/trace.go:171","msg":"trace[1400237763] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19933; }","duration":"208.697025ms","start":"2026-08-14T23:54:24.267612Z","end":"2026-08-14T23:54:24.476309Z","steps":["trace[1400237763] 'agreement among raft nodes before linearized reading' (duration: 208.489991ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:54:24.476522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.398241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-14T23:54:24.476566Z","caller":"traceutil/trace.go:171","msg":"trace[2037442302] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19933; }","duration":"207.456832ms","start":"2026-08-14T23:54:24.269096Z","end":"2026-08-14T23:54:24.476552Z","steps":["trace[2037442302] 'agreement among raft nodes before linearized reading' (duration: 207.064104ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:54:24.476608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.829853ms","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-08-14T23:54:24.476525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.528004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-14T23:54:24.476647Z","caller":"traceutil/trace.go:171","msg":"trace[127492093] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19933; }","duration":"234.901904ms","start":"2026-08-14T23:54:24.241732Z","end":"2026-08-14T23:54:24.476634Z","steps":["trace[127492093] 'agreement among raft nodes before linearized reading' (duration: 234.312113ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:54:24.476664Z","caller":"traceutil/trace.go:171","msg":"trace[439271692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19933; }","duration":"216.660446ms","start":"2026-08-14T23:54:24.259984Z","end":"2026-08-14T23:54:24.476645Z","steps":["trace[439271692] 'agreement among raft nodes before linearized reading' (duration: 216.120886ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:54:24.608471Z","caller":"traceutil/trace.go:171","msg":"trace[1327484538] transaction","detail":"{read_only:false; response_revision:19934; number_of_response:1; }","duration":"127.298169ms","start":"2026-08-14T23:54:24.481148Z","end":"2026-08-14T23:54:24.608446Z","steps":["trace[1327484538] 'process raft request' (duration: 127.136036ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:54:24.78515Z","caller":"traceutil/trace.go:171","msg":"trace[1470109814] transaction","detail":"{read_only:false; response_revision:19935; number_of_response:1; }","duration":"303.898144ms","start":"2026-08-14T23:54:24.481227Z","end":"2026-08-14T23:54:24.785125Z","steps":["trace[1470109814] 'process raft request' (duration: 303.595638ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:54:24.785149Z","caller":"traceutil/trace.go:171","msg":"trace[521398311] transaction","detail":"{read_only:false; response_revision:19936; number_of_response:1; }","duration":"303.805253ms","start":"2026-08-14T23:54:24.481319Z","end":"2026-08-14T23:54:24.785124Z","steps":["trace[521398311] 'process raft request' (duration: 303.701521ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:54:24.785268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:54:24.481192Z","time spent":"304.014567ms","remote":"127.0.0.1:46144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T23:54:24.785295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:54:24.48131Z","time spent":"303.909745ms","remote":"127.0.0.1:46144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T23:54:24.785371Z","caller":"traceutil/trace.go:171","msg":"trace[2099604866] transaction","detail":"{read_only:false; response_revision:19937; number_of_response:1; }","duration":"304.036448ms","start":"2026-08-14T23:54:24.481323Z","end":"2026-08-14T23:54:24.78536Z","steps":["trace[2099604866] 'process raft request' (duration: 303.733332ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:54:24.785446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:54:24.481309Z","time spent":"304.099988ms","remote":"127.0.0.1:46144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T23:54:24.785737Z","caller":"traceutil/trace.go:171","msg":"trace[1012411992] transaction","detail":"{read_only:false; response_revision:19938; number_of_response:1; }","duration":"303.777843ms","start":"2026-08-14T23:54:24.481942Z","end":"2026-08-14T23:54:24.78572Z","steps":["trace[1012411992] 'process raft request' (duration: 303.336074ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:54:24.785844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:54:24.481934Z","time spent":"303.840384ms","remote":"127.0.0.1:46144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T23:54:24.786098Z","caller":"traceutil/trace.go:171","msg":"trace[1748688676] linearizableReadLoop","detail":"{readStateIndex:20604; appliedIndex:20598; }","duration":"303.449007ms","start":"2026-08-14T23:54:24.482584Z","end":"2026-08-14T23:54:24.786033Z","steps":["trace[1748688676] 'read index received' (duration: 125.692769ms)","trace[1748688676] 'applied index is now lower than readState.Index' (duration: 177.754998ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:54:24.786225Z","caller":"traceutil/trace.go:171","msg":"trace[823422597] transaction","detail":"{read_only:false; response_revision:19939; number_of_response:1; }","duration":"304.17588ms","start":"2026-08-14T23:54:24.482029Z","end":"2026-08-14T23:54:24.786205Z","steps":["trace[823422597] 'process raft request' (duration: 303.62659ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:54:24.786286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.685041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-14T23:54:24.786313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:54:24.482016Z","time spent":"304.259113ms","remote":"127.0.0.1:46144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T23:54:24.786328Z","caller":"traceutil/trace.go:171","msg":"trace[1144916660] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:19939; }","duration":"303.755833ms","start":"2026-08-14T23:54:24.482557Z","end":"2026-08-14T23:54:24.786313Z","steps":["trace[1144916660] 'agreement among raft nodes before linearized reading' (duration: 303.656692ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:54:24.786371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:54:24.482545Z","time spent":"303.812704ms","remote":"127.0.0.1:42020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":1,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true "} {"level":"warn","ts":"2026-08-14T23:54:24.786574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.685946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-14T23:54:24.786649Z","caller":"traceutil/trace.go:171","msg":"trace[1135197699] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:19939; }","duration":"168.802887ms","start":"2026-08-14T23:54:24.617826Z","end":"2026-08-14T23:54:24.786629Z","steps":["trace[1135197699] 'agreement among raft nodes before linearized reading' (duration: 168.633314ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:54:55.828502Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18047} {"level":"info","ts":"2026-08-14T23:54:55.94203Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18047,"took":"108.292077ms","hash":1534211116,"current-db-size-bytes":58576896,"current-db-size":"59 MB","current-db-size-in-use-bytes":29356032,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-14T23:54:55.94213Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1534211116,"revision":18047,"compact-revision":15492} {"level":"warn","ts":"2026-08-14T23:58:04.965898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.389492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/cert-manager-test/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/cert-manager-test0\" limit:10000 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T23:58:04.966001Z","caller":"traceutil/trace.go:171","msg":"trace[1759922481] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/cert-manager-test/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers/cert-manager-test0; response_count:0; response_revision:22235; }","duration":"136.561795ms","start":"2026-08-14T23:58:04.829419Z","end":"2026-08-14T23:58:04.965981Z","steps":["trace[1759922481] 'range keys from in-memory index tree' (duration: 136.3087ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:58:04.965992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.91129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" limit:10000 ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-14T23:58:04.966017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.670066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T23:58:04.966202Z","caller":"traceutil/trace.go:171","msg":"trace[1662550230] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers0; response_count:0; response_revision:22235; }","duration":"137.89512ms","start":"2026-08-14T23:58:04.828285Z","end":"2026-08-14T23:58:04.96618Z","steps":["trace[1662550230] 'count revisions from in-memory index tree' (duration: 137.521253ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:58:04.966197Z","caller":"traceutil/trace.go:171","msg":"trace[865526302] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers0; response_count:0; response_revision:22235; }","duration":"138.169906ms","start":"2026-08-14T23:58:04.828004Z","end":"2026-08-14T23:58:04.966173Z","steps":["trace[865526302] 'range keys from in-memory index tree' (duration: 137.647116ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:58:05.229433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.204931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/cert-manager-test/\" range_end:\"/registry/rabbitmq.com/exchanges/cert-manager-test0\" limit:10000 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T23:58:05.229503Z","caller":"traceutil/trace.go:171","msg":"trace[1234935689] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/cert-manager-test/; range_end:/registry/rabbitmq.com/exchanges/cert-manager-test0; response_count:0; response_revision:22237; }","duration":"163.302963ms","start":"2026-08-14T23:58:05.066185Z","end":"2026-08-14T23:58:05.229488Z","steps":["trace[1234935689] 'range keys from in-memory index tree' (duration: 163.151971ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:58:05.237187Z","caller":"traceutil/trace.go:171","msg":"trace[1159101631] transaction","detail":"{read_only:false; response_revision:22238; number_of_response:1; }","duration":"148.089134ms","start":"2026-08-14T23:58:05.089066Z","end":"2026-08-14T23:58:05.237155Z","steps":["trace[1159101631] 'process raft request' (duration: 147.801499ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:59:18.723199Z","caller":"traceutil/trace.go:171","msg":"trace[1975100446] transaction","detail":"{read_only:false; response_revision:23115; number_of_response:1; }","duration":"108.3732ms","start":"2026-08-14T23:59:18.614797Z","end":"2026-08-14T23:59:18.72317Z","steps":["trace[1975100446] 'process raft request' (duration: 108.192267ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:59:55.835645Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20178} {"level":"info","ts":"2026-08-14T23:59:55.945643Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20178,"took":"105.710989ms","hash":3390227473,"current-db-size-bytes":58576896,"current-db-size":"59 MB","current-db-size-in-use-bytes":43622400,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-08-14T23:59:55.945708Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3390227473,"revision":20178,"compact-revision":18047} {"level":"info","ts":"2026-08-15T00:04:55.841848Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23398} {"level":"info","ts":"2026-08-15T00:04:55.969885Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23398,"took":"124.225408ms","hash":1031246175,"current-db-size-bytes":58576896,"current-db-size":"59 MB","current-db-size-in-use-bytes":31191040,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-15T00:04:55.969951Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1031246175,"revision":23398,"compact-revision":20178}