{"level":"warn","ts":"2026-09-18T01:17:25.155272Z","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-18T01:17:25.15568Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.140: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.140:2380","--initial-cluster=instance=https://162.253.55.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.140: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-18T01:17:25.155784Z","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-18T01:17:25.155807Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.140:2380"]} {"level":"info","ts":"2026-09-18T01:17:25.155827Z","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-18T01:17:25.156535Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"]} {"level":"info","ts":"2026-09-18T01:17:25.156626Z","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.140:2380"],"listen-peer-urls":["https://162.253.55.140:2380"],"advertise-client-urls":["https://162.253.55.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.140: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-18T01:17:25.162269Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.329501ms"} {"level":"info","ts":"2026-09-18T01:17:25.16966Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cf809824ab95ac81","cluster-id":"6f89f5fbef18dc61"} {"level":"info","ts":"2026-09-18T01:17:25.169725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T01:17:25.169744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became follower at term 0"} {"level":"info","ts":"2026-09-18T01:17:25.169755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cf809824ab95ac81 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T01:17:25.169762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became follower at term 1"} {"level":"info","ts":"2026-09-18T01:17:25.1698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=(14952118046135004289)"} {"level":"warn","ts":"2026-09-18T01:17:25.184135Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T01:17:25.186293Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T01:17:25.191989Z","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-18T01:17:25.194862Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cf809824ab95ac81","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T01:17:25.195472Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cf809824ab95ac81","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T01:17:25.195672Z","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-18T01:17:25.195724Z","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-18T01:17:25.195732Z","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-18T01:17:25.196423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=(14952118046135004289)"} {"level":"info","ts":"2026-09-18T01:17:25.196605Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6f89f5fbef18dc61","local-member-id":"cf809824ab95ac81","added-peer-id":"cf809824ab95ac81","added-peer-peer-urls":["https://162.253.55.140:2380"]} {"level":"info","ts":"2026-09-18T01:17:25.199835Z","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-18T01:17:25.199949Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.140:2380"} {"level":"info","ts":"2026-09-18T01:17:25.2Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.140:2380"} {"level":"info","ts":"2026-09-18T01:17:25.20026Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cf809824ab95ac81","initial-advertise-peer-urls":["https://162.253.55.140:2380"],"listen-peer-urls":["https://162.253.55.140:2380"],"advertise-client-urls":["https://162.253.55.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T01:17:25.200333Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T01:17:25.471462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T01:17:25.471575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T01:17:25.471603Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 received MsgPreVoteResp from cf809824ab95ac81 at term 1"} {"level":"info","ts":"2026-09-18T01:17:25.471634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became candidate at term 2"} {"level":"info","ts":"2026-09-18T01:17:25.471652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 received MsgVoteResp from cf809824ab95ac81 at term 2"} {"level":"info","ts":"2026-09-18T01:17:25.471667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became leader at term 2"} {"level":"info","ts":"2026-09-18T01:17:25.471686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cf809824ab95ac81 elected leader cf809824ab95ac81 at term 2"} {"level":"info","ts":"2026-09-18T01:17:25.474082Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cf809824ab95ac81","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.140:2379]}","request-path":"/0/members/cf809824ab95ac81/attributes","cluster-id":"6f89f5fbef18dc61","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T01:17:25.474137Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T01:17:25.474278Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T01:17:25.47474Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T01:17:25.474787Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T01:17:25.474839Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T01:17:25.477867Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.140:2379"} {"level":"info","ts":"2026-09-18T01:17:25.477965Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-18T01:17:25.478634Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6f89f5fbef18dc61","local-member-id":"cf809824ab95ac81","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T01:17:25.47874Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T01:17:25.478773Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-09-18T01:17:28.911038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.440987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-09-18T01:17:28.911129Z","caller":"traceutil/trace.go:171","msg":"trace[134645472] transaction","detail":"{read_only:false; response_revision:123; number_of_response:1; }","duration":"526.624367ms","start":"2026-09-18T01:17:28.384491Z","end":"2026-09-18T01:17:28.911115Z","steps":["trace[134645472] 'process raft request' (duration: 172.727542ms)","trace[134645472] 'compare' (duration: 353.298344ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:17:28.911184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:17:28.384473Z","time spent":"526.683558ms","remote":"127.0.0.1:41556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":827,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T01:17:29.105443Z","caller":"traceutil/trace.go:171","msg":"trace[1124983940] transaction","detail":"{read_only:false; response_revision:124; number_of_response:1; }","duration":"187.064373ms","start":"2026-09-18T01:17:28.918345Z","end":"2026-09-18T01:17:29.105409Z","steps":["trace[1124983940] 'process raft request' (duration: 186.851209ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:18:07.961722Z","caller":"traceutil/trace.go:171","msg":"trace[1910500532] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"126.356454ms","start":"2026-09-18T01:18:07.835336Z","end":"2026-09-18T01:18:07.961692Z","steps":["trace[1910500532] 'process raft request' (duration: 126.213161ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:18:10.188172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.327692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:18:10.188258Z","caller":"traceutil/trace.go:171","msg":"trace[401801604] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:542; }","duration":"120.500214ms","start":"2026-09-18T01:18:10.067738Z","end":"2026-09-18T01:18:10.188238Z","steps":["trace[401801604] 'range keys from in-memory index tree' (duration: 120.191539ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:18:16.5218Z","caller":"traceutil/trace.go:171","msg":"trace[848649680] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"140.028459ms","start":"2026-09-18T01:18:16.38174Z","end":"2026-09-18T01:18:16.521769Z","steps":["trace[848649680] 'process raft request' (duration: 139.4603ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:18:16.921248Z","caller":"traceutil/trace.go:171","msg":"trace[212485819] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:676; }","duration":"222.902309ms","start":"2026-09-18T01:18:16.698312Z","end":"2026-09-18T01:18:16.921214Z","steps":["trace[212485819] 'read index received' (duration: 222.589024ms)","trace[212485819] 'applied index is now lower than readState.Index' (duration: 311.985µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:18:16.921353Z","caller":"traceutil/trace.go:171","msg":"trace[1062472884] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"332.427057ms","start":"2026-09-18T01:18:16.588875Z","end":"2026-09-18T01:18:16.921302Z","steps":["trace[1062472884] 'process raft request' (duration: 332.157713ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:18:16.921453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.120673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T01:18:16.921501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:18:16.588847Z","time spent":"332.58399ms","remote":"127.0.0.1:41728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T01:18:16.921516Z","caller":"traceutil/trace.go:171","msg":"trace[859087570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"223.231715ms","start":"2026-09-18T01:18:16.698266Z","end":"2026-09-18T01:18:16.921497Z","steps":["trace[859087570] 'agreement among raft nodes before linearized reading' (duration: 223.065332ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:18:16.92157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.955791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bl55g\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-18T01:18:16.921653Z","caller":"traceutil/trace.go:171","msg":"trace[710952095] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bl55g; range_end:; response_count:1; response_revision:650; }","duration":"195.048042ms","start":"2026-09-18T01:18:16.726583Z","end":"2026-09-18T01:18:16.921631Z","steps":["trace[710952095] 'agreement among raft nodes before linearized reading' (duration: 194.810618ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:18:17.098234Z","caller":"traceutil/trace.go:171","msg":"trace[1875127481] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"156.157322ms","start":"2026-09-18T01:18:16.942053Z","end":"2026-09-18T01:18:17.09821Z","steps":["trace[1875127481] 'process raft request' (duration: 149.739847ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:18:34.262128Z","caller":"traceutil/trace.go:171","msg":"trace[1050653162] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"117.595694ms","start":"2026-09-18T01:18:34.144494Z","end":"2026-09-18T01:18:34.26209Z","steps":["trace[1050653162] 'process raft request' (duration: 117.393831ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:19:51.023941Z","caller":"traceutil/trace.go:171","msg":"trace[762652093] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"105.844659ms","start":"2026-09-18T01:19:50.918061Z","end":"2026-09-18T01:19:51.023906Z","steps":["trace[762652093] 'process raft request' (duration: 105.644866ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:19:51.107142Z","caller":"traceutil/trace.go:171","msg":"trace[535827741] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"139.525531ms","start":"2026-09-18T01:19:50.967565Z","end":"2026-09-18T01:19:51.10709Z","steps":["trace[535827741] 'process raft request' (duration: 106.317735ms)","trace[535827741] 'compare' (duration: 32.885332ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:19:51.370918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.617349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-44403ea4-b62d-443f-8dee-d4f962932c1b\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-18T01:19:51.371032Z","caller":"traceutil/trace.go:171","msg":"trace[1951473971] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-44403ea4-b62d-443f-8dee-d4f962932c1b; range_end:; response_count:1; response_revision:1884; }","duration":"147.811783ms","start":"2026-09-18T01:19:51.22319Z","end":"2026-09-18T01:19:51.371002Z","steps":["trace[1951473971] 'range keys from in-memory index tree' (duration: 147.366535ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:20:26.393723Z","caller":"traceutil/trace.go:171","msg":"trace[1511535206] linearizableReadLoop","detail":"{readStateIndex:2213; appliedIndex:2212; }","duration":"168.375179ms","start":"2026-09-18T01:20:26.225309Z","end":"2026-09-18T01:20:26.393684Z","steps":["trace[1511535206] 'read index received' (duration: 142.534307ms)","trace[1511535206] 'applied index is now lower than readState.Index' (duration: 25.839162ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:20:26.394068Z","caller":"traceutil/trace.go:171","msg":"trace[376578419] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"171.396811ms","start":"2026-09-18T01:20:26.22265Z","end":"2026-09-18T01:20:26.394047Z","steps":["trace[376578419] 'process raft request' (duration: 145.172496ms)","trace[376578419] 'compare' (duration: 25.569923ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:20:26.394828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.488798ms","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-18T01:20:26.394961Z","caller":"traceutil/trace.go:171","msg":"trace[1699238062] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2132; }","duration":"169.668673ms","start":"2026-09-18T01:20:26.225261Z","end":"2026-09-18T01:20:26.39493Z","steps":["trace[1699238062] 'agreement among raft nodes before linearized reading' (duration: 169.066022ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:20:26.602535Z","caller":"traceutil/trace.go:171","msg":"trace[1273425229] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"202.334507ms","start":"2026-09-18T01:20:26.400135Z","end":"2026-09-18T01:20:26.60247Z","steps":["trace[1273425229] 'process raft request' (duration: 196.037303ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:33.442739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.894772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-000f4062-ce0b-48b6-9e35-334e6d701dbe\" ","response":"range_response_count:1 size:4079"} {"level":"info","ts":"2026-09-18T01:26:33.44281Z","caller":"traceutil/trace.go:171","msg":"trace[297802539] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-000f4062-ce0b-48b6-9e35-334e6d701dbe; range_end:; response_count:1; response_revision:4821; }","duration":"157.003554ms","start":"2026-09-18T01:26:33.28579Z","end":"2026-09-18T01:26:33.442794Z","steps":["trace[297802539] 'range keys from in-memory index tree' (duration: 156.81243ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:33.44281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.696883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-18T01:26:33.442914Z","caller":"traceutil/trace.go:171","msg":"trace[1809413420] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4821; }","duration":"104.847126ms","start":"2026-09-18T01:26:33.338047Z","end":"2026-09-18T01:26:33.442894Z","steps":["trace[1809413420] 'range keys from in-memory index tree' (duration: 104.483228ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:33.44291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.711622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:26:33.443003Z","caller":"traceutil/trace.go:171","msg":"trace[1167060040] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4821; }","duration":"166.827144ms","start":"2026-09-18T01:26:33.276141Z","end":"2026-09-18T01:26:33.442968Z","steps":["trace[1167060040] 'range keys from in-memory index tree' (duration: 166.62597ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:26:41.031357Z","caller":"traceutil/trace.go:171","msg":"trace[1040552561] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"331.055018ms","start":"2026-09-18T01:26:40.700278Z","end":"2026-09-18T01:26:41.031333Z","steps":["trace[1040552561] 'process raft request' (duration: 330.923645ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:41.031488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:26:40.700257Z","time spent":"331.173531ms","remote":"127.0.0.1:41262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2614,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T01:26:41.091247Z","caller":"traceutil/trace.go:171","msg":"trace[694335099] linearizableReadLoop","detail":"{readStateIndex:5148; appliedIndex:5146; }","duration":"255.12621ms","start":"2026-09-18T01:26:40.836097Z","end":"2026-09-18T01:26:41.091223Z","steps":["trace[694335099] 'read index received' (duration: 195.113481ms)","trace[694335099] 'applied index is now lower than readState.Index' (duration: 60.011559ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:26:41.091444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.337634ms","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-18T01:26:41.091481Z","caller":"traceutil/trace.go:171","msg":"trace[766516805] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4962; }","duration":"255.428026ms","start":"2026-09-18T01:26:40.836043Z","end":"2026-09-18T01:26:41.091471Z","steps":["trace[766516805] 'agreement among raft nodes before linearized reading' (duration: 255.293853ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:26:41.091432Z","caller":"traceutil/trace.go:171","msg":"trace[1831688344] transaction","detail":"{read_only:false; response_revision:4962; number_of_response:1; }","duration":"383.913785ms","start":"2026-09-18T01:26:40.707387Z","end":"2026-09-18T01:26:41.0913Z","steps":["trace[1831688344] 'process raft request' (duration: 383.71893ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:41.092219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:26:40.707361Z","time spent":"384.368385ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10347,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T01:26:41.117746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.399385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-18d52c41-bda8-4124-81bc-5a1575e059bb\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-18T01:26:41.117816Z","caller":"traceutil/trace.go:171","msg":"trace[782245674] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-18d52c41-bda8-4124-81bc-5a1575e059bb; range_end:; response_count:1; response_revision:4966; }","duration":"188.517348ms","start":"2026-09-18T01:26:40.929282Z","end":"2026-09-18T01:26:41.117799Z","steps":["trace[782245674] 'agreement among raft nodes before linearized reading' (duration: 188.318534ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:41.674863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.874498ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T01:26:41.675236Z","caller":"traceutil/trace.go:171","msg":"trace[459521594] linearizableReadLoop","detail":"{readStateIndex:5162; appliedIndex:5161; }","duration":"395.415945ms","start":"2026-09-18T01:26:41.279795Z","end":"2026-09-18T01:26:41.675211Z","steps":["trace[459521594] 'read index received' (duration: 127.106848ms)","trace[459521594] 'applied index is now lower than readState.Index' (duration: 268.307407ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:26:41.675288Z","caller":"traceutil/trace.go:171","msg":"trace[1003128721] transaction","detail":"{read_only:false; response_revision:4976; number_of_response:1; }","duration":"411.385576ms","start":"2026-09-18T01:26:41.263889Z","end":"2026-09-18T01:26:41.675275Z","steps":["trace[1003128721] 'process raft request' (duration: 142.997318ms)","trace[1003128721] 'compare' (duration: 267.741004ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:26:41.675449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.031866ms","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-18T01:26:41.675466Z","caller":"traceutil/trace.go:171","msg":"trace[158072047] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4976; }","duration":"440.055826ms","start":"2026-09-18T01:26:41.235406Z","end":"2026-09-18T01:26:41.675462Z","steps":["trace[158072047] 'agreement among raft nodes before linearized reading' (duration: 439.920824ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:41.675482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:26:41.235358Z","time spent":"440.119548ms","remote":"127.0.0.1:41472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-18T01:26:41.675559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:26:41.263866Z","time spent":"411.59796ms","remote":"127.0.0.1:41236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":891,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T01:26:41.67563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.467862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-z4r5r\" ","response":"range_response_count:1 size:9352"} {"level":"info","ts":"2026-09-18T01:26:41.675647Z","caller":"traceutil/trace.go:171","msg":"trace[992014799] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-z4r5r; range_end:; response_count:1; response_revision:4976; }","duration":"414.522023ms","start":"2026-09-18T01:26:41.261118Z","end":"2026-09-18T01:26:41.67564Z","steps":["trace[992014799] 'agreement among raft nodes before linearized reading' (duration: 414.169295ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:41.675659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:26:41.261098Z","time spent":"414.557604ms","remote":"127.0.0.1:41382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":9375,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-z4r5r\" "} {"level":"info","ts":"2026-09-18T01:26:41.737169Z","caller":"traceutil/trace.go:171","msg":"trace[2018166920] transaction","detail":"{read_only:false; response_revision:4977; number_of_response:1; }","duration":"449.411505ms","start":"2026-09-18T01:26:41.287728Z","end":"2026-09-18T01:26:41.737139Z","steps":["trace[2018166920] 'process raft request' (duration: 449.16045ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:41.737402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:26:41.28771Z","time spent":"449.622381ms","remote":"127.0.0.1:41236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":918,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T01:26:41.737502Z","caller":"traceutil/trace.go:171","msg":"trace[1512688642] transaction","detail":"{read_only:false; response_revision:4979; number_of_response:1; }","duration":"439.387582ms","start":"2026-09-18T01:26:41.298106Z","end":"2026-09-18T01:26:41.737494Z","steps":["trace[1512688642] 'process raft request' (duration: 438.929692ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:41.737588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:26:41.298094Z","time spent":"439.433164ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6497,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T01:26:41.737459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.466034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T01:26:41.737663Z","caller":"traceutil/trace.go:171","msg":"trace[1786586675] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:4979; }","duration":"324.703599ms","start":"2026-09-18T01:26:41.412953Z","end":"2026-09-18T01:26:41.737656Z","steps":["trace[1786586675] 'agreement among raft nodes before linearized reading' (duration: 324.451564ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:41.737683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:26:41.412947Z","time spent":"324.729539ms","remote":"127.0.0.1:48948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-09-18T01:26:41.737772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.554415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-09-18T01:26:41.737806Z","caller":"traceutil/trace.go:171","msg":"trace[792350041] transaction","detail":"{read_only:false; response_revision:4978; number_of_response:1; }","duration":"448.099838ms","start":"2026-09-18T01:26:41.289682Z","end":"2026-09-18T01:26:41.737782Z","steps":["trace[792350041] 'process raft request' (duration: 447.313191ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:41.737951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:26:41.289673Z","time spent":"448.21907ms","remote":"127.0.0.1:41236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":911,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T01:26:41.737914Z","caller":"traceutil/trace.go:171","msg":"trace[632935336] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4979; }","duration":"453.762628ms","start":"2026-09-18T01:26:41.284118Z","end":"2026-09-18T01:26:41.737881Z","steps":["trace[632935336] 'agreement among raft nodes before linearized reading' (duration: 453.35175ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:41.738084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:26:41.284105Z","time spent":"453.956993ms","remote":"127.0.0.1:41262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":1,"response size":731,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" "} {"level":"warn","ts":"2026-09-18T01:26:41.738098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.938079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-09-18T01:26:41.738157Z","caller":"traceutil/trace.go:171","msg":"trace[1175120890] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4979; }","duration":"376.037762ms","start":"2026-09-18T01:26:41.362103Z","end":"2026-09-18T01:26:41.738141Z","steps":["trace[1175120890] 'agreement among raft nodes before linearized reading' (duration: 375.288416ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:41.7382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:26:41.362081Z","time spent":"376.104094ms","remote":"127.0.0.1:41472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-09-18T01:26:41.737454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.672998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-18T01:26:41.738408Z","caller":"traceutil/trace.go:171","msg":"trace[1773078706] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4979; }","duration":"241.6728ms","start":"2026-09-18T01:26:41.496715Z","end":"2026-09-18T01:26:41.738387Z","steps":["trace[1773078706] 'agreement among raft nodes before linearized reading' (duration: 240.540176ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:41.739583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.459947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-09-18T01:26:41.740097Z","caller":"traceutil/trace.go:171","msg":"trace[723026615] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4979; }","duration":"343.070321ms","start":"2026-09-18T01:26:41.397006Z","end":"2026-09-18T01:26:41.740076Z","steps":["trace[723026615] 'agreement among raft nodes before linearized reading' (duration: 340.276891ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:41.74029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:26:41.396986Z","time spent":"343.284225ms","remote":"127.0.0.1:41278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2207441,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-09-18T01:26:42.049968Z","caller":"traceutil/trace.go:171","msg":"trace[2013567024] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4986; }","duration":"171.899152ms","start":"2026-09-18T01:26:41.878038Z","end":"2026-09-18T01:26:42.049937Z","steps":["trace[2013567024] 'process raft request' (duration: 171.83368ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:26:42.050012Z","caller":"traceutil/trace.go:171","msg":"trace[1913105359] transaction","detail":"{read_only:false; response_revision:4986; number_of_response:1; }","duration":"173.716101ms","start":"2026-09-18T01:26:41.876257Z","end":"2026-09-18T01:26:42.049973Z","steps":["trace[1913105359] 'process raft request' (duration: 173.488546ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:42.050058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.686516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-z4r5r.18d645ee56d441c3\" ","response":"range_response_count:1 size:832"} {"level":"info","ts":"2026-09-18T01:26:42.050105Z","caller":"traceutil/trace.go:171","msg":"trace[727264385] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-z4r5r.18d645ee56d441c3; range_end:; response_count:1; response_revision:4986; }","duration":"139.745207ms","start":"2026-09-18T01:26:41.910348Z","end":"2026-09-18T01:26:42.050093Z","steps":["trace[727264385] 'agreement among raft nodes before linearized reading' (duration: 139.577773ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:42.050154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.62443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-09-18T01:26:42.050251Z","caller":"traceutil/trace.go:171","msg":"trace[1184135565] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4986; }","duration":"158.758253ms","start":"2026-09-18T01:26:41.891466Z","end":"2026-09-18T01:26:42.050225Z","steps":["trace[1184135565] 'agreement among raft nodes before linearized reading' (duration: 158.520008ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:42.050513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.104033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:60886"} {"level":"info","ts":"2026-09-18T01:26:42.050566Z","caller":"traceutil/trace.go:171","msg":"trace[266495205] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4986; }","duration":"177.179265ms","start":"2026-09-18T01:26:41.873371Z","end":"2026-09-18T01:26:42.050551Z","steps":["trace[266495205] 'agreement among raft nodes before linearized reading' (duration: 176.804108ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:42.062863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.444225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-09-18T01:26:42.062934Z","caller":"traceutil/trace.go:171","msg":"trace[1385118534] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4989; }","duration":"104.577807ms","start":"2026-09-18T01:26:41.958338Z","end":"2026-09-18T01:26:42.062916Z","steps":["trace[1385118534] 'agreement among raft nodes before linearized reading' (duration: 104.408204ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:55.664107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.786085ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T01:26:55.664236Z","caller":"traceutil/trace.go:171","msg":"trace[1458909186] transaction","detail":"{read_only:false; response_revision:5171; number_of_response:1; }","duration":"432.824403ms","start":"2026-09-18T01:26:55.231386Z","end":"2026-09-18T01:26:55.66421Z","steps":["trace[1458909186] 'process raft request' (duration: 324.796994ms)","trace[1458909186] 'compare' (duration: 107.647041ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:26:55.664326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:26:55.231353Z","time spent":"432.925235ms","remote":"127.0.0.1:41472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T01:26:55.673088Z","caller":"traceutil/trace.go:171","msg":"trace[483962805] linearizableReadLoop","detail":"{readStateIndex:5364; appliedIndex:5362; }","duration":"284.691248ms","start":"2026-09-18T01:26:55.388371Z","end":"2026-09-18T01:26:55.673062Z","steps":["trace[483962805] 'read index received' (duration: 167.833212ms)","trace[483962805] 'applied index is now lower than readState.Index' (duration: 116.856566ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:26:55.673347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.961753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10395"} {"level":"warn","ts":"2026-09-18T01:26:55.673706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.140644ms","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-18T01:26:55.673795Z","caller":"traceutil/trace.go:171","msg":"trace[714206389] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5171; }","duration":"237.293728ms","start":"2026-09-18T01:26:55.436487Z","end":"2026-09-18T01:26:55.673781Z","steps":["trace[714206389] 'agreement among raft nodes before linearized reading' (duration: 237.008132ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:26:55.674074Z","caller":"traceutil/trace.go:171","msg":"trace[1891648001] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5171; }","duration":"285.71381ms","start":"2026-09-18T01:26:55.38832Z","end":"2026-09-18T01:26:55.674034Z","steps":["trace[1891648001] 'agreement among raft nodes before linearized reading' (duration: 284.885592ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:27:25.722584Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2880} {"level":"info","ts":"2026-09-18T01:27:25.803284Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2880,"took":"79.20214ms","hash":949204184,"current-db-size-bytes":52523008,"current-db-size":"52 MB","current-db-size-in-use-bytes":27656192,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-18T01:27:25.803344Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":949204184,"revision":2880,"compact-revision":-1} {"level":"info","ts":"2026-09-18T01:30:54.389005Z","caller":"traceutil/trace.go:171","msg":"trace[1663829672] transaction","detail":"{read_only:false; response_revision:6920; number_of_response:1; }","duration":"150.231548ms","start":"2026-09-18T01:30:54.238744Z","end":"2026-09-18T01:30:54.388976Z","steps":["trace[1663829672] 'process raft request' (duration: 107.510931ms)","trace[1663829672] 'compare' (duration: 42.109355ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:32:25.7297Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5368} {"level":"info","ts":"2026-09-18T01:32:25.812117Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5368,"took":"80.787632ms","hash":1601263215,"current-db-size-bytes":52523008,"current-db-size":"52 MB","current-db-size-in-use-bytes":18587648,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-18T01:32:25.812207Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1601263215,"revision":5368,"compact-revision":2880}