{"level":"warn","ts":"2026-09-08T22:47:32.091249Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-08T22:47:32.091328Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.144:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.144:2380","--initial-cluster=instance=https://162.253.55.144:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.144:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.144: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-09-08T22:47:32.091384Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-08T22:47:32.091424Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.144:2380"]} {"level":"info","ts":"2026-09-08T22:47:32.091444Z","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-09-08T22:47:32.092072Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.144:2379"]} {"level":"info","ts":"2026-09-08T22:47:32.09218Z","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":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.144:2380"],"listen-peer-urls":["https://162.253.55.144:2380"],"advertise-client-urls":["https://162.253.55.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.144: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-09-08T22:47:32.098866Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.368363ms"} {"level":"info","ts":"2026-09-08T22:47:32.106904Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"873be3731fd7a3eb","cluster-id":"9ca43c00a45c1826"} {"level":"info","ts":"2026-09-08T22:47:32.106984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb switched to configuration voters=()"} {"level":"info","ts":"2026-09-08T22:47:32.107019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb became follower at term 0"} {"level":"info","ts":"2026-09-08T22:47:32.107045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 873be3731fd7a3eb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-08T22:47:32.107061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb became follower at term 1"} {"level":"info","ts":"2026-09-08T22:47:32.10709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb switched to configuration voters=(9744632302341170155)"} {"level":"warn","ts":"2026-09-08T22:47:32.117723Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-08T22:47:32.119144Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-08T22:47:32.120399Z","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-09-08T22:47:32.122443Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"873be3731fd7a3eb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-08T22:47:32.122571Z","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-09-08T22:47:32.122578Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"873be3731fd7a3eb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-08T22:47:32.122676Z","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-09-08T22:47:32.122692Z","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-09-08T22:47:32.124356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb switched to configuration voters=(9744632302341170155)"} {"level":"info","ts":"2026-09-08T22:47:32.124527Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ca43c00a45c1826","local-member-id":"873be3731fd7a3eb","added-peer-id":"873be3731fd7a3eb","added-peer-peer-urls":["https://162.253.55.144:2380"]} {"level":"info","ts":"2026-09-08T22:47:32.1267Z","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-09-08T22:47:32.126929Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"873be3731fd7a3eb","initial-advertise-peer-urls":["https://162.253.55.144:2380"],"listen-peer-urls":["https://162.253.55.144:2380"],"advertise-client-urls":["https://162.253.55.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-08T22:47:32.126973Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-08T22:47:32.127025Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.144:2380"} {"level":"info","ts":"2026-09-08T22:47:32.12706Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.144:2380"} {"level":"info","ts":"2026-09-08T22:47:32.708124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb is starting a new election at term 1"} {"level":"info","ts":"2026-09-08T22:47:32.708184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb became pre-candidate at term 1"} {"level":"info","ts":"2026-09-08T22:47:32.708217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb received MsgPreVoteResp from 873be3731fd7a3eb at term 1"} {"level":"info","ts":"2026-09-08T22:47:32.708235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb became candidate at term 2"} {"level":"info","ts":"2026-09-08T22:47:32.708246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb received MsgVoteResp from 873be3731fd7a3eb at term 2"} {"level":"info","ts":"2026-09-08T22:47:32.708261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb became leader at term 2"} {"level":"info","ts":"2026-09-08T22:47:32.708275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 873be3731fd7a3eb elected leader 873be3731fd7a3eb at term 2"} {"level":"info","ts":"2026-09-08T22:47:32.70923Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T22:47:32.710184Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T22:47:32.710199Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"873be3731fd7a3eb","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.144:2379]}","request-path":"/0/members/873be3731fd7a3eb/attributes","cluster-id":"9ca43c00a45c1826","publish-timeout":"7s"} {"level":"info","ts":"2026-09-08T22:47:32.710423Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T22:47:32.710642Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-08T22:47:32.710703Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-08T22:47:32.711728Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-08T22:47:32.711814Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ca43c00a45c1826","local-member-id":"873be3731fd7a3eb","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T22:47:32.711904Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T22:47:32.711929Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T22:47:32.714836Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.144:2379"} {"level":"info","ts":"2026-09-08T22:48:08.370058Z","caller":"traceutil/trace.go:171","msg":"trace[1959141532] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"113.691518ms","start":"2026-09-08T22:48:08.256344Z","end":"2026-09-08T22:48:08.370036Z","steps":["trace[1959141532] 'process raft request' (duration: 113.569665ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:48:08.376034Z","caller":"traceutil/trace.go:171","msg":"trace[1784987696] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"117.145363ms","start":"2026-09-08T22:48:08.258866Z","end":"2026-09-08T22:48:08.376011Z","steps":["trace[1784987696] 'process raft request' (duration: 116.938358ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:48:08.376148Z","caller":"traceutil/trace.go:171","msg":"trace[707192829] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"101.244123ms","start":"2026-09-08T22:48:08.274885Z","end":"2026-09-08T22:48:08.376129Z","steps":["trace[707192829] 'process raft request' (duration: 101.10963ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:48:08.376124Z","caller":"traceutil/trace.go:171","msg":"trace[1920737614] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"113.967064ms","start":"2026-09-08T22:48:08.262133Z","end":"2026-09-08T22:48:08.3761Z","steps":["trace[1920737614] 'process raft request' (duration: 113.773099ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:48:08.376336Z","caller":"traceutil/trace.go:171","msg":"trace[1696763636] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"101.275054ms","start":"2026-09-08T22:48:08.275045Z","end":"2026-09-08T22:48:08.37632Z","steps":["trace[1696763636] 'process raft request' (duration: 100.987767ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:48:08.376352Z","caller":"traceutil/trace.go:171","msg":"trace[1849671667] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"100.00702ms","start":"2026-09-08T22:48:08.276321Z","end":"2026-09-08T22:48:08.376328Z","steps":["trace[1849671667] 'process raft request' (duration: 99.746743ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:48:14.961179Z","caller":"traceutil/trace.go:171","msg":"trace[814073831] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"314.583744ms","start":"2026-09-08T22:48:14.646558Z","end":"2026-09-08T22:48:14.961141Z","steps":["trace[814073831] 'process raft request' (duration: 314.318425ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:48:14.96159Z","caller":"traceutil/trace.go:171","msg":"trace[1747971859] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:651; }","duration":"135.974456ms","start":"2026-09-08T22:48:14.825605Z","end":"2026-09-08T22:48:14.96158Z","steps":["trace[1747971859] 'read index received' (duration: 135.220873ms)","trace[1747971859] 'applied index is now lower than readState.Index' (duration: 753.073µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:48:14.961648Z","caller":"traceutil/trace.go:171","msg":"trace[374579252] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"288.442143ms","start":"2026-09-08T22:48:14.673199Z","end":"2026-09-08T22:48:14.961641Z","steps":["trace[374579252] 'process raft request' (duration: 287.950648ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:48:14.961869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.216486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-08T22:48:14.961927Z","caller":"traceutil/trace.go:171","msg":"trace[264033823] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:629; }","duration":"136.35582ms","start":"2026-09-08T22:48:14.82556Z","end":"2026-09-08T22:48:14.961915Z","steps":["trace[264033823] 'agreement among raft nodes before linearized reading' (duration: 136.177034ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:48:14.962037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:48:14.646534Z","time spent":"314.731889ms","remote":"127.0.0.1:42068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T22:48:15.157091Z","caller":"traceutil/trace.go:171","msg":"trace[400312846] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"170.105623ms","start":"2026-09-08T22:48:14.986957Z","end":"2026-09-08T22:48:15.157063Z","steps":["trace[400312846] 'process raft request' (duration: 169.953498ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:48:15.744815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.244485ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T22:48:15.745098Z","caller":"traceutil/trace.go:171","msg":"trace[1493404491] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"703.661341ms","start":"2026-09-08T22:48:15.041421Z","end":"2026-09-08T22:48:15.745082Z","steps":["trace[1493404491] 'process raft request' (duration: 703.493356ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:48:15.745238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:48:15.041404Z","time spent":"703.777736ms","remote":"127.0.0.1:42068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T22:48:15.74523Z","caller":"traceutil/trace.go:171","msg":"trace[394845082] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:655; }","duration":"344.151109ms","start":"2026-09-08T22:48:15.401051Z","end":"2026-09-08T22:48:15.745202Z","steps":["trace[394845082] 'read index received' (duration: 32.931µs)","trace[394845082] 'applied index is now lower than readState.Index' (duration: 344.116888ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:48:15.745379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.323734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:48:15.745618Z","caller":"traceutil/trace.go:171","msg":"trace[2141427533] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"705.857363ms","start":"2026-09-08T22:48:15.039132Z","end":"2026-09-08T22:48:15.744989Z","steps":["trace[2141427533] 'process raft request' (duration: 233.165974ms)","trace[2141427533] 'compare' (duration: 472.125001ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:48:15.745726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:48:15.039108Z","time spent":"706.567376ms","remote":"127.0.0.1:42068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T22:48:15.745631Z","caller":"traceutil/trace.go:171","msg":"trace[63412303] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"703.965302ms","start":"2026-09-08T22:48:15.041438Z","end":"2026-09-08T22:48:15.745403Z","steps":["trace[63412303] 'process raft request' (duration: 703.558219ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:48:15.74591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:48:15.04142Z","time spent":"704.456378ms","remote":"127.0.0.1:42032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-08T22:48:15.746039Z","caller":"traceutil/trace.go:171","msg":"trace[1887561973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"344.404977ms","start":"2026-09-08T22:48:15.401003Z","end":"2026-09-08T22:48:15.745408Z","steps":["trace[1887561973] 'agreement among raft nodes before linearized reading' (duration: 344.304554ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:48:15.746196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:48:15.400984Z","time spent":"345.191864ms","remote":"127.0.0.1:41778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-08T22:48:17.157319Z","caller":"traceutil/trace.go:171","msg":"trace[175347684] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:678; }","duration":"107.81744ms","start":"2026-09-08T22:48:17.049481Z","end":"2026-09-08T22:48:17.157298Z","steps":["trace[175347684] 'read index received' (duration: 107.675625ms)","trace[175347684] 'applied index is now lower than readState.Index' (duration: 140.985µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:48:17.157409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.910723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:48:17.157431Z","caller":"traceutil/trace.go:171","msg":"trace[1679854957] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:654; }","duration":"107.963215ms","start":"2026-09-08T22:48:17.049461Z","end":"2026-09-08T22:48:17.157424Z","steps":["trace[1679854957] 'agreement among raft nodes before linearized reading' (duration: 107.911833ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:48:17.157484Z","caller":"traceutil/trace.go:171","msg":"trace[931263028] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"118.210326ms","start":"2026-09-08T22:48:17.039258Z","end":"2026-09-08T22:48:17.157469Z","steps":["trace[931263028] 'process raft request' (duration: 117.915436ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:49:52.74651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.232396ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T22:49:52.746921Z","caller":"traceutil/trace.go:171","msg":"trace[762251534] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"377.704544ms","start":"2026-09-08T22:49:52.369177Z","end":"2026-09-08T22:49:52.746882Z","steps":["trace[762251534] 'process raft request' (duration: 276.945032ms)","trace[762251534] 'compare' (duration: 99.85231ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:49:52.74713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:49:52.369156Z","time spent":"377.859435ms","remote":"127.0.0.1:41988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T22:49:52.781386Z","caller":"traceutil/trace.go:171","msg":"trace[737151528] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"253.09209ms","start":"2026-09-08T22:49:52.52826Z","end":"2026-09-08T22:49:52.781352Z","steps":["trace[737151528] 'process raft request' (duration: 252.872174ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:50:05.6218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.378424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:50:05.621913Z","caller":"traceutil/trace.go:171","msg":"trace[2032703448] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1980; }","duration":"221.527804ms","start":"2026-09-08T22:50:05.400362Z","end":"2026-09-08T22:50:05.62189Z","steps":["trace[2032703448] 'range keys from in-memory index tree' (duration: 221.242644ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:50:09.028172Z","caller":"traceutil/trace.go:171","msg":"trace[1785633917] transaction","detail":"{read_only:false; response_revision:2008; number_of_response:1; }","duration":"406.531201ms","start":"2026-09-08T22:50:08.621613Z","end":"2026-09-08T22:50:09.028144Z","steps":["trace[1785633917] 'process raft request' (duration: 406.381721ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:50:09.028344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:50:08.621588Z","time spent":"406.65874ms","remote":"127.0.0.1:42068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T22:50:09.028967Z","caller":"traceutil/trace.go:171","msg":"trace[1346934371] linearizableReadLoop","detail":"{readStateIndex:2087; appliedIndex:2085; }","duration":"140.876818ms","start":"2026-09-08T22:50:08.888075Z","end":"2026-09-08T22:50:09.028952Z","steps":["trace[1346934371] 'read index received' (duration: 139.982086ms)","trace[1346934371] 'applied index is now lower than readState.Index' (duration: 893.402µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:50:09.02907Z","caller":"traceutil/trace.go:171","msg":"trace[1464394515] transaction","detail":"{read_only:false; response_revision:2010; number_of_response:1; }","duration":"117.573954ms","start":"2026-09-08T22:50:08.911484Z","end":"2026-09-08T22:50:09.029058Z","steps":["trace[1464394515] 'process raft request' (duration: 117.402092ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:50:09.029158Z","caller":"traceutil/trace.go:171","msg":"trace[1285180362] transaction","detail":"{read_only:false; response_revision:2009; number_of_response:1; }","duration":"186.667772ms","start":"2026-09-08T22:50:08.842422Z","end":"2026-09-08T22:50:09.02909Z","steps":["trace[1285180362] 'process raft request' (duration: 186.368961ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:50:09.02949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.421627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-08T22:50:09.029532Z","caller":"traceutil/trace.go:171","msg":"trace[298096341] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2010; }","duration":"141.503153ms","start":"2026-09-08T22:50:08.888018Z","end":"2026-09-08T22:50:09.029521Z","steps":["trace[298096341] 'agreement among raft nodes before linearized reading' (duration: 141.385405ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:23.629144Z","caller":"traceutil/trace.go:171","msg":"trace[1948685452] transaction","detail":"{read_only:false; response_revision:2823; number_of_response:1; }","duration":"216.320292ms","start":"2026-09-08T22:52:23.412803Z","end":"2026-09-08T22:52:23.629123Z","steps":["trace[1948685452] 'process raft request' (duration: 216.197221ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:23.654503Z","caller":"traceutil/trace.go:171","msg":"trace[538646338] transaction","detail":"{read_only:false; response_revision:2824; number_of_response:1; }","duration":"240.087793ms","start":"2026-09-08T22:52:23.414388Z","end":"2026-09-08T22:52:23.654476Z","steps":["trace[538646338] 'process raft request' (duration: 239.80682ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:23.654705Z","caller":"traceutil/trace.go:171","msg":"trace[169859291] transaction","detail":"{read_only:false; response_revision:2825; number_of_response:1; }","duration":"126.25684ms","start":"2026-09-08T22:52:23.528426Z","end":"2026-09-08T22:52:23.654683Z","steps":["trace[169859291] 'process raft request' (duration: 125.913316ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:23.655037Z","caller":"traceutil/trace.go:171","msg":"trace[1127090263] transaction","detail":"{read_only:false; response_revision:2826; number_of_response:1; }","duration":"123.235904ms","start":"2026-09-08T22:52:23.531786Z","end":"2026-09-08T22:52:23.655022Z","steps":["trace[1127090263] 'process raft request' (duration: 122.622038ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:52:57.39967Z","caller":"traceutil/trace.go:171","msg":"trace[659354101] transaction","detail":"{read_only:false; response_revision:3014; number_of_response:1; }","duration":"126.307579ms","start":"2026-09-08T22:52:57.273342Z","end":"2026-09-08T22:52:57.39965Z","steps":["trace[659354101] 'process raft request' (duration: 125.998222ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:57:10.810542Z","caller":"traceutil/trace.go:171","msg":"trace[1587549489] transaction","detail":"{read_only:false; response_revision:4869; number_of_response:1; }","duration":"159.67467ms","start":"2026-09-08T22:57:10.650788Z","end":"2026-09-08T22:57:10.810463Z","steps":["trace[1587549489] 'process raft request' (duration: 159.484019ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:57:10.857969Z","caller":"traceutil/trace.go:171","msg":"trace[467175504] transaction","detail":"{read_only:false; response_revision:4873; number_of_response:1; }","duration":"110.519402ms","start":"2026-09-08T22:57:10.747432Z","end":"2026-09-08T22:57:10.857951Z","steps":["trace[467175504] 'process raft request' (duration: 110.461618ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:57:10.858054Z","caller":"traceutil/trace.go:171","msg":"trace[522321693] transaction","detail":"{read_only:false; response_revision:4870; number_of_response:1; }","duration":"160.603396ms","start":"2026-09-08T22:57:10.697417Z","end":"2026-09-08T22:57:10.85802Z","steps":["trace[522321693] 'process raft request' (duration: 160.284917ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:57:10.858063Z","caller":"traceutil/trace.go:171","msg":"trace[286348452] transaction","detail":"{read_only:false; response_revision:4871; number_of_response:1; }","duration":"146.179897ms","start":"2026-09-08T22:57:10.7118Z","end":"2026-09-08T22:57:10.85798Z","steps":["trace[286348452] 'process raft request' (duration: 145.998066ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:57:10.858222Z","caller":"traceutil/trace.go:171","msg":"trace[888166687] linearizableReadLoop","detail":"{readStateIndex:5061; appliedIndex:5059; }","duration":"154.507869ms","start":"2026-09-08T22:57:10.703688Z","end":"2026-09-08T22:57:10.858196Z","steps":["trace[888166687] 'read index received' (duration: 106.50909ms)","trace[888166687] 'applied index is now lower than readState.Index' (duration: 47.997599ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:57:10.858254Z","caller":"traceutil/trace.go:171","msg":"trace[1926966996] transaction","detail":"{read_only:false; response_revision:4872; number_of_response:1; }","duration":"145.55564ms","start":"2026-09-08T22:57:10.712656Z","end":"2026-09-08T22:57:10.858211Z","steps":["trace[1926966996] 'process raft request' (duration: 145.195979ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:57:10.858483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.726383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-08T22:57:10.858539Z","caller":"traceutil/trace.go:171","msg":"trace[796269433] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4874; }","duration":"154.889343ms","start":"2026-09-08T22:57:10.703636Z","end":"2026-09-08T22:57:10.858525Z","steps":["trace[796269433] 'agreement among raft nodes before linearized reading' (duration: 154.6725ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:57:10.875981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.883253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-08T22:57:10.876061Z","caller":"traceutil/trace.go:171","msg":"trace[2029249812] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4876; }","duration":"159.991759ms","start":"2026-09-08T22:57:10.716056Z","end":"2026-09-08T22:57:10.876047Z","steps":["trace[2029249812] 'agreement among raft nodes before linearized reading' (duration: 159.808818ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:57:10.876067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.778456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T22:57:10.876125Z","caller":"traceutil/trace.go:171","msg":"trace[1492787359] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:4876; }","duration":"109.881003ms","start":"2026-09-08T22:57:10.766228Z","end":"2026-09-08T22:57:10.876109Z","steps":["trace[1492787359] 'agreement among raft nodes before linearized reading' (duration: 109.739524ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:57:10.876329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.755464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-09-08T22:57:10.876443Z","caller":"traceutil/trace.go:171","msg":"trace[1045135974] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4876; }","duration":"157.903963ms","start":"2026-09-08T22:57:10.71852Z","end":"2026-09-08T22:57:10.876424Z","steps":["trace[1045135974] 'agreement among raft nodes before linearized reading' (duration: 157.503229ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:57:24.034972Z","caller":"traceutil/trace.go:171","msg":"trace[1011285224] linearizableReadLoop","detail":"{readStateIndex:5408; appliedIndex:5406; }","duration":"121.39582ms","start":"2026-09-08T22:57:23.913551Z","end":"2026-09-08T22:57:24.034946Z","steps":["trace[1011285224] 'read index received' (duration: 96.40179ms)","trace[1011285224] 'applied index is now lower than readState.Index' (duration: 24.99289ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:57:24.035288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.838113ms","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-09-08T22:57:24.035367Z","caller":"traceutil/trace.go:171","msg":"trace[1319403201] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5209; }","duration":"104.959971ms","start":"2026-09-08T22:57:23.930388Z","end":"2026-09-08T22:57:24.035348Z","steps":["trace[1319403201] 'agreement among raft nodes before linearized reading' (duration: 104.731857ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:57:24.036156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.581112ms","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-09-08T22:57:24.03622Z","caller":"traceutil/trace.go:171","msg":"trace[1737601680] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5209; }","duration":"122.676988ms","start":"2026-09-08T22:57:23.913526Z","end":"2026-09-08T22:57:24.036203Z","steps":["trace[1737601680] 'agreement among raft nodes before linearized reading' (duration: 121.530218ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:57:24.036672Z","caller":"traceutil/trace.go:171","msg":"trace[400559498] transaction","detail":"{read_only:false; response_revision:5209; number_of_response:1; }","duration":"122.56787ms","start":"2026-09-08T22:57:23.912557Z","end":"2026-09-08T22:57:24.035125Z","steps":["trace[400559498] 'process raft request' (duration: 122.24081ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:57:32.742654Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2884} {"level":"info","ts":"2026-09-08T22:57:32.816526Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2884,"took":"72.330894ms","hash":924972506,"current-db-size-bytes":50950144,"current-db-size":"51 MB","current-db-size-in-use-bytes":27373568,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-08T22:57:32.816652Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":924972506,"revision":2884,"compact-revision":-1} {"level":"info","ts":"2026-09-08T22:57:37.137643Z","caller":"traceutil/trace.go:171","msg":"trace[595859679] transaction","detail":"{read_only:false; response_revision:5391; number_of_response:1; }","duration":"166.554727ms","start":"2026-09-08T22:57:36.971062Z","end":"2026-09-08T22:57:37.137616Z","steps":["trace[595859679] 'process raft request' (duration: 166.331013ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:57:37.437701Z","caller":"traceutil/trace.go:171","msg":"trace[1225098912] transaction","detail":"{read_only:false; response_revision:5392; number_of_response:1; }","duration":"457.616774ms","start":"2026-09-08T22:57:36.980053Z","end":"2026-09-08T22:57:37.43767Z","steps":["trace[1225098912] 'process raft request' (duration: 457.348138ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:57:37.437894Z","caller":"traceutil/trace.go:171","msg":"trace[454960363] transaction","detail":"{read_only:false; response_revision:5393; number_of_response:1; }","duration":"429.397473ms","start":"2026-09-08T22:57:37.008453Z","end":"2026-09-08T22:57:37.437851Z","steps":["trace[454960363] 'process raft request' (duration: 429.161319ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:57:37.43791Z","caller":"traceutil/trace.go:171","msg":"trace[1673362071] linearizableReadLoop","detail":"{readStateIndex:5600; appliedIndex:5597; }","duration":"427.316856ms","start":"2026-09-08T22:57:37.010556Z","end":"2026-09-08T22:57:37.437873Z","steps":["trace[1673362071] 'read index received' (duration: 126.833022ms)","trace[1673362071] 'applied index is now lower than readState.Index' (duration: 300.479914ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:57:37.437986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:57:36.980037Z","time spent":"457.748682ms","remote":"127.0.0.1:42068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T22:57:37.438132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.580603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-08T22:57:37.438183Z","caller":"traceutil/trace.go:171","msg":"trace[428391803] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5393; }","duration":"427.662207ms","start":"2026-09-08T22:57:37.010508Z","end":"2026-09-08T22:57:37.43817Z","steps":["trace[428391803] 'agreement among raft nodes before linearized reading' (duration: 427.464504ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:57:37.438219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:57:37.010496Z","time spent":"427.715181ms","remote":"127.0.0.1:42068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-08T22:57:37.438229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:57:37.008421Z","time spent":"429.708523ms","remote":"127.0.0.1:42068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T22:57:37.831251Z","caller":"traceutil/trace.go:171","msg":"trace[1304271710] linearizableReadLoop","detail":"{readStateIndex:5604; appliedIndex:5603; }","duration":"134.544054ms","start":"2026-09-08T22:57:37.696684Z","end":"2026-09-08T22:57:37.831228Z","steps":["trace[1304271710] 'read index received' (duration: 134.30462ms)","trace[1304271710] 'applied index is now lower than readState.Index' (duration: 238.444µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:57:37.831342Z","caller":"traceutil/trace.go:171","msg":"trace[2033472690] transaction","detail":"{read_only:false; response_revision:5397; number_of_response:1; }","duration":"147.281735ms","start":"2026-09-08T22:57:37.684029Z","end":"2026-09-08T22:57:37.831311Z","steps":["trace[2033472690] 'process raft request' (duration: 147.019419ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:57:37.831677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.980931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16549"} {"level":"info","ts":"2026-09-08T22:57:37.831999Z","caller":"traceutil/trace.go:171","msg":"trace[1704493736] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5397; }","duration":"135.385946ms","start":"2026-09-08T22:57:37.696597Z","end":"2026-09-08T22:57:37.831983Z","steps":["trace[1704493736] 'agreement among raft nodes before linearized reading' (duration: 134.782918ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:57:37.966684Z","caller":"traceutil/trace.go:171","msg":"trace[1371602157] linearizableReadLoop","detail":"{readStateIndex:5605; appliedIndex:5604; }","duration":"115.000855ms","start":"2026-09-08T22:57:37.851659Z","end":"2026-09-08T22:57:37.96666Z","steps":["trace[1371602157] 'read index received' (duration: 114.895309ms)","trace[1371602157] 'applied index is now lower than readState.Index' (duration: 104.646µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:57:37.966737Z","caller":"traceutil/trace.go:171","msg":"trace[2112725539] transaction","detail":"{read_only:false; response_revision:5398; number_of_response:1; }","duration":"115.105241ms","start":"2026-09-08T22:57:37.85161Z","end":"2026-09-08T22:57:37.966715Z","steps":["trace[2112725539] 'process raft request' (duration: 114.906639ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:57:37.966897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.218799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1455"} {"level":"info","ts":"2026-09-08T22:57:37.967022Z","caller":"traceutil/trace.go:171","msg":"trace[704993014] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5398; }","duration":"115.428521ms","start":"2026-09-08T22:57:37.851583Z","end":"2026-09-08T22:57:37.967012Z","steps":["trace[704993014] 'agreement among raft nodes before linearized reading' (duration: 115.174886ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:57:38.005959Z","caller":"traceutil/trace.go:171","msg":"trace[1593488956] transaction","detail":"{read_only:false; response_revision:5399; number_of_response:1; }","duration":"152.265572ms","start":"2026-09-08T22:57:37.853669Z","end":"2026-09-08T22:57:38.005935Z","steps":["trace[1593488956] 'process raft request' (duration: 127.685743ms)","trace[1593488956] 'compare' (duration: 24.228687ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T23:01:15.382862Z","caller":"traceutil/trace.go:171","msg":"trace[1176701725] linearizableReadLoop","detail":"{readStateIndex:7238; appliedIndex:7237; }","duration":"132.430993ms","start":"2026-09-08T23:01:15.250403Z","end":"2026-09-08T23:01:15.382834Z","steps":["trace[1176701725] 'read index received' (duration: 78.271181ms)","trace[1176701725] 'applied index is now lower than readState.Index' (duration: 54.158722ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T23:01:15.382907Z","caller":"traceutil/trace.go:171","msg":"trace[90420107] transaction","detail":"{read_only:false; response_revision:6981; number_of_response:1; }","duration":"149.44171ms","start":"2026-09-08T23:01:15.233434Z","end":"2026-09-08T23:01:15.382876Z","steps":["trace[90420107] 'process raft request' (duration: 95.283888ms)","trace[90420107] 'compare' (duration: 54.003372ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T23:01:15.383074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.650632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-08T23:01:15.383106Z","caller":"traceutil/trace.go:171","msg":"trace[1762020871] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6981; }","duration":"132.719612ms","start":"2026-09-08T23:01:15.250376Z","end":"2026-09-08T23:01:15.383096Z","steps":["trace[1762020871] 'agreement among raft nodes before linearized reading' (duration: 132.578233ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:01:38.491393Z","caller":"traceutil/trace.go:171","msg":"trace[44740653] transaction","detail":"{read_only:false; response_revision:7157; number_of_response:1; }","duration":"127.190527ms","start":"2026-09-08T23:01:38.364185Z","end":"2026-09-08T23:01:38.491376Z","steps":["trace[44740653] 'process raft request' (duration: 127.086305ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:01:38.576413Z","caller":"traceutil/trace.go:171","msg":"trace[1987890496] linearizableReadLoop","detail":"{readStateIndex:7423; appliedIndex:7421; }","duration":"179.124539ms","start":"2026-09-08T23:01:38.397271Z","end":"2026-09-08T23:01:38.576396Z","steps":["trace[1987890496] 'read index received' (duration: 93.978503ms)","trace[1987890496] 'applied index is now lower than readState.Index' (duration: 85.145316ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T23:01:38.576515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.23433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T23:01:38.576533Z","caller":"traceutil/trace.go:171","msg":"trace[448444345] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7158; }","duration":"179.26717ms","start":"2026-09-08T23:01:38.397261Z","end":"2026-09-08T23:01:38.576528Z","steps":["trace[448444345] 'agreement among raft nodes before linearized reading' (duration: 179.1906ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:01:38.57668Z","caller":"traceutil/trace.go:171","msg":"trace[1797837759] transaction","detail":"{read_only:false; response_revision:7158; number_of_response:1; }","duration":"212.382562ms","start":"2026-09-08T23:01:38.364292Z","end":"2026-09-08T23:01:38.576674Z","steps":["trace[1797837759] 'process raft request' (duration: 211.99945ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T23:02:32.748999Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5359} {"level":"info","ts":"2026-09-08T23:02:32.836811Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5359,"took":"85.982803ms","hash":1758211680,"current-db-size-bytes":50950144,"current-db-size":"51 MB","current-db-size-in-use-bytes":19513344,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-09-08T23:02:32.836878Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1758211680,"revision":5359,"compact-revision":2884}