{"level":"warn","ts":"2026-07-30T20:55:13.200979Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T20:55:13.201077Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.123:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.123:2380","--initial-cluster=instance=https://199.19.213.123:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.123:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.123: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-07-30T20:55:13.201146Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T20:55:13.201155Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.123:2380"]} {"level":"info","ts":"2026-07-30T20:55:13.201172Z","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-07-30T20:55:13.201806Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.123:2379"]} {"level":"info","ts":"2026-07-30T20:55:13.201891Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.123:2380"],"listen-peer-urls":["https://199.19.213.123:2380"],"advertise-client-urls":["https://199.19.213.123:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.123:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.123: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-07-30T20:55:13.204528Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.430846ms"} {"level":"info","ts":"2026-07-30T20:55:13.210358Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3e4829a3cabe4fce","cluster-id":"b469cfed817d8605"} {"level":"info","ts":"2026-07-30T20:55:13.21045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce switched to configuration voters=()"} {"level":"info","ts":"2026-07-30T20:55:13.210483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce became follower at term 0"} {"level":"info","ts":"2026-07-30T20:55:13.210505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3e4829a3cabe4fce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-30T20:55:13.210519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce became follower at term 1"} {"level":"info","ts":"2026-07-30T20:55:13.210572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce switched to configuration voters=(4487882812132577230)"} {"level":"warn","ts":"2026-07-30T20:55:13.215651Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-30T20:55:13.217458Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-30T20:55:13.218776Z","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-07-30T20:55:13.221482Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3e4829a3cabe4fce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-30T20:55:13.22165Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3e4829a3cabe4fce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-30T20:55:13.221899Z","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-07-30T20:55:13.221995Z","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-07-30T20:55:13.222012Z","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-07-30T20:55:13.224653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce switched to configuration voters=(4487882812132577230)"} {"level":"info","ts":"2026-07-30T20:55:13.2254Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b469cfed817d8605","local-member-id":"3e4829a3cabe4fce","added-peer-id":"3e4829a3cabe4fce","added-peer-peer-urls":["https://199.19.213.123:2380"]} {"level":"info","ts":"2026-07-30T20:55:13.226795Z","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-07-30T20:55:13.22698Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.123:2380"} {"level":"info","ts":"2026-07-30T20:55:13.227066Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.123:2380"} {"level":"info","ts":"2026-07-30T20:55:13.227118Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3e4829a3cabe4fce","initial-advertise-peer-urls":["https://199.19.213.123:2380"],"listen-peer-urls":["https://199.19.213.123:2380"],"advertise-client-urls":["https://199.19.213.123:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.123:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-30T20:55:13.227179Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-30T20:55:13.610766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce is starting a new election at term 1"} {"level":"info","ts":"2026-07-30T20:55:13.610867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce became pre-candidate at term 1"} {"level":"info","ts":"2026-07-30T20:55:13.610995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce received MsgPreVoteResp from 3e4829a3cabe4fce at term 1"} {"level":"info","ts":"2026-07-30T20:55:13.611085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce became candidate at term 2"} {"level":"info","ts":"2026-07-30T20:55:13.611158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce received MsgVoteResp from 3e4829a3cabe4fce at term 2"} {"level":"info","ts":"2026-07-30T20:55:13.611216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce became leader at term 2"} {"level":"info","ts":"2026-07-30T20:55:13.611244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3e4829a3cabe4fce elected leader 3e4829a3cabe4fce at term 2"} {"level":"info","ts":"2026-07-30T20:55:13.614317Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T20:55:13.615494Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T20:55:13.615556Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3e4829a3cabe4fce","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.123:2379]}","request-path":"/0/members/3e4829a3cabe4fce/attributes","cluster-id":"b469cfed817d8605","publish-timeout":"7s"} {"level":"info","ts":"2026-07-30T20:55:13.615742Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T20:55:13.61592Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b469cfed817d8605","local-member-id":"3e4829a3cabe4fce","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T20:55:13.616033Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-30T20:55:13.616088Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-30T20:55:13.616194Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T20:55:13.616289Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T20:55:13.618633Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-30T20:55:13.619647Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.123:2379"} {"level":"info","ts":"2026-07-30T20:55:56.162887Z","caller":"traceutil/trace.go:171","msg":"trace[1230788597] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"175.252342ms","start":"2026-07-30T20:55:55.987603Z","end":"2026-07-30T20:55:56.162856Z","steps":["trace[1230788597] 'process raft request' (duration: 174.406236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:55:56.645537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.028411ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T20:55:56.645757Z","caller":"traceutil/trace.go:171","msg":"trace[2121398778] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:658; }","duration":"356.489832ms","start":"2026-07-30T20:55:56.289254Z","end":"2026-07-30T20:55:56.645744Z","steps":["trace[2121398778] 'read index received' (duration: 168.86848ms)","trace[2121398778] 'applied index is now lower than readState.Index' (duration: 187.620342ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:55:56.645754Z","caller":"traceutil/trace.go:171","msg":"trace[2084786775] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"466.013115ms","start":"2026-07-30T20:55:56.17971Z","end":"2026-07-30T20:55:56.645723Z","steps":["trace[2084786775] 'compare' (duration: 458.925699ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:55:56.64584Z","caller":"traceutil/trace.go:171","msg":"trace[491730163] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"420.488127ms","start":"2026-07-30T20:55:56.225329Z","end":"2026-07-30T20:55:56.645817Z","steps":["trace[491730163] 'process raft request' (duration: 420.383625ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:55:56.645883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.628244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6q5pd\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-30T20:55:56.645902Z","caller":"traceutil/trace.go:171","msg":"trace[2011426295] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6q5pd; range_end:; response_count:1; response_revision:635; }","duration":"356.719827ms","start":"2026-07-30T20:55:56.289176Z","end":"2026-07-30T20:55:56.645896Z","steps":["trace[2011426295] 'agreement among raft nodes before linearized reading' (duration: 356.636245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:55:56.645941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:55:56.289142Z","time spent":"356.792887ms","remote":"127.0.0.1:58652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-6q5pd\" "} {"level":"warn","ts":"2026-07-30T20:55:56.645944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:55:56.225302Z","time spent":"420.594069ms","remote":"127.0.0.1:58980","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-07-30T20:55:56.645927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:55:56.179696Z","time spent":"466.135728ms","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T20:55:56.64606Z","caller":"traceutil/trace.go:171","msg":"trace[1840840100] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"452.905332ms","start":"2026-07-30T20:55:56.193114Z","end":"2026-07-30T20:55:56.646019Z","steps":["trace[1840840100] 'process raft request' (duration: 452.510855ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:55:56.646247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:55:56.193081Z","time spent":"453.069065ms","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T20:55:56.646287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.039484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-30T20:55:56.646373Z","caller":"traceutil/trace.go:171","msg":"trace[1493989364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:635; }","duration":"282.174306ms","start":"2026-07-30T20:55:56.364174Z","end":"2026-07-30T20:55:56.646349Z","steps":["trace[1493989364] 'agreement among raft nodes before linearized reading' (duration: 281.946502ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:12.135216Z","caller":"traceutil/trace.go:171","msg":"trace[1706177878] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"191.15438ms","start":"2026-07-30T20:56:11.944033Z","end":"2026-07-30T20:56:12.135187Z","steps":["trace[1706177878] 'process raft request' (duration: 190.960387ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:56:51.763061Z","caller":"traceutil/trace.go:171","msg":"trace[1404760538] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"212.853656ms","start":"2026-07-30T20:56:51.550171Z","end":"2026-07-30T20:56:51.763025Z","steps":["trace[1404760538] 'process raft request' (duration: 212.674853ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:57:17.849412Z","caller":"traceutil/trace.go:171","msg":"trace[2054054717] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"347.688194ms","start":"2026-07-30T20:57:17.501686Z","end":"2026-07-30T20:57:17.849374Z","steps":["trace[2054054717] 'process raft request' (duration: 347.396589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:17.849648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:57:17.501666Z","time spent":"347.869307ms","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T20:58:08.185926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.546908ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T20:58:08.186082Z","caller":"traceutil/trace.go:171","msg":"trace[1313325338] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"288.440687ms","start":"2026-07-30T20:58:07.897618Z","end":"2026-07-30T20:58:08.186059Z","steps":["trace[1313325338] 'process raft request' (duration: 36.638534ms)","trace[1313325338] 'compare' (duration: 251.378565ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:58:08.262483Z","caller":"traceutil/trace.go:171","msg":"trace[974541463] linearizableReadLoop","detail":"{readStateIndex:2239; appliedIndex:2238; }","duration":"289.470176ms","start":"2026-07-30T20:58:07.97297Z","end":"2026-07-30T20:58:08.26244Z","steps":["trace[974541463] 'read index received' (duration: 289.237692ms)","trace[974541463] 'applied index is now lower than readState.Index' (duration: 230.984µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:08.262835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.422729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-07-30T20:58:08.262881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.886284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-30T20:58:08.262586Z","caller":"traceutil/trace.go:171","msg":"trace[1314157156] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"316.287413ms","start":"2026-07-30T20:58:07.946263Z","end":"2026-07-30T20:58:08.26255Z","steps":["trace[1314157156] 'process raft request' (duration: 315.977108ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:08.263006Z","caller":"traceutil/trace.go:171","msg":"trace[692295472] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2155; }","duration":"290.059117ms","start":"2026-07-30T20:58:07.972914Z","end":"2026-07-30T20:58:08.262973Z","steps":["trace[692295472] 'agreement among raft nodes before linearized reading' (duration: 289.707551ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:08.263097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:07.946247Z","time spent":"316.778583ms","remote":"127.0.0.1:58756","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-07-30T20:58:08.262918Z","caller":"traceutil/trace.go:171","msg":"trace[1889266996] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2155; }","duration":"119.576712ms","start":"2026-07-30T20:58:08.143319Z","end":"2026-07-30T20:58:08.262896Z","steps":["trace[1889266996] 'agreement among raft nodes before linearized reading' (duration: 119.339607ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:08.405631Z","caller":"traceutil/trace.go:171","msg":"trace[1257746183] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"136.89681ms","start":"2026-07-30T20:58:08.268683Z","end":"2026-07-30T20:58:08.40558Z","steps":["trace[1257746183] 'process raft request' (duration: 136.559194ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:08.405719Z","caller":"traceutil/trace.go:171","msg":"trace[418659293] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"136.802238ms","start":"2026-07-30T20:58:08.268909Z","end":"2026-07-30T20:58:08.405711Z","steps":["trace[418659293] 'process raft request' (duration: 136.515323ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:08.40565Z","caller":"traceutil/trace.go:171","msg":"trace[2137301227] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"136.802557ms","start":"2026-07-30T20:58:08.268818Z","end":"2026-07-30T20:58:08.40562Z","steps":["trace[2137301227] 'process raft request' (duration: 136.561933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:23.599963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.784947ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T20:58:23.600176Z","caller":"traceutil/trace.go:171","msg":"trace[1058990034] linearizableReadLoop","detail":"{readStateIndex:2382; appliedIndex:2381; }","duration":"172.184158ms","start":"2026-07-30T20:58:23.427969Z","end":"2026-07-30T20:58:23.600153Z","steps":["trace[1058990034] 'read index received' (duration: 7.037974ms)","trace[1058990034] 'applied index is now lower than readState.Index' (duration: 165.144904ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:58:23.600195Z","caller":"traceutil/trace.go:171","msg":"trace[550115819] transaction","detail":"{read_only:false; response_revision:2294; number_of_response:1; }","duration":"192.907019ms","start":"2026-07-30T20:58:23.407258Z","end":"2026-07-30T20:58:23.600165Z","steps":["trace[550115819] 'process raft request' (duration: 27.803066ms)","trace[550115819] 'compare' (duration: 164.646705ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:58:23.600329Z","caller":"traceutil/trace.go:171","msg":"trace[1189411428] transaction","detail":"{read_only:false; response_revision:2295; number_of_response:1; }","duration":"126.079886ms","start":"2026-07-30T20:58:23.474225Z","end":"2026-07-30T20:58:23.600305Z","steps":["trace[1189411428] 'process raft request' (duration: 125.876541ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:23.600456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.485924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-30T20:58:23.60051Z","caller":"traceutil/trace.go:171","msg":"trace[1030310304] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2295; }","duration":"172.588206ms","start":"2026-07-30T20:58:23.427908Z","end":"2026-07-30T20:58:23.600496Z","steps":["trace[1030310304] 'agreement among raft nodes before linearized reading' (duration: 172.367792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:23.600686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.30713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-07-30T20:58:23.600786Z","caller":"traceutil/trace.go:171","msg":"trace[595972673] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2295; }","duration":"172.447973ms","start":"2026-07-30T20:58:23.428314Z","end":"2026-07-30T20:58:23.600762Z","steps":["trace[595972673] 'agreement among raft nodes before linearized reading' (duration: 172.144277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:47.643672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.428742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:58:47.643767Z","caller":"traceutil/trace.go:171","msg":"trace[1013228413] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2455; }","duration":"144.603756ms","start":"2026-07-30T20:58:47.499143Z","end":"2026-07-30T20:58:47.643747Z","steps":["trace[1013228413] 'range keys from in-memory index tree' (duration: 144.30476ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:47.883835Z","caller":"traceutil/trace.go:171","msg":"trace[570697894] linearizableReadLoop","detail":"{readStateIndex:2549; appliedIndex:2548; }","duration":"323.645089ms","start":"2026-07-30T20:58:47.560162Z","end":"2026-07-30T20:58:47.883807Z","steps":["trace[570697894] 'read index received' (duration: 323.493576ms)","trace[570697894] 'applied index is now lower than readState.Index' (duration: 149.973µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:58:47.883986Z","caller":"traceutil/trace.go:171","msg":"trace[1516357436] transaction","detail":"{read_only:false; response_revision:2456; number_of_response:1; }","duration":"348.410674ms","start":"2026-07-30T20:58:47.535563Z","end":"2026-07-30T20:58:47.883974Z","steps":["trace[1516357436] 'process raft request' (duration: 348.077368ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:47.884117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:47.535541Z","time spent":"348.470704ms","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T20:58:47.884296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.118028ms","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-07-30T20:58:47.884454Z","caller":"traceutil/trace.go:171","msg":"trace[1145362621] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2456; }","duration":"324.272141ms","start":"2026-07-30T20:58:47.560096Z","end":"2026-07-30T20:58:47.884368Z","steps":["trace[1145362621] 'agreement among raft nodes before linearized reading' (duration: 324.036567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:47.884521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:47.560072Z","time spent":"324.431634ms","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-30T20:58:47.96551Z","caller":"traceutil/trace.go:171","msg":"trace[302097873] transaction","detail":"{read_only:false; response_revision:2457; number_of_response:1; }","duration":"268.646817ms","start":"2026-07-30T20:58:47.696833Z","end":"2026-07-30T20:58:47.96548Z","steps":["trace[302097873] 'process raft request' (duration: 267.255281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:47.965808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.620806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-30T20:58:47.965865Z","caller":"traceutil/trace.go:171","msg":"trace[1664311114] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2458; }","duration":"103.677637ms","start":"2026-07-30T20:58:47.862167Z","end":"2026-07-30T20:58:47.965845Z","steps":["trace[1664311114] 'agreement among raft nodes before linearized reading' (duration: 103.398902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:47.965886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.40668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-30T20:58:47.965953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.013909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-30T20:58:47.966003Z","caller":"traceutil/trace.go:171","msg":"trace[1255884801] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:2458; }","duration":"327.595624ms","start":"2026-07-30T20:58:47.63838Z","end":"2026-07-30T20:58:47.965976Z","steps":["trace[1255884801] 'agreement among raft nodes before linearized reading' (duration: 327.214647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:47.966067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:47.638358Z","time spent":"327.697585ms","remote":"127.0.0.1:35530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":16,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-07-30T20:58:47.966011Z","caller":"traceutil/trace.go:171","msg":"trace[2008129786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2458; }","duration":"247.100152ms","start":"2026-07-30T20:58:47.718897Z","end":"2026-07-30T20:58:47.965997Z","steps":["trace[2008129786] 'agreement among raft nodes before linearized reading' (duration: 246.688665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:00:52.829765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.133489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-30T21:00:52.829828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.63206ms","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-07-30T21:00:52.83018Z","caller":"traceutil/trace.go:171","msg":"trace[529858418] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3338; }","duration":"234.959826ms","start":"2026-07-30T21:00:52.595158Z","end":"2026-07-30T21:00:52.830118Z","steps":["trace[529858418] 'range keys from in-memory index tree' (duration: 234.464656ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:00:52.830274Z","caller":"traceutil/trace.go:171","msg":"trace[595663104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3338; }","duration":"235.383664ms","start":"2026-07-30T21:00:52.594524Z","end":"2026-07-30T21:00:52.829907Z","steps":["trace[595663104] 'range keys from in-memory index tree' (duration: 234.739992ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:05:13.644898Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3075} {"level":"info","ts":"2026-07-30T21:05:13.738826Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3075,"took":"92.815937ms","hash":598734145,"current-db-size-bytes":35864576,"current-db-size":"36 MB","current-db-size-in-use-bytes":8794112,"current-db-size-in-use":"8.8 MB"} {"level":"info","ts":"2026-07-30T21:05:13.738907Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":598734145,"revision":3075,"compact-revision":-1} {"level":"info","ts":"2026-07-30T21:10:13.651948Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5010} {"level":"info","ts":"2026-07-30T21:10:13.70002Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5010,"took":"46.768691ms","hash":82163853,"current-db-size-bytes":35864576,"current-db-size":"36 MB","current-db-size-in-use-bytes":11804672,"current-db-size-in-use":"12 MB"} {"level":"info","ts":"2026-07-30T21:10:13.700104Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":82163853,"revision":5010,"compact-revision":3075} {"level":"warn","ts":"2026-07-30T21:13:53.593675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.776837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-30T21:13:53.593747Z","caller":"traceutil/trace.go:171","msg":"trace[391447180] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:9044; }","duration":"159.90429ms","start":"2026-07-30T21:13:53.433829Z","end":"2026-07-30T21:13:53.593733Z","steps":["trace[391447180] 'count revisions from in-memory index tree' (duration: 159.669506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:13:53.594288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.978116ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:13:53.594402Z","caller":"traceutil/trace.go:171","msg":"trace[473184394] linearizableReadLoop","detail":"{readStateIndex:9339; appliedIndex:9338; }","duration":"103.048418ms","start":"2026-07-30T21:13:53.491337Z","end":"2026-07-30T21:13:53.594385Z","steps":["trace[473184394] 'read index received' (duration: 72.621µs)","trace[473184394] 'applied index is now lower than readState.Index' (duration: 102.974057ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:13:53.594524Z","caller":"traceutil/trace.go:171","msg":"trace[1284944400] transaction","detail":"{read_only:false; response_revision:9045; number_of_response:1; }","duration":"153.549362ms","start":"2026-07-30T21:13:53.440943Z","end":"2026-07-30T21:13:53.594493Z","steps":["trace[1284944400] 'process raft request' (duration: 29.931654ms)","trace[1284944400] 'compare' (duration: 122.557378ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:13:53.594649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.310393ms","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-07-30T21:13:53.594717Z","caller":"traceutil/trace.go:171","msg":"trace[1606328272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9045; }","duration":"103.409475ms","start":"2026-07-30T21:13:53.491291Z","end":"2026-07-30T21:13:53.5947Z","steps":["trace[1606328272] 'agreement among raft nodes before linearized reading' (duration: 103.214691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:13:53.594759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.14949ms","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-07-30T21:13:53.594847Z","caller":"traceutil/trace.go:171","msg":"trace[250804202] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9045; }","duration":"103.266282ms","start":"2026-07-30T21:13:53.491562Z","end":"2026-07-30T21:13:53.594828Z","steps":["trace[250804202] 'agreement among raft nodes before linearized reading' (duration: 103.035498ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:15:13.658407Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7290} {"level":"info","ts":"2026-07-30T21:15:13.714305Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7290,"took":"54.221148ms","hash":2108420765,"current-db-size-bytes":35864576,"current-db-size":"36 MB","current-db-size-in-use-bytes":12931072,"current-db-size-in-use":"13 MB"} {"level":"info","ts":"2026-07-30T21:15:13.714385Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2108420765,"revision":7290,"compact-revision":5010} {"level":"info","ts":"2026-07-30T21:15:16.041198Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3e4829a3cabe4fce","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-30T21:15:16.045698Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-30T21:15:16.045803Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-30T21:19:47.786143Z","caller":"traceutil/trace.go:171","msg":"trace[1309078362] linearizableReadLoop","detail":"{readStateIndex:12177; appliedIndex:12176; }","duration":"191.318572ms","start":"2026-07-30T21:19:47.594765Z","end":"2026-07-30T21:19:47.786084Z","steps":["trace[1309078362] 'read index received' (duration: 191.049798ms)","trace[1309078362] 'applied index is now lower than readState.Index' (duration: 264.514µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:19:47.786246Z","caller":"traceutil/trace.go:171","msg":"trace[231319192] transaction","detail":"{read_only:false; response_revision:11798; number_of_response:1; }","duration":"217.529051ms","start":"2026-07-30T21:19:47.568681Z","end":"2026-07-30T21:19:47.78621Z","steps":["trace[231319192] 'process raft request' (duration: 217.198966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:19:47.786376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.584835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-30T21:19:47.786434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.663709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-07-30T21:19:47.786459Z","caller":"traceutil/trace.go:171","msg":"trace[1295689833] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11798; }","duration":"191.708698ms","start":"2026-07-30T21:19:47.594729Z","end":"2026-07-30T21:19:47.786438Z","steps":["trace[1295689833] 'agreement among raft nodes before linearized reading' (duration: 191.512795ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:19:47.786483Z","caller":"traceutil/trace.go:171","msg":"trace[1434923192] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:11798; }","duration":"167.775651ms","start":"2026-07-30T21:19:47.618692Z","end":"2026-07-30T21:19:47.786468Z","steps":["trace[1434923192] 'agreement among raft nodes before linearized reading' (duration: 167.583219ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:19:47.969686Z","caller":"traceutil/trace.go:171","msg":"trace[860459275] transaction","detail":"{read_only:false; response_revision:11800; number_of_response:1; }","duration":"176.534986ms","start":"2026-07-30T21:19:47.793125Z","end":"2026-07-30T21:19:47.96966Z","steps":["trace[860459275] 'process raft request' (duration: 152.747593ms)","trace[860459275] 'compare' (duration: 23.676771ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:20:08.932132Z","caller":"traceutil/trace.go:171","msg":"trace[825706024] transaction","detail":"{read_only:false; response_revision:12227; number_of_response:1; }","duration":"213.879085ms","start":"2026-07-30T21:20:08.718228Z","end":"2026-07-30T21:20:08.932107Z","steps":["trace[825706024] 'process raft request' (duration: 213.722953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:20:09.13529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.972321ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:20:09.135624Z","caller":"traceutil/trace.go:171","msg":"trace[69116804] transaction","detail":"{read_only:false; response_revision:12228; number_of_response:1; }","duration":"250.051718ms","start":"2026-07-30T21:20:08.885537Z","end":"2026-07-30T21:20:09.135589Z","steps":["trace[69116804] 'process raft request' (duration: 97.692691ms)","trace[69116804] 'compare' (duration: 151.765108ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:20:09.135643Z","caller":"traceutil/trace.go:171","msg":"trace[2033914165] transaction","detail":"{read_only:false; response_revision:12229; number_of_response:1; }","duration":"162.543442ms","start":"2026-07-30T21:20:08.973081Z","end":"2026-07-30T21:20:09.135625Z","steps":["trace[2033914165] 'process raft request' (duration: 162.477451ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:20:13.663711Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9672} {"level":"info","ts":"2026-07-30T21:20:13.719858Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9672,"took":"54.328979ms","hash":1612240541,"current-db-size-bytes":35864576,"current-db-size":"36 MB","current-db-size-in-use-bytes":15429632,"current-db-size-in-use":"15 MB"} {"level":"info","ts":"2026-07-30T21:20:13.720017Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1612240541,"revision":9672,"compact-revision":7290} {"level":"info","ts":"2026-07-30T21:21:29.431838Z","caller":"traceutil/trace.go:171","msg":"trace[107922864] transaction","detail":"{read_only:false; response_revision:13119; number_of_response:1; }","duration":"107.103616ms","start":"2026-07-30T21:21:29.324711Z","end":"2026-07-30T21:21:29.431815Z","steps":["trace[107922864] 'process raft request' (duration: 23.742573ms)","trace[107922864] 'compare' (duration: 83.20472ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:21:35.046529Z","caller":"traceutil/trace.go:171","msg":"trace[1339017964] transaction","detail":"{read_only:false; response_revision:13153; number_of_response:1; }","duration":"148.381022ms","start":"2026-07-30T21:21:34.898114Z","end":"2026-07-30T21:21:35.046495Z","steps":["trace[1339017964] 'process raft request' (duration: 119.838905ms)","trace[1339017964] 'compare' (duration: 28.398265ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:21:36.518475Z","caller":"traceutil/trace.go:171","msg":"trace[1316550765] linearizableReadLoop","detail":"{readStateIndex:13574; appliedIndex:13573; }","duration":"136.667532ms","start":"2026-07-30T21:21:36.381781Z","end":"2026-07-30T21:21:36.518448Z","steps":["trace[1316550765] 'read index received' (duration: 136.423498ms)","trace[1316550765] 'applied index is now lower than readState.Index' (duration: 242.904µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:21:36.518744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.920237ms","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-07-30T21:21:36.518743Z","caller":"traceutil/trace.go:171","msg":"trace[1848986299] transaction","detail":"{read_only:false; response_revision:13159; number_of_response:1; }","duration":"142.317739ms","start":"2026-07-30T21:21:36.376366Z","end":"2026-07-30T21:21:36.518684Z","steps":["trace[1848986299] 'process raft request' (duration: 141.916731ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:21:36.518831Z","caller":"traceutil/trace.go:171","msg":"trace[467603835] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:13159; }","duration":"137.0574ms","start":"2026-07-30T21:21:36.381751Z","end":"2026-07-30T21:21:36.518808Z","steps":["trace[467603835] 'agreement among raft nodes before linearized reading' (duration: 136.890387ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:25:13.670027Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12277} {"level":"info","ts":"2026-07-30T21:25:13.75067Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12277,"took":"78.685701ms","hash":2106445449,"current-db-size-bytes":35864576,"current-db-size":"36 MB","current-db-size-in-use-bytes":14278656,"current-db-size-in-use":"14 MB"} {"level":"info","ts":"2026-07-30T21:25:13.750749Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2106445449,"revision":12277,"compact-revision":9672} {"level":"warn","ts":"2026-07-30T21:29:14.131005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.639612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-07-30T21:29:14.1311Z","caller":"traceutil/trace.go:171","msg":"trace[147891659] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:16633; }","duration":"101.781725ms","start":"2026-07-30T21:29:14.029298Z","end":"2026-07-30T21:29:14.131079Z","steps":["trace[147891659] 'range keys from in-memory index tree' (duration: 101.446979ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:29:21.302252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.140586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T21:29:21.30233Z","caller":"traceutil/trace.go:171","msg":"trace[1713359801] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:16684; }","duration":"141.25694ms","start":"2026-07-30T21:29:21.161055Z","end":"2026-07-30T21:29:21.302312Z","steps":["trace[1713359801] 'count revisions from in-memory index tree' (duration: 141.056515ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:29:21.584722Z","caller":"traceutil/trace.go:171","msg":"trace[1088059698] linearizableReadLoop","detail":"{readStateIndex:17207; appliedIndex:17206; }","duration":"206.987605ms","start":"2026-07-30T21:29:21.377707Z","end":"2026-07-30T21:29:21.584694Z","steps":["trace[1088059698] 'read index received' (duration: 206.794392ms)","trace[1088059698] 'applied index is now lower than readState.Index' (duration: 191.633µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:29:21.584822Z","caller":"traceutil/trace.go:171","msg":"trace[908286095] transaction","detail":"{read_only:false; response_revision:16685; number_of_response:1; }","duration":"246.887547ms","start":"2026-07-30T21:29:21.337906Z","end":"2026-07-30T21:29:21.584793Z","steps":["trace[908286095] 'process raft request' (duration: 246.587282ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:29:21.58497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.377903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T21:29:21.585036Z","caller":"traceutil/trace.go:171","msg":"trace[599650580] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-endpoints; range_end:; response_count:0; response_revision:16685; }","duration":"195.521225ms","start":"2026-07-30T21:29:21.389505Z","end":"2026-07-30T21:29:21.585026Z","steps":["trace[599650580] 'agreement among raft nodes before linearized reading' (duration: 195.399553ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:29:21.585133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.399513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-p2cgj\" ","response":"range_response_count:1 size:9710"} {"level":"warn","ts":"2026-07-30T21:29:21.58518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.166939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-db-sync\" ","response":"range_response_count:1 size:5234"} {"level":"info","ts":"2026-07-30T21:29:21.585256Z","caller":"traceutil/trace.go:171","msg":"trace[992010177] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-p2cgj; range_end:; response_count:1; response_revision:16685; }","duration":"207.575737ms","start":"2026-07-30T21:29:21.37765Z","end":"2026-07-30T21:29:21.585226Z","steps":["trace[992010177] 'agreement among raft nodes before linearized reading' (duration: 207.23028ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:29:21.585275Z","caller":"traceutil/trace.go:171","msg":"trace[329514675] range","detail":"{range_begin:/registry/jobs/openstack/neutron-db-sync; range_end:; response_count:1; response_revision:16685; }","duration":"151.309871ms","start":"2026-07-30T21:29:21.433939Z","end":"2026-07-30T21:29:21.585249Z","steps":["trace[329514675] 'agreement among raft nodes before linearized reading' (duration: 151.025454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:29:21.58538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.504376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T21:29:21.585471Z","caller":"traceutil/trace.go:171","msg":"trace[1840507662] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:16685; }","duration":"195.622888ms","start":"2026-07-30T21:29:21.38983Z","end":"2026-07-30T21:29:21.585453Z","steps":["trace[1840507662] 'agreement among raft nodes before linearized reading' (duration: 195.496406ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:29:21.585368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.551187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T21:29:21.58561Z","caller":"traceutil/trace.go:171","msg":"trace[972282737] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:0; response_revision:16685; }","duration":"195.824532ms","start":"2026-07-30T21:29:21.389753Z","end":"2026-07-30T21:29:21.585577Z","steps":["trace[972282737] 'agreement among raft nodes before linearized reading' (duration: 195.507916ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:29:55.849946Z","caller":"traceutil/trace.go:171","msg":"trace[1659159409] transaction","detail":"{read_only:false; response_revision:16973; number_of_response:1; }","duration":"110.98349ms","start":"2026-07-30T21:29:55.738933Z","end":"2026-07-30T21:29:55.849917Z","steps":["trace[1659159409] 'process raft request' (duration: 110.790416ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:30:13.677081Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14609} {"level":"info","ts":"2026-07-30T21:30:13.749399Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14609,"took":"70.214212ms","hash":159196053,"current-db-size-bytes":35864576,"current-db-size":"36 MB","current-db-size-in-use-bytes":16998400,"current-db-size-in-use":"17 MB"} {"level":"info","ts":"2026-07-30T21:30:13.74948Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":159196053,"revision":14609,"compact-revision":12277} {"level":"info","ts":"2026-07-30T21:35:13.68546Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17090} {"level":"info","ts":"2026-07-30T21:35:13.758938Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17090,"took":"71.568824ms","hash":3868046318,"current-db-size-bytes":35864576,"current-db-size":"36 MB","current-db-size-in-use-bytes":16273408,"current-db-size-in-use":"16 MB"} {"level":"info","ts":"2026-07-30T21:35:13.759Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3868046318,"revision":17090,"compact-revision":14609} {"level":"info","ts":"2026-07-30T21:35:33.721443Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3e4829a3cabe4fce","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-30T21:35:33.725536Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-30T21:35:33.725656Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-30T21:40:13.69139Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19248} {"level":"info","ts":"2026-07-30T21:40:13.760455Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19248,"took":"66.926703ms","hash":3201867839,"current-db-size-bytes":35864576,"current-db-size":"36 MB","current-db-size-in-use-bytes":14172160,"current-db-size-in-use":"14 MB"} {"level":"info","ts":"2026-07-30T21:40:13.760526Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3201867839,"revision":19248,"compact-revision":17090} {"level":"info","ts":"2026-07-30T21:45:13.699319Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21177} {"level":"info","ts":"2026-07-30T21:45:13.775216Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21177,"took":"74.146574ms","hash":1167298518,"current-db-size-bytes":35864576,"current-db-size":"36 MB","current-db-size-in-use-bytes":14131200,"current-db-size-in-use":"14 MB"} {"level":"info","ts":"2026-07-30T21:45:13.775266Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1167298518,"revision":21177,"compact-revision":19248}