{"level":"warn","ts":"2026-09-19T01:52:20.923364Z","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-19T01:52:20.923469Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.28: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.28:2380","--initial-cluster=instance=https://162.253.55.28:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.28:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.28: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-19T01:52:20.923542Z","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-19T01:52:20.923549Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.28:2380"]} {"level":"info","ts":"2026-09-19T01:52:20.923568Z","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-19T01:52:20.924206Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.28:2379"]} {"level":"info","ts":"2026-09-19T01:52:20.924275Z","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.28:2380"],"listen-peer-urls":["https://162.253.55.28:2380"],"advertise-client-urls":["https://162.253.55.28:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.28:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.28: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-19T01:52:20.928955Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.473527ms"} {"level":"info","ts":"2026-09-19T01:52:20.935044Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"328ca0518abde8c2","cluster-id":"3b57b7d6a07cbbf8"} {"level":"info","ts":"2026-09-19T01:52:20.935101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 switched to configuration voters=()"} {"level":"info","ts":"2026-09-19T01:52:20.935117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 became follower at term 0"} {"level":"info","ts":"2026-09-19T01:52:20.935127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 328ca0518abde8c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-19T01:52:20.935134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 became follower at term 1"} {"level":"info","ts":"2026-09-19T01:52:20.935169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 switched to configuration voters=(3642462470716385474)"} {"level":"warn","ts":"2026-09-19T01:52:20.942647Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-19T01:52:20.943936Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-19T01:52:20.946175Z","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-19T01:52:20.94791Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"328ca0518abde8c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-19T01:52:20.948101Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"328ca0518abde8c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-19T01:52:20.948138Z","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-19T01:52:20.948296Z","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-19T01:52:20.948308Z","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-19T01:52:20.949153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 switched to configuration voters=(3642462470716385474)"} {"level":"info","ts":"2026-09-19T01:52:20.949389Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3b57b7d6a07cbbf8","local-member-id":"328ca0518abde8c2","added-peer-id":"328ca0518abde8c2","added-peer-peer-urls":["https://162.253.55.28:2380"]} {"level":"info","ts":"2026-09-19T01:52:20.951085Z","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-19T01:52:20.951182Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.28:2380"} {"level":"info","ts":"2026-09-19T01:52:20.951243Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.28:2380"} {"level":"info","ts":"2026-09-19T01:52:20.951543Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"328ca0518abde8c2","initial-advertise-peer-urls":["https://162.253.55.28:2380"],"listen-peer-urls":["https://162.253.55.28:2380"],"advertise-client-urls":["https://162.253.55.28:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.28:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-19T01:52:20.951579Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-19T01:52:21.335789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 is starting a new election at term 1"} {"level":"info","ts":"2026-09-19T01:52:21.335893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-19T01:52:21.335944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 received MsgPreVoteResp from 328ca0518abde8c2 at term 1"} {"level":"info","ts":"2026-09-19T01:52:21.335965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 became candidate at term 2"} {"level":"info","ts":"2026-09-19T01:52:21.335976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 received MsgVoteResp from 328ca0518abde8c2 at term 2"} {"level":"info","ts":"2026-09-19T01:52:21.335991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 became leader at term 2"} {"level":"info","ts":"2026-09-19T01:52:21.33601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 328ca0518abde8c2 elected leader 328ca0518abde8c2 at term 2"} {"level":"info","ts":"2026-09-19T01:52:21.364356Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"328ca0518abde8c2","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.28:2379]}","request-path":"/0/members/328ca0518abde8c2/attributes","cluster-id":"3b57b7d6a07cbbf8","publish-timeout":"7s"} {"level":"info","ts":"2026-09-19T01:52:21.364458Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T01:52:21.364788Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T01:52:21.364912Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:52:21.365586Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-19T01:52:21.365613Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-19T01:52:21.367514Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3b57b7d6a07cbbf8","local-member-id":"328ca0518abde8c2","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:52:21.367648Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:52:21.367685Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T01:52:21.369103Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.28:2379"} {"level":"info","ts":"2026-09-19T01:52:21.369473Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-19T01:53:03.159114Z","caller":"traceutil/trace.go:171","msg":"trace[795396525] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"121.904922ms","start":"2026-09-19T01:53:03.03719Z","end":"2026-09-19T01:53:03.159095Z","steps":["trace[795396525] 'process raft request' (duration: 121.773629ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:53:03.166662Z","caller":"traceutil/trace.go:171","msg":"trace[475501277] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"125.667584ms","start":"2026-09-19T01:53:03.040965Z","end":"2026-09-19T01:53:03.166633Z","steps":["trace[475501277] 'process raft request' (duration: 125.402737ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:53:03.166975Z","caller":"traceutil/trace.go:171","msg":"trace[346449739] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"109.460871ms","start":"2026-09-19T01:53:03.057336Z","end":"2026-09-19T01:53:03.166797Z","steps":["trace[346449739] 'process raft request' (duration: 109.191573ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:53:03.166943Z","caller":"traceutil/trace.go:171","msg":"trace[1137773331] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"109.374387ms","start":"2026-09-19T01:53:03.057447Z","end":"2026-09-19T01:53:03.166821Z","steps":["trace[1137773331] 'process raft request' (duration: 109.124121ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:53:07.997538Z","caller":"traceutil/trace.go:171","msg":"trace[1702608055] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"187.4669ms","start":"2026-09-19T01:53:07.810042Z","end":"2026-09-19T01:53:07.997509Z","steps":["trace[1702608055] 'process raft request' (duration: 187.256535ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:53:08.190772Z","caller":"traceutil/trace.go:171","msg":"trace[792220124] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"126.117773ms","start":"2026-09-19T01:53:08.06462Z","end":"2026-09-19T01:53:08.190738Z","steps":["trace[792220124] 'read index received' (duration: 125.194801ms)","trace[792220124] 'applied index is now lower than readState.Index' (duration: 921.612µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:53:08.190931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.275156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:53:08.191078Z","caller":"traceutil/trace.go:171","msg":"trace[522775388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"126.485141ms","start":"2026-09-19T01:53:08.06457Z","end":"2026-09-19T01:53:08.191055Z","steps":["trace[522775388] 'agreement among raft nodes before linearized reading' (duration: 126.282046ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:53:08.190932Z","caller":"traceutil/trace.go:171","msg":"trace[2103931949] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"165.33928ms","start":"2026-09-19T01:53:08.025523Z","end":"2026-09-19T01:53:08.190862Z","steps":["trace[2103931949] 'process raft request' (duration: 164.579792ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:53:08.409148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.96018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:53:08.409271Z","caller":"traceutil/trace.go:171","msg":"trace[1403584705] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:632; }","duration":"138.178795ms","start":"2026-09-19T01:53:08.271068Z","end":"2026-09-19T01:53:08.409247Z","steps":["trace[1403584705] 'range keys from in-memory index tree' (duration: 137.662153ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:53:08.409254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.222554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-19T01:53:08.409366Z","caller":"traceutil/trace.go:171","msg":"trace[1960920529] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"120.408468ms","start":"2026-09-19T01:53:08.288933Z","end":"2026-09-19T01:53:08.409342Z","steps":["trace[1960920529] 'range keys from in-memory index tree' (duration: 119.982029ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:53:09.849806Z","caller":"traceutil/trace.go:171","msg":"trace[568157617] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"379.215522ms","start":"2026-09-19T01:53:09.470557Z","end":"2026-09-19T01:53:09.849772Z","steps":["trace[568157617] 'process raft request' (duration: 379.083749ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:53:09.850208Z","caller":"traceutil/trace.go:171","msg":"trace[2022937621] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:662; }","duration":"337.886772ms","start":"2026-09-19T01:53:09.512292Z","end":"2026-09-19T01:53:09.850179Z","steps":["trace[2022937621] 'read index received' (duration: 337.872982ms)","trace[2022937621] 'applied index is now lower than readState.Index' (duration: 11.85µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:53:09.850502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.160838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-19T01:53:09.850547Z","caller":"traceutil/trace.go:171","msg":"trace[659032808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"338.285321ms","start":"2026-09-19T01:53:09.512246Z","end":"2026-09-19T01:53:09.850531Z","steps":["trace[659032808] 'agreement among raft nodes before linearized reading' (duration: 338.059796ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:53:09.850748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:53:09.512225Z","time spent":"338.481935ms","remote":"127.0.0.1:58354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-19T01:53:09.851256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:53:09.470539Z","time spent":"379.668133ms","remote":"127.0.0.1:58354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T01:53:10.35056Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16772144671686118504,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-19T01:53:10.455962Z","caller":"traceutil/trace.go:171","msg":"trace[1954680621] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"620.182107ms","start":"2026-09-19T01:53:09.835751Z","end":"2026-09-19T01:53:10.455933Z","steps":["trace[1954680621] 'process raft request' (duration: 602.315744ms)","trace[1954680621] 'compare' (duration: 16.997232ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:53:10.456116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:53:09.835725Z","time spent":"620.311339ms","remote":"127.0.0.1:58208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:53:10.456885Z","caller":"traceutil/trace.go:171","msg":"trace[1980215121] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"601.662135ms","start":"2026-09-19T01:53:09.855189Z","end":"2026-09-19T01:53:10.456851Z","steps":["trace[1980215121] 'process raft request' (duration: 600.690793ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:53:10.457044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:53:09.855165Z","time spent":"601.795909ms","remote":"127.0.0.1:58354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T01:53:10.519535Z","caller":"traceutil/trace.go:171","msg":"trace[1375648726] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:662; }","duration":"669.164454ms","start":"2026-09-19T01:53:09.850286Z","end":"2026-09-19T01:53:10.51945Z","steps":["trace[1375648726] 'read index received' (duration: 587.566871ms)","trace[1375648726] 'applied index is now lower than readState.Index' (duration: 81.596383ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:53:10.519927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.191092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-19T01:53:10.519961Z","caller":"traceutil/trace.go:171","msg":"trace[1764244323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:643; }","duration":"517.275784ms","start":"2026-09-19T01:53:10.002677Z","end":"2026-09-19T01:53:10.519952Z","steps":["trace[1764244323] 'agreement among raft nodes before linearized reading' (duration: 517.152371ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:53:10.519985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:53:10.002654Z","time spent":"517.324605ms","remote":"127.0.0.1:58354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-19T01:53:10.520123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.259467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:53:10.520147Z","caller":"traceutil/trace.go:171","msg":"trace[1427662275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"456.311858ms","start":"2026-09-19T01:53:10.063828Z","end":"2026-09-19T01:53:10.52014Z","steps":["trace[1427662275] 'agreement among raft nodes before linearized reading' (duration: 456.275647ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:53:10.520164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:53:10.063809Z","time spent":"456.350428ms","remote":"127.0.0.1:58088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-19T01:53:10.520796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.214812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tpn6f\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-19T01:53:10.520966Z","caller":"traceutil/trace.go:171","msg":"trace[375506794] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tpn6f; range_end:; response_count:1; response_revision:643; }","duration":"767.340825ms","start":"2026-09-19T01:53:09.753551Z","end":"2026-09-19T01:53:10.520892Z","steps":["trace[375506794] 'agreement among raft nodes before linearized reading' (duration: 767.172151ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:53:10.521033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T01:53:09.753487Z","time spent":"767.53692ms","remote":"127.0.0.1:58298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-tpn6f\" "} {"level":"info","ts":"2026-09-19T01:53:11.897834Z","caller":"traceutil/trace.go:171","msg":"trace[427436276] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"113.909229ms","start":"2026-09-19T01:53:11.783891Z","end":"2026-09-19T01:53:11.8978Z","steps":["trace[427436276] 'process raft request' (duration: 113.719355ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:54:32.856059Z","caller":"traceutil/trace.go:171","msg":"trace[1387737607] transaction","detail":"{read_only:false; response_revision:1671; number_of_response:1; }","duration":"128.26827ms","start":"2026-09-19T01:54:32.727705Z","end":"2026-09-19T01:54:32.855973Z","steps":["trace[1387737607] 'process raft request' (duration: 122.700794ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:54:33.242363Z","caller":"traceutil/trace.go:171","msg":"trace[2101840561] transaction","detail":"{read_only:false; response_revision:1676; number_of_response:1; }","duration":"101.792865ms","start":"2026-09-19T01:54:33.140536Z","end":"2026-09-19T01:54:33.242328Z","steps":["trace[2101840561] 'process raft request' (duration: 101.581782ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:54:46.784277Z","caller":"traceutil/trace.go:171","msg":"trace[314802441] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"294.788612ms","start":"2026-09-19T01:54:46.489459Z","end":"2026-09-19T01:54:46.784248Z","steps":["trace[314802441] 'process raft request' (duration: 228.598509ms)","trace[314802441] 'compare' (duration: 65.516384ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:54:46.784325Z","caller":"traceutil/trace.go:171","msg":"trace[1202248051] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"292.818693ms","start":"2026-09-19T01:54:46.491464Z","end":"2026-09-19T01:54:46.784283Z","steps":["trace[1202248051] 'process raft request' (duration: 292.360966ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:54:55.222217Z","caller":"traceutil/trace.go:171","msg":"trace[1832716711] linearizableReadLoop","detail":"{readStateIndex:2042; appliedIndex:2040; }","duration":"125.054991ms","start":"2026-09-19T01:54:55.097134Z","end":"2026-09-19T01:54:55.222189Z","steps":["trace[1832716711] 'read index received' (duration: 50.404543ms)","trace[1832716711] 'applied index is now lower than readState.Index' (duration: 74.649138ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:54:55.222394Z","caller":"traceutil/trace.go:171","msg":"trace[1459361663] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"129.944472ms","start":"2026-09-19T01:54:55.092437Z","end":"2026-09-19T01:54:55.222381Z","steps":["trace[1459361663] 'process raft request' (duration: 129.015559ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:54:55.223318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.164796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11272"} {"level":"info","ts":"2026-09-19T01:54:55.223369Z","caller":"traceutil/trace.go:171","msg":"trace[1796984440] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1981; }","duration":"126.252729ms","start":"2026-09-19T01:54:55.097101Z","end":"2026-09-19T01:54:55.223353Z","steps":["trace[1796984440] 'agreement among raft nodes before linearized reading' (duration: 126.121376ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:55:19.10777Z","caller":"traceutil/trace.go:171","msg":"trace[180328012] linearizableReadLoop","detail":"{readStateIndex:2223; appliedIndex:2222; }","duration":"106.828777ms","start":"2026-09-19T01:55:19.000904Z","end":"2026-09-19T01:55:19.107733Z","steps":["trace[180328012] 'read index received' (duration: 105.866944ms)","trace[180328012] 'applied index is now lower than readState.Index' (duration: 960.483µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:55:19.107796Z","caller":"traceutil/trace.go:171","msg":"trace[1103390078] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"115.161673ms","start":"2026-09-19T01:55:18.992597Z","end":"2026-09-19T01:55:19.107758Z","steps":["trace[1103390078] 'process raft request' (duration: 114.265461ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:55:19.108617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.70182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-19T01:55:19.10871Z","caller":"traceutil/trace.go:171","msg":"trace[2040156650] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2153; }","duration":"107.875692ms","start":"2026-09-19T01:55:19.000811Z","end":"2026-09-19T01:55:19.108687Z","steps":["trace[2040156650] 'agreement among raft nodes before linearized reading' (duration: 107.05323ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:56:19.88281Z","caller":"traceutil/trace.go:171","msg":"trace[2038081961] transaction","detail":"{read_only:false; response_revision:2672; number_of_response:1; }","duration":"167.641133ms","start":"2026-09-19T01:56:19.715145Z","end":"2026-09-19T01:56:19.882786Z","steps":["trace[2038081961] 'process raft request' (duration: 167.492511ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:56:19.918067Z","caller":"traceutil/trace.go:171","msg":"trace[1862603641] transaction","detail":"{read_only:false; response_revision:2673; number_of_response:1; }","duration":"201.925671ms","start":"2026-09-19T01:56:19.716113Z","end":"2026-09-19T01:56:19.918039Z","steps":["trace[1862603641] 'process raft request' (duration: 197.682205ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:56:52.903111Z","caller":"traceutil/trace.go:171","msg":"trace[813658958] transaction","detail":"{read_only:false; response_revision:2899; number_of_response:1; }","duration":"196.036775ms","start":"2026-09-19T01:56:52.707049Z","end":"2026-09-19T01:56:52.903085Z","steps":["trace[813658958] 'process raft request' (duration: 149.208645ms)","trace[813658958] 'compare' (duration: 46.63234ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:56:52.903107Z","caller":"traceutil/trace.go:171","msg":"trace[543997736] linearizableReadLoop","detail":"{readStateIndex:3011; appliedIndex:3009; }","duration":"161.441739ms","start":"2026-09-19T01:56:52.741636Z","end":"2026-09-19T01:56:52.903078Z","steps":["trace[543997736] 'read index received' (duration: 1.372559ms)","trace[543997736] 'applied index is now lower than readState.Index' (duration: 160.06733ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:56:52.903407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.748145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-09-19T01:56:52.903491Z","caller":"traceutil/trace.go:171","msg":"trace[1251826536] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2899; }","duration":"161.87083ms","start":"2026-09-19T01:56:52.741605Z","end":"2026-09-19T01:56:52.903476Z","steps":["trace[1251826536] 'agreement among raft nodes before linearized reading' (duration: 161.647639ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:56:52.903503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.135393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-09-19T01:56:52.903559Z","caller":"traceutil/trace.go:171","msg":"trace[1903367216] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2899; }","duration":"157.228568ms","start":"2026-09-19T01:56:52.746316Z","end":"2026-09-19T01:56:52.903545Z","steps":["trace[1903367216] 'agreement among raft nodes before linearized reading' (duration: 157.092471ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:56:53.155482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.619738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T01:56:53.155784Z","caller":"traceutil/trace.go:171","msg":"trace[1303542821] transaction","detail":"{read_only:false; response_revision:2900; number_of_response:1; }","duration":"247.826873ms","start":"2026-09-19T01:56:52.907891Z","end":"2026-09-19T01:56:53.155718Z","steps":["trace[1303542821] 'process raft request' (duration: 99.622386ms)","trace[1303542821] 'compare' (duration: 147.508403ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:56:53.155854Z","caller":"traceutil/trace.go:171","msg":"trace[708301684] transaction","detail":"{read_only:false; response_revision:2901; number_of_response:1; }","duration":"247.731799ms","start":"2026-09-19T01:56:52.908039Z","end":"2026-09-19T01:56:53.155771Z","steps":["trace[708301684] 'process raft request' (duration: 247.55443ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:59:16.869182Z","caller":"traceutil/trace.go:171","msg":"trace[1107463273] linearizableReadLoop","detail":"{readStateIndex:4671; appliedIndex:4670; }","duration":"110.620028ms","start":"2026-09-19T01:59:16.758537Z","end":"2026-09-19T01:59:16.869157Z","steps":["trace[1107463273] 'read index received' (duration: 75.615294ms)","trace[1107463273] 'applied index is now lower than readState.Index' (duration: 35.003984ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:59:16.869392Z","caller":"traceutil/trace.go:171","msg":"trace[808779583] transaction","detail":"{read_only:false; response_revision:4480; number_of_response:1; }","duration":"132.339556ms","start":"2026-09-19T01:59:16.736991Z","end":"2026-09-19T01:59:16.869331Z","steps":["trace[808779583] 'process raft request' (duration: 97.135756ms)","trace[808779583] 'compare' (duration: 34.918162ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:59:16.869674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.122053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-09-19T01:59:16.869754Z","caller":"traceutil/trace.go:171","msg":"trace[1470799594] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4480; }","duration":"111.240056ms","start":"2026-09-19T01:59:16.758501Z","end":"2026-09-19T01:59:16.869741Z","steps":["trace[1470799594] 'agreement among raft nodes before linearized reading' (duration: 111.022009ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:16.870584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.517355ms","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-19T01:59:16.87062Z","caller":"traceutil/trace.go:171","msg":"trace[1362703058] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4480; }","duration":"106.595017ms","start":"2026-09-19T01:59:16.764016Z","end":"2026-09-19T01:59:16.870612Z","steps":["trace[1362703058] 'agreement among raft nodes before linearized reading' (duration: 105.655332ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:16.871706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.422255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-19T01:59:16.872079Z","caller":"traceutil/trace.go:171","msg":"trace[1074678096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4480; }","duration":"103.586544ms","start":"2026-09-19T01:59:16.768469Z","end":"2026-09-19T01:59:16.872056Z","steps":["trace[1074678096] 'agreement among raft nodes before linearized reading' (duration: 101.329423ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:59:19.049107Z","caller":"traceutil/trace.go:171","msg":"trace[1353020336] linearizableReadLoop","detail":"{readStateIndex:4690; appliedIndex:4688; }","duration":"156.663157ms","start":"2026-09-19T01:59:18.892419Z","end":"2026-09-19T01:59:19.049082Z","steps":["trace[1353020336] 'read index received' (duration: 45.951748ms)","trace[1353020336] 'applied index is now lower than readState.Index' (duration: 110.710129ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:59:19.049242Z","caller":"traceutil/trace.go:171","msg":"trace[872576817] transaction","detail":"{read_only:false; response_revision:4499; number_of_response:1; }","duration":"162.28187ms","start":"2026-09-19T01:59:18.886926Z","end":"2026-09-19T01:59:19.049208Z","steps":["trace[872576817] 'process raft request' (duration: 158.177588ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:19.049609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.17643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-09-19T01:59:19.049651Z","caller":"traceutil/trace.go:171","msg":"trace[1841767501] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4499; }","duration":"157.274293ms","start":"2026-09-19T01:59:18.892363Z","end":"2026-09-19T01:59:19.049637Z","steps":["trace[1841767501] 'agreement among raft nodes before linearized reading' (duration: 156.844361ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:59:25.109194Z","caller":"traceutil/trace.go:171","msg":"trace[147117087] transaction","detail":"{read_only:false; response_revision:4663; number_of_response:1; }","duration":"216.537085ms","start":"2026-09-19T01:59:24.89263Z","end":"2026-09-19T01:59:25.109168Z","steps":["trace[147117087] 'process raft request' (duration: 216.33871ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:25.109309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.627418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:59:25.109347Z","caller":"traceutil/trace.go:171","msg":"trace[615501718] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4665; }","duration":"216.7177ms","start":"2026-09-19T01:59:24.892619Z","end":"2026-09-19T01:59:25.109337Z","steps":["trace[615501718] 'agreement among raft nodes before linearized reading' (duration: 216.623028ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:59:25.109301Z","caller":"traceutil/trace.go:171","msg":"trace[1872501785] transaction","detail":"{read_only:false; response_revision:4664; number_of_response:1; }","duration":"210.74207ms","start":"2026-09-19T01:59:24.89853Z","end":"2026-09-19T01:59:25.109272Z","steps":["trace[1872501785] 'process raft request' (duration: 210.556335ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:59:25.109621Z","caller":"traceutil/trace.go:171","msg":"trace[15434584] transaction","detail":"{read_only:false; response_revision:4665; number_of_response:1; }","duration":"167.718953ms","start":"2026-09-19T01:59:24.94189Z","end":"2026-09-19T01:59:25.109609Z","steps":["trace[15434584] 'process raft request' (duration: 167.25788ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:59:25.109205Z","caller":"traceutil/trace.go:171","msg":"trace[521520926] linearizableReadLoop","detail":"{readStateIndex:4860; appliedIndex:4858; }","duration":"216.529055ms","start":"2026-09-19T01:59:24.89266Z","end":"2026-09-19T01:59:25.109189Z","steps":["trace[521520926] 'read index received' (duration: 63.777884ms)","trace[521520926] 'applied index is now lower than readState.Index' (duration: 152.750081ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:59:25.116763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.143854ms","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-19T01:59:25.116821Z","caller":"traceutil/trace.go:171","msg":"trace[213103244] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4666; }","duration":"152.209716ms","start":"2026-09-19T01:59:24.964597Z","end":"2026-09-19T01:59:25.116807Z","steps":["trace[213103244] 'agreement among raft nodes before linearized reading' (duration: 152.065412ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:59:25.356915Z","caller":"traceutil/trace.go:171","msg":"trace[1886805162] transaction","detail":"{read_only:false; response_revision:4671; number_of_response:1; }","duration":"134.577749ms","start":"2026-09-19T01:59:25.222313Z","end":"2026-09-19T01:59:25.356891Z","steps":["trace[1886805162] 'process raft request' (duration: 134.456086ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:59:25.40606Z","caller":"traceutil/trace.go:171","msg":"trace[1686242844] linearizableReadLoop","detail":"{readStateIndex:4869; appliedIndex:4867; }","duration":"130.137981ms","start":"2026-09-19T01:59:25.275904Z","end":"2026-09-19T01:59:25.406042Z","steps":["trace[1686242844] 'read index received' (duration: 80.928291ms)","trace[1686242844] 'applied index is now lower than readState.Index' (duration: 49.20852ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T01:59:25.40619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.96792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T01:59:25.406213Z","caller":"traceutil/trace.go:171","msg":"trace[243751025] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:4671; }","duration":"182.036572ms","start":"2026-09-19T01:59:25.224169Z","end":"2026-09-19T01:59:25.406206Z","steps":["trace[243751025] 'agreement among raft nodes before linearized reading' (duration: 181.96461ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:25.406371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.241286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-09-19T01:59:25.406441Z","caller":"traceutil/trace.go:171","msg":"trace[2095198673] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4671; }","duration":"126.35958ms","start":"2026-09-19T01:59:25.280061Z","end":"2026-09-19T01:59:25.406421Z","steps":["trace[2095198673] 'agreement among raft nodes before linearized reading' (duration: 126.127583ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:25.406551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.997296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-09-19T01:59:25.406574Z","caller":"traceutil/trace.go:171","msg":"trace[1043052614] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4671; }","duration":"124.042518ms","start":"2026-09-19T01:59:25.282525Z","end":"2026-09-19T01:59:25.406568Z","steps":["trace[1043052614] 'agreement among raft nodes before linearized reading' (duration: 123.929525ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:25.406735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.843778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-09-19T01:59:25.406771Z","caller":"traceutil/trace.go:171","msg":"trace[1314507257] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4671; }","duration":"124.91235ms","start":"2026-09-19T01:59:25.28185Z","end":"2026-09-19T01:59:25.406762Z","steps":["trace[1314507257] 'agreement among raft nodes before linearized reading' (duration: 124.815667ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:59:25.408035Z","caller":"traceutil/trace.go:171","msg":"trace[912885989] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4671; }","duration":"185.726491ms","start":"2026-09-19T01:59:25.222252Z","end":"2026-09-19T01:59:25.407979Z","steps":["trace[912885989] 'process raft request' (duration: 164.80185ms)","trace[912885989] 'compare' (duration: 18.942898ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:59:25.587272Z","caller":"traceutil/trace.go:171","msg":"trace[1305345663] linearizableReadLoop","detail":"{readStateIndex:4874; appliedIndex:4872; }","duration":"106.795805ms","start":"2026-09-19T01:59:25.48045Z","end":"2026-09-19T01:59:25.587246Z","steps":["trace[1305345663] 'read index received' (duration: 29.261065ms)","trace[1305345663] 'applied index is now lower than readState.Index' (duration: 77.5333ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:59:25.587646Z","caller":"traceutil/trace.go:171","msg":"trace[1728587968] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4676; }","duration":"108.065268ms","start":"2026-09-19T01:59:25.479541Z","end":"2026-09-19T01:59:25.587607Z","steps":["trace[1728587968] 'process raft request' (duration: 107.464533ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:25.588173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.677818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-09-19T01:59:25.588282Z","caller":"traceutil/trace.go:171","msg":"trace[711357592] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4677; }","duration":"107.817021ms","start":"2026-09-19T01:59:25.480437Z","end":"2026-09-19T01:59:25.588254Z","steps":["trace[711357592] 'agreement among raft nodes before linearized reading' (duration: 107.523594ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:59:25.878065Z","caller":"traceutil/trace.go:171","msg":"trace[68829501] linearizableReadLoop","detail":"{readStateIndex:4878; appliedIndex:4876; }","duration":"110.204045ms","start":"2026-09-19T01:59:25.767826Z","end":"2026-09-19T01:59:25.87803Z","steps":["trace[68829501] 'read index received' (duration: 18.071174ms)","trace[68829501] 'applied index is now lower than readState.Index' (duration: 92.131021ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:59:25.878261Z","caller":"traceutil/trace.go:171","msg":"trace[771625359] transaction","detail":"{read_only:false; response_revision:4680; number_of_response:1; }","duration":"123.887264ms","start":"2026-09-19T01:59:25.754336Z","end":"2026-09-19T01:59:25.878223Z","steps":["trace[771625359] 'process raft request' (duration: 123.461242ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:25.878382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.528845ms","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-19T01:59:25.878449Z","caller":"traceutil/trace.go:171","msg":"trace[2002994436] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4680; }","duration":"110.647087ms","start":"2026-09-19T01:59:25.767782Z","end":"2026-09-19T01:59:25.878429Z","steps":["trace[2002994436] 'agreement among raft nodes before linearized reading' (duration: 110.37195ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:37.91729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.220568ms","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-09-19T01:59:37.917364Z","caller":"traceutil/trace.go:171","msg":"trace[1618370467] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:4869; }","duration":"266.355632ms","start":"2026-09-19T01:59:37.65099Z","end":"2026-09-19T01:59:37.917346Z","steps":["trace[1618370467] 'count revisions from in-memory index tree' (duration: 266.133226ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T01:59:38.121851Z","caller":"traceutil/trace.go:171","msg":"trace[1114915550] linearizableReadLoop","detail":"{readStateIndex:5075; appliedIndex:5074; }","duration":"176.107945ms","start":"2026-09-19T01:59:37.945721Z","end":"2026-09-19T01:59:38.121829Z","steps":["trace[1114915550] 'read index received' (duration: 175.991342ms)","trace[1114915550] 'applied index is now lower than readState.Index' (duration: 116.073µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T01:59:38.121886Z","caller":"traceutil/trace.go:171","msg":"trace[1396947024] transaction","detail":"{read_only:false; response_revision:4870; number_of_response:1; }","duration":"192.019507ms","start":"2026-09-19T01:59:37.929849Z","end":"2026-09-19T01:59:38.121868Z","steps":["trace[1396947024] 'process raft request' (duration: 191.870724ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T01:59:38.122018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.28154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-19T01:59:38.122038Z","caller":"traceutil/trace.go:171","msg":"trace[110730010] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4870; }","duration":"176.344131ms","start":"2026-09-19T01:59:37.945687Z","end":"2026-09-19T01:59:38.122031Z","steps":["trace[110730010] 'agreement among raft nodes before linearized reading' (duration: 176.233139ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:00:14.767591Z","caller":"traceutil/trace.go:171","msg":"trace[1509506418] transaction","detail":"{read_only:false; response_revision:5252; number_of_response:1; }","duration":"140.445176ms","start":"2026-09-19T02:00:14.62712Z","end":"2026-09-19T02:00:14.767565Z","steps":["trace[1509506418] 'process raft request' (duration: 74.707024ms)","trace[1509506418] 'compare' (duration: 65.621769ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T02:02:21.403251Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3087} {"level":"info","ts":"2026-09-19T02:02:21.537322Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3087,"took":"132.117247ms","hash":772400922,"current-db-size-bytes":58556416,"current-db-size":"59 MB","current-db-size-in-use-bytes":31006720,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-19T02:02:21.537435Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":772400922,"revision":3087,"compact-revision":-1} {"level":"info","ts":"2026-09-19T02:03:37.626116Z","caller":"traceutil/trace.go:171","msg":"trace[192716532] transaction","detail":"{read_only:false; response_revision:6952; number_of_response:1; }","duration":"121.625408ms","start":"2026-09-19T02:03:37.504441Z","end":"2026-09-19T02:03:37.626067Z","steps":["trace[192716532] 'process raft request' (duration: 121.420314ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:03:37.737041Z","caller":"traceutil/trace.go:171","msg":"trace[1533164460] linearizableReadLoop","detail":"{readStateIndex:7264; appliedIndex:7263; }","duration":"108.407337ms","start":"2026-09-19T02:03:37.628604Z","end":"2026-09-19T02:03:37.737012Z","steps":["trace[1533164460] 'read index received' (duration: 103.039309ms)","trace[1533164460] 'applied index is now lower than readState.Index' (duration: 5.367058ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T02:03:37.737412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.771763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"info","ts":"2026-09-19T02:03:37.737491Z","caller":"traceutil/trace.go:171","msg":"trace[304185028] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:6953; }","duration":"108.875126ms","start":"2026-09-19T02:03:37.628599Z","end":"2026-09-19T02:03:37.737474Z","steps":["trace[304185028] 'agreement among raft nodes before linearized reading' (duration: 108.57432ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:03:37.738089Z","caller":"traceutil/trace.go:171","msg":"trace[458858557] transaction","detail":"{read_only:false; response_revision:6953; number_of_response:1; }","duration":"202.649708ms","start":"2026-09-19T02:03:37.535424Z","end":"2026-09-19T02:03:37.738073Z","steps":["trace[458858557] 'process raft request' (duration: 196.292152ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T02:07:21.411957Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6216} {"level":"info","ts":"2026-09-19T02:07:21.51956Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6216,"took":"106.056276ms","hash":3413961171,"current-db-size-bytes":58556416,"current-db-size":"59 MB","current-db-size-in-use-bytes":18743296,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-19T02:07:21.519638Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3413961171,"revision":6216,"compact-revision":3087}