{"level":"warn","ts":"2026-08-20T06:15:46.303739Z","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-20T06:15:46.30384Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235: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.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235: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-20T06:15:46.303916Z","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-20T06:15:46.303924Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-08-20T06:15:46.30394Z","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-20T06:15:46.304601Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-08-20T06:15:46.30467Z","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.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235: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-20T06:15:46.308434Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.535816ms"} {"level":"info","ts":"2026-08-20T06:15:46.31462Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-08-20T06:15:46.314732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-08-20T06:15:46.314774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-08-20T06:15:46.314816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-20T06:15:46.314858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-08-20T06:15:46.314915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-08-20T06:15:46.321064Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-20T06:15:46.322546Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-20T06:15:46.324182Z","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-20T06:15:46.327345Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-20T06:15:46.327826Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-20T06:15:46.328001Z","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-20T06:15:46.328121Z","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-20T06:15:46.328134Z","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-20T06:15:46.330526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-08-20T06:15:46.330674Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-08-20T06:15:46.332097Z","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-20T06:15:46.332142Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-08-20T06:15:46.332195Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-08-20T06:15:46.332419Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-20T06:15:46.33247Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-20T06:15:47.116856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-08-20T06:15:47.116964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-20T06:15:47.116993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-08-20T06:15:47.117023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-08-20T06:15:47.117034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-08-20T06:15:47.11705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-08-20T06:15:47.117064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-08-20T06:15:47.11857Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-08-20T06:15:47.118574Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-20T06:15:47.118633Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-20T06:15:47.118629Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T06:15:47.119094Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-20T06:15:47.119161Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-20T06:15:47.119795Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T06:15:47.120027Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T06:15:47.120123Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T06:15:47.120948Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-20T06:15:47.125354Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"info","ts":"2026-08-20T06:16:14.266938Z","caller":"traceutil/trace.go:171","msg":"trace[1787549328] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"151.61325ms","start":"2026-08-20T06:16:14.115287Z","end":"2026-08-20T06:16:14.2669Z","steps":["trace[1787549328] 'process raft request' (duration: 151.423977ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:16:26.98555Z","caller":"traceutil/trace.go:171","msg":"trace[20259557] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:677; }","duration":"105.762818ms","start":"2026-08-20T06:16:26.879771Z","end":"2026-08-20T06:16:26.985534Z","steps":["trace[20259557] 'read index received' (duration: 105.546444ms)","trace[20259557] 'applied index is now lower than readState.Index' (duration: 215.854µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T06:16:26.985834Z","caller":"traceutil/trace.go:171","msg":"trace[490392187] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"121.209025ms","start":"2026-08-20T06:16:26.864618Z","end":"2026-08-20T06:16:26.985827Z","steps":["trace[490392187] 'process raft request' (duration: 120.716056ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T06:16:26.985958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.174785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T06:16:26.985992Z","caller":"traceutil/trace.go:171","msg":"trace[1317418524] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:656; }","duration":"106.246807ms","start":"2026-08-20T06:16:26.879741Z","end":"2026-08-20T06:16:26.985988Z","steps":["trace[1317418524] 'agreement among raft nodes before linearized reading' (duration: 106.180046ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T06:16:26.986088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.172295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T06:16:26.986106Z","caller":"traceutil/trace.go:171","msg":"trace[1345635690] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:656; }","duration":"106.215776ms","start":"2026-08-20T06:16:26.879885Z","end":"2026-08-20T06:16:26.986101Z","steps":["trace[1345635690] 'agreement among raft nodes before linearized reading' (duration: 106.185455ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T06:16:26.986674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.254607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T06:16:26.986696Z","caller":"traceutil/trace.go:171","msg":"trace[1535418868] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:656; }","duration":"106.294298ms","start":"2026-08-20T06:16:26.880396Z","end":"2026-08-20T06:16:26.986691Z","steps":["trace[1535418868] 'agreement among raft nodes before linearized reading' (duration: 106.263037ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T06:16:26.986756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.796257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T06:16:26.986773Z","caller":"traceutil/trace.go:171","msg":"trace[1485406936] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:656; }","duration":"106.851128ms","start":"2026-08-20T06:16:26.879918Z","end":"2026-08-20T06:16:26.986769Z","steps":["trace[1485406936] 'agreement among raft nodes before linearized reading' (duration: 106.825538ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:16:33.06326Z","caller":"traceutil/trace.go:171","msg":"trace[912116061] linearizableReadLoop","detail":"{readStateIndex:723; appliedIndex:722; }","duration":"183.330681ms","start":"2026-08-20T06:16:32.879903Z","end":"2026-08-20T06:16:33.063234Z","steps":["trace[912116061] 'read index received' (duration: 183.087526ms)","trace[912116061] 'applied index is now lower than readState.Index' (duration: 241.944µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T06:16:33.063368Z","caller":"traceutil/trace.go:171","msg":"trace[789127935] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"205.425592ms","start":"2026-08-20T06:16:32.857922Z","end":"2026-08-20T06:16:33.063348Z","steps":["trace[789127935] 'process raft request' (duration: 205.142406ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T06:16:33.063445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.512674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T06:16:33.06351Z","caller":"traceutil/trace.go:171","msg":"trace[1236878102] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:700; }","duration":"183.666417ms","start":"2026-08-20T06:16:32.879828Z","end":"2026-08-20T06:16:33.063494Z","steps":["trace[1236878102] 'agreement among raft nodes before linearized reading' (duration: 183.535464ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T06:16:33.534133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.11649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-20T06:16:33.534234Z","caller":"traceutil/trace.go:171","msg":"trace[1449548068] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:701; }","duration":"338.293553ms","start":"2026-08-20T06:16:33.195922Z","end":"2026-08-20T06:16:33.534215Z","steps":["trace[1449548068] 'range keys from in-memory index tree' (duration: 337.872355ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T06:16:33.534277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.613697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-20T06:16:33.534283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T06:16:33.195897Z","time spent":"338.374214ms","remote":"127.0.0.1:43274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":511,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-20T06:16:33.53437Z","caller":"traceutil/trace.go:171","msg":"trace[372362138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:701; }","duration":"197.75909ms","start":"2026-08-20T06:16:33.336585Z","end":"2026-08-20T06:16:33.534344Z","steps":["trace[372362138] 'range keys from in-memory index tree' (duration: 197.418593ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:16:48.168597Z","caller":"traceutil/trace.go:171","msg":"trace[789535987] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"220.91682ms","start":"2026-08-20T06:16:47.947656Z","end":"2026-08-20T06:16:48.168573Z","steps":["trace[789535987] 'process raft request' (duration: 220.737167ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:17:15.275818Z","caller":"traceutil/trace.go:171","msg":"trace[56789437] linearizableReadLoop","detail":"{readStateIndex:1233; appliedIndex:1232; }","duration":"130.020695ms","start":"2026-08-20T06:17:15.145775Z","end":"2026-08-20T06:17:15.275796Z","steps":["trace[56789437] 'read index received' (duration: 129.794201ms)","trace[56789437] 'applied index is now lower than readState.Index' (duration: 225.444µs)"],"step_count":2} {"level":"warn","ts":"2026-08-20T06:17:15.275996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.197348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-20T06:17:15.27599Z","caller":"traceutil/trace.go:171","msg":"trace[1926150451] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"162.771256ms","start":"2026-08-20T06:17:15.113075Z","end":"2026-08-20T06:17:15.275846Z","steps":["trace[1926150451] 'process raft request' (duration: 162.527732ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:17:15.276063Z","caller":"traceutil/trace.go:171","msg":"trace[1246651037] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1200; }","duration":"130.321501ms","start":"2026-08-20T06:17:15.145728Z","end":"2026-08-20T06:17:15.276049Z","steps":["trace[1246651037] 'agreement among raft nodes before linearized reading' (duration: 130.191998ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:18:00.224288Z","caller":"traceutil/trace.go:171","msg":"trace[1280980621] linearizableReadLoop","detail":"{readStateIndex:1936; appliedIndex:1935; }","duration":"244.966095ms","start":"2026-08-20T06:17:59.979294Z","end":"2026-08-20T06:18:00.22426Z","steps":["trace[1280980621] 'read index received' (duration: 244.799271ms)","trace[1280980621] 'applied index is now lower than readState.Index' (duration: 164.974µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T06:18:00.224404Z","caller":"traceutil/trace.go:171","msg":"trace[1877497595] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"253.326453ms","start":"2026-08-20T06:17:59.97105Z","end":"2026-08-20T06:18:00.224377Z","steps":["trace[1877497595] 'process raft request' (duration: 253.030477ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T06:18:00.2247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.391862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-20T06:18:00.224768Z","caller":"traceutil/trace.go:171","msg":"trace[2048912350] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1866; }","duration":"245.515215ms","start":"2026-08-20T06:17:59.979237Z","end":"2026-08-20T06:18:00.224752Z","steps":["trace[2048912350] 'agreement among raft nodes before linearized reading' (duration: 245.165388ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T06:18:00.232849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.950697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-20T06:18:00.232914Z","caller":"traceutil/trace.go:171","msg":"trace[1841704120] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1867; }","duration":"252.043859ms","start":"2026-08-20T06:17:59.980853Z","end":"2026-08-20T06:18:00.232897Z","steps":["trace[1841704120] 'agreement among raft nodes before linearized reading' (duration: 251.931447ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T06:18:00.233002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.191056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-20T06:18:00.233064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.708841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-20T06:18:00.233134Z","caller":"traceutil/trace.go:171","msg":"trace[2013111357] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1867; }","duration":"233.815933ms","start":"2026-08-20T06:17:59.999298Z","end":"2026-08-20T06:18:00.233114Z","steps":["trace[2013111357] 'agreement among raft nodes before linearized reading' (duration: 233.62836ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:18:00.233075Z","caller":"traceutil/trace.go:171","msg":"trace[7874929] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:1867; }","duration":"123.297078ms","start":"2026-08-20T06:18:00.109762Z","end":"2026-08-20T06:18:00.23306Z","steps":["trace[7874929] 'agreement among raft nodes before linearized reading' (duration: 123.100695ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:18:00.610011Z","caller":"traceutil/trace.go:171","msg":"trace[338794828] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"184.881076ms","start":"2026-08-20T06:18:00.425105Z","end":"2026-08-20T06:18:00.609986Z","steps":["trace[338794828] 'process raft request' (duration: 175.853775ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:18:49.191696Z","caller":"traceutil/trace.go:171","msg":"trace[2047436759] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"160.100105ms","start":"2026-08-20T06:18:49.031546Z","end":"2026-08-20T06:18:49.191646Z","steps":["trace[2047436759] 'process raft request' (duration: 102.613036ms)","trace[2047436759] 'compare' (duration: 57.137333ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T06:18:49.191939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.805719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-20T06:18:49.192021Z","caller":"traceutil/trace.go:171","msg":"trace[734754495] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:2182; }","duration":"141.931181ms","start":"2026-08-20T06:18:49.050071Z","end":"2026-08-20T06:18:49.192003Z","steps":["trace[734754495] 'agreement among raft nodes before linearized reading' (duration: 141.747467ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:18:49.191587Z","caller":"traceutil/trace.go:171","msg":"trace[853180608] linearizableReadLoop","detail":"{readStateIndex:2263; appliedIndex:2261; }","duration":"141.458111ms","start":"2026-08-20T06:18:49.050085Z","end":"2026-08-20T06:18:49.191543Z","steps":["trace[853180608] 'read index received' (duration: 9.078931ms)","trace[853180608] 'applied index is now lower than readState.Index' (duration: 132.37721ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T06:20:04.412597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.999571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T06:20:04.412843Z","caller":"traceutil/trace.go:171","msg":"trace[109425332] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2785; }","duration":"107.308787ms","start":"2026-08-20T06:20:04.305504Z","end":"2026-08-20T06:20:04.412812Z","steps":["trace[109425332] 'count revisions from in-memory index tree' (duration: 106.896049ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T06:20:04.412819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.717631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-20T06:20:04.412714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.575605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-08-20T06:20:04.41294Z","caller":"traceutil/trace.go:171","msg":"trace[2059331285] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2785; }","duration":"187.922325ms","start":"2026-08-20T06:20:04.224987Z","end":"2026-08-20T06:20:04.412909Z","steps":["trace[2059331285] 'count revisions from in-memory index tree' (duration: 187.615388ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:20:04.41299Z","caller":"traceutil/trace.go:171","msg":"trace[1816513060] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2785; }","duration":"152.86345ms","start":"2026-08-20T06:20:04.26008Z","end":"2026-08-20T06:20:04.412944Z","steps":["trace[1816513060] 'range keys from in-memory index tree' (duration: 152.423642ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:23:47.552376Z","caller":"traceutil/trace.go:171","msg":"trace[1203888509] transaction","detail":"{read_only:false; response_revision:4802; number_of_response:1; }","duration":"225.582445ms","start":"2026-08-20T06:23:47.326767Z","end":"2026-08-20T06:23:47.552349Z","steps":["trace[1203888509] 'process raft request' (duration: 225.283379ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:23:47.715566Z","caller":"traceutil/trace.go:171","msg":"trace[2075088003] transaction","detail":"{read_only:false; response_revision:4805; number_of_response:1; }","duration":"145.098636ms","start":"2026-08-20T06:23:47.570443Z","end":"2026-08-20T06:23:47.715542Z","steps":["trace[2075088003] 'process raft request' (duration: 145.014154ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:23:47.715565Z","caller":"traceutil/trace.go:171","msg":"trace[839782723] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"148.762544ms","start":"2026-08-20T06:23:47.56677Z","end":"2026-08-20T06:23:47.715533Z","steps":["trace[839782723] 'process raft request' (duration: 66.259907ms)","trace[839782723] 'compare' (duration: 82.294833ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T06:23:47.715604Z","caller":"traceutil/trace.go:171","msg":"trace[990156326] transaction","detail":"{read_only:false; response_revision:4806; number_of_response:1; }","duration":"144.465364ms","start":"2026-08-20T06:23:47.571098Z","end":"2026-08-20T06:23:47.715563Z","steps":["trace[990156326] 'process raft request' (duration: 144.399413ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:23:49.100105Z","caller":"traceutil/trace.go:171","msg":"trace[585609724] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"128.729904ms","start":"2026-08-20T06:23:48.971349Z","end":"2026-08-20T06:23:49.100079Z","steps":["trace[585609724] 'process raft request' (duration: 92.183831ms)","trace[585609724] 'compare' (duration: 36.402541ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T06:24:02.924416Z","caller":"traceutil/trace.go:171","msg":"trace[1706166096] linearizableReadLoop","detail":"{readStateIndex:5389; appliedIndex:5388; }","duration":"128.101923ms","start":"2026-08-20T06:24:02.796288Z","end":"2026-08-20T06:24:02.92439Z","steps":["trace[1706166096] 'read index received' (duration: 126.231887ms)","trace[1706166096] 'applied index is now lower than readState.Index' (duration: 1.869036ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T06:24:02.92448Z","caller":"traceutil/trace.go:171","msg":"trace[1343621069] transaction","detail":"{read_only:false; response_revision:5193; number_of_response:1; }","duration":"206.95614ms","start":"2026-08-20T06:24:02.717486Z","end":"2026-08-20T06:24:02.924442Z","steps":["trace[1343621069] 'process raft request' (duration: 205.087034ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T06:24:02.924701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.392368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-20T06:24:02.924824Z","caller":"traceutil/trace.go:171","msg":"trace[1436446043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5193; }","duration":"128.553442ms","start":"2026-08-20T06:24:02.796251Z","end":"2026-08-20T06:24:02.924804Z","steps":["trace[1436446043] 'agreement among raft nodes before linearized reading' (duration: 128.273156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T06:24:10.256967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.650876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-20T06:24:10.257067Z","caller":"traceutil/trace.go:171","msg":"trace[839222814] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:5354; }","duration":"125.780669ms","start":"2026-08-20T06:24:10.131263Z","end":"2026-08-20T06:24:10.257044Z","steps":["trace[839222814] 'range keys from in-memory index tree' (duration: 125.330439ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:24:10.452173Z","caller":"traceutil/trace.go:171","msg":"trace[1838710974] linearizableReadLoop","detail":"{readStateIndex:5556; appliedIndex:5555; }","duration":"181.106729ms","start":"2026-08-20T06:24:10.271043Z","end":"2026-08-20T06:24:10.45215Z","steps":["trace[1838710974] 'read index received' (duration: 121.323964ms)","trace[1838710974] 'applied index is now lower than readState.Index' (duration: 59.781805ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T06:24:10.452219Z","caller":"traceutil/trace.go:171","msg":"trace[485498044] transaction","detail":"{read_only:false; response_revision:5355; number_of_response:1; }","duration":"185.022273ms","start":"2026-08-20T06:24:10.267153Z","end":"2026-08-20T06:24:10.452175Z","steps":["trace[485498044] 'process raft request' (duration: 125.143996ms)","trace[485498044] 'compare' (duration: 59.730814ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T06:24:10.452448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.385574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9880d3eb-a4d1-4626-a0e6-c9fd9b81899c\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-08-20T06:24:10.452429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.318913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T06:24:10.452494Z","caller":"traceutil/trace.go:171","msg":"trace[309104092] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9880d3eb-a4d1-4626-a0e6-c9fd9b81899c; range_end:; response_count:1; response_revision:5355; }","duration":"181.472256ms","start":"2026-08-20T06:24:10.271011Z","end":"2026-08-20T06:24:10.452483Z","steps":["trace[309104092] 'agreement among raft nodes before linearized reading' (duration: 181.292762ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:24:10.452511Z","caller":"traceutil/trace.go:171","msg":"trace[2078181118] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:5355; }","duration":"171.437205ms","start":"2026-08-20T06:24:10.281056Z","end":"2026-08-20T06:24:10.452493Z","steps":["trace[2078181118] 'agreement among raft nodes before linearized reading' (duration: 171.252832ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T06:24:10.452595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.91986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9880d3eb-a4d1-4626-a0e6-c9fd9b81899c\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-20T06:24:10.452691Z","caller":"traceutil/trace.go:171","msg":"trace[1900042954] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9880d3eb-a4d1-4626-a0e6-c9fd9b81899c; range_end:; response_count:1; response_revision:5355; }","duration":"146.111684ms","start":"2026-08-20T06:24:10.306557Z","end":"2026-08-20T06:24:10.452669Z","steps":["trace[1900042954] 'agreement among raft nodes before linearized reading' (duration: 145.769708ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T06:24:10.452694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.676564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-08-20T06:24:10.452784Z","caller":"traceutil/trace.go:171","msg":"trace[1634319201] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5355; }","duration":"107.98897ms","start":"2026-08-20T06:24:10.344779Z","end":"2026-08-20T06:24:10.452768Z","steps":["trace[1634319201] 'agreement among raft nodes before linearized reading' (duration: 107.534342ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:24:10.586377Z","caller":"traceutil/trace.go:171","msg":"trace[1955138702] linearizableReadLoop","detail":"{readStateIndex:5557; appliedIndex:5556; }","duration":"126.192696ms","start":"2026-08-20T06:24:10.460155Z","end":"2026-08-20T06:24:10.586347Z","steps":["trace[1955138702] 'read index received' (duration: 101.352894ms)","trace[1955138702] 'applied index is now lower than readState.Index' (duration: 24.838632ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T06:24:10.586587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.41458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-20T06:24:10.586455Z","caller":"traceutil/trace.go:171","msg":"trace[1543907825] transaction","detail":"{read_only:false; response_revision:5356; number_of_response:1; }","duration":"126.449892ms","start":"2026-08-20T06:24:10.459967Z","end":"2026-08-20T06:24:10.586417Z","steps":["trace[1543907825] 'process raft request' (duration: 101.576009ms)","trace[1543907825] 'compare' (duration: 24.670899ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T06:24:10.586634Z","caller":"traceutil/trace.go:171","msg":"trace[950280326] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:5356; }","duration":"126.504233ms","start":"2026-08-20T06:24:10.46012Z","end":"2026-08-20T06:24:10.586624Z","steps":["trace[950280326] 'agreement among raft nodes before linearized reading' (duration: 126.37911ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:24:10.724121Z","caller":"traceutil/trace.go:171","msg":"trace[803920044] transaction","detail":"{read_only:false; response_revision:5357; number_of_response:1; }","duration":"132.815142ms","start":"2026-08-20T06:24:10.591233Z","end":"2026-08-20T06:24:10.724048Z","steps":["trace[803920044] 'process raft request' (duration: 129.01289ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:24:10.729954Z","caller":"traceutil/trace.go:171","msg":"trace[1162373124] transaction","detail":"{read_only:false; response_revision:5358; number_of_response:1; }","duration":"138.235304ms","start":"2026-08-20T06:24:10.591679Z","end":"2026-08-20T06:24:10.729914Z","steps":["trace[1162373124] 'process raft request' (duration: 137.924529ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:24:10.730106Z","caller":"traceutil/trace.go:171","msg":"trace[2114145613] transaction","detail":"{read_only:false; response_revision:5359; number_of_response:1; }","duration":"132.844273ms","start":"2026-08-20T06:24:10.597236Z","end":"2026-08-20T06:24:10.73008Z","steps":["trace[2114145613] 'process raft request' (duration: 132.514956ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:25:47.185611Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3021} {"level":"info","ts":"2026-08-20T06:25:47.270958Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3021,"took":"83.674998ms","hash":3349434208,"current-db-size-bytes":59043840,"current-db-size":"59 MB","current-db-size-in-use-bytes":32563200,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-20T06:25:47.271041Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3349434208,"revision":3021,"compact-revision":-1} {"level":"info","ts":"2026-08-20T06:30:02.743523Z","caller":"traceutil/trace.go:171","msg":"trace[716703546] linearizableReadLoop","detail":"{readStateIndex:8384; appliedIndex:8383; }","duration":"129.337472ms","start":"2026-08-20T06:30:02.614158Z","end":"2026-08-20T06:30:02.743496Z","steps":["trace[716703546] 'read index received' (duration: 129.148659ms)","trace[716703546] 'applied index is now lower than readState.Index' (duration: 187.263µs)"],"step_count":2} {"level":"warn","ts":"2026-08-20T06:30:02.743792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.615277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-20T06:30:02.743839Z","caller":"traceutil/trace.go:171","msg":"trace[751760438] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8099; }","duration":"129.734419ms","start":"2026-08-20T06:30:02.614093Z","end":"2026-08-20T06:30:02.743827Z","steps":["trace[751760438] 'agreement among raft nodes before linearized reading' (duration: 129.532096ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:30:02.74442Z","caller":"traceutil/trace.go:171","msg":"trace[248207047] transaction","detail":"{read_only:false; response_revision:8099; number_of_response:1; }","duration":"259.762816ms","start":"2026-08-20T06:30:02.484637Z","end":"2026-08-20T06:30:02.744399Z","steps":["trace[248207047] 'process raft request' (duration: 258.683085ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:30:02.897662Z","caller":"traceutil/trace.go:171","msg":"trace[397348126] linearizableReadLoop","detail":"{readStateIndex:8385; appliedIndex:8384; }","duration":"116.581919ms","start":"2026-08-20T06:30:02.781048Z","end":"2026-08-20T06:30:02.89763Z","steps":["trace[397348126] 'read index received' (duration: 80.538272ms)","trace[397348126] 'applied index is now lower than readState.Index' (duration: 36.041687ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T06:30:02.897867Z","caller":"traceutil/trace.go:171","msg":"trace[1373325044] transaction","detail":"{read_only:false; response_revision:8100; number_of_response:1; }","duration":"147.953716ms","start":"2026-08-20T06:30:02.749862Z","end":"2026-08-20T06:30:02.897816Z","steps":["trace[1373325044] 'process raft request' (duration: 111.763817ms)","trace[1373325044] 'compare' (duration: 35.840883ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T06:30:02.897949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.873674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-20T06:30:02.898095Z","caller":"traceutil/trace.go:171","msg":"trace[1931240469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8100; }","duration":"117.095979ms","start":"2026-08-20T06:30:02.780986Z","end":"2026-08-20T06:30:02.898082Z","steps":["trace[1931240469] 'agreement among raft nodes before linearized reading' (duration: 116.766132ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:30:15.532249Z","caller":"traceutil/trace.go:171","msg":"trace[1776542620] transaction","detail":"{read_only:false; response_revision:8164; number_of_response:1; }","duration":"220.389766ms","start":"2026-08-20T06:30:15.311834Z","end":"2026-08-20T06:30:15.532224Z","steps":["trace[1776542620] 'process raft request' (duration: 220.211492ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:30:15.626995Z","caller":"traceutil/trace.go:171","msg":"trace[2023428269] transaction","detail":"{read_only:false; response_revision:8165; number_of_response:1; }","duration":"146.220194ms","start":"2026-08-20T06:30:15.480734Z","end":"2026-08-20T06:30:15.626955Z","steps":["trace[2023428269] 'process raft request' (duration: 145.98503ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:30:44.566516Z","caller":"traceutil/trace.go:171","msg":"trace[631562945] transaction","detail":"{read_only:false; response_revision:8372; number_of_response:1; }","duration":"145.260946ms","start":"2026-08-20T06:30:44.42122Z","end":"2026-08-20T06:30:44.566481Z","steps":["trace[631562945] 'process raft request' (duration: 130.353883ms)","trace[631562945] 'compare' (duration: 14.19852ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T06:30:44.729394Z","caller":"traceutil/trace.go:171","msg":"trace[241536465] transaction","detail":"{read_only:false; response_revision:8373; number_of_response:1; }","duration":"259.706545ms","start":"2026-08-20T06:30:44.46962Z","end":"2026-08-20T06:30:44.729327Z","steps":["trace[241536465] 'process raft request' (duration: 219.239095ms)","trace[241536465] 'compare' (duration: 40.146294ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T06:30:47.192549Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6198} {"level":"info","ts":"2026-08-20T06:30:47.294763Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6198,"took":"99.932623ms","hash":3490237324,"current-db-size-bytes":59043840,"current-db-size":"59 MB","current-db-size-in-use-bytes":23318528,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-20T06:30:47.294826Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3490237324,"revision":6198,"compact-revision":3021} {"level":"warn","ts":"2026-08-20T06:30:54.836775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.486428ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T06:30:54.836917Z","caller":"traceutil/trace.go:171","msg":"trace[1982266353] linearizableReadLoop","detail":"{readStateIndex:8766; appliedIndex:8765; }","duration":"162.673551ms","start":"2026-08-20T06:30:54.674223Z","end":"2026-08-20T06:30:54.836897Z","steps":["trace[1982266353] 'read index received' (duration: 53.360047ms)","trace[1982266353] 'applied index is now lower than readState.Index' (duration: 109.311934ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T06:30:54.8371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.883215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-4cg8p\" ","response":"range_response_count:1 size:2803"} {"level":"info","ts":"2026-08-20T06:30:54.83713Z","caller":"traceutil/trace.go:171","msg":"trace[778139068] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-4cg8p; range_end:; response_count:1; response_revision:8465; }","duration":"162.972046ms","start":"2026-08-20T06:30:54.674146Z","end":"2026-08-20T06:30:54.837118Z","steps":["trace[778139068] 'agreement among raft nodes before linearized reading' (duration: 162.804653ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:30:54.837508Z","caller":"traceutil/trace.go:171","msg":"trace[38631904] transaction","detail":"{read_only:false; response_revision:8465; number_of_response:1; }","duration":"164.294761ms","start":"2026-08-20T06:30:54.673197Z","end":"2026-08-20T06:30:54.837491Z","steps":["trace[38631904] 'process raft request' (duration: 54.478408ms)","trace[38631904] 'compare' (duration: 108.349256ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T06:30:55.617636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.586269ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"info","ts":"2026-08-20T06:30:55.617807Z","caller":"traceutil/trace.go:171","msg":"trace[1408343675] linearizableReadLoop","detail":"{readStateIndex:8777; appliedIndex:8775; }","duration":"213.811907ms","start":"2026-08-20T06:30:55.40397Z","end":"2026-08-20T06:30:55.617782Z","steps":["trace[1408343675] 'read index received' (duration: 23.293395ms)","trace[1408343675] 'applied index is now lower than readState.Index' (duration: 190.516532ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T06:30:55.617972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.97553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T06:30:55.618026Z","caller":"traceutil/trace.go:171","msg":"trace[2005631168] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8475; }","duration":"214.091213ms","start":"2026-08-20T06:30:55.403918Z","end":"2026-08-20T06:30:55.618009Z","steps":["trace[2005631168] 'agreement among raft nodes before linearized reading' (duration: 213.94086ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:31:40.847995Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000241c.wal"} {"level":"info","ts":"2026-08-20T06:33:07.920875Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-20T06:33:07.926498Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-20T06:33:07.926617Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-20T06:34:25.316172Z","caller":"traceutil/trace.go:171","msg":"trace[1424037487] transaction","detail":"{read_only:false; response_revision:10278; number_of_response:1; }","duration":"103.749049ms","start":"2026-08-20T06:34:25.212383Z","end":"2026-08-20T06:34:25.316132Z","steps":["trace[1424037487] 'process raft request' (duration: 103.442573ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:35:43.970245Z","caller":"traceutil/trace.go:171","msg":"trace[1326173713] linearizableReadLoop","detail":"{readStateIndex:11411; appliedIndex:11410; }","duration":"102.145141ms","start":"2026-08-20T06:35:43.868069Z","end":"2026-08-20T06:35:43.970214Z","steps":["trace[1326173713] 'read index received' (duration: 102.037699ms)","trace[1326173713] 'applied index is now lower than readState.Index' (duration: 106.322µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T06:35:43.970336Z","caller":"traceutil/trace.go:171","msg":"trace[2026044836] transaction","detail":"{read_only:false; response_revision:11035; number_of_response:1; }","duration":"123.65899ms","start":"2026-08-20T06:35:43.846626Z","end":"2026-08-20T06:35:43.970285Z","steps":["trace[2026044836] 'process raft request' (duration: 123.385555ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T06:35:43.97056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.454556ms","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-20T06:35:43.970684Z","caller":"traceutil/trace.go:171","msg":"trace[471262287] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:11035; }","duration":"102.65845ms","start":"2026-08-20T06:35:43.867994Z","end":"2026-08-20T06:35:43.970652Z","steps":["trace[471262287] 'agreement among raft nodes before linearized reading' (duration: 102.377625ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T06:35:44.05675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.775794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-20T06:35:44.056876Z","caller":"traceutil/trace.go:171","msg":"trace[1836021716] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:11036; }","duration":"144.990298ms","start":"2026-08-20T06:35:43.911857Z","end":"2026-08-20T06:35:44.056847Z","steps":["trace[1836021716] 'agreement among raft nodes before linearized reading' (duration: 144.735603ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T06:35:44.388723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.30353ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T06:35:44.388842Z","caller":"traceutil/trace.go:171","msg":"trace[1705118555] linearizableReadLoop","detail":"{readStateIndex:11413; appliedIndex:11412; }","duration":"257.052098ms","start":"2026-08-20T06:35:44.131769Z","end":"2026-08-20T06:35:44.388821Z","steps":["trace[1705118555] 'read index received' (duration: 150.595756ms)","trace[1705118555] 'applied index is now lower than readState.Index' (duration: 106.454562ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T06:35:44.389016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.251541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-20T06:35:44.389055Z","caller":"traceutil/trace.go:171","msg":"trace[594399870] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11037; }","duration":"257.311243ms","start":"2026-08-20T06:35:44.131731Z","end":"2026-08-20T06:35:44.389043Z","steps":["trace[594399870] 'agreement among raft nodes before linearized reading' (duration: 257.1448ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:35:44.389057Z","caller":"traceutil/trace.go:171","msg":"trace[960571292] transaction","detail":"{read_only:false; response_revision:11037; number_of_response:1; }","duration":"258.470494ms","start":"2026-08-20T06:35:44.130539Z","end":"2026-08-20T06:35:44.38901Z","steps":["trace[960571292] 'process raft request' (duration: 151.759177ms)","trace[960571292] 'compare' (duration: 106.022925ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T06:35:44.845138Z","caller":"traceutil/trace.go:171","msg":"trace[1223697375] transaction","detail":"{read_only:false; response_revision:11042; number_of_response:1; }","duration":"117.356961ms","start":"2026-08-20T06:35:44.727753Z","end":"2026-08-20T06:35:44.84511Z","steps":["trace[1223697375] 'process raft request' (duration: 117.189878ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:35:47.20397Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8387} {"level":"info","ts":"2026-08-20T06:35:47.283007Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8387,"took":"75.96833ms","hash":1339396373,"current-db-size-bytes":59043840,"current-db-size":"59 MB","current-db-size-in-use-bytes":27070464,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-20T06:35:47.28309Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1339396373,"revision":8387,"compact-revision":6198} {"level":"info","ts":"2026-08-20T06:40:47.210809Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11054} {"level":"info","ts":"2026-08-20T06:40:47.290296Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11054,"took":"76.4158ms","hash":1770252387,"current-db-size-bytes":59043840,"current-db-size":"59 MB","current-db-size-in-use-bytes":27942912,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-20T06:40:47.290374Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1770252387,"revision":11054,"compact-revision":8387} {"level":"info","ts":"2026-08-20T06:41:33.51357Z","caller":"traceutil/trace.go:171","msg":"trace[173560678] transaction","detail":"{read_only:false; response_revision:13423; number_of_response:1; }","duration":"136.951605ms","start":"2026-08-20T06:41:33.37659Z","end":"2026-08-20T06:41:33.513541Z","steps":["trace[173560678] 'process raft request' (duration: 119.139295ms)","trace[173560678] 'compare' (duration: 17.686567ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T06:42:11.058376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.494534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-20T06:42:11.058479Z","caller":"traceutil/trace.go:171","msg":"trace[2103881529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13637; }","duration":"106.658157ms","start":"2026-08-20T06:42:10.951804Z","end":"2026-08-20T06:42:11.058463Z","steps":["trace[2103881529] 'range keys from in-memory index tree' (duration: 106.29925ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:43:59.857866Z","caller":"traceutil/trace.go:171","msg":"trace[546084343] transaction","detail":"{read_only:false; response_revision:14878; number_of_response:1; }","duration":"116.055796ms","start":"2026-08-20T06:43:59.74178Z","end":"2026-08-20T06:43:59.857836Z","steps":["trace[546084343] 'process raft request' (duration: 115.881233ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:44:00.032903Z","caller":"traceutil/trace.go:171","msg":"trace[153672755] linearizableReadLoop","detail":"{readStateIndex:15385; appliedIndex:15383; }","duration":"203.13902ms","start":"2026-08-20T06:43:59.829725Z","end":"2026-08-20T06:44:00.032864Z","steps":["trace[153672755] 'read index received' (duration: 27.974174ms)","trace[153672755] 'applied index is now lower than readState.Index' (duration: 175.163396ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T06:44:00.03296Z","caller":"traceutil/trace.go:171","msg":"trace[1831836041] transaction","detail":"{read_only:false; response_revision:14879; number_of_response:1; }","duration":"289.874047ms","start":"2026-08-20T06:43:59.743052Z","end":"2026-08-20T06:44:00.032926Z","steps":["trace[1831836041] 'process raft request' (duration: 259.571829ms)","trace[1831836041] 'compare' (duration: 30.085904ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T06:44:00.033104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.352684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-20T06:44:00.033151Z","caller":"traceutil/trace.go:171","msg":"trace[1117094153] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:14879; }","duration":"203.431165ms","start":"2026-08-20T06:43:59.829706Z","end":"2026-08-20T06:44:00.033137Z","steps":["trace[1117094153] 'agreement among raft nodes before linearized reading' (duration: 203.317823ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T06:44:00.047063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.388866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T06:44:00.047117Z","caller":"traceutil/trace.go:171","msg":"trace[551640216] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14880; }","duration":"168.461667ms","start":"2026-08-20T06:43:59.878642Z","end":"2026-08-20T06:44:00.047104Z","steps":["trace[551640216] 'agreement among raft nodes before linearized reading' (duration: 168.368515ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T06:44:00.047154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.976019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-08-20T06:44:00.04728Z","caller":"traceutil/trace.go:171","msg":"trace[761592070] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14880; }","duration":"167.162213ms","start":"2026-08-20T06:43:59.880094Z","end":"2026-08-20T06:44:00.047256Z","steps":["trace[761592070] 'agreement among raft nodes before linearized reading' (duration: 166.854857ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T06:44:00.047299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.965486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T06:44:00.047342Z","caller":"traceutil/trace.go:171","msg":"trace[736349943] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14880; }","duration":"169.042428ms","start":"2026-08-20T06:43:59.87829Z","end":"2026-08-20T06:44:00.047332Z","steps":["trace[736349943] 'agreement among raft nodes before linearized reading' (duration: 168.982347ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T06:44:00.047528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.337884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-08-20T06:44:00.047543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.235632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T06:44:00.047572Z","caller":"traceutil/trace.go:171","msg":"trace[1002810361] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14880; }","duration":"103.397044ms","start":"2026-08-20T06:43:59.944159Z","end":"2026-08-20T06:44:00.047556Z","steps":["trace[1002810361] 'agreement among raft nodes before linearized reading' (duration: 103.283332ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:44:00.047576Z","caller":"traceutil/trace.go:171","msg":"trace[1505232168] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14880; }","duration":"169.314353ms","start":"2026-08-20T06:43:59.878251Z","end":"2026-08-20T06:44:00.047566Z","steps":["trace[1505232168] 'agreement among raft nodes before linearized reading' (duration: 169.268042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T06:44:00.047804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.759502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T06:44:00.047844Z","caller":"traceutil/trace.go:171","msg":"trace[596787842] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14880; }","duration":"103.820453ms","start":"2026-08-20T06:43:59.944014Z","end":"2026-08-20T06:44:00.047834Z","steps":["trace[596787842] 'agreement among raft nodes before linearized reading' (duration: 103.763322ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:45:47.216478Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13096} {"level":"info","ts":"2026-08-20T06:45:47.291487Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13096,"took":"71.494626ms","hash":2384473625,"current-db-size-bytes":59043840,"current-db-size":"59 MB","current-db-size-in-use-bytes":31526912,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-20T06:45:47.291554Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2384473625,"revision":13096,"compact-revision":11054} {"level":"info","ts":"2026-08-20T06:50:47.224081Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15639} {"level":"info","ts":"2026-08-20T06:50:47.321844Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15639,"took":"94.171018ms","hash":117691919,"current-db-size-bytes":59043840,"current-db-size":"59 MB","current-db-size-in-use-bytes":33677312,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-20T06:50:47.321916Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":117691919,"revision":15639,"compact-revision":13096} {"level":"info","ts":"2026-08-20T06:53:22.242607Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-20T06:53:22.247234Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-20T06:53:22.247306Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-20T06:55:39.907498Z","caller":"traceutil/trace.go:171","msg":"trace[1084263292] transaction","detail":"{read_only:false; response_revision:20122; number_of_response:1; }","duration":"209.069133ms","start":"2026-08-20T06:55:39.698401Z","end":"2026-08-20T06:55:39.90747Z","steps":["trace[1084263292] 'process raft request' (duration: 208.900989ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:55:39.907985Z","caller":"traceutil/trace.go:171","msg":"trace[156415879] linearizableReadLoop","detail":"{readStateIndex:20804; appliedIndex:20804; }","duration":"171.608039ms","start":"2026-08-20T06:55:39.736367Z","end":"2026-08-20T06:55:39.907975Z","steps":["trace[156415879] 'read index received' (duration: 171.604309ms)","trace[156415879] 'applied index is now lower than readState.Index' (duration: 3.15µs)"],"step_count":2} {"level":"warn","ts":"2026-08-20T06:55:39.908548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.162779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-20T06:55:39.908583Z","caller":"traceutil/trace.go:171","msg":"trace[1125851866] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20122; }","duration":"172.257131ms","start":"2026-08-20T06:55:39.736312Z","end":"2026-08-20T06:55:39.908569Z","steps":["trace[1125851866] 'agreement among raft nodes before linearized reading' (duration: 171.841293ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:55:47.231697Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18159} {"level":"info","ts":"2026-08-20T06:55:47.355232Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18159,"took":"118.577295ms","hash":3476617196,"current-db-size-bytes":59043840,"current-db-size":"59 MB","current-db-size-in-use-bytes":28934144,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-20T06:55:47.355309Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3476617196,"revision":18159,"compact-revision":15639} {"level":"info","ts":"2026-08-20T06:55:50.135296Z","caller":"traceutil/trace.go:171","msg":"trace[1412122653] transaction","detail":"{read_only:false; response_revision:20175; number_of_response:1; }","duration":"166.255156ms","start":"2026-08-20T06:55:49.969008Z","end":"2026-08-20T06:55:50.135263Z","steps":["trace[1412122653] 'process raft request' (duration: 133.99596ms)","trace[1412122653] 'compare' (duration: 32.072402ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T06:55:55.620996Z","caller":"traceutil/trace.go:171","msg":"trace[1530806984] transaction","detail":"{read_only:false; response_revision:20202; number_of_response:1; }","duration":"136.214583ms","start":"2026-08-20T06:55:55.48475Z","end":"2026-08-20T06:55:55.620964Z","steps":["trace[1530806984] 'process raft request' (duration: 135.964018ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T06:55:55.771015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.914521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T06:55:55.771252Z","caller":"traceutil/trace.go:171","msg":"trace[2026070214] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:20202; }","duration":"130.246367ms","start":"2026-08-20T06:55:55.640974Z","end":"2026-08-20T06:55:55.77122Z","steps":["trace[2026070214] 'count revisions from in-memory index tree' (duration: 129.733988ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T06:55:56.058316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.179192ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-20T06:55:56.058454Z","caller":"traceutil/trace.go:171","msg":"trace[879424846] linearizableReadLoop","detail":"{readStateIndex:20889; appliedIndex:20888; }","duration":"312.117943ms","start":"2026-08-20T06:55:55.746313Z","end":"2026-08-20T06:55:56.058431Z","steps":["trace[879424846] 'read index received' (duration: 53.981222ms)","trace[879424846] 'applied index is now lower than readState.Index' (duration: 258.135201ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T06:55:56.058618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.834925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T06:55:56.058663Z","caller":"traceutil/trace.go:171","msg":"trace[1997214691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20202; }","duration":"180.932717ms","start":"2026-08-20T06:55:55.877717Z","end":"2026-08-20T06:55:56.058649Z","steps":["trace[1997214691] 'agreement among raft nodes before linearized reading' (duration: 180.838805ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T06:55:56.058702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.020688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T06:55:56.058821Z","caller":"traceutil/trace.go:171","msg":"trace[1941881821] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:20202; }","duration":"103.197002ms","start":"2026-08-20T06:55:55.955589Z","end":"2026-08-20T06:55:56.058786Z","steps":["trace[1941881821] 'agreement among raft nodes before linearized reading' (duration: 103.012568ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T06:55:56.059074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.700403ms","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-20T06:55:56.059212Z","caller":"traceutil/trace.go:171","msg":"trace[325957748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20202; }","duration":"312.946858ms","start":"2026-08-20T06:55:55.746239Z","end":"2026-08-20T06:55:56.059185Z","steps":["trace[325957748] 'agreement among raft nodes before linearized reading' (duration: 312.290406ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T06:55:56.059383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T06:55:55.746211Z","time spent":"313.124002ms","remote":"127.0.0.1:43274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-20T06:55:56.322991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.899455ms","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-20T06:55:56.323058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.955411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T06:55:56.324337Z","caller":"traceutil/trace.go:171","msg":"trace[1241018571] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20204; }","duration":"159.096219ms","start":"2026-08-20T06:55:56.16398Z","end":"2026-08-20T06:55:56.323076Z","steps":["trace[1241018571] 'range keys from in-memory index tree' (duration: 158.66995ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:55:56.325511Z","caller":"traceutil/trace.go:171","msg":"trace[514351534] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:20204; }","duration":"131.128064ms","start":"2026-08-20T06:55:56.192001Z","end":"2026-08-20T06:55:56.323129Z","steps":["trace[514351534] 'count revisions from in-memory index tree' (duration: 130.819378ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:55:56.654973Z","caller":"traceutil/trace.go:171","msg":"trace[235067999] transaction","detail":"{read_only:false; response_revision:20206; number_of_response:1; }","duration":"319.164166ms","start":"2026-08-20T06:55:56.335777Z","end":"2026-08-20T06:55:56.654941Z","steps":["trace[235067999] 'process raft request' (duration: 318.939502ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T06:55:56.65517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T06:55:56.335761Z","time spent":"319.303059ms","remote":"127.0.0.1:43274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T06:55:56.671411Z","caller":"traceutil/trace.go:171","msg":"trace[2144010711] transaction","detail":"{read_only:false; response_revision:20207; number_of_response:1; }","duration":"161.013826ms","start":"2026-08-20T06:55:56.51036Z","end":"2026-08-20T06:55:56.671374Z","steps":["trace[2144010711] 'process raft request' (duration: 160.831243ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T06:59:38.769769Z","caller":"traceutil/trace.go:171","msg":"trace[1877259578] transaction","detail":"{read_only:false; response_revision:22545; number_of_response:1; }","duration":"158.456897ms","start":"2026-08-20T06:59:38.611255Z","end":"2026-08-20T06:59:38.769712Z","steps":["trace[1877259578] 'process raft request' (duration: 156.490129ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T07:00:47.238532Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20160} {"level":"info","ts":"2026-08-20T07:00:47.353608Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20160,"took":"109.673354ms","hash":1439682442,"current-db-size-bytes":59043840,"current-db-size":"59 MB","current-db-size-in-use-bytes":43352064,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-20T07:00:47.353669Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1439682442,"revision":20160,"compact-revision":18159} {"level":"info","ts":"2026-08-20T07:05:47.245045Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23425} {"level":"info","ts":"2026-08-20T07:05:47.388228Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23425,"took":"138.183308ms","hash":1712934932,"current-db-size-bytes":59043840,"current-db-size":"59 MB","current-db-size-in-use-bytes":31567872,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-20T07:05:47.388298Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1712934932,"revision":23425,"compact-revision":20160}