{"level":"warn","ts":"2026-09-01T18:27:08.196947Z","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-01T18:27:08.197098Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.59: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.59:2380","--initial-cluster=instance=https://162.253.55.59:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.59:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.59: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-01T18:27:08.19722Z","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-01T18:27:08.197243Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.59:2380"]} {"level":"info","ts":"2026-09-01T18:27:08.197279Z","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-01T18:27:08.198584Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.59:2379"]} {"level":"info","ts":"2026-09-01T18:27:08.1988Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.59:2380"],"listen-peer-urls":["https://162.253.55.59:2380"],"advertise-client-urls":["https://162.253.55.59:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.59:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.59: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-01T18:27:08.204389Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.969581ms"} {"level":"info","ts":"2026-09-01T18:27:08.213843Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f5132dc9ac390b66","cluster-id":"458d1cb56ab56fc4"} {"level":"info","ts":"2026-09-01T18:27:08.213964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 switched to configuration voters=()"} {"level":"info","ts":"2026-09-01T18:27:08.214009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 became follower at term 0"} {"level":"info","ts":"2026-09-01T18:27:08.214041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f5132dc9ac390b66 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-01T18:27:08.214059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 became follower at term 1"} {"level":"info","ts":"2026-09-01T18:27:08.214145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 switched to configuration voters=(17659508908050942822)"} {"level":"warn","ts":"2026-09-01T18:27:08.228098Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-01T18:27:08.230473Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-01T18:27:08.232676Z","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-01T18:27:08.23683Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f5132dc9ac390b66","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-01T18:27:08.237084Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f5132dc9ac390b66","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-01T18:27:08.237329Z","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-01T18:27:08.237498Z","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-01T18:27:08.237569Z","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-01T18:27:08.239796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 switched to configuration voters=(17659508908050942822)"} {"level":"info","ts":"2026-09-01T18:27:08.240335Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"458d1cb56ab56fc4","local-member-id":"f5132dc9ac390b66","added-peer-id":"f5132dc9ac390b66","added-peer-peer-urls":["https://162.253.55.59:2380"]} {"level":"info","ts":"2026-09-01T18:27:08.244239Z","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-01T18:27:08.244421Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.59:2380"} {"level":"info","ts":"2026-09-01T18:27:08.24449Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.59:2380"} {"level":"info","ts":"2026-09-01T18:27:08.244826Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f5132dc9ac390b66","initial-advertise-peer-urls":["https://162.253.55.59:2380"],"listen-peer-urls":["https://162.253.55.59:2380"],"advertise-client-urls":["https://162.253.55.59:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.59:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-01T18:27:08.244936Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-01T18:27:08.417584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 is starting a new election at term 1"} {"level":"info","ts":"2026-09-01T18:27:08.417655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-01T18:27:08.417684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 received MsgPreVoteResp from f5132dc9ac390b66 at term 1"} {"level":"info","ts":"2026-09-01T18:27:08.417707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 became candidate at term 2"} {"level":"info","ts":"2026-09-01T18:27:08.417735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 received MsgVoteResp from f5132dc9ac390b66 at term 2"} {"level":"info","ts":"2026-09-01T18:27:08.417751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f5132dc9ac390b66 became leader at term 2"} {"level":"info","ts":"2026-09-01T18:27:08.417765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f5132dc9ac390b66 elected leader f5132dc9ac390b66 at term 2"} {"level":"info","ts":"2026-09-01T18:27:08.429865Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:27:08.437945Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f5132dc9ac390b66","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.59:2379]}","request-path":"/0/members/f5132dc9ac390b66/attributes","cluster-id":"458d1cb56ab56fc4","publish-timeout":"7s"} {"level":"info","ts":"2026-09-01T18:27:08.437956Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T18:27:08.437996Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T18:27:08.438415Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-01T18:27:08.438507Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-01T18:27:08.4416Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-01T18:27:08.441834Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.59:2379"} {"level":"info","ts":"2026-09-01T18:27:08.445664Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"458d1cb56ab56fc4","local-member-id":"f5132dc9ac390b66","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:27:08.44587Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:27:08.445938Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:27:12.150216Z","caller":"traceutil/trace.go:171","msg":"trace[682832637] transaction","detail":"{read_only:false; response_revision:185; number_of_response:1; }","duration":"116.166382ms","start":"2026-09-01T18:27:12.034019Z","end":"2026-09-01T18:27:12.150186Z","steps":["trace[682832637] 'process raft request' (duration: 108.565787ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:27:27.031139Z","caller":"traceutil/trace.go:171","msg":"trace[33303679] linearizableReadLoop","detail":"{readStateIndex:391; appliedIndex:390; }","duration":"100.230133ms","start":"2026-09-01T18:27:26.930885Z","end":"2026-09-01T18:27:27.031115Z","steps":["trace[33303679] 'read index received' (duration: 100.115431ms)","trace[33303679] 'applied index is now lower than readState.Index' (duration: 113.722µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:27:27.031279Z","caller":"traceutil/trace.go:171","msg":"trace[12802251] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"186.401433ms","start":"2026-09-01T18:27:26.844816Z","end":"2026-09-01T18:27:27.031217Z","steps":["trace[12802251] 'process raft request' (duration: 186.162167ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:27:27.031342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.431148ms","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-01T18:27:27.031484Z","caller":"traceutil/trace.go:171","msg":"trace[1416495086] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:377; }","duration":"100.584141ms","start":"2026-09-01T18:27:26.930871Z","end":"2026-09-01T18:27:27.031456Z","steps":["trace[1416495086] 'agreement among raft nodes before linearized reading' (duration: 100.353526ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:27:27.300994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.552571ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:27:27.301077Z","caller":"traceutil/trace.go:171","msg":"trace[738303769] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"264.827243ms","start":"2026-09-01T18:27:27.036236Z","end":"2026-09-01T18:27:27.301063Z","steps":["trace[738303769] 'process raft request' (duration: 147.812961ms)","trace[738303769] 'compare' (duration: 116.409427ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:27:45.946958Z","caller":"traceutil/trace.go:171","msg":"trace[1672677372] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"126.087871ms","start":"2026-09-01T18:27:45.820842Z","end":"2026-09-01T18:27:45.94693Z","steps":["trace[1672677372] 'process raft request' (duration: 125.871256ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:27:45.952378Z","caller":"traceutil/trace.go:171","msg":"trace[1179718358] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"130.41523ms","start":"2026-09-01T18:27:45.821943Z","end":"2026-09-01T18:27:45.952358Z","steps":["trace[1179718358] 'process raft request' (duration: 130.193465ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:27:45.952824Z","caller":"traceutil/trace.go:171","msg":"trace[2040086326] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"130.349459ms","start":"2026-09-01T18:27:45.822448Z","end":"2026-09-01T18:27:45.952797Z","steps":["trace[2040086326] 'process raft request' (duration: 129.848898ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:27:45.953212Z","caller":"traceutil/trace.go:171","msg":"trace[1169386071] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"124.544525ms","start":"2026-09-01T18:27:45.828623Z","end":"2026-09-01T18:27:45.953168Z","steps":["trace[1169386071] 'process raft request' (duration: 123.960571ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:27:45.954026Z","caller":"traceutil/trace.go:171","msg":"trace[2081377749] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"121.772181ms","start":"2026-09-01T18:27:45.83223Z","end":"2026-09-01T18:27:45.954002Z","steps":["trace[2081377749] 'process raft request' (duration: 120.848379ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:27:52.374035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.888162ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:27:52.374142Z","caller":"traceutil/trace.go:171","msg":"trace[398779439] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"149.740578ms","start":"2026-09-01T18:27:52.224386Z","end":"2026-09-01T18:27:52.374127Z","steps":["trace[398779439] 'read index received' (duration: 36.662222ms)","trace[398779439] 'applied index is now lower than readState.Index' (duration: 113.076816ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:27:52.37421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.83359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.59\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-01T18:27:52.374236Z","caller":"traceutil/trace.go:171","msg":"trace[2007490865] range","detail":"{range_begin:/registry/masterleases/162.253.55.59; range_end:; response_count:1; response_revision:592; }","duration":"149.906983ms","start":"2026-09-01T18:27:52.22432Z","end":"2026-09-01T18:27:52.374227Z","steps":["trace[2007490865] 'agreement among raft nodes before linearized reading' (duration: 149.856712ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:27:52.374278Z","caller":"traceutil/trace.go:171","msg":"trace[1136087956] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"164.059978ms","start":"2026-09-01T18:27:52.210133Z","end":"2026-09-01T18:27:52.374193Z","steps":["trace[1136087956] 'process raft request' (duration: 50.93561ms)","trace[1136087956] 'compare' (duration: 112.767339ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:27:52.690821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.013865ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:27:52.690935Z","caller":"traceutil/trace.go:171","msg":"trace[1815059076] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"259.271954ms","start":"2026-09-01T18:27:52.431643Z","end":"2026-09-01T18:27:52.690915Z","steps":["trace[1815059076] 'read index received' (duration: 113.000704ms)","trace[1815059076] 'applied index is now lower than readState.Index' (duration: 146.26998ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:27:52.69096Z","caller":"traceutil/trace.go:171","msg":"trace[610142894] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"303.909559ms","start":"2026-09-01T18:27:52.38702Z","end":"2026-09-01T18:27:52.69093Z","steps":["trace[610142894] 'process raft request' (duration: 157.685771ms)","trace[610142894] 'compare' (duration: 145.906961ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:27:52.691028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.391687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:27:52.691057Z","caller":"traceutil/trace.go:171","msg":"trace[157492028] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"259.462479ms","start":"2026-09-01T18:27:52.431584Z","end":"2026-09-01T18:27:52.691046Z","steps":["trace[157492028] 'agreement among raft nodes before linearized reading' (duration: 259.384017ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:27:52.69111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:27:52.386997Z","time spent":"304.040923ms","remote":"127.0.0.1:59636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:27:52.874804Z","caller":"traceutil/trace.go:171","msg":"trace[1415598856] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"181.502978ms","start":"2026-09-01T18:27:52.693276Z","end":"2026-09-01T18:27:52.874779Z","steps":["trace[1415598856] 'read index received' (duration: 180.72155ms)","trace[1415598856] 'applied index is now lower than readState.Index' (duration: 780.648µs)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:27:52.874947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.652861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-09-01T18:27:52.874928Z","caller":"traceutil/trace.go:171","msg":"trace[129479061] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"242.898648ms","start":"2026-09-01T18:27:52.631999Z","end":"2026-09-01T18:27:52.874897Z","steps":["trace[129479061] 'process raft request' (duration: 242.247853ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:27:52.874981Z","caller":"traceutil/trace.go:171","msg":"trace[490268572] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:594; }","duration":"181.749814ms","start":"2026-09-01T18:27:52.69322Z","end":"2026-09-01T18:27:52.87497Z","steps":["trace[490268572] 'agreement among raft nodes before linearized reading' (duration: 181.648472ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:27:53.537363Z","caller":"traceutil/trace.go:171","msg":"trace[1221567224] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"350.918278ms","start":"2026-09-01T18:27:53.186417Z","end":"2026-09-01T18:27:53.537335Z","steps":["trace[1221567224] 'read index received' (duration: 350.56206ms)","trace[1221567224] 'applied index is now lower than readState.Index' (duration: 355.058µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:27:53.537485Z","caller":"traceutil/trace.go:171","msg":"trace[2116939695] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"591.166177ms","start":"2026-09-01T18:27:52.946251Z","end":"2026-09-01T18:27:53.537417Z","steps":["trace[2116939695] 'process raft request' (duration: 590.86404ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:27:53.537758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:27:52.946219Z","time spent":"591.417902ms","remote":"127.0.0.1:60146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-01T18:27:53.537777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.768527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T18:27:53.537804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.37786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-01T18:27:53.5378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.510934ms","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-01T18:27:53.537879Z","caller":"traceutil/trace.go:171","msg":"trace[988183403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:595; }","duration":"351.509863ms","start":"2026-09-01T18:27:53.186348Z","end":"2026-09-01T18:27:53.537858Z","steps":["trace[988183403] 'agreement among raft nodes before linearized reading' (duration: 351.143414ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:27:53.537895Z","caller":"traceutil/trace.go:171","msg":"trace[442283506] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"122.643077ms","start":"2026-09-01T18:27:53.415226Z","end":"2026-09-01T18:27:53.537869Z","steps":["trace[442283506] 'agreement among raft nodes before linearized reading' (duration: 122.36064ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:27:53.537944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:27:53.186315Z","time spent":"351.615446ms","remote":"127.0.0.1:59890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-01T18:27:53.537879Z","caller":"traceutil/trace.go:171","msg":"trace[289248201] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"104.92521ms","start":"2026-09-01T18:27:53.432925Z","end":"2026-09-01T18:27:53.53785Z","steps":["trace[289248201] 'agreement among raft nodes before linearized reading' (duration: 104.713904ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:28:13.412212Z","caller":"traceutil/trace.go:171","msg":"trace[877057055] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"105.361159ms","start":"2026-09-01T18:28:13.306826Z","end":"2026-09-01T18:28:13.412187Z","steps":["trace[877057055] 'process raft request' (duration: 105.227946ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:29:35.143865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.119651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4538ba7f-0f6d-44db-a72e-ad6636f7182a\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-01T18:29:35.143946Z","caller":"traceutil/trace.go:171","msg":"trace[1564334612] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"304.536552ms","start":"2026-09-01T18:29:34.83936Z","end":"2026-09-01T18:29:35.143896Z","steps":["trace[1564334612] 'process raft request' (duration: 264.428173ms)","trace[1564334612] 'compare' (duration: 39.894284ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:29:35.14402Z","caller":"traceutil/trace.go:171","msg":"trace[1692938206] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4538ba7f-0f6d-44db-a72e-ad6636f7182a; range_end:; response_count:1; response_revision:1859; }","duration":"416.329816ms","start":"2026-09-01T18:29:34.727657Z","end":"2026-09-01T18:29:35.143986Z","steps":["trace[1692938206] 'range keys from in-memory index tree' (duration: 415.856846ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:29:35.144082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:29:34.72764Z","time spent":"416.427778ms","remote":"127.0.0.1:59796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4538ba7f-0f6d-44db-a72e-ad6636f7182a\" "} {"level":"warn","ts":"2026-09-01T18:29:35.144158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:29:34.839328Z","time spent":"304.712986ms","remote":"127.0.0.1:59890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:29:35.189544Z","caller":"traceutil/trace.go:171","msg":"trace[413735729] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"319.430484ms","start":"2026-09-01T18:29:34.870059Z","end":"2026-09-01T18:29:35.18949Z","steps":["trace[413735729] 'process raft request' (duration: 319.221529ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:29:35.189786Z","caller":"traceutil/trace.go:171","msg":"trace[1626867269] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"204.589581ms","start":"2026-09-01T18:29:34.985153Z","end":"2026-09-01T18:29:35.189743Z","steps":["trace[1626867269] 'process raft request' (duration: 204.292264ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:29:35.189844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.741791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:29:35.189897Z","caller":"traceutil/trace.go:171","msg":"trace[972437606] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1862; }","duration":"193.835154ms","start":"2026-09-01T18:29:34.996048Z","end":"2026-09-01T18:29:35.189883Z","steps":["trace[972437606] 'agreement among raft nodes before linearized reading' (duration: 193.738742ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:29:35.189767Z","caller":"traceutil/trace.go:171","msg":"trace[1213917160] linearizableReadLoop","detail":"{readStateIndex:1933; appliedIndex:1930; }","duration":"193.595418ms","start":"2026-09-01T18:29:34.996086Z","end":"2026-09-01T18:29:35.189681Z","steps":["trace[1213917160] 'read index received' (duration: 107.71966ms)","trace[1213917160] 'applied index is now lower than readState.Index' (duration: 85.872718ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:29:35.190076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:29:34.870045Z","time spent":"319.581248ms","remote":"127.0.0.1:59890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:29:35.236808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.423806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:29:35.236911Z","caller":"traceutil/trace.go:171","msg":"trace[29280637] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1863; }","duration":"125.59878ms","start":"2026-09-01T18:29:35.111281Z","end":"2026-09-01T18:29:35.23688Z","steps":["trace[29280637] 'agreement among raft nodes before linearized reading' (duration: 125.401175ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:29:35.52415Z","caller":"traceutil/trace.go:171","msg":"trace[978385630] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"241.511268ms","start":"2026-09-01T18:29:35.282611Z","end":"2026-09-01T18:29:35.524122Z","steps":["trace[978385630] 'process raft request' (duration: 241.318244ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:29:35.597373Z","caller":"traceutil/trace.go:171","msg":"trace[606249768] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"313.910987ms","start":"2026-09-01T18:29:35.283435Z","end":"2026-09-01T18:29:35.597346Z","steps":["trace[606249768] 'process raft request' (duration: 313.690472ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:29:35.597544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:29:35.283413Z","time spent":"314.03167ms","remote":"127.0.0.1:59776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1152,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:29:35.597842Z","caller":"traceutil/trace.go:171","msg":"trace[1687766714] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"313.749504ms","start":"2026-09-01T18:29:35.284082Z","end":"2026-09-01T18:29:35.597831Z","steps":["trace[1687766714] 'process raft request' (duration: 313.211622ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:29:35.597792Z","caller":"traceutil/trace.go:171","msg":"trace[1929927025] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"313.963809ms","start":"2026-09-01T18:29:35.283737Z","end":"2026-09-01T18:29:35.597701Z","steps":["trace[1929927025] 'process raft request' (duration: 313.525029ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:29:35.59789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:29:35.284074Z","time spent":"313.793534ms","remote":"127.0.0.1:59894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1756,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:29:35.597921Z","caller":"traceutil/trace.go:171","msg":"trace[54156368] linearizableReadLoop","detail":"{readStateIndex:1942; appliedIndex:1935; }","duration":"202.726358ms","start":"2026-09-01T18:29:35.395166Z","end":"2026-09-01T18:29:35.597892Z","steps":["trace[54156368] 'read index received' (duration: 128.760343ms)","trace[54156368] 'applied index is now lower than readState.Index' (duration: 73.964975ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:29:35.597971Z","caller":"traceutil/trace.go:171","msg":"trace[440322340] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"311.802099ms","start":"2026-09-01T18:29:35.286161Z","end":"2026-09-01T18:29:35.597964Z","steps":["trace[440322340] 'process raft request' (duration: 311.679756ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:29:35.598006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:29:35.28615Z","time spent":"311.838199ms","remote":"127.0.0.1:60112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6114,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:29:35.597986Z","caller":"traceutil/trace.go:171","msg":"trace[431571897] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"313.468766ms","start":"2026-09-01T18:29:35.284482Z","end":"2026-09-01T18:29:35.597951Z","steps":["trace[431571897] 'process raft request' (duration: 313.307303ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:29:35.598099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:29:35.283667Z","time spent":"314.269115ms","remote":"127.0.0.1:59776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1169,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:29:35.598114Z","caller":"traceutil/trace.go:171","msg":"trace[2018759275] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"313.785855ms","start":"2026-09-01T18:29:35.284302Z","end":"2026-09-01T18:29:35.598088Z","steps":["trace[2018759275] 'process raft request' (duration: 313.358675ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:29:35.598125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:29:35.284475Z","time spent":"313.573639ms","remote":"127.0.0.1:59894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:29:35.598205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.978018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-09-01T18:29:35.598223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:29:35.284291Z","time spent":"313.875127ms","remote":"127.0.0.1:59776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1202,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:29:35.598246Z","caller":"traceutil/trace.go:171","msg":"trace[1515631035] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1871; }","duration":"170.058239ms","start":"2026-09-01T18:29:35.428174Z","end":"2026-09-01T18:29:35.598232Z","steps":["trace[1515631035] 'agreement among raft nodes before linearized reading' (duration: 169.870635ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:29:35.598254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.38443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-09-01T18:29:35.598277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.107697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4538ba7f-0f6d-44db-a72e-ad6636f7182a\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-09-01T18:29:35.598332Z","caller":"traceutil/trace.go:171","msg":"trace[672207715] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1871; }","duration":"137.523884ms","start":"2026-09-01T18:29:35.46079Z","end":"2026-09-01T18:29:35.598314Z","steps":["trace[672207715] 'agreement among raft nodes before linearized reading' (duration: 137.305799ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:29:35.598356Z","caller":"traceutil/trace.go:171","msg":"trace[1739577015] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4538ba7f-0f6d-44db-a72e-ad6636f7182a; range_end:; response_count:1; response_revision:1871; }","duration":"203.243111ms","start":"2026-09-01T18:29:35.39509Z","end":"2026-09-01T18:29:35.598333Z","steps":["trace[1739577015] 'agreement among raft nodes before linearized reading' (duration: 202.941654ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:29:35.598228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.053175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:29:35.598464Z","caller":"traceutil/trace.go:171","msg":"trace[297207302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1871; }","duration":"165.333081ms","start":"2026-09-01T18:29:35.433102Z","end":"2026-09-01T18:29:35.598435Z","steps":["trace[297207302] 'agreement among raft nodes before linearized reading' (duration: 165.015864ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:29:50.402205Z","caller":"traceutil/trace.go:171","msg":"trace[1989757298] linearizableReadLoop","detail":"{readStateIndex:2073; appliedIndex:2072; }","duration":"157.428031ms","start":"2026-09-01T18:29:50.244746Z","end":"2026-09-01T18:29:50.402174Z","steps":["trace[1989757298] 'read index received' (duration: 151.856443ms)","trace[1989757298] 'applied index is now lower than readState.Index' (duration: 5.570518ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:29:50.402287Z","caller":"traceutil/trace.go:171","msg":"trace[483186010] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"170.297995ms","start":"2026-09-01T18:29:50.231944Z","end":"2026-09-01T18:29:50.402242Z","steps":["trace[483186010] 'process raft request' (duration: 164.643906ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:29:50.402473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.641245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:29:50.40259Z","caller":"traceutil/trace.go:171","msg":"trace[491340425] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1998; }","duration":"157.87212ms","start":"2026-09-01T18:29:50.244692Z","end":"2026-09-01T18:29:50.402564Z","steps":["trace[491340425] 'agreement among raft nodes before linearized reading' (duration: 157.617385ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:30:23.564681Z","caller":"traceutil/trace.go:171","msg":"trace[778661029] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"199.359203ms","start":"2026-09-01T18:30:23.365274Z","end":"2026-09-01T18:30:23.564634Z","steps":["trace[778661029] 'process raft request' (duration: 178.042875ms)","trace[778661029] 'compare' (duration: 21.198085ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:30:23.564777Z","caller":"traceutil/trace.go:171","msg":"trace[2124914423] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"129.196537ms","start":"2026-09-01T18:30:23.435572Z","end":"2026-09-01T18:30:23.564768Z","steps":["trace[2124914423] 'process raft request' (duration: 129.051584ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:30:23.564669Z","caller":"traceutil/trace.go:171","msg":"trace[1049735975] linearizableReadLoop","detail":"{readStateIndex:2264; appliedIndex:2263; }","duration":"132.476923ms","start":"2026-09-01T18:30:23.432176Z","end":"2026-09-01T18:30:23.564653Z","steps":["trace[1049735975] 'read index received' (duration: 111.156155ms)","trace[1049735975] 'applied index is now lower than readState.Index' (duration: 21.319918ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:30:23.564881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.667457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:30:23.56497Z","caller":"traceutil/trace.go:171","msg":"trace[1967250097] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2182; }","duration":"132.81454ms","start":"2026-09-01T18:30:23.432133Z","end":"2026-09-01T18:30:23.564947Z","steps":["trace[1967250097] 'agreement among raft nodes before linearized reading' (duration: 132.608756ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:30:29.065687Z","caller":"traceutil/trace.go:171","msg":"trace[1761411463] transaction","detail":"{read_only:false; response_revision:2210; number_of_response:1; }","duration":"111.773139ms","start":"2026-09-01T18:30:28.953865Z","end":"2026-09-01T18:30:29.065638Z","steps":["trace[1761411463] 'process raft request' (duration: 111.320759ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:30:29.677248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.005706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T18:30:29.677344Z","caller":"traceutil/trace.go:171","msg":"trace[1265589327] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:2211; }","duration":"175.151009ms","start":"2026-09-01T18:30:29.502171Z","end":"2026-09-01T18:30:29.677322Z","steps":["trace[1265589327] 'count revisions from in-memory index tree' (duration: 174.902223ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:30:29.78611Z","caller":"traceutil/trace.go:171","msg":"trace[1849461001] transaction","detail":"{read_only:false; response_revision:2212; number_of_response:1; }","duration":"201.526443ms","start":"2026-09-01T18:30:29.584552Z","end":"2026-09-01T18:30:29.786079Z","steps":["trace[1849461001] 'process raft request' (duration: 201.266757ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:30:30.150941Z","caller":"traceutil/trace.go:171","msg":"trace[411621461] linearizableReadLoop","detail":"{readStateIndex:2297; appliedIndex:2296; }","duration":"275.969016ms","start":"2026-09-01T18:30:29.874947Z","end":"2026-09-01T18:30:30.150916Z","steps":["trace[411621461] 'read index received' (duration: 267.097324ms)","trace[411621461] 'applied index is now lower than readState.Index' (duration: 8.870462ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:30:30.151296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.327394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-01T18:30:30.151377Z","caller":"traceutil/trace.go:171","msg":"trace[154604852] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2213; }","duration":"276.464877ms","start":"2026-09-01T18:30:29.874893Z","end":"2026-09-01T18:30:30.151358Z","steps":["trace[154604852] 'agreement among raft nodes before linearized reading' (duration: 276.199741ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:30:30.151416Z","caller":"traceutil/trace.go:171","msg":"trace[1067055355] transaction","detail":"{read_only:false; response_revision:2213; number_of_response:1; }","duration":"566.562677ms","start":"2026-09-01T18:30:29.584571Z","end":"2026-09-01T18:30:30.151134Z","steps":["trace[1067055355] 'process raft request' (duration: 557.49483ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:30:30.15148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.468162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-01T18:30:30.15148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.551469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:30:30.151546Z","caller":"traceutil/trace.go:171","msg":"trace[1626525234] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:2213; }","duration":"194.614295ms","start":"2026-09-01T18:30:29.956915Z","end":"2026-09-01T18:30:30.15153Z","steps":["trace[1626525234] 'agreement among raft nodes before linearized reading' (duration: 194.348909ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:30:30.151566Z","caller":"traceutil/trace.go:171","msg":"trace[1722959007] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2213; }","duration":"106.678261ms","start":"2026-09-01T18:30:30.044865Z","end":"2026-09-01T18:30:30.151544Z","steps":["trace[1722959007] 'agreement among raft nodes before linearized reading' (duration: 106.426666ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:30:30.151587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:30:29.584542Z","time spent":"566.945887ms","remote":"127.0.0.1:59890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:32:04.183482Z","caller":"traceutil/trace.go:171","msg":"trace[1035113329] transaction","detail":"{read_only:false; response_revision:2918; number_of_response:1; }","duration":"197.737513ms","start":"2026-09-01T18:32:03.985716Z","end":"2026-09-01T18:32:04.183453Z","steps":["trace[1035113329] 'process raft request' (duration: 197.5604ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:32:09.713775Z","caller":"traceutil/trace.go:171","msg":"trace[1657125615] transaction","detail":"{read_only:false; response_revision:2951; number_of_response:1; }","duration":"104.45291ms","start":"2026-09-01T18:32:09.609272Z","end":"2026-09-01T18:32:09.713725Z","steps":["trace[1657125615] 'process raft request' (duration: 104.287386ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:37:00.403133Z","caller":"traceutil/trace.go:171","msg":"trace[641634702] transaction","detail":"{read_only:false; response_revision:5412; number_of_response:1; }","duration":"105.531159ms","start":"2026-09-01T18:37:00.297576Z","end":"2026-09-01T18:37:00.403107Z","steps":["trace[641634702] 'process raft request' (duration: 105.364765ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:37:00.408182Z","caller":"traceutil/trace.go:171","msg":"trace[1694214741] transaction","detail":"{read_only:false; response_revision:5413; number_of_response:1; }","duration":"100.530533ms","start":"2026-09-01T18:37:00.307622Z","end":"2026-09-01T18:37:00.408153Z","steps":["trace[1694214741] 'process raft request' (duration: 100.3966ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:37:04.909323Z","caller":"traceutil/trace.go:171","msg":"trace[1937053931] transaction","detail":"{read_only:false; response_revision:5484; number_of_response:1; }","duration":"215.339156ms","start":"2026-09-01T18:37:04.693939Z","end":"2026-09-01T18:37:04.909278Z","steps":["trace[1937053931] 'process raft request' (duration: 149.342798ms)","trace[1937053931] 'compare' (duration: 65.745233ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:37:05.436211Z","caller":"traceutil/trace.go:171","msg":"trace[830255904] transaction","detail":"{read_only:false; response_revision:5488; number_of_response:1; }","duration":"148.14512ms","start":"2026-09-01T18:37:05.288035Z","end":"2026-09-01T18:37:05.43618Z","steps":["trace[830255904] 'process raft request' (duration: 71.71351ms)","trace[830255904] 'compare' (duration: 76.190704ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:37:05.436304Z","caller":"traceutil/trace.go:171","msg":"trace[1897161057] linearizableReadLoop","detail":"{readStateIndex:5697; appliedIndex:5695; }","duration":"145.676703ms","start":"2026-09-01T18:37:05.29059Z","end":"2026-09-01T18:37:05.436267Z","steps":["trace[1897161057] 'read index received' (duration: 23.03017ms)","trace[1897161057] 'applied index is now lower than readState.Index' (duration: 122.640843ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:37:05.436552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.928539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T18:37:05.43658Z","caller":"traceutil/trace.go:171","msg":"trace[697609127] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5491; }","duration":"146.00354ms","start":"2026-09-01T18:37:05.290567Z","end":"2026-09-01T18:37:05.436571Z","steps":["trace[697609127] 'agreement among raft nodes before linearized reading' (duration: 145.871067ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:37:05.436654Z","caller":"traceutil/trace.go:171","msg":"trace[747730275] transaction","detail":"{read_only:false; response_revision:5489; number_of_response:1; }","duration":"118.525459ms","start":"2026-09-01T18:37:05.318093Z","end":"2026-09-01T18:37:05.436618Z","steps":["trace[747730275] 'process raft request' (duration: 118.072179ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:37:05.43681Z","caller":"traceutil/trace.go:171","msg":"trace[240380480] transaction","detail":"{read_only:false; response_revision:5490; number_of_response:1; }","duration":"118.659972ms","start":"2026-09-01T18:37:05.318091Z","end":"2026-09-01T18:37:05.436751Z","steps":["trace[240380480] 'process raft request' (duration: 118.167321ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:37:05.436823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.803858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"warn","ts":"2026-09-01T18:37:05.436727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.044618ms","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-01T18:37:05.43715Z","caller":"traceutil/trace.go:171","msg":"trace[2081339639] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5491; }","duration":"125.490347ms","start":"2026-09-01T18:37:05.311642Z","end":"2026-09-01T18:37:05.437132Z","steps":["trace[2081339639] 'agreement among raft nodes before linearized reading' (duration: 124.918875ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:37:05.436989Z","caller":"traceutil/trace.go:171","msg":"trace[124485529] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5491; }","duration":"107.011612ms","start":"2026-09-01T18:37:05.329928Z","end":"2026-09-01T18:37:05.43694Z","steps":["trace[124485529] 'agreement among raft nodes before linearized reading' (duration: 106.679035ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:37:05.436967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.588032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16231"} {"level":"info","ts":"2026-09-01T18:37:05.437703Z","caller":"traceutil/trace.go:171","msg":"trace[1941876767] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5491; }","duration":"120.35393ms","start":"2026-09-01T18:37:05.317312Z","end":"2026-09-01T18:37:05.437666Z","steps":["trace[1941876767] 'agreement among raft nodes before linearized reading' (duration: 119.514651ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:37:08.62585Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2943} {"level":"info","ts":"2026-09-01T18:37:08.712458Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2943,"took":"84.778951ms","hash":1717304488,"current-db-size-bytes":56233984,"current-db-size":"56 MB","current-db-size-in-use-bytes":31125504,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-01T18:37:08.712524Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1717304488,"revision":2943,"compact-revision":-1} {"level":"info","ts":"2026-09-01T18:37:15.212088Z","caller":"traceutil/trace.go:171","msg":"trace[1583324946] transaction","detail":"{read_only:false; response_revision:5646; number_of_response:1; }","duration":"100.716268ms","start":"2026-09-01T18:37:15.111347Z","end":"2026-09-01T18:37:15.212064Z","steps":["trace[1583324946] 'process raft request' (duration: 89.943759ms)","trace[1583324946] 'compare' (duration: 10.539792ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:37:30.893982Z","caller":"traceutil/trace.go:171","msg":"trace[2037570600] linearizableReadLoop","detail":"{readStateIndex:6151; appliedIndex:6150; }","duration":"256.719578ms","start":"2026-09-01T18:37:30.637233Z","end":"2026-09-01T18:37:30.893953Z","steps":["trace[2037570600] 'read index received' (duration: 256.476462ms)","trace[2037570600] 'applied index is now lower than readState.Index' (duration: 241.766µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:37:30.894062Z","caller":"traceutil/trace.go:171","msg":"trace[574812550] transaction","detail":"{read_only:false; response_revision:5931; number_of_response:1; }","duration":"273.992026ms","start":"2026-09-01T18:37:30.62002Z","end":"2026-09-01T18:37:30.894012Z","steps":["trace[574812550] 'process raft request' (duration: 273.7262ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:37:30.894285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.468103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-09-01T18:37:30.894286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.028495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c517bb47-55fe-42f4-8d50-f7f8f8635dcd\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-09-01T18:37:30.894361Z","caller":"traceutil/trace.go:171","msg":"trace[941142354] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5931; }","duration":"210.592586ms","start":"2026-09-01T18:37:30.683752Z","end":"2026-09-01T18:37:30.894345Z","steps":["trace[941142354] 'agreement among raft nodes before linearized reading' (duration: 210.385992ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:37:30.894392Z","caller":"traceutil/trace.go:171","msg":"trace[769559857] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c517bb47-55fe-42f4-8d50-f7f8f8635dcd; range_end:; response_count:1; response_revision:5931; }","duration":"257.177768ms","start":"2026-09-01T18:37:30.637189Z","end":"2026-09-01T18:37:30.894366Z","steps":["trace[769559857] 'agreement among raft nodes before linearized reading' (duration: 256.884151ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:37:30.894401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.736279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-09-01T18:37:30.894463Z","caller":"traceutil/trace.go:171","msg":"trace[754186853] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5931; }","duration":"160.831262ms","start":"2026-09-01T18:37:30.733611Z","end":"2026-09-01T18:37:30.894442Z","steps":["trace[754186853] 'agreement among raft nodes before linearized reading' (duration: 160.654238ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:37:31.058038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.318193ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:37:31.058179Z","caller":"traceutil/trace.go:171","msg":"trace[505913281] transaction","detail":"{read_only:false; response_revision:5932; number_of_response:1; }","duration":"158.769311ms","start":"2026-09-01T18:37:30.899382Z","end":"2026-09-01T18:37:31.058151Z","steps":["trace[505913281] 'process raft request' (duration: 29.211992ms)","trace[505913281] 'compare' (duration: 129.075168ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:37:31.060762Z","caller":"traceutil/trace.go:171","msg":"trace[1223702587] linearizableReadLoop","detail":"{readStateIndex:6153; appliedIndex:6151; }","duration":"158.903425ms","start":"2026-09-01T18:37:30.90183Z","end":"2026-09-01T18:37:31.060734Z","steps":["trace[1223702587] 'read index received' (duration: 26.780187ms)","trace[1223702587] 'applied index is now lower than readState.Index' (duration: 132.121598ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:37:31.061039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.198081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-09-01T18:37:31.061097Z","caller":"traceutil/trace.go:171","msg":"trace[142887682] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5934; }","duration":"159.324505ms","start":"2026-09-01T18:37:30.901751Z","end":"2026-09-01T18:37:31.061075Z","steps":["trace[142887682] 'agreement among raft nodes before linearized reading' (duration: 159.11111ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:37:31.061133Z","caller":"traceutil/trace.go:171","msg":"trace[1060562623] transaction","detail":"{read_only:false; response_revision:5933; number_of_response:1; }","duration":"161.465034ms","start":"2026-09-01T18:37:30.899511Z","end":"2026-09-01T18:37:31.060976Z","steps":["trace[1060562623] 'process raft request' (duration: 160.989753ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:37:31.061207Z","caller":"traceutil/trace.go:171","msg":"trace[1253512235] transaction","detail":"{read_only:false; response_revision:5934; number_of_response:1; }","duration":"157.802659ms","start":"2026-09-01T18:37:30.903368Z","end":"2026-09-01T18:37:31.06117Z","steps":["trace[1253512235] 'process raft request' (duration: 157.279617ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:42:08.633738Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5567} {"level":"info","ts":"2026-09-01T18:42:08.734512Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5567,"took":"98.538604ms","hash":1548396248,"current-db-size-bytes":56233984,"current-db-size":"56 MB","current-db-size-in-use-bytes":28712960,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-01T18:42:08.734596Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1548396248,"revision":5567,"compact-revision":2943} {"level":"info","ts":"2026-09-01T18:43:53.156024Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000238a.wal"} {"level":"warn","ts":"2026-09-01T18:43:53.969229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.369024ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:43:53.969632Z","caller":"traceutil/trace.go:171","msg":"trace[1453728291] linearizableReadLoop","detail":"{readStateIndex:9100; appliedIndex:9098; }","duration":"593.118297ms","start":"2026-09-01T18:43:53.376496Z","end":"2026-09-01T18:43:53.969614Z","steps":["trace[1453728291] 'read index received' (duration: 288.525448ms)","trace[1453728291] 'applied index is now lower than readState.Index' (duration: 304.591589ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:43:53.969654Z","caller":"traceutil/trace.go:171","msg":"trace[1639152323] transaction","detail":"{read_only:false; response_revision:8784; number_of_response:1; }","duration":"684.549625ms","start":"2026-09-01T18:43:53.285065Z","end":"2026-09-01T18:43:53.969614Z","steps":["trace[1639152323] 'process raft request' (duration: 379.70585ms)","trace[1639152323] 'compare' (duration: 304.16649ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:43:53.969855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:43:53.285057Z","time spent":"684.689568ms","remote":"127.0.0.1:59890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:43:53.970075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.534716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-09-01T18:43:53.970151Z","caller":"traceutil/trace.go:171","msg":"trace[1244939827] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8785; }","duration":"593.678911ms","start":"2026-09-01T18:43:53.376454Z","end":"2026-09-01T18:43:53.970133Z","steps":["trace[1244939827] 'agreement among raft nodes before linearized reading' (duration: 593.393283ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:43:53.970122Z","caller":"traceutil/trace.go:171","msg":"trace[991517604] transaction","detail":"{read_only:false; response_revision:8785; number_of_response:1; }","duration":"672.619221ms","start":"2026-09-01T18:43:53.297477Z","end":"2026-09-01T18:43:53.970096Z","steps":["trace[991517604] 'process raft request' (duration: 671.966745ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:43:53.970613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:43:53.297454Z","time spent":"673.111962ms","remote":"127.0.0.1:59890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:43:53.970206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.032872ms","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-01T18:43:53.9707Z","caller":"traceutil/trace.go:171","msg":"trace[38344097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8785; }","duration":"250.568984ms","start":"2026-09-01T18:43:53.720109Z","end":"2026-09-01T18:43:53.970678Z","steps":["trace[38344097] 'agreement among raft nodes before linearized reading' (duration: 249.846607ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:43:53.970234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:43:53.376438Z","time spent":"593.784384ms","remote":"127.0.0.1:59796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12234,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-01T18:44:04.644561Z","caller":"traceutil/trace.go:171","msg":"trace[1598662447] linearizableReadLoop","detail":"{readStateIndex:9155; appliedIndex:9154; }","duration":"218.234548ms","start":"2026-09-01T18:44:04.426304Z","end":"2026-09-01T18:44:04.644539Z","steps":["trace[1598662447] 'read index received' (duration: 218.114205ms)","trace[1598662447] 'applied index is now lower than readState.Index' (duration: 119.413µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:44:04.644942Z","caller":"traceutil/trace.go:171","msg":"trace[2055812692] transaction","detail":"{read_only:false; response_revision:8838; number_of_response:1; }","duration":"219.438708ms","start":"2026-09-01T18:44:04.425488Z","end":"2026-09-01T18:44:04.644927Z","steps":["trace[2055812692] 'process raft request' (duration: 218.860314ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:44:04.645231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.911895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-k2z9r\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-01T18:44:04.645265Z","caller":"traceutil/trace.go:171","msg":"trace[1419103045] range","detail":"{range_begin:/registry/pods/openstack/keepalived-k2z9r; range_end:; response_count:1; response_revision:8838; }","duration":"218.996807ms","start":"2026-09-01T18:44:04.426256Z","end":"2026-09-01T18:44:04.645253Z","steps":["trace[1419103045] 'agreement among raft nodes before linearized reading' (duration: 218.870064ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:44:04.645362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.679505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:44:04.645425Z","caller":"traceutil/trace.go:171","msg":"trace[168917965] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8838; }","duration":"213.782376ms","start":"2026-09-01T18:44:04.431626Z","end":"2026-09-01T18:44:04.645408Z","steps":["trace[168917965] 'agreement among raft nodes before linearized reading' (duration: 213.668644ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:44:05.364681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.443997ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:44:05.364786Z","caller":"traceutil/trace.go:171","msg":"trace[196404680] linearizableReadLoop","detail":"{readStateIndex:9159; appliedIndex:9158; }","duration":"201.265348ms","start":"2026-09-01T18:44:05.163505Z","end":"2026-09-01T18:44:05.364771Z","steps":["trace[196404680] 'read index received' (duration: 49.911µs)","trace[196404680] 'applied index is now lower than readState.Index' (duration: 201.214337ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:44:05.364866Z","caller":"traceutil/trace.go:171","msg":"trace[988606577] transaction","detail":"{read_only:false; response_revision:8842; number_of_response:1; }","duration":"300.090385ms","start":"2026-09-01T18:44:05.064765Z","end":"2026-09-01T18:44:05.364856Z","steps":["trace[988606577] 'process raft request' (duration: 54.385312ms)","trace[988606577] 'compare' (duration: 245.300404ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:44:05.36496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:44:05.064751Z","time spent":"300.178717ms","remote":"127.0.0.1:59890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:44:05.365126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.697461ms","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-01T18:44:05.365202Z","caller":"traceutil/trace.go:171","msg":"trace[301797552] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8842; }","duration":"192.816303ms","start":"2026-09-01T18:44:05.172366Z","end":"2026-09-01T18:44:05.365182Z","steps":["trace[301797552] 'agreement among raft nodes before linearized reading' (duration: 192.617079ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:44:05.365271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.761149ms","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-01T18:44:05.365335Z","caller":"traceutil/trace.go:171","msg":"trace[473895629] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8842; }","duration":"201.859671ms","start":"2026-09-01T18:44:05.163457Z","end":"2026-09-01T18:44:05.365317Z","steps":["trace[473895629] 'agreement among raft nodes before linearized reading' (duration: 201.670927ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:44:05.513731Z","caller":"traceutil/trace.go:171","msg":"trace[795676850] transaction","detail":"{read_only:false; response_revision:8844; number_of_response:1; }","duration":"144.667444ms","start":"2026-09-01T18:44:05.369001Z","end":"2026-09-01T18:44:05.513668Z","steps":["trace[795676850] 'process raft request' (duration: 144.485089ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:44:05.513774Z","caller":"traceutil/trace.go:171","msg":"trace[1961582191] transaction","detail":"{read_only:false; response_revision:8843; number_of_response:1; }","duration":"145.065092ms","start":"2026-09-01T18:44:05.368685Z","end":"2026-09-01T18:44:05.51375Z","steps":["trace[1961582191] 'process raft request' (duration: 98.314985ms)","trace[1961582191] 'compare' (duration: 46.378179ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:44:05.513946Z","caller":"traceutil/trace.go:171","msg":"trace[792374947] transaction","detail":"{read_only:false; response_revision:8845; number_of_response:1; }","duration":"143.582678ms","start":"2026-09-01T18:44:05.370335Z","end":"2026-09-01T18:44:05.513918Z","steps":["trace[792374947] 'process raft request' (duration: 143.23106ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:44:06.30326Z","caller":"traceutil/trace.go:171","msg":"trace[36599610] transaction","detail":"{read_only:false; response_revision:8849; number_of_response:1; }","duration":"132.229837ms","start":"2026-09-01T18:44:06.170988Z","end":"2026-09-01T18:44:06.303218Z","steps":["trace[36599610] 'process raft request' (duration: 92.167703ms)","trace[36599610] 'compare' (duration: 39.869819ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:44:31.477875Z","caller":"traceutil/trace.go:171","msg":"trace[1093618516] transaction","detail":"{read_only:false; response_revision:9034; number_of_response:1; }","duration":"117.61777ms","start":"2026-09-01T18:44:31.360218Z","end":"2026-09-01T18:44:31.477836Z","steps":["trace[1093618516] 'process raft request' (duration: 117.074338ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:44:35.417918Z","caller":"traceutil/trace.go:171","msg":"trace[1346704373] linearizableReadLoop","detail":"{readStateIndex:9381; appliedIndex:9380; }","duration":"460.615284ms","start":"2026-09-01T18:44:34.957248Z","end":"2026-09-01T18:44:35.417863Z","steps":["trace[1346704373] 'read index received' (duration: 460.480721ms)","trace[1346704373] 'applied index is now lower than readState.Index' (duration: 133.553µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:44:35.417971Z","caller":"traceutil/trace.go:171","msg":"trace[1048040101] transaction","detail":"{read_only:false; response_revision:9055; number_of_response:1; }","duration":"532.784577ms","start":"2026-09-01T18:44:34.885151Z","end":"2026-09-01T18:44:35.417935Z","steps":["trace[1048040101] 'process raft request' (duration: 532.568322ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:44:35.418133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.868151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"warn","ts":"2026-09-01T18:44:35.418154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:44:34.885127Z","time spent":"532.92514ms","remote":"127.0.0.1:59890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:44:35.418184Z","caller":"traceutil/trace.go:171","msg":"trace[383447320] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9055; }","duration":"460.969412ms","start":"2026-09-01T18:44:34.9572Z","end":"2026-09-01T18:44:35.418169Z","steps":["trace[383447320] 'agreement among raft nodes before linearized reading' (duration: 460.802438ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:44:35.418251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:44:34.957158Z","time spent":"461.080275ms","remote":"127.0.0.1:59796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12234,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-01T18:44:35.47003Z","caller":"traceutil/trace.go:171","msg":"trace[1937723588] transaction","detail":"{read_only:false; response_revision:9056; number_of_response:1; }","duration":"474.699459ms","start":"2026-09-01T18:44:34.995287Z","end":"2026-09-01T18:44:35.469986Z","steps":["trace[1937723588] 'process raft request' (duration: 474.239299ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:44:35.470212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:44:34.995236Z","time spent":"474.878363ms","remote":"127.0.0.1:59890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:44:35.470457Z","caller":"traceutil/trace.go:171","msg":"trace[462354317] transaction","detail":"{read_only:false; response_revision:9057; number_of_response:1; }","duration":"417.728627ms","start":"2026-09-01T18:44:35.052718Z","end":"2026-09-01T18:44:35.470446Z","steps":["trace[462354317] 'process raft request' (duration: 416.965289ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:44:35.470523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:44:35.052695Z","time spent":"417.796389ms","remote":"127.0.0.1:59890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T18:44:35.470655Z","caller":"traceutil/trace.go:171","msg":"trace[1452045193] transaction","detail":"{read_only:false; response_revision:9058; number_of_response:1; }","duration":"250.80255ms","start":"2026-09-01T18:44:35.219844Z","end":"2026-09-01T18:44:35.470647Z","steps":["trace[1452045193] 'process raft request' (duration: 249.874279ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:44:38.234913Z","caller":"traceutil/trace.go:171","msg":"trace[915652627] transaction","detail":"{read_only:false; response_revision:9073; number_of_response:1; }","duration":"106.668278ms","start":"2026-09-01T18:44:38.128205Z","end":"2026-09-01T18:44:38.234873Z","steps":["trace[915652627] 'process raft request' (duration: 106.517245ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:44:38.284296Z","caller":"traceutil/trace.go:171","msg":"trace[1346809889] transaction","detail":"{read_only:false; response_revision:9074; number_of_response:1; }","duration":"109.742279ms","start":"2026-09-01T18:44:38.174538Z","end":"2026-09-01T18:44:38.284281Z","steps":["trace[1346809889] 'process raft request' (duration: 109.627236ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:44:38.426554Z","caller":"traceutil/trace.go:171","msg":"trace[307334280] transaction","detail":"{read_only:false; response_revision:9075; number_of_response:1; }","duration":"138.099153ms","start":"2026-09-01T18:44:38.28842Z","end":"2026-09-01T18:44:38.426519Z","steps":["trace[307334280] 'process raft request' (duration: 66.158725ms)","trace[307334280] 'compare' (duration: 71.801025ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:44:38.428202Z","caller":"traceutil/trace.go:171","msg":"trace[628356949] transaction","detail":"{read_only:false; response_revision:9076; number_of_response:1; }","duration":"139.446793ms","start":"2026-09-01T18:44:38.288734Z","end":"2026-09-01T18:44:38.428181Z","steps":["trace[628356949] 'process raft request' (duration: 139.31978ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:44:46.488144Z","caller":"traceutil/trace.go:171","msg":"trace[525278789] transaction","detail":"{read_only:false; response_revision:9116; number_of_response:1; }","duration":"178.743829ms","start":"2026-09-01T18:44:46.309379Z","end":"2026-09-01T18:44:46.488122Z","steps":["trace[525278789] 'process raft request' (duration: 178.583746ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:44:46.718596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.655273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:44:46.718702Z","caller":"traceutil/trace.go:171","msg":"trace[1605697796] linearizableReadLoop","detail":"{readStateIndex:9445; appliedIndex:9443; }","duration":"254.633047ms","start":"2026-09-01T18:44:46.464052Z","end":"2026-09-01T18:44:46.718685Z","steps":["trace[1605697796] 'read index received' (duration: 23.973512ms)","trace[1605697796] 'applied index is now lower than readState.Index' (duration: 230.658225ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:44:46.718847Z","caller":"traceutil/trace.go:171","msg":"trace[1383291491] transaction","detail":"{read_only:false; response_revision:9117; number_of_response:1; }","duration":"261.962907ms","start":"2026-09-01T18:44:46.456839Z","end":"2026-09-01T18:44:46.718802Z","steps":["trace[1383291491] 'process raft request' (duration: 91.019707ms)","trace[1383291491] 'compare' (duration: 170.461719ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:44:46.718953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.630758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-01T18:44:46.719009Z","caller":"traceutil/trace.go:171","msg":"trace[1534725313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9117; }","duration":"244.70469ms","start":"2026-09-01T18:44:46.474287Z","end":"2026-09-01T18:44:46.718992Z","steps":["trace[1534725313] 'agreement among raft nodes before linearized reading' (duration: 244.499874ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:44:46.718993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.927086ms","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-01T18:44:46.719081Z","caller":"traceutil/trace.go:171","msg":"trace[1501807571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9117; }","duration":"255.067099ms","start":"2026-09-01T18:44:46.46399Z","end":"2026-09-01T18:44:46.719058Z","steps":["trace[1501807571] 'agreement among raft nodes before linearized reading' (duration: 254.786512ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:44:47.750258Z","caller":"traceutil/trace.go:171","msg":"trace[882367418] transaction","detail":"{read_only:false; response_revision:9120; number_of_response:1; }","duration":"229.666152ms","start":"2026-09-01T18:44:47.520567Z","end":"2026-09-01T18:44:47.750233Z","steps":["trace[882367418] 'process raft request' (duration: 229.450057ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:44:47.750422Z","caller":"traceutil/trace.go:171","msg":"trace[1731242673] transaction","detail":"{read_only:false; response_revision:9121; number_of_response:1; }","duration":"209.201681ms","start":"2026-09-01T18:44:47.54121Z","end":"2026-09-01T18:44:47.750412Z","steps":["trace[1731242673] 'process raft request' (duration: 208.977526ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:44:47.750433Z","caller":"traceutil/trace.go:171","msg":"trace[1965582112] linearizableReadLoop","detail":"{readStateIndex:9449; appliedIndex:9447; }","duration":"228.214669ms","start":"2026-09-01T18:44:47.522188Z","end":"2026-09-01T18:44:47.750403Z","steps":["trace[1965582112] 'read index received' (duration: 96.201057ms)","trace[1965582112] 'applied index is now lower than readState.Index' (duration: 132.011602ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:44:47.750567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.357612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:44:47.750634Z","caller":"traceutil/trace.go:171","msg":"trace[1193852319] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:9121; }","duration":"228.488975ms","start":"2026-09-01T18:44:47.52213Z","end":"2026-09-01T18:44:47.750619Z","steps":["trace[1193852319] 'agreement among raft nodes before linearized reading' (duration: 228.364752ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:45:01.346366Z","caller":"traceutil/trace.go:171","msg":"trace[832582414] transaction","detail":"{read_only:false; response_revision:9267; number_of_response:1; }","duration":"114.231313ms","start":"2026-09-01T18:45:01.23211Z","end":"2026-09-01T18:45:01.346341Z","steps":["trace[832582414] 'process raft request' (duration: 114.06733ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:45:47.549731Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f5132dc9ac390b66","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T18:45:47.558937Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-01T18:45:47.559135Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-01T18:47:08.64601Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7953} {"level":"info","ts":"2026-09-01T18:47:08.748154Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7953,"took":"98.555397ms","hash":2505264812,"current-db-size-bytes":56233984,"current-db-size":"56 MB","current-db-size-in-use-bytes":24887296,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-09-01T18:47:08.748235Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2505264812,"revision":7953,"compact-revision":5567} {"level":"info","ts":"2026-09-01T18:51:55.428859Z","caller":"traceutil/trace.go:171","msg":"trace[2056311248] transaction","detail":"{read_only:false; response_revision:12409; number_of_response:1; }","duration":"278.948802ms","start":"2026-09-01T18:51:55.149882Z","end":"2026-09-01T18:51:55.428831Z","steps":["trace[2056311248] 'process raft request' (duration: 278.813788ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:51:55.433905Z","caller":"traceutil/trace.go:171","msg":"trace[1091417363] transaction","detail":"{read_only:false; response_revision:12410; number_of_response:1; }","duration":"283.942846ms","start":"2026-09-01T18:51:55.149945Z","end":"2026-09-01T18:51:55.433887Z","steps":["trace[1091417363] 'process raft request' (duration: 283.808733ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:51:55.43426Z","caller":"traceutil/trace.go:171","msg":"trace[425711141] linearizableReadLoop","detail":"{readStateIndex:12841; appliedIndex:12839; }","duration":"226.196087ms","start":"2026-09-01T18:51:55.208046Z","end":"2026-09-01T18:51:55.434242Z","steps":["trace[425711141] 'read index received' (duration: 220.653199ms)","trace[425711141] 'applied index is now lower than readState.Index' (duration: 5.541298ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:51:55.43445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.759844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T18:51:55.43445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.950058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:51:55.434491Z","caller":"traceutil/trace.go:171","msg":"trace[1566429063] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12412; }","duration":"181.820675ms","start":"2026-09-01T18:51:55.25266Z","end":"2026-09-01T18:51:55.434481Z","steps":["trace[1566429063] 'agreement among raft nodes before linearized reading' (duration: 181.754123ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:51:55.434504Z","caller":"traceutil/trace.go:171","msg":"trace[1276969688] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12412; }","duration":"182.01493ms","start":"2026-09-01T18:51:55.252474Z","end":"2026-09-01T18:51:55.434489Z","steps":["trace[1276969688] 'agreement among raft nodes before linearized reading' (duration: 181.927647ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:51:55.434553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.906218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"warn","ts":"2026-09-01T18:51:55.434603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.095572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:51:55.434643Z","caller":"traceutil/trace.go:171","msg":"trace[1634082523] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12412; }","duration":"182.155742ms","start":"2026-09-01T18:51:55.252476Z","end":"2026-09-01T18:51:55.434632Z","steps":["trace[1634082523] 'agreement among raft nodes before linearized reading' (duration: 182.099921ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:51:55.434644Z","caller":"traceutil/trace.go:171","msg":"trace[1569849431] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12412; }","duration":"179.011851ms","start":"2026-09-01T18:51:55.255615Z","end":"2026-09-01T18:51:55.434626Z","steps":["trace[1569849431] 'agreement among raft nodes before linearized reading' (duration: 178.791025ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:51:55.434691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.99574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:51:55.434745Z","caller":"traceutil/trace.go:171","msg":"trace[40264203] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12412; }","duration":"182.100212ms","start":"2026-09-01T18:51:55.252632Z","end":"2026-09-01T18:51:55.434732Z","steps":["trace[40264203] 'agreement among raft nodes before linearized reading' (duration: 182.02125ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:51:55.434889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.833172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-09-01T18:51:55.434962Z","caller":"traceutil/trace.go:171","msg":"trace[1419409455] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:12412; }","duration":"226.931564ms","start":"2026-09-01T18:51:55.208018Z","end":"2026-09-01T18:51:55.434949Z","steps":["trace[1419409455] 'agreement among raft nodes before linearized reading' (duration: 226.77151ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:51:56.439437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.384926ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:51:56.439589Z","caller":"traceutil/trace.go:171","msg":"trace[424238218] linearizableReadLoop","detail":"{readStateIndex:12846; appliedIndex:12845; }","duration":"257.560669ms","start":"2026-09-01T18:51:56.182009Z","end":"2026-09-01T18:51:56.43957Z","steps":["trace[424238218] 'read index received' (duration: 35.830095ms)","trace[424238218] 'applied index is now lower than readState.Index' (duration: 221.729144ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:51:56.439604Z","caller":"traceutil/trace.go:171","msg":"trace[932333043] transaction","detail":"{read_only:false; response_revision:12415; number_of_response:1; }","duration":"271.679024ms","start":"2026-09-01T18:51:56.167864Z","end":"2026-09-01T18:51:56.439543Z","steps":["trace[932333043] 'process raft request' (duration: 50.097594ms)","trace[932333043] 'compare' (duration: 221.270823ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:51:56.439781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.870001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-01T18:51:56.439827Z","caller":"traceutil/trace.go:171","msg":"trace[563630365] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12415; }","duration":"272.955043ms","start":"2026-09-01T18:51:56.166858Z","end":"2026-09-01T18:51:56.439813Z","steps":["trace[563630365] 'agreement among raft nodes before linearized reading' (duration: 272.796309ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:51:56.454609Z","caller":"traceutil/trace.go:171","msg":"trace[838742286] transaction","detail":"{read_only:false; response_revision:12416; number_of_response:1; }","duration":"108.397115ms","start":"2026-09-01T18:51:56.346186Z","end":"2026-09-01T18:51:56.454583Z","steps":["trace[838742286] 'process raft request' (duration: 108.149139ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:51:56.454629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.360186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T18:51:56.454718Z","caller":"traceutil/trace.go:171","msg":"trace[1693438633] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:12416; }","duration":"155.496409ms","start":"2026-09-01T18:51:56.299197Z","end":"2026-09-01T18:51:56.454694Z","steps":["trace[1693438633] 'agreement among raft nodes before linearized reading' (duration: 155.306935ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:51:56.599778Z","caller":"traceutil/trace.go:171","msg":"trace[21982246] linearizableReadLoop","detail":"{readStateIndex:12848; appliedIndex:12847; }","duration":"143.966534ms","start":"2026-09-01T18:51:56.455778Z","end":"2026-09-01T18:51:56.599745Z","steps":["trace[21982246] 'read index received' (duration: 89.400369ms)","trace[21982246] 'applied index is now lower than readState.Index' (duration: 54.564635ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:51:56.599978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.133828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:51:56.600031Z","caller":"traceutil/trace.go:171","msg":"trace[478761967] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12417; }","duration":"144.273321ms","start":"2026-09-01T18:51:56.45574Z","end":"2026-09-01T18:51:56.600013Z","steps":["trace[478761967] 'agreement among raft nodes before linearized reading' (duration: 144.139098ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:51:56.600071Z","caller":"traceutil/trace.go:171","msg":"trace[1515258222] transaction","detail":"{read_only:false; response_revision:12417; number_of_response:1; }","duration":"156.047912ms","start":"2026-09-01T18:51:56.443981Z","end":"2026-09-01T18:51:56.600029Z","steps":["trace[1515258222] 'process raft request' (duration: 101.2177ms)","trace[1515258222] 'compare' (duration: 54.373582ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:52:08.655289Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10341} {"level":"info","ts":"2026-09-01T18:52:08.741884Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10341,"took":"84.320351ms","hash":1234353968,"current-db-size-bytes":56233984,"current-db-size":"56 MB","current-db-size-in-use-bytes":25591808,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-01T18:52:08.74197Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1234353968,"revision":10341,"compact-revision":7953} {"level":"info","ts":"2026-09-01T18:57:08.671214Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12509} {"level":"info","ts":"2026-09-01T18:57:08.75702Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12509,"took":"83.579283ms","hash":2803548839,"current-db-size-bytes":56233984,"current-db-size":"56 MB","current-db-size-in-use-bytes":27947008,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-01T18:57:08.757092Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2803548839,"revision":12509,"compact-revision":10341} {"level":"info","ts":"2026-09-01T18:58:04.204654Z","caller":"traceutil/trace.go:171","msg":"trace[1874762267] transaction","detail":"{read_only:false; response_revision:14897; number_of_response:1; }","duration":"113.78942ms","start":"2026-09-01T18:58:04.090831Z","end":"2026-09-01T18:58:04.204621Z","steps":["trace[1874762267] 'process raft request' (duration: 113.605806ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:58:24.022617Z","caller":"traceutil/trace.go:171","msg":"trace[303590102] transaction","detail":"{read_only:false; response_revision:15008; number_of_response:1; }","duration":"108.58953ms","start":"2026-09-01T18:58:23.913999Z","end":"2026-09-01T18:58:24.022589Z","steps":["trace[303590102] 'process raft request' (duration: 95.354175ms)","trace[303590102] 'compare' (duration: 12.853246ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:58:42.309037Z","caller":"traceutil/trace.go:171","msg":"trace[1990830097] transaction","detail":"{read_only:false; response_revision:15114; number_of_response:1; }","duration":"209.253156ms","start":"2026-09-01T18:58:42.099691Z","end":"2026-09-01T18:58:42.308944Z","steps":["trace[1990830097] 'process raft request' (duration: 209.085212ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:58:42.526465Z","caller":"traceutil/trace.go:171","msg":"trace[195840792] linearizableReadLoop","detail":"{readStateIndex:15643; appliedIndex:15642; }","duration":"209.688656ms","start":"2026-09-01T18:58:42.316753Z","end":"2026-09-01T18:58:42.526442Z","steps":["trace[195840792] 'read index received' (duration: 180.045903ms)","trace[195840792] 'applied index is now lower than readState.Index' (duration: 29.641653ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:58:42.526575Z","caller":"traceutil/trace.go:171","msg":"trace[1270531356] transaction","detail":"{read_only:false; response_revision:15115; number_of_response:1; }","duration":"407.197322ms","start":"2026-09-01T18:58:42.119365Z","end":"2026-09-01T18:58:42.526563Z","steps":["trace[1270531356] 'process raft request' (duration: 377.485448ms)","trace[1270531356] 'compare' (duration: 29.340974ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:58:42.526676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:58:42.119335Z","time spent":"407.262823ms","remote":"127.0.0.1:59890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:58:42.526733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.488308ms","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-01T18:58:42.526812Z","caller":"traceutil/trace.go:171","msg":"trace[871535469] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15115; }","duration":"138.613811ms","start":"2026-09-01T18:58:42.38818Z","end":"2026-09-01T18:58:42.526794Z","steps":["trace[871535469] 'agreement among raft nodes before linearized reading' (duration: 138.415506ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:58:42.526995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.241828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.59\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-01T18:58:42.527026Z","caller":"traceutil/trace.go:171","msg":"trace[396634675] range","detail":"{range_begin:/registry/masterleases/162.253.55.59; range_end:; response_count:1; response_revision:15115; }","duration":"210.30891ms","start":"2026-09-01T18:58:42.316708Z","end":"2026-09-01T18:58:42.527017Z","steps":["trace[396634675] 'agreement among raft nodes before linearized reading' (duration: 210.205577ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:58:53.165647Z","caller":"traceutil/trace.go:171","msg":"trace[1396766414] transaction","detail":"{read_only:false; response_revision:15293; number_of_response:1; }","duration":"108.837054ms","start":"2026-09-01T18:58:53.056788Z","end":"2026-09-01T18:58:53.165625Z","steps":["trace[1396766414] 'process raft request' (duration: 108.412185ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:58:53.486389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.153218ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:58:53.486775Z","caller":"traceutil/trace.go:171","msg":"trace[2130925394] transaction","detail":"{read_only:false; response_revision:15295; number_of_response:1; }","duration":"282.374569ms","start":"2026-09-01T18:58:53.204353Z","end":"2026-09-01T18:58:53.486727Z","steps":["trace[2130925394] 'process raft request' (duration: 170.78758ms)","trace[2130925394] 'compare' (duration: 110.998955ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:58:53.751915Z","caller":"traceutil/trace.go:171","msg":"trace[1998445950] transaction","detail":"{read_only:false; response_revision:15296; number_of_response:1; }","duration":"183.477352ms","start":"2026-09-01T18:58:53.568368Z","end":"2026-09-01T18:58:53.751845Z","steps":["trace[1998445950] 'process raft request' (duration: 183.279458ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:00:36.039069Z","caller":"traceutil/trace.go:171","msg":"trace[395110140] transaction","detail":"{read_only:false; response_revision:16425; number_of_response:1; }","duration":"110.338229ms","start":"2026-09-01T19:00:35.928703Z","end":"2026-09-01T19:00:36.039041Z","steps":["trace[395110140] 'process raft request' (duration: 110.157795ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:00:36.617767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.194257ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T19:00:36.618054Z","caller":"traceutil/trace.go:171","msg":"trace[864754275] linearizableReadLoop","detail":"{readStateIndex:16992; appliedIndex:16990; }","duration":"275.280706ms","start":"2026-09-01T19:00:36.342748Z","end":"2026-09-01T19:00:36.618028Z","steps":["trace[864754275] 'read index received' (duration: 167.615028ms)","trace[864754275] 'applied index is now lower than readState.Index' (duration: 107.664298ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T19:00:36.61819Z","caller":"traceutil/trace.go:171","msg":"trace[221353225] transaction","detail":"{read_only:false; response_revision:16428; number_of_response:1; }","duration":"277.172419ms","start":"2026-09-01T19:00:36.341002Z","end":"2026-09-01T19:00:36.618174Z","steps":["trace[221353225] 'process raft request' (duration: 276.885012ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:00:36.618308Z","caller":"traceutil/trace.go:171","msg":"trace[1210457157] transaction","detail":"{read_only:false; response_revision:16427; number_of_response:1; }","duration":"277.524047ms","start":"2026-09-01T19:00:36.340707Z","end":"2026-09-01T19:00:36.618231Z","steps":["trace[1210457157] 'process raft request' (duration: 169.747636ms)","trace[1210457157] 'compare' (duration: 106.745227ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T19:00:36.619509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.711029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:81 size:785146"} {"level":"info","ts":"2026-09-01T19:00:36.619637Z","caller":"traceutil/trace.go:171","msg":"trace[366459409] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:81; response_revision:16428; }","duration":"276.906634ms","start":"2026-09-01T19:00:36.342707Z","end":"2026-09-01T19:00:36.619614Z","steps":["trace[366459409] 'agreement among raft nodes before linearized reading' (duration: 275.46646ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:00:36.727583Z","caller":"traceutil/trace.go:171","msg":"trace[1572174813] linearizableReadLoop","detail":"{readStateIndex:16993; appliedIndex:16992; }","duration":"109.421888ms","start":"2026-09-01T19:00:36.618135Z","end":"2026-09-01T19:00:36.727557Z","steps":["trace[1572174813] 'read index received' (duration: 109.242174ms)","trace[1572174813] 'applied index is now lower than readState.Index' (duration: 178.774µs)"],"step_count":2} {"level":"warn","ts":"2026-09-01T19:00:36.727646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.337832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T19:00:36.727686Z","caller":"traceutil/trace.go:171","msg":"trace[2058786631] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:16429; }","duration":"177.474024ms","start":"2026-09-01T19:00:36.550198Z","end":"2026-09-01T19:00:36.727672Z","steps":["trace[2058786631] 'agreement among raft nodes before linearized reading' (duration: 177.384022ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:00:36.727792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.095068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-nhngb\" ","response":"range_response_count:1 size:22090"} {"level":"warn","ts":"2026-09-01T19:00:36.727755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.197382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-01T19:00:36.727836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.514702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-01T19:00:36.727844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.885989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T19:00:36.727892Z","caller":"traceutil/trace.go:171","msg":"trace[1915377011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16429; }","duration":"295.589863ms","start":"2026-09-01T19:00:36.432284Z","end":"2026-09-01T19:00:36.727874Z","steps":["trace[1915377011] 'agreement among raft nodes before linearized reading' (duration: 295.505041ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:00:36.727908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.642449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T19:00:36.727923Z","caller":"traceutil/trace.go:171","msg":"trace[1005733990] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:16429; }","duration":"312.046721ms","start":"2026-09-01T19:00:36.415854Z","end":"2026-09-01T19:00:36.727901Z","steps":["trace[1005733990] 'agreement among raft nodes before linearized reading' (duration: 311.839627ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:00:36.727989Z","caller":"traceutil/trace.go:171","msg":"trace[589790653] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16429; }","duration":"154.772132ms","start":"2026-09-01T19:00:36.5732Z","end":"2026-09-01T19:00:36.727972Z","steps":["trace[589790653] 'agreement among raft nodes before linearized reading' (duration: 154.629519ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:00:36.727989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.261284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5522"} {"level":"warn","ts":"2026-09-01T19:00:36.728033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.242426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5522"} {"level":"warn","ts":"2026-09-01T19:00:36.728021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:00:36.415834Z","time spent":"312.174845ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true "} {"level":"info","ts":"2026-09-01T19:00:36.728061Z","caller":"traceutil/trace.go:171","msg":"trace[385914262] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16429; }","duration":"269.299478ms","start":"2026-09-01T19:00:36.458754Z","end":"2026-09-01T19:00:36.728054Z","steps":["trace[385914262] 'agreement among raft nodes before linearized reading' (duration: 269.195835ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:00:36.728071Z","caller":"traceutil/trace.go:171","msg":"trace[1495195645] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16429; }","duration":"152.401228ms","start":"2026-09-01T19:00:36.575648Z","end":"2026-09-01T19:00:36.728049Z","steps":["trace[1495195645] 'agreement among raft nodes before linearized reading' (duration: 152.101921ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:00:36.728153Z","caller":"traceutil/trace.go:171","msg":"trace[581303830] transaction","detail":"{read_only:false; response_revision:16429; number_of_response:1; }","duration":"200.925034ms","start":"2026-09-01T19:00:36.527216Z","end":"2026-09-01T19:00:36.728141Z","steps":["trace[581303830] 'process raft request' (duration: 200.204868ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:00:36.728161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.533089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:669"} {"level":"info","ts":"2026-09-01T19:00:36.728186Z","caller":"traceutil/trace.go:171","msg":"trace[1212219829] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16429; }","duration":"271.610311ms","start":"2026-09-01T19:00:36.456569Z","end":"2026-09-01T19:00:36.72818Z","steps":["trace[1212219829] 'agreement among raft nodes before linearized reading' (duration: 271.544339ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:00:36.728232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.475335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:669"} {"level":"info","ts":"2026-09-01T19:00:36.728265Z","caller":"traceutil/trace.go:171","msg":"trace[165156549] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16429; }","duration":"154.529206ms","start":"2026-09-01T19:00:36.573727Z","end":"2026-09-01T19:00:36.728256Z","steps":["trace[165156549] 'agreement among raft nodes before linearized reading' (duration: 154.445224ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:00:36.727824Z","caller":"traceutil/trace.go:171","msg":"trace[2070487892] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-nhngb; range_end:; response_count:1; response_revision:16429; }","duration":"370.155008ms","start":"2026-09-01T19:00:36.35766Z","end":"2026-09-01T19:00:36.727815Z","steps":["trace[2070487892] 'agreement among raft nodes before linearized reading' (duration: 370.017635ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:00:36.728378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:00:36.357648Z","time spent":"370.714591ms","remote":"127.0.0.1:59796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22113,"request content":"key:\"/registry/pods/openstack/nova-compute-default-nhngb\" "} {"level":"info","ts":"2026-09-01T19:00:36.728857Z","caller":"traceutil/trace.go:171","msg":"trace[1583346506] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16429; }","duration":"271.401846ms","start":"2026-09-01T19:00:36.456445Z","end":"2026-09-01T19:00:36.727847Z","steps":["trace[1583346506] 'agreement among raft nodes before linearized reading' (duration: 271.188261ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:02:05.944406Z","caller":"traceutil/trace.go:171","msg":"trace[35381443] linearizableReadLoop","detail":"{readStateIndex:17593; appliedIndex:17592; }","duration":"214.787724ms","start":"2026-09-01T19:02:05.72956Z","end":"2026-09-01T19:02:05.944348Z","steps":["trace[35381443] 'read index received' (duration: 123.332799ms)","trace[35381443] 'applied index is now lower than readState.Index' (duration: 91.453335ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T19:02:05.944706Z","caller":"traceutil/trace.go:171","msg":"trace[504569416] transaction","detail":"{read_only:false; response_revision:17009; number_of_response:1; }","duration":"219.776018ms","start":"2026-09-01T19:02:05.724914Z","end":"2026-09-01T19:02:05.94469Z","steps":["trace[504569416] 'process raft request' (duration: 128.083479ms)","trace[504569416] 'compare' (duration: 90.946452ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T19:02:05.94483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.255064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-01T19:02:05.944933Z","caller":"traceutil/trace.go:171","msg":"trace[1933793240] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17009; }","duration":"215.440538ms","start":"2026-09-01T19:02:05.729471Z","end":"2026-09-01T19:02:05.944911Z","steps":["trace[1933793240] 'agreement among raft nodes before linearized reading' (duration: 215.187343ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:02:05.953835Z","caller":"traceutil/trace.go:171","msg":"trace[880021542] transaction","detail":"{read_only:false; response_revision:17010; number_of_response:1; }","duration":"126.236146ms","start":"2026-09-01T19:02:05.827582Z","end":"2026-09-01T19:02:05.953818Z","steps":["trace[880021542] 'process raft request' (duration: 126.147523ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:02:05.961194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.487553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-nhngb\" ","response":"range_response_count:1 size:22090"} {"level":"info","ts":"2026-09-01T19:02:05.961284Z","caller":"traceutil/trace.go:171","msg":"trace[1220582188] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-nhngb; range_end:; response_count:1; response_revision:17012; }","duration":"193.605095ms","start":"2026-09-01T19:02:05.767654Z","end":"2026-09-01T19:02:05.961259Z","steps":["trace[1220582188] 'agreement among raft nodes before linearized reading' (duration: 193.398171ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:02:06.251433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.621042ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T19:02:06.251517Z","caller":"traceutil/trace.go:171","msg":"trace[517456989] linearizableReadLoop","detail":"{readStateIndex:17597; appliedIndex:17596; }","duration":"279.599125ms","start":"2026-09-01T19:02:05.971903Z","end":"2026-09-01T19:02:06.251502Z","steps":["trace[517456989] 'read index received' (duration: 73.732027ms)","trace[517456989] 'applied index is now lower than readState.Index' (duration: 205.866068ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T19:02:06.251565Z","caller":"traceutil/trace.go:171","msg":"trace[1443425810] transaction","detail":"{read_only:false; response_revision:17013; number_of_response:1; }","duration":"285.771027ms","start":"2026-09-01T19:02:05.965767Z","end":"2026-09-01T19:02:06.251538Z","steps":["trace[1443425810] 'process raft request' (duration: 79.96927ms)","trace[1443425810] 'compare' (duration: 205.325476ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T19:02:06.252199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.293712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:85 size:824826"} {"level":"info","ts":"2026-09-01T19:02:06.252226Z","caller":"traceutil/trace.go:171","msg":"trace[1697753299] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:85; response_revision:17013; }","duration":"280.346213ms","start":"2026-09-01T19:02:05.971872Z","end":"2026-09-01T19:02:06.252219Z","steps":["trace[1697753299] 'agreement among raft nodes before linearized reading' (duration: 279.683307ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:02:06.252519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.899987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-k2z9r\" ","response":"range_response_count:1 size:6521"} {"level":"warn","ts":"2026-09-01T19:02:06.252565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.749541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:683"} {"level":"info","ts":"2026-09-01T19:02:06.252603Z","caller":"traceutil/trace.go:171","msg":"trace[1416531540] range","detail":"{range_begin:/registry/pods/openstack/keepalived-k2z9r; range_end:; response_count:1; response_revision:17014; }","duration":"205.011459ms","start":"2026-09-01T19:02:06.047573Z","end":"2026-09-01T19:02:06.252584Z","steps":["trace[1416531540] 'agreement among raft nodes before linearized reading' (duration: 204.763913ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:02:06.252612Z","caller":"traceutil/trace.go:171","msg":"trace[1975192845] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:17014; }","duration":"220.815512ms","start":"2026-09-01T19:02:06.031781Z","end":"2026-09-01T19:02:06.252597Z","steps":["trace[1975192845] 'agreement among raft nodes before linearized reading' (duration: 220.672979ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:02:06.252878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.956405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:669"} {"level":"info","ts":"2026-09-01T19:02:06.252915Z","caller":"traceutil/trace.go:171","msg":"trace[1167759261] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:17014; }","duration":"221.010177ms","start":"2026-09-01T19:02:06.031895Z","end":"2026-09-01T19:02:06.252905Z","steps":["trace[1167759261] 'agreement among raft nodes before linearized reading' (duration: 220.916555ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:02:06.25293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.17159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T19:02:06.253003Z","caller":"traceutil/trace.go:171","msg":"trace[47671499] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:17014; }","duration":"221.268942ms","start":"2026-09-01T19:02:06.031722Z","end":"2026-09-01T19:02:06.252991Z","steps":["trace[47671499] 'agreement among raft nodes before linearized reading' (duration: 221.17682ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:02:06.252869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.898645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:85 size:824826"} {"level":"info","ts":"2026-09-01T19:02:06.253102Z","caller":"traceutil/trace.go:171","msg":"trace[908819945] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:85; response_revision:17014; }","duration":"281.154501ms","start":"2026-09-01T19:02:05.97194Z","end":"2026-09-01T19:02:06.253094Z","steps":["trace[908819945] 'agreement among raft nodes before linearized reading' (duration: 280.334533ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:02:06.253124Z","caller":"traceutil/trace.go:171","msg":"trace[1655904704] transaction","detail":"{read_only:false; response_revision:17014; number_of_response:1; }","duration":"165.794886ms","start":"2026-09-01T19:02:06.087317Z","end":"2026-09-01T19:02:06.253112Z","steps":["trace[1655904704] 'process raft request' (duration: 164.851745ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:02:06.252879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.673788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-b545z\" ","response":"range_response_count:1 size:9788"} {"level":"info","ts":"2026-09-01T19:02:06.253312Z","caller":"traceutil/trace.go:171","msg":"trace[971752576] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-b545z; range_end:; response_count:1; response_revision:17014; }","duration":"148.158269ms","start":"2026-09-01T19:02:06.105145Z","end":"2026-09-01T19:02:06.253303Z","steps":["trace[971752576] 'agreement among raft nodes before linearized reading' (duration: 147.662318ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:02:08.678245Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14538} {"level":"info","ts":"2026-09-01T19:02:08.777469Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14538,"took":"96.269076ms","hash":1040312088,"current-db-size-bytes":56233984,"current-db-size":"56 MB","current-db-size-in-use-bytes":31735808,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-01T19:02:08.777531Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1040312088,"revision":14538,"compact-revision":12509} {"level":"warn","ts":"2026-09-01T19:02:36.659871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.431751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-01T19:02:36.659988Z","caller":"traceutil/trace.go:171","msg":"trace[157303267] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17176; }","duration":"245.579894ms","start":"2026-09-01T19:02:36.414368Z","end":"2026-09-01T19:02:36.659948Z","steps":["trace[157303267] 'range keys from in-memory index tree' (duration: 245.260157ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:02:36.659875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.449766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T19:02:36.660075Z","caller":"traceutil/trace.go:171","msg":"trace[1631056621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17176; }","duration":"227.678611ms","start":"2026-09-01T19:02:36.432362Z","end":"2026-09-01T19:02:36.66004Z","steps":["trace[1631056621] 'range keys from in-memory index tree' (duration: 227.357185ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:06:12.615232Z","caller":"traceutil/trace.go:171","msg":"trace[1474608964] transaction","detail":"{read_only:false; response_revision:18922; number_of_response:1; }","duration":"302.528536ms","start":"2026-09-01T19:06:12.312682Z","end":"2026-09-01T19:06:12.615211Z","steps":["trace[1474608964] 'process raft request' (duration: 302.322991ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:06:12.615372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:06:12.312659Z","time spent":"302.648558ms","remote":"127.0.0.1:41756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7438,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T19:06:12.615368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.600252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T19:06:12.615199Z","caller":"traceutil/trace.go:171","msg":"trace[524799699] linearizableReadLoop","detail":"{readStateIndex:19568; appliedIndex:19567; }","duration":"297.449139ms","start":"2026-09-01T19:06:12.317724Z","end":"2026-09-01T19:06:12.615173Z","steps":["trace[524799699] 'read index received' (duration: 297.158722ms)","trace[524799699] 'applied index is now lower than readState.Index' (duration: 289.447µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T19:06:12.615432Z","caller":"traceutil/trace.go:171","msg":"trace[1465281128] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:18922; }","duration":"297.734565ms","start":"2026-09-01T19:06:12.317677Z","end":"2026-09-01T19:06:12.615411Z","steps":["trace[1465281128] 'agreement among raft nodes before linearized reading' (duration: 297.597162ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:06:12.615625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.778362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-01T19:06:12.615653Z","caller":"traceutil/trace.go:171","msg":"trace[196604151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18922; }","duration":"180.814743ms","start":"2026-09-01T19:06:12.434832Z","end":"2026-09-01T19:06:12.615646Z","steps":["trace[196604151] 'agreement among raft nodes before linearized reading' (duration: 180.701311ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:06:12.615658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.8985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.59\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-09-01T19:06:12.615721Z","caller":"traceutil/trace.go:171","msg":"trace[917488534] range","detail":"{range_begin:/registry/masterleases/162.253.55.59; range_end:; response_count:1; response_revision:18922; }","duration":"261.998883ms","start":"2026-09-01T19:06:12.353703Z","end":"2026-09-01T19:06:12.615702Z","steps":["trace[917488534] 'agreement among raft nodes before linearized reading' (duration: 261.812578ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:06:12.615885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.490537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-01T19:06:12.615796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.747161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-01T19:06:12.615935Z","caller":"traceutil/trace.go:171","msg":"trace[14287227] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:18922; }","duration":"194.58898ms","start":"2026-09-01T19:06:12.421328Z","end":"2026-09-01T19:06:12.615917Z","steps":["trace[14287227] 'agreement among raft nodes before linearized reading' (duration: 194.503518ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:06:12.615948Z","caller":"traceutil/trace.go:171","msg":"trace[1264484012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18922; }","duration":"180.908565ms","start":"2026-09-01T19:06:12.435034Z","end":"2026-09-01T19:06:12.615943Z","steps":["trace[1264484012] 'agreement among raft nodes before linearized reading' (duration: 180.727831ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:06:12.615766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.73446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T19:06:12.616113Z","caller":"traceutil/trace.go:171","msg":"trace[1522656609] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18922; }","duration":"184.104388ms","start":"2026-09-01T19:06:12.431989Z","end":"2026-09-01T19:06:12.616093Z","steps":["trace[1522656609] 'agreement among raft nodes before linearized reading' (duration: 183.73415ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:06:14.272334Z","caller":"traceutil/trace.go:171","msg":"trace[1425684843] linearizableReadLoop","detail":"{readStateIndex:19587; appliedIndex:19586; }","duration":"133.211506ms","start":"2026-09-01T19:06:14.139093Z","end":"2026-09-01T19:06:14.272305Z","steps":["trace[1425684843] 'read index received' (duration: 132.985391ms)","trace[1425684843] 'applied index is now lower than readState.Index' (duration: 224.995µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T19:06:14.272399Z","caller":"traceutil/trace.go:171","msg":"trace[858479880] transaction","detail":"{read_only:false; response_revision:18940; number_of_response:1; }","duration":"135.287534ms","start":"2026-09-01T19:06:14.13708Z","end":"2026-09-01T19:06:14.272368Z","steps":["trace[858479880] 'process raft request' (duration: 135.074079ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:06:14.273708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.598239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:98 size:962985"} {"level":"info","ts":"2026-09-01T19:06:14.273783Z","caller":"traceutil/trace.go:171","msg":"trace[161072616] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:98; response_revision:18940; }","duration":"134.727622ms","start":"2026-09-01T19:06:14.139039Z","end":"2026-09-01T19:06:14.273766Z","steps":["trace[161072616] 'agreement among raft nodes before linearized reading' (duration: 133.374191ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:06:20.073935Z","caller":"traceutil/trace.go:171","msg":"trace[586339827] linearizableReadLoop","detail":"{readStateIndex:19639; appliedIndex:19638; }","duration":"182.376699ms","start":"2026-09-01T19:06:19.891536Z","end":"2026-09-01T19:06:20.073913Z","steps":["trace[586339827] 'read index received' (duration: 182.187414ms)","trace[586339827] 'applied index is now lower than readState.Index' (duration: 187.665µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T19:06:20.074266Z","caller":"traceutil/trace.go:171","msg":"trace[283652279] transaction","detail":"{read_only:false; response_revision:18991; number_of_response:1; }","duration":"182.968353ms","start":"2026-09-01T19:06:19.891284Z","end":"2026-09-01T19:06:20.074252Z","steps":["trace[283652279] 'process raft request' (duration: 182.422261ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:06:20.07552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.916546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-j6qgg\" ","response":"range_response_count:1 size:20736"} {"level":"info","ts":"2026-09-01T19:06:20.075585Z","caller":"traceutil/trace.go:171","msg":"trace[529349107] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-j6qgg; range_end:; response_count:1; response_revision:18991; }","duration":"105.036309ms","start":"2026-09-01T19:06:19.970535Z","end":"2026-09-01T19:06:20.075572Z","steps":["trace[529349107] 'agreement among raft nodes before linearized reading' (duration: 104.429195ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:06:20.076298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.752333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:98 size:963479"} {"level":"info","ts":"2026-09-01T19:06:20.076593Z","caller":"traceutil/trace.go:171","msg":"trace[1167503536] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:98; response_revision:18991; }","duration":"185.083771ms","start":"2026-09-01T19:06:19.891497Z","end":"2026-09-01T19:06:20.076581Z","steps":["trace[1167503536] 'agreement among raft nodes before linearized reading' (duration: 182.89581ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:06:20.076288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.637785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:98 size:963479"} {"level":"info","ts":"2026-09-01T19:06:20.07674Z","caller":"traceutil/trace.go:171","msg":"trace[713144864] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:98; response_revision:18991; }","duration":"183.156917ms","start":"2026-09-01T19:06:19.893546Z","end":"2026-09-01T19:06:20.076703Z","steps":["trace[713144864] 'agreement among raft nodes before linearized reading' (duration: 181.269494ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:06:20.404628Z","caller":"traceutil/trace.go:171","msg":"trace[1446142126] transaction","detail":"{read_only:false; response_revision:18992; number_of_response:1; }","duration":"326.548148ms","start":"2026-09-01T19:06:20.078049Z","end":"2026-09-01T19:06:20.404597Z","steps":["trace[1446142126] 'process raft request' (duration: 293.903037ms)","trace[1446142126] 'compare' (duration: 32.495928ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T19:06:20.404786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:06:20.078019Z","time spent":"326.682991ms","remote":"127.0.0.1:59698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":867,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T19:06:20.65737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.272628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T19:06:20.657843Z","caller":"traceutil/trace.go:171","msg":"trace[1162821264] transaction","detail":"{read_only:false; response_revision:18993; number_of_response:1; }","duration":"557.923766ms","start":"2026-09-01T19:06:20.099877Z","end":"2026-09-01T19:06:20.657801Z","steps":["trace[1162821264] 'process raft request' (duration: 444.140117ms)","trace[1162821264] 'compare' (duration: 113.119593ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T19:06:20.658021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:06:20.099835Z","time spent":"558.0827ms","remote":"127.0.0.1:59796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20853,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T19:06:20.658208Z","caller":"traceutil/trace.go:171","msg":"trace[1829830195] transaction","detail":"{read_only:false; response_revision:18994; number_of_response:1; }","duration":"390.994072ms","start":"2026-09-01T19:06:20.267195Z","end":"2026-09-01T19:06:20.658189Z","steps":["trace[1829830195] 'process raft request' (duration: 390.396408ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:06:20.65834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:06:20.267152Z","time spent":"391.140185ms","remote":"127.0.0.1:59792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T19:06:20.658453Z","caller":"traceutil/trace.go:171","msg":"trace[43151136] transaction","detail":"{read_only:false; response_revision:18995; number_of_response:1; }","duration":"363.630941ms","start":"2026-09-01T19:06:20.294808Z","end":"2026-09-01T19:06:20.658439Z","steps":["trace[43151136] 'process raft request' (duration: 363.291904ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:06:20.658376Z","caller":"traceutil/trace.go:171","msg":"trace[1672826293] linearizableReadLoop","detail":"{readStateIndex:19641; appliedIndex:19639; }","duration":"540.042483ms","start":"2026-09-01T19:06:20.118311Z","end":"2026-09-01T19:06:20.658354Z","steps":["trace[1672826293] 'read index received' (duration: 253.735152ms)","trace[1672826293] 'applied index is now lower than readState.Index' (duration: 286.306131ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T19:06:20.658524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:06:20.294778Z","time spent":"363.708163ms","remote":"127.0.0.1:59890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T19:06:20.658535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.206117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-01T19:06:20.658672Z","caller":"traceutil/trace.go:171","msg":"trace[2080258342] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:18995; }","duration":"540.384051ms","start":"2026-09-01T19:06:20.118274Z","end":"2026-09-01T19:06:20.658658Z","steps":["trace[2080258342] 'agreement among raft nodes before linearized reading' (duration: 540.133585ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:06:20.65871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:06:20.118256Z","time spent":"540.441273ms","remote":"127.0.0.1:38740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-09-01T19:06:20.658835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.601097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-29804820-7865t\" ","response":"range_response_count:1 size:7036"} {"level":"info","ts":"2026-09-01T19:06:20.658931Z","caller":"traceutil/trace.go:171","msg":"trace[1603203420] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-29804820-7865t; range_end:; response_count:1; response_revision:18995; }","duration":"305.768491ms","start":"2026-09-01T19:06:20.353139Z","end":"2026-09-01T19:06:20.658907Z","steps":["trace[1603203420] 'agreement among raft nodes before linearized reading' (duration: 305.459033ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:06:20.659006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:06:20.353125Z","time spent":"305.870393ms","remote":"127.0.0.1:59796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":7059,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-29804820-7865t\" "} {"level":"warn","ts":"2026-09-01T19:06:20.659084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.293032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-09-01T19:06:20.659339Z","caller":"traceutil/trace.go:171","msg":"trace[455685646] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18995; }","duration":"286.64634ms","start":"2026-09-01T19:06:20.372604Z","end":"2026-09-01T19:06:20.65925Z","steps":["trace[455685646] 'agreement among raft nodes before linearized reading' (duration: 286.017405ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:06:50.948331Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f5132dc9ac390b66","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T19:06:50.954784Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-01T19:06:50.954902Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-01T19:07:08.685145Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17033} {"level":"info","ts":"2026-09-01T19:07:08.790162Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17033,"took":"101.258338ms","hash":727086064,"current-db-size-bytes":56233984,"current-db-size":"56 MB","current-db-size-in-use-bytes":33394688,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-01T19:07:08.790246Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":727086064,"revision":17033,"compact-revision":14538} {"level":"info","ts":"2026-09-01T19:12:08.693536Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19466} {"level":"info","ts":"2026-09-01T19:12:08.825458Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19466,"took":"128.572247ms","hash":2614602045,"current-db-size-bytes":56233984,"current-db-size":"56 MB","current-db-size-in-use-bytes":31019008,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-01T19:12:08.825539Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2614602045,"revision":19466,"compact-revision":17033} {"level":"info","ts":"2026-09-01T19:12:56.992576Z","caller":"traceutil/trace.go:171","msg":"trace[866906362] transaction","detail":"{read_only:false; response_revision:21979; number_of_response:1; }","duration":"108.099955ms","start":"2026-09-01T19:12:56.884453Z","end":"2026-09-01T19:12:56.992553Z","steps":["trace[866906362] 'process raft request' (duration: 107.922911ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:12:57.219302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.420524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T19:12:57.219434Z","caller":"traceutil/trace.go:171","msg":"trace[399207776] linearizableReadLoop","detail":"{readStateIndex:22731; appliedIndex:22730; }","duration":"131.574017ms","start":"2026-09-01T19:12:57.087839Z","end":"2026-09-01T19:12:57.219413Z","steps":["trace[399207776] 'read index received' (duration: 56.081µs)","trace[399207776] 'applied index is now lower than readState.Index' (duration: 131.516076ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T19:12:57.219509Z","caller":"traceutil/trace.go:171","msg":"trace[1763761345] transaction","detail":"{read_only:false; response_revision:21980; number_of_response:1; }","duration":"334.4029ms","start":"2026-09-01T19:12:56.885081Z","end":"2026-09-01T19:12:57.219484Z","steps":["trace[1763761345] 'process raft request' (duration: 134.69947ms)","trace[1763761345] 'compare' (duration: 199.27362ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T19:12:57.219632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.791852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"warn","ts":"2026-09-01T19:12:57.219661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:12:56.885056Z","time spent":"334.531712ms","remote":"127.0.0.1:59890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T19:12:57.219679Z","caller":"traceutil/trace.go:171","msg":"trace[286606595] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:21980; }","duration":"131.840913ms","start":"2026-09-01T19:12:57.087826Z","end":"2026-09-01T19:12:57.219667Z","steps":["trace[286606595] 'agreement among raft nodes before linearized reading' (duration: 131.648309ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:12:57.615307Z","caller":"traceutil/trace.go:171","msg":"trace[2100341052] linearizableReadLoop","detail":"{readStateIndex:22732; appliedIndex:22731; }","duration":"107.349158ms","start":"2026-09-01T19:12:57.507933Z","end":"2026-09-01T19:12:57.615282Z","steps":["trace[2100341052] 'read index received' (duration: 107.216965ms)","trace[2100341052] 'applied index is now lower than readState.Index' (duration: 130.813µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T19:12:57.61546Z","caller":"traceutil/trace.go:171","msg":"trace[1412854982] transaction","detail":"{read_only:false; response_revision:21981; number_of_response:1; }","duration":"378.434027ms","start":"2026-09-01T19:12:57.236992Z","end":"2026-09-01T19:12:57.615426Z","steps":["trace[1412854982] 'process raft request' (duration: 378.106879ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:12:57.615596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.645475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-01T19:12:57.615638Z","caller":"traceutil/trace.go:171","msg":"trace[1821410167] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21981; }","duration":"107.780108ms","start":"2026-09-01T19:12:57.507846Z","end":"2026-09-01T19:12:57.615626Z","steps":["trace[1821410167] 'agreement among raft nodes before linearized reading' (duration: 107.559783ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:12:57.615655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:12:57.236975Z","time spent":"378.563499ms","remote":"127.0.0.1:59890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T19:12:58.238534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.699174ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-01T19:12:58.23882Z","caller":"traceutil/trace.go:171","msg":"trace[2004810098] linearizableReadLoop","detail":"{readStateIndex:22733; appliedIndex:22732; }","duration":"623.425802ms","start":"2026-09-01T19:12:57.615371Z","end":"2026-09-01T19:12:58.238797Z","steps":["trace[2004810098] 'read index received' (duration: 117.554994ms)","trace[2004810098] 'applied index is now lower than readState.Index' (duration: 505.869318ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T19:12:58.239113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.726168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-01T19:12:58.239138Z","caller":"traceutil/trace.go:171","msg":"trace[368833133] transaction","detail":"{read_only:false; response_revision:21982; number_of_response:1; }","duration":"618.873907ms","start":"2026-09-01T19:12:57.620235Z","end":"2026-09-01T19:12:58.239109Z","steps":["trace[368833133] 'process raft request' (duration: 618.402026ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:12:58.239172Z","caller":"traceutil/trace.go:171","msg":"trace[414904005] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21981; }","duration":"723.83537ms","start":"2026-09-01T19:12:57.515321Z","end":"2026-09-01T19:12:58.239156Z","steps":["trace[414904005] 'agreement among raft nodes before linearized reading' (duration: 723.657996ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:12:58.239453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:12:57.620177Z","time spent":"619.185405ms","remote":"127.0.0.1:59890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T19:12:58.239517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:12:57.515302Z","time spent":"724.194789ms","remote":"127.0.0.1:59890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-01T19:12:58.424235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.214976ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T19:12:58.424382Z","caller":"traceutil/trace.go:171","msg":"trace[1953460936] transaction","detail":"{read_only:false; response_revision:21983; number_of_response:1; }","duration":"189.771761ms","start":"2026-09-01T19:12:58.234576Z","end":"2026-09-01T19:12:58.424348Z","steps":["trace[1953460936] 'process raft request' (duration: 17.336479ms)","trace[1953460936] 'compare' (duration: 172.056072ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T19:12:58.424429Z","caller":"traceutil/trace.go:171","msg":"trace[327809499] transaction","detail":"{read_only:false; response_revision:21984; number_of_response:1; }","duration":"180.698321ms","start":"2026-09-01T19:12:58.243717Z","end":"2026-09-01T19:12:58.424415Z","steps":["trace[327809499] 'process raft request' (duration: 180.62987ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:12:58.424542Z","caller":"traceutil/trace.go:171","msg":"trace[1892144216] linearizableReadLoop","detail":"{readStateIndex:22735; appliedIndex:22734; }","duration":"185.635556ms","start":"2026-09-01T19:12:58.238891Z","end":"2026-09-01T19:12:58.424527Z","steps":["trace[1892144216] 'read index received' (duration: 13.032161ms)","trace[1892144216] 'applied index is now lower than readState.Index' (duration: 172.602015ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T19:12:58.424949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.382655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-09-01T19:12:58.425016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.950801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-01T19:12:58.425036Z","caller":"traceutil/trace.go:171","msg":"trace[1885920150] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21984; }","duration":"297.502138ms","start":"2026-09-01T19:12:58.127501Z","end":"2026-09-01T19:12:58.425003Z","steps":["trace[1885920150] 'agreement among raft nodes before linearized reading' (duration: 297.257352ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:12:58.425075Z","caller":"traceutil/trace.go:171","msg":"trace[745720218] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:21984; }","duration":"727.055034ms","start":"2026-09-01T19:12:57.698Z","end":"2026-09-01T19:12:58.425055Z","steps":["trace[745720218] 'agreement among raft nodes before linearized reading' (duration: 726.584513ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:12:58.42513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:12:57.697973Z","time spent":"727.142316ms","remote":"127.0.0.1:59776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":80,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-09-01T19:12:58.83552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.282861ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T19:12:58.835718Z","caller":"traceutil/trace.go:171","msg":"trace[2121920698] transaction","detail":"{read_only:false; response_revision:21987; number_of_response:1; }","duration":"389.804668ms","start":"2026-09-01T19:12:58.445896Z","end":"2026-09-01T19:12:58.835701Z","steps":["trace[2121920698] 'process raft request' (duration: 389.766177ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:12:58.835782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:12:58.445883Z","time spent":"389.865489ms","remote":"127.0.0.1:59890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T19:12:58.835767Z","caller":"traceutil/trace.go:171","msg":"trace[715419781] transaction","detail":"{read_only:false; response_revision:21985; number_of_response:1; }","duration":"406.584256ms","start":"2026-09-01T19:12:58.429151Z","end":"2026-09-01T19:12:58.835735Z","steps":["trace[715419781] 'process raft request' (duration: 145.004168ms)","trace[715419781] 'compare' (duration: 261.101327ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T19:12:58.83579Z","caller":"traceutil/trace.go:171","msg":"trace[1348990624] linearizableReadLoop","detail":"{readStateIndex:22737; appliedIndex:22736; }","duration":"403.230008ms","start":"2026-09-01T19:12:58.432521Z","end":"2026-09-01T19:12:58.835751Z","steps":["trace[1348990624] 'read index received' (duration: 141.728102ms)","trace[1348990624] 'applied index is now lower than readState.Index' (duration: 261.500206ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T19:12:58.835905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.379453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T19:12:58.835944Z","caller":"traceutil/trace.go:171","msg":"trace[2075518238] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21987; }","duration":"403.450584ms","start":"2026-09-01T19:12:58.432482Z","end":"2026-09-01T19:12:58.835933Z","steps":["trace[2075518238] 'agreement among raft nodes before linearized reading' (duration: 403.351662ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:12:58.835942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:12:58.429132Z","time spent":"406.699869ms","remote":"127.0.0.1:59890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T19:12:58.835977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:12:58.432462Z","time spent":"403.508076ms","remote":"127.0.0.1:59624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-01T19:12:58.835982Z","caller":"traceutil/trace.go:171","msg":"trace[1818700090] transaction","detail":"{read_only:false; response_revision:21986; number_of_response:1; }","duration":"391.172791ms","start":"2026-09-01T19:12:58.444792Z","end":"2026-09-01T19:12:58.835965Z","steps":["trace[1818700090] 'process raft request' (duration: 390.823792ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:12:58.836068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:12:58.444775Z","time spent":"391.242872ms","remote":"127.0.0.1:59890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T19:15:03.485954Z","caller":"traceutil/trace.go:171","msg":"trace[22316867] transaction","detail":"{read_only:false; response_revision:23016; number_of_response:1; }","duration":"157.422894ms","start":"2026-09-01T19:15:03.328489Z","end":"2026-09-01T19:15:03.485912Z","steps":["trace[22316867] 'process raft request' (duration: 157.141568ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:15:03.498017Z","caller":"traceutil/trace.go:171","msg":"trace[425180327] transaction","detail":"{read_only:false; response_revision:23017; number_of_response:1; }","duration":"164.653801ms","start":"2026-09-01T19:15:03.333331Z","end":"2026-09-01T19:15:03.497985Z","steps":["trace[425180327] 'process raft request' (duration: 164.465307ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:17:04.400282Z","caller":"traceutil/trace.go:171","msg":"trace[457329976] linearizableReadLoop","detail":"{readStateIndex:25326; appliedIndex:25325; }","duration":"113.275989ms","start":"2026-09-01T19:17:04.286981Z","end":"2026-09-01T19:17:04.400257Z","steps":["trace[457329976] 'read index received' (duration: 113.023083ms)","trace[457329976] 'applied index is now lower than readState.Index' (duration: 251.856µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T19:17:04.400705Z","caller":"traceutil/trace.go:171","msg":"trace[952697639] transaction","detail":"{read_only:false; response_revision:24500; number_of_response:1; }","duration":"157.958092ms","start":"2026-09-01T19:17:04.242733Z","end":"2026-09-01T19:17:04.400691Z","steps":["trace[952697639] 'process raft request' (duration: 157.324626ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:17:04.401063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.070677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-01T19:17:04.401106Z","caller":"traceutil/trace.go:171","msg":"trace[1311883308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24500; }","duration":"114.175ms","start":"2026-09-01T19:17:04.286922Z","end":"2026-09-01T19:17:04.401097Z","steps":["trace[1311883308] 'agreement among raft nodes before linearized reading' (duration: 114.024176ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:17:04.40126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.54913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-01T19:17:04.401353Z","caller":"traceutil/trace.go:171","msg":"trace[357034548] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24500; }","duration":"108.734974ms","start":"2026-09-01T19:17:04.292595Z","end":"2026-09-01T19:17:04.40133Z","steps":["trace[357034548] 'agreement among raft nodes before linearized reading' (duration: 108.495759ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:17:04.812642Z","caller":"traceutil/trace.go:171","msg":"trace[1945688267] linearizableReadLoop","detail":"{readStateIndex:25328; appliedIndex:25326; }","duration":"388.252377ms","start":"2026-09-01T19:17:04.424355Z","end":"2026-09-01T19:17:04.812607Z","steps":["trace[1945688267] 'read index received' (duration: 5.029117ms)","trace[1945688267] 'applied index is now lower than readState.Index' (duration: 383.22137ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T19:17:04.812829Z","caller":"traceutil/trace.go:171","msg":"trace[220978868] transaction","detail":"{read_only:false; response_revision:24502; number_of_response:1; }","duration":"406.1174ms","start":"2026-09-01T19:17:04.406694Z","end":"2026-09-01T19:17:04.812811Z","steps":["trace[220978868] 'process raft request' (duration: 401.509463ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:17:04.812979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:17:04.406672Z","time spent":"406.196701ms","remote":"127.0.0.1:59890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T19:17:04.813051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.703097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/openstackmachines.infrastructure.cluster.x-k8s.io\" ","response":"range_response_count:1 size:44554"} {"level":"info","ts":"2026-09-01T19:17:04.813132Z","caller":"traceutil/trace.go:171","msg":"trace[1876202401] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/openstackmachines.infrastructure.cluster.x-k8s.io; range_end:; response_count:1; response_revision:24502; }","duration":"388.83784ms","start":"2026-09-01T19:17:04.424271Z","end":"2026-09-01T19:17:04.813109Z","steps":["trace[1876202401] 'agreement among raft nodes before linearized reading' (duration: 388.596365ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:17:04.813169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:17:04.42421Z","time spent":"388.950263ms","remote":"127.0.0.1:59656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":44577,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/openstackmachines.infrastructure.cluster.x-k8s.io\" "} {"level":"warn","ts":"2026-09-01T19:17:04.819354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.52825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/openstackclusters.infrastructure.cluster.x-k8s.io\" ","response":"range_response_count:1 size:87328"} {"level":"info","ts":"2026-09-01T19:17:04.819455Z","caller":"traceutil/trace.go:171","msg":"trace[223353056] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/openstackclusters.infrastructure.cluster.x-k8s.io; range_end:; response_count:1; response_revision:24503; }","duration":"323.713595ms","start":"2026-09-01T19:17:04.495722Z","end":"2026-09-01T19:17:04.819436Z","steps":["trace[223353056] 'agreement among raft nodes before linearized reading' (duration: 323.413937ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:17:04.819502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:17:04.495696Z","time spent":"323.792227ms","remote":"127.0.0.1:59656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":87351,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/openstackclusters.infrastructure.cluster.x-k8s.io\" "} {"level":"info","ts":"2026-09-01T19:17:04.819686Z","caller":"traceutil/trace.go:171","msg":"trace[836729467] transaction","detail":"{read_only:false; response_revision:24503; number_of_response:1; }","duration":"295.490912ms","start":"2026-09-01T19:17:04.524153Z","end":"2026-09-01T19:17:04.819644Z","steps":["trace[836729467] 'process raft request' (duration: 294.856817ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:17:04.819849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.306651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-09-01T19:17:04.819924Z","caller":"traceutil/trace.go:171","msg":"trace[983060101] range","detail":"{range_begin:/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi; range_end:; response_count:1; response_revision:24503; }","duration":"309.363752ms","start":"2026-09-01T19:17:04.510527Z","end":"2026-09-01T19:17:04.819891Z","steps":["trace[983060101] 'agreement among raft nodes before linearized reading' (duration: 309.184748ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:17:04.819961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:17:04.510509Z","time spent":"309.442275ms","remote":"127.0.0.1:59890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":649,"request content":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" "} {"level":"warn","ts":"2026-09-01T19:17:04.820204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.93189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-01T19:17:04.820289Z","caller":"traceutil/trace.go:171","msg":"trace[1372561055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24503; }","duration":"257.067053ms","start":"2026-09-01T19:17:04.5632Z","end":"2026-09-01T19:17:04.820267Z","steps":["trace[1372561055] 'agreement among raft nodes before linearized reading' (duration: 256.193993ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:17:08.700647Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21735} {"level":"info","ts":"2026-09-01T19:17:08.81489Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21735,"took":"110.456094ms","hash":1675755686,"current-db-size-bytes":56233984,"current-db-size":"56 MB","current-db-size-in-use-bytes":42397696,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-01T19:17:08.814956Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1675755686,"revision":21735,"compact-revision":19466} {"level":"info","ts":"2026-09-01T19:18:38.548397Z","caller":"traceutil/trace.go:171","msg":"trace[1073007392] transaction","detail":"{read_only:false; response_revision:25477; number_of_response:1; }","duration":"159.315363ms","start":"2026-09-01T19:18:38.389047Z","end":"2026-09-01T19:18:38.548362Z","steps":["trace[1073007392] 'process raft request' (duration: 152.579197ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:18:38.553844Z","caller":"traceutil/trace.go:171","msg":"trace[945225553] linearizableReadLoop","detail":"{readStateIndex:26328; appliedIndex:26326; }","duration":"121.716754ms","start":"2026-09-01T19:18:38.432091Z","end":"2026-09-01T19:18:38.553808Z","steps":["trace[945225553] 'read index received' (duration: 109.707087ms)","trace[945225553] 'applied index is now lower than readState.Index' (duration: 12.008247ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T19:18:38.554013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.901068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T19:18:38.554061Z","caller":"traceutil/trace.go:171","msg":"trace[1151345845] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25478; }","duration":"122.001881ms","start":"2026-09-01T19:18:38.432046Z","end":"2026-09-01T19:18:38.554048Z","steps":["trace[1151345845] 'agreement among raft nodes before linearized reading' (duration: 121.881418ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:18:38.554113Z","caller":"traceutil/trace.go:171","msg":"trace[75434316] transaction","detail":"{read_only:false; response_revision:25478; number_of_response:1; }","duration":"156.530548ms","start":"2026-09-01T19:18:38.397555Z","end":"2026-09-01T19:18:38.554086Z","steps":["trace[75434316] 'process raft request' (duration: 156.086138ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:20:00.417011Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006962.wal"} {"level":"info","ts":"2026-09-01T19:22:08.709876Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24544} {"level":"info","ts":"2026-09-01T19:22:08.876898Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24544,"took":"162.2113ms","hash":562769006,"current-db-size-bytes":56233984,"current-db-size":"56 MB","current-db-size-in-use-bytes":34160640,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-01T19:22:08.876975Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":562769006,"revision":24544,"compact-revision":21735} {"level":"info","ts":"2026-09-01T19:24:42.185853Z","caller":"traceutil/trace.go:171","msg":"trace[893667764] linearizableReadLoop","detail":"{readStateIndex:29119; appliedIndex:29117; }","duration":"153.550644ms","start":"2026-09-01T19:24:42.032277Z","end":"2026-09-01T19:24:42.185827Z","steps":["trace[893667764] 'read index received' (duration: 45.870592ms)","trace[893667764] 'applied index is now lower than readState.Index' (duration: 107.678762ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T19:24:42.185897Z","caller":"traceutil/trace.go:171","msg":"trace[40870138] transaction","detail":"{read_only:false; response_revision:28192; number_of_response:1; }","duration":"158.403008ms","start":"2026-09-01T19:24:42.02746Z","end":"2026-09-01T19:24:42.185863Z","steps":["trace[40870138] 'process raft request' (duration: 110.309644ms)","trace[40870138] 'compare' (duration: 47.94098ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T19:24:42.186103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.117287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/kube-dns\" ","response":"range_response_count:1 size:1212"} {"level":"info","ts":"2026-09-01T19:24:42.186143Z","caller":"traceutil/trace.go:171","msg":"trace[1088471768] range","detail":"{range_begin:/registry/services/specs/kube-system/kube-dns; range_end:; response_count:1; response_revision:28192; }","duration":"141.205499ms","start":"2026-09-01T19:24:42.044927Z","end":"2026-09-01T19:24:42.186132Z","steps":["trace[1088471768] 'agreement among raft nodes before linearized reading' (duration: 141.049786ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:24:42.18618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.873242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:219"} {"level":"info","ts":"2026-09-01T19:24:42.186281Z","caller":"traceutil/trace.go:171","msg":"trace[1430659926] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:28192; }","duration":"154.032966ms","start":"2026-09-01T19:24:42.032227Z","end":"2026-09-01T19:24:42.18626Z","steps":["trace[1430659926] 'agreement among raft nodes before linearized reading' (duration: 153.723379ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:24:42.186402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.171796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-01T19:24:42.186615Z","caller":"traceutil/trace.go:171","msg":"trace[1857466507] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28192; }","duration":"121.35717ms","start":"2026-09-01T19:24:42.065167Z","end":"2026-09-01T19:24:42.186524Z","steps":["trace[1857466507] 'agreement among raft nodes before linearized reading' (duration: 120.880149ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:24:42.625215Z","caller":"traceutil/trace.go:171","msg":"trace[292188085] linearizableReadLoop","detail":"{readStateIndex:29123; appliedIndex:29122; }","duration":"132.929928ms","start":"2026-09-01T19:24:42.49226Z","end":"2026-09-01T19:24:42.62519Z","steps":["trace[292188085] 'read index received' (duration: 128.785772ms)","trace[292188085] 'applied index is now lower than readState.Index' (duration: 4.141976ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T19:24:42.625301Z","caller":"traceutil/trace.go:171","msg":"trace[1739906684] transaction","detail":"{read_only:false; response_revision:28195; number_of_response:1; }","duration":"133.114182ms","start":"2026-09-01T19:24:42.492103Z","end":"2026-09-01T19:24:42.625217Z","steps":["trace[1739906684] 'process raft request' (duration: 128.853014ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:24:42.625816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.453645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:69 size:51159"} {"level":"info","ts":"2026-09-01T19:24:42.625874Z","caller":"traceutil/trace.go:171","msg":"trace[534192878] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:69; response_revision:28196; }","duration":"103.554898ms","start":"2026-09-01T19:24:42.522306Z","end":"2026-09-01T19:24:42.62586Z","steps":["trace[534192878] 'agreement among raft nodes before linearized reading' (duration: 103.054696ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T19:24:42.625895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.627574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:69 size:51159"} {"level":"info","ts":"2026-09-01T19:24:42.625935Z","caller":"traceutil/trace.go:171","msg":"trace[921368591] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:69; response_revision:28196; }","duration":"133.693196ms","start":"2026-09-01T19:24:42.49223Z","end":"2026-09-01T19:24:42.625923Z","steps":["trace[921368591] 'agreement among raft nodes before linearized reading' (duration: 133.084431ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:24:42.931748Z","caller":"traceutil/trace.go:171","msg":"trace[998819034] transaction","detail":"{read_only:false; response_revision:28197; number_of_response:1; }","duration":"100.005145ms","start":"2026-09-01T19:24:42.831719Z","end":"2026-09-01T19:24:42.931724Z","steps":["trace[998819034] 'process raft request' (duration: 99.733999ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T19:26:35.550682Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f5132dc9ac390b66","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T19:26:35.557567Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-01T19:26:35.557696Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-01T19:27:08.726366Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27074} {"level":"info","ts":"2026-09-01T19:27:08.862656Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27074,"took":"131.569726ms","hash":2730958558,"current-db-size-bytes":56233984,"current-db-size":"56 MB","current-db-size-in-use-bytes":29593600,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-01T19:27:08.862725Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2730958558,"revision":27074,"compact-revision":24544}