{"level":"warn","ts":"2026-03-25T21:04:34.951503Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-25T21:04:34.951611Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.204: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.204:2380","--initial-cluster=instance=https://162.253.55.204:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.204:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.204: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-03-25T21:04:34.951727Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-25T21:04:34.951746Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-03-25T21:04:34.951769Z","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-03-25T21:04:34.95266Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"]} {"level":"info","ts":"2026-03-25T21:04:34.95279Z","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.204:2380"],"listen-peer-urls":["https://162.253.55.204:2380"],"advertise-client-urls":["https://162.253.55.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.204: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-03-25T21:04:34.956426Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.322317ms"} {"level":"info","ts":"2026-03-25T21:04:34.967162Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a11c20306510c1f7","cluster-id":"97da89d0cbebd2b5"} {"level":"info","ts":"2026-03-25T21:04:34.967278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=()"} {"level":"info","ts":"2026-03-25T21:04:34.967308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 0"} {"level":"info","ts":"2026-03-25T21:04:34.967337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a11c20306510c1f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-25T21:04:34.967354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 1"} {"level":"info","ts":"2026-03-25T21:04:34.967412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"warn","ts":"2026-03-25T21:04:34.980014Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-25T21:04:34.981726Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-25T21:04:34.98313Z","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-03-25T21:04:34.985838Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a11c20306510c1f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-25T21:04:34.986272Z","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-03-25T21:04:34.986275Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a11c20306510c1f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-25T21:04:34.986436Z","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-03-25T21:04:34.986533Z","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-03-25T21:04:34.988851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"info","ts":"2026-03-25T21:04:34.989806Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"97da89d0cbebd2b5","local-member-id":"a11c20306510c1f7","added-peer-id":"a11c20306510c1f7","added-peer-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-03-25T21:04:34.991118Z","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-03-25T21:04:34.991244Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-03-25T21:04:34.99129Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-03-25T21:04:34.991466Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a11c20306510c1f7","initial-advertise-peer-urls":["https://162.253.55.204:2380"],"listen-peer-urls":["https://162.253.55.204:2380"],"advertise-client-urls":["https://162.253.55.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-25T21:04:34.991501Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-25T21:04:35.267861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 is starting a new election at term 1"} {"level":"info","ts":"2026-03-25T21:04:35.267928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-25T21:04:35.267967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgPreVoteResp from a11c20306510c1f7 at term 1"} {"level":"info","ts":"2026-03-25T21:04:35.267988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became candidate at term 2"} {"level":"info","ts":"2026-03-25T21:04:35.267999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgVoteResp from a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-03-25T21:04:35.26802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became leader at term 2"} {"level":"info","ts":"2026-03-25T21:04:35.268035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a11c20306510c1f7 elected leader a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-03-25T21:04:35.270308Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a11c20306510c1f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.204:2379]}","request-path":"/0/members/a11c20306510c1f7/attributes","cluster-id":"97da89d0cbebd2b5","publish-timeout":"7s"} {"level":"info","ts":"2026-03-25T21:04:35.270459Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T21:04:35.270523Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T21:04:35.270665Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:04:35.271582Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"97da89d0cbebd2b5","local-member-id":"a11c20306510c1f7","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:04:35.271722Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:04:35.271757Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:04:35.272538Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.204:2379"} {"level":"info","ts":"2026-03-25T21:04:35.27281Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-25T21:04:35.272844Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-25T21:04:35.273303Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-25T21:04:49.748443Z","caller":"traceutil/trace.go:171","msg":"trace[398587371] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"245.024513ms","start":"2026-03-25T21:04:49.503393Z","end":"2026-03-25T21:04:49.748417Z","steps":["trace[398587371] 'process raft request' (duration: 244.833489ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:17.506975Z","caller":"traceutil/trace.go:171","msg":"trace[90965339] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"158.72121ms","start":"2026-03-25T21:05:17.348226Z","end":"2026-03-25T21:05:17.506947Z","steps":["trace[90965339] 'process raft request' (duration: 158.058324ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:18.305002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.695412ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:05:18.305198Z","caller":"traceutil/trace.go:171","msg":"trace[56895607] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:590; }","duration":"219.906248ms","start":"2026-03-25T21:05:18.085272Z","end":"2026-03-25T21:05:18.305178Z","steps":["trace[56895607] 'range keys from in-memory index tree' (duration: 219.677972ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:18.305466Z","caller":"traceutil/trace.go:171","msg":"trace[569549139] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"265.38102ms","start":"2026-03-25T21:05:18.040062Z","end":"2026-03-25T21:05:18.305443Z","steps":["trace[569549139] 'process raft request' (duration: 258.098328ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:18.305526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.729713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-25T21:05:18.305645Z","caller":"traceutil/trace.go:171","msg":"trace[1649415081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:590; }","duration":"377.189894ms","start":"2026-03-25T21:05:17.928423Z","end":"2026-03-25T21:05:18.305613Z","steps":["trace[1649415081] 'range keys from in-memory index tree' (duration: 376.539269ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:18.305699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:17.928397Z","time spent":"377.288787ms","remote":"127.0.0.1:39986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-25T21:06:46.167904Z","caller":"traceutil/trace.go:171","msg":"trace[1274546098] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"114.710212ms","start":"2026-03-25T21:06:46.053148Z","end":"2026-03-25T21:06:46.167858Z","steps":["trace[1274546098] 'process raft request' (duration: 44.754158ms)","trace[1274546098] 'compare' (duration: 69.720279ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:06:46.481668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.940596ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:06:46.481805Z","caller":"traceutil/trace.go:171","msg":"trace[1151930715] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"306.928388ms","start":"2026-03-25T21:06:46.174858Z","end":"2026-03-25T21:06:46.481786Z","steps":["trace[1151930715] 'process raft request' (duration: 150.264644ms)","trace[1151930715] 'compare' (duration: 155.565978ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:06:46.481867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:06:46.174828Z","time spent":"307.009259ms","remote":"127.0.0.1:39888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:06:46.498546Z","caller":"traceutil/trace.go:171","msg":"trace[1077903284] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"323.235503ms","start":"2026-03-25T21:06:46.175282Z","end":"2026-03-25T21:06:46.498517Z","steps":["trace[1077903284] 'process raft request' (duration: 323.144691ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:06:46.498571Z","caller":"traceutil/trace.go:171","msg":"trace[1909111276] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"323.392586ms","start":"2026-03-25T21:06:46.175141Z","end":"2026-03-25T21:06:46.498533Z","steps":["trace[1909111276] 'process raft request' (duration: 323.189272ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:06:46.498715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:06:46.175249Z","time spent":"323.384866ms","remote":"127.0.0.1:39998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1471,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:06:46.498804Z","caller":"traceutil/trace.go:171","msg":"trace[560500157] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"323.456999ms","start":"2026-03-25T21:06:46.175324Z","end":"2026-03-25T21:06:46.498781Z","steps":["trace[560500157] 'process raft request' (duration: 323.146021ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:06:46.498815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:06:46.175127Z","time spent":"323.593931ms","remote":"127.0.0.1:39998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1529,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:06:46.498907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:06:46.175306Z","time spent":"323.551451ms","remote":"127.0.0.1:39888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":977,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:06:46.499204Z","caller":"traceutil/trace.go:171","msg":"trace[1442443944] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"319.616217ms","start":"2026-03-25T21:06:46.179558Z","end":"2026-03-25T21:06:46.499174Z","steps":["trace[1442443944] 'process raft request' (duration: 319.108594ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:06:46.499215Z","caller":"traceutil/trace.go:171","msg":"trace[1578146494] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"224.085929ms","start":"2026-03-25T21:06:46.275102Z","end":"2026-03-25T21:06:46.499188Z","steps":["trace[1578146494] 'process raft request' (duration: 223.920705ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:06:46.499434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:06:46.179539Z","time spent":"319.761731ms","remote":"127.0.0.1:39910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:06:46.929738Z","caller":"traceutil/trace.go:171","msg":"trace[959108531] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"184.40383ms","start":"2026-03-25T21:06:46.745303Z","end":"2026-03-25T21:06:46.929707Z","steps":["trace[959108531] 'process raft request' (duration: 184.204765ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:06:58.674245Z","caller":"traceutil/trace.go:171","msg":"trace[456091038] linearizableReadLoop","detail":"{readStateIndex:1984; appliedIndex:1983; }","duration":"110.180686ms","start":"2026-03-25T21:06:58.56404Z","end":"2026-03-25T21:06:58.67422Z","steps":["trace[456091038] 'read index received' (duration: 109.93188ms)","trace[456091038] 'applied index is now lower than readState.Index' (duration: 247.666µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:06:58.67435Z","caller":"traceutil/trace.go:171","msg":"trace[1453078576] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"136.039477ms","start":"2026-03-25T21:06:58.538279Z","end":"2026-03-25T21:06:58.674318Z","steps":["trace[1453078576] 'process raft request' (duration: 135.757401ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:06:58.674481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.431332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.204\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-25T21:06:58.674545Z","caller":"traceutil/trace.go:171","msg":"trace[1201854852] range","detail":"{range_begin:/registry/masterleases/162.253.55.204; range_end:; response_count:1; response_revision:1911; }","duration":"110.512763ms","start":"2026-03-25T21:06:58.564018Z","end":"2026-03-25T21:06:58.674531Z","steps":["trace[1201854852] 'agreement among raft nodes before linearized reading' (duration: 110.305339ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:31.829784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.789384ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:07:31.830077Z","caller":"traceutil/trace.go:171","msg":"trace[1158322512] linearizableReadLoop","detail":"{readStateIndex:2185; appliedIndex:2183; }","duration":"224.354581ms","start":"2026-03-25T21:07:31.605699Z","end":"2026-03-25T21:07:31.830054Z","steps":["trace[1158322512] 'read index received' (duration: 122.840327ms)","trace[1158322512] 'applied index is now lower than readState.Index' (duration: 101.512804ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:07:31.830209Z","caller":"traceutil/trace.go:171","msg":"trace[2020766200] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"365.241243ms","start":"2026-03-25T21:07:31.464953Z","end":"2026-03-25T21:07:31.830194Z","steps":["trace[2020766200] 'process raft request' (duration: 365.019468ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:31.830302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:31.464932Z","time spent":"365.323305ms","remote":"127.0.0.1:39986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:07:31.830321Z","caller":"traceutil/trace.go:171","msg":"trace[1434314551] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"365.382916ms","start":"2026-03-25T21:07:31.464905Z","end":"2026-03-25T21:07:31.830288Z","steps":["trace[1434314551] 'process raft request' (duration: 127.986198ms)","trace[1434314551] 'compare' (duration: 236.307483ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:07:31.830353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.624408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-25T21:07:31.830426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:31.464883Z","time spent":"365.488109ms","remote":"127.0.0.1:39986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:07:31.830457Z","caller":"traceutil/trace.go:171","msg":"trace[1255648094] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:2105; }","duration":"224.77302ms","start":"2026-03-25T21:07:31.605665Z","end":"2026-03-25T21:07:31.830438Z","steps":["trace[1255648094] 'agreement among raft nodes before linearized reading' (duration: 224.588356ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:31.830408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.53431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:07:31.830583Z","caller":"traceutil/trace.go:171","msg":"trace[1249467482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2105; }","duration":"206.794626ms","start":"2026-03-25T21:07:31.623769Z","end":"2026-03-25T21:07:31.830563Z","steps":["trace[1249467482] 'agreement among raft nodes before linearized reading' (duration: 206.459489ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:31.998034Z","caller":"traceutil/trace.go:171","msg":"trace[436306013] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"123.029481ms","start":"2026-03-25T21:07:31.87497Z","end":"2026-03-25T21:07:31.998Z","steps":["trace[436306013] 'process raft request' (duration: 95.753209ms)","trace[436306013] 'compare' (duration: 27.005006ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:08:54.53828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.020847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:08:54.5384Z","caller":"traceutil/trace.go:171","msg":"trace[48561987] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:2602; }","duration":"184.208172ms","start":"2026-03-25T21:08:54.354165Z","end":"2026-03-25T21:08:54.538373Z","steps":["trace[48561987] 'count revisions from in-memory index tree' (duration: 183.909165ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:54.538688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.167489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-25T21:08:54.538792Z","caller":"traceutil/trace.go:171","msg":"trace[1359508352] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2602; }","duration":"134.349453ms","start":"2026-03-25T21:08:54.404422Z","end":"2026-03-25T21:08:54.538771Z","steps":["trace[1359508352] 'range keys from in-memory index tree' (duration: 133.922103ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:54.538672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.764529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-03-25T21:08:54.538952Z","caller":"traceutil/trace.go:171","msg":"trace[1806944446] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2602; }","duration":"124.075326ms","start":"2026-03-25T21:08:54.414812Z","end":"2026-03-25T21:08:54.538887Z","steps":["trace[1806944446] 'range keys from in-memory index tree' (duration: 123.157843ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:08:54.740738Z","caller":"traceutil/trace.go:171","msg":"trace[546201450] transaction","detail":"{read_only:false; response_revision:2604; number_of_response:1; }","duration":"197.591325ms","start":"2026-03-25T21:08:54.543115Z","end":"2026-03-25T21:08:54.740707Z","steps":["trace[546201450] 'process raft request' (duration: 123.662945ms)","trace[546201450] 'compare' (duration: 73.730966ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:08:54.740878Z","caller":"traceutil/trace.go:171","msg":"trace[688906498] transaction","detail":"{read_only:false; response_revision:2605; number_of_response:1; }","duration":"197.317388ms","start":"2026-03-25T21:08:54.543518Z","end":"2026-03-25T21:08:54.740835Z","steps":["trace[688906498] 'process raft request' (duration: 197.143804ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:08:54.741057Z","caller":"traceutil/trace.go:171","msg":"trace[228907338] linearizableReadLoop","detail":"{readStateIndex:2704; appliedIndex:2702; }","duration":"152.246444ms","start":"2026-03-25T21:08:54.588764Z","end":"2026-03-25T21:08:54.741011Z","steps":["trace[228907338] 'read index received' (duration: 77.970946ms)","trace[228907338] 'applied index is now lower than readState.Index' (duration: 74.263758ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:08:54.741372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.553531ms","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-03-25T21:08:54.741491Z","caller":"traceutil/trace.go:171","msg":"trace[241552972] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2605; }","duration":"152.765426ms","start":"2026-03-25T21:08:54.588701Z","end":"2026-03-25T21:08:54.741466Z","steps":["trace[241552972] 'agreement among raft nodes before linearized reading' (duration: 152.379917ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:54.741564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.356804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:08:54.741622Z","caller":"traceutil/trace.go:171","msg":"trace[285896793] transaction","detail":"{read_only:false; response_revision:2606; number_of_response:1; }","duration":"139.777344ms","start":"2026-03-25T21:08:54.601834Z","end":"2026-03-25T21:08:54.741611Z","steps":["trace[285896793] 'process raft request' (duration: 139.522328ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:08:54.741632Z","caller":"traceutil/trace.go:171","msg":"trace[585792942] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2606; }","duration":"117.475267ms","start":"2026-03-25T21:08:54.62414Z","end":"2026-03-25T21:08:54.741615Z","steps":["trace[585792942] 'agreement among raft nodes before linearized reading' (duration: 117.331843ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:11:34.508253Z","caller":"traceutil/trace.go:171","msg":"trace[1068086157] transaction","detail":"{read_only:false; response_revision:3632; number_of_response:1; }","duration":"107.114626ms","start":"2026-03-25T21:11:34.401118Z","end":"2026-03-25T21:11:34.508233Z","steps":["trace[1068086157] 'process raft request' (duration: 106.979302ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:11:34.599134Z","caller":"traceutil/trace.go:171","msg":"trace[1276845857] linearizableReadLoop","detail":"{readStateIndex:3771; appliedIndex:3770; }","duration":"132.318151ms","start":"2026-03-25T21:11:34.466784Z","end":"2026-03-25T21:11:34.599103Z","steps":["trace[1276845857] 'read index received' (duration: 41.543093ms)","trace[1276845857] 'applied index is now lower than readState.Index' (duration: 90.773808ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:11:34.599299Z","caller":"traceutil/trace.go:171","msg":"trace[1373125439] transaction","detail":"{read_only:false; response_revision:3633; number_of_response:1; }","duration":"166.571491ms","start":"2026-03-25T21:11:34.432686Z","end":"2026-03-25T21:11:34.599257Z","steps":["trace[1373125439] 'process raft request' (duration: 166.186972ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:11:34.599423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.618188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-03-25T21:11:34.599475Z","caller":"traceutil/trace.go:171","msg":"trace[478456009] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3633; }","duration":"132.728601ms","start":"2026-03-25T21:11:34.466731Z","end":"2026-03-25T21:11:34.59946Z","steps":["trace[478456009] 'agreement among raft nodes before linearized reading' (duration: 132.497656ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:12:51.582712Z","caller":"traceutil/trace.go:171","msg":"trace[1009821238] transaction","detail":"{read_only:false; response_revision:4572; number_of_response:1; }","duration":"151.93376ms","start":"2026-03-25T21:12:51.430723Z","end":"2026-03-25T21:12:51.582657Z","steps":["trace[1009821238] 'process raft request' (duration: 151.705294ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:12:51.582682Z","caller":"traceutil/trace.go:171","msg":"trace[695280483] transaction","detail":"{read_only:false; response_revision:4571; number_of_response:1; }","duration":"152.629016ms","start":"2026-03-25T21:12:51.429984Z","end":"2026-03-25T21:12:51.582613Z","steps":["trace[695280483] 'process raft request' (duration: 124.98335ms)","trace[695280483] 'compare' (duration: 27.298527ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:12:51.582752Z","caller":"traceutil/trace.go:171","msg":"trace[1404026952] transaction","detail":"{read_only:false; response_revision:4573; number_of_response:1; }","duration":"120.553883ms","start":"2026-03-25T21:12:51.462152Z","end":"2026-03-25T21:12:51.582706Z","steps":["trace[1404026952] 'process raft request' (duration: 120.323398ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:12:56.001001Z","caller":"traceutil/trace.go:171","msg":"trace[440866003] transaction","detail":"{read_only:false; response_revision:4758; number_of_response:1; }","duration":"130.727419ms","start":"2026-03-25T21:12:55.870249Z","end":"2026-03-25T21:12:56.000976Z","steps":["trace[440866003] 'process raft request' (duration: 130.550365ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:12:56.03548Z","caller":"traceutil/trace.go:171","msg":"trace[188263502] transaction","detail":"{read_only:false; response_revision:4759; number_of_response:1; }","duration":"164.315077ms","start":"2026-03-25T21:12:55.871114Z","end":"2026-03-25T21:12:56.035429Z","steps":["trace[188263502] 'process raft request' (duration: 164.047041ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:12:56.035768Z","caller":"traceutil/trace.go:171","msg":"trace[214968904] linearizableReadLoop","detail":"{readStateIndex:4940; appliedIndex:4938; }","duration":"109.364184ms","start":"2026-03-25T21:12:55.926354Z","end":"2026-03-25T21:12:56.035718Z","steps":["trace[214968904] 'read index received' (duration: 74.507815ms)","trace[214968904] 'applied index is now lower than readState.Index' (duration: 34.852599ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:12:56.036032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.63712ms","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-03-25T21:12:56.036149Z","caller":"traceutil/trace.go:171","msg":"trace[534713660] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4761; }","duration":"109.839816ms","start":"2026-03-25T21:12:55.926279Z","end":"2026-03-25T21:12:56.036119Z","steps":["trace[534713660] 'agreement among raft nodes before linearized reading' (duration: 109.582589ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:09.214178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.843283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-25T21:13:09.214284Z","caller":"traceutil/trace.go:171","msg":"trace[2087451528] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:5010; }","duration":"107.053708ms","start":"2026-03-25T21:13:09.107211Z","end":"2026-03-25T21:13:09.214265Z","steps":["trace[2087451528] 'count revisions from in-memory index tree' (duration: 106.71435ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:29.211363Z","caller":"traceutil/trace.go:171","msg":"trace[1353442334] transaction","detail":"{read_only:false; response_revision:5324; number_of_response:1; }","duration":"184.611266ms","start":"2026-03-25T21:13:29.026718Z","end":"2026-03-25T21:13:29.211329Z","steps":["trace[1353442334] 'process raft request' (duration: 184.396481ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:14:35.517358Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2818} {"level":"info","ts":"2026-03-25T21:14:35.590112Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2818,"took":"71.133602ms","hash":874602519,"current-db-size-bytes":58454016,"current-db-size":"58 MB","current-db-size-in-use-bytes":33202176,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-03-25T21:14:35.590177Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":874602519,"revision":2818,"compact-revision":-1} {"level":"info","ts":"2026-03-25T21:19:18.580852Z","caller":"traceutil/trace.go:171","msg":"trace[721409029] linearizableReadLoop","detail":"{readStateIndex:8273; appliedIndex:8271; }","duration":"276.464001ms","start":"2026-03-25T21:19:18.304359Z","end":"2026-03-25T21:19:18.580823Z","steps":["trace[721409029] 'read index received' (duration: 69.817472ms)","trace[721409029] 'applied index is now lower than readState.Index' (duration: 206.645409ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:19:18.581105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.727118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-03-25T21:19:18.58115Z","caller":"traceutil/trace.go:171","msg":"trace[439992641] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7992; }","duration":"276.833661ms","start":"2026-03-25T21:19:18.304303Z","end":"2026-03-25T21:19:18.581137Z","steps":["trace[439992641] 'agreement among raft nodes before linearized reading' (duration: 276.630456ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:19:18.581183Z","caller":"traceutil/trace.go:171","msg":"trace[343987920] transaction","detail":"{read_only:false; response_revision:7992; number_of_response:1; }","duration":"285.318743ms","start":"2026-03-25T21:19:18.295831Z","end":"2026-03-25T21:19:18.581149Z","steps":["trace[343987920] 'process raft request' (duration: 271.863714ms)","trace[343987920] 'compare' (duration: 12.904016ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:19:19.430152Z","caller":"traceutil/trace.go:171","msg":"trace[886343769] linearizableReadLoop","detail":"{readStateIndex:8278; appliedIndex:8277; }","duration":"173.88665ms","start":"2026-03-25T21:19:19.256243Z","end":"2026-03-25T21:19:19.43013Z","steps":["trace[886343769] 'read index received' (duration: 173.608174ms)","trace[886343769] 'applied index is now lower than readState.Index' (duration: 277.486µs)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:19:19.430428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.162868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-25T21:19:19.430234Z","caller":"traceutil/trace.go:171","msg":"trace[1857641405] transaction","detail":"{read_only:false; response_revision:7996; number_of_response:1; }","duration":"253.598439ms","start":"2026-03-25T21:19:19.176603Z","end":"2026-03-25T21:19:19.430202Z","steps":["trace[1857641405] 'process raft request' (duration: 253.371443ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:19:19.430476Z","caller":"traceutil/trace.go:171","msg":"trace[288791643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7996; }","duration":"174.26188ms","start":"2026-03-25T21:19:19.256199Z","end":"2026-03-25T21:19:19.430461Z","steps":["trace[288791643] 'agreement among raft nodes before linearized reading' (duration: 174.018915ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:19:20.482391Z","caller":"traceutil/trace.go:171","msg":"trace[2022595178] transaction","detail":"{read_only:false; response_revision:8003; number_of_response:1; }","duration":"101.376883ms","start":"2026-03-25T21:19:20.38097Z","end":"2026-03-25T21:19:20.482347Z","steps":["trace[2022595178] 'process raft request' (duration: 101.134858ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:19:35.523158Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5919} {"level":"info","ts":"2026-03-25T21:19:35.601135Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5919,"took":"76.608944ms","hash":1031390940,"current-db-size-bytes":58454016,"current-db-size":"58 MB","current-db-size-in-use-bytes":26144768,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-25T21:19:35.601231Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1031390940,"revision":5919,"compact-revision":2818} {"level":"info","ts":"2026-03-25T21:19:46.871302Z","caller":"traceutil/trace.go:171","msg":"trace[578858001] transaction","detail":"{read_only:false; response_revision:8200; number_of_response:1; }","duration":"136.064439ms","start":"2026-03-25T21:19:46.735212Z","end":"2026-03-25T21:19:46.871276Z","steps":["trace[578858001] 'process raft request' (duration: 136.015388ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:19:46.871394Z","caller":"traceutil/trace.go:171","msg":"trace[26491855] transaction","detail":"{read_only:false; response_revision:8199; number_of_response:1; }","duration":"140.355912ms","start":"2026-03-25T21:19:46.730997Z","end":"2026-03-25T21:19:46.871353Z","steps":["trace[26491855] 'process raft request' (duration: 129.859352ms)","trace[26491855] 'compare' (duration: 10.259295ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:19:59.973759Z","caller":"traceutil/trace.go:171","msg":"trace[1887183000] transaction","detail":"{read_only:false; response_revision:8310; number_of_response:1; }","duration":"101.408575ms","start":"2026-03-25T21:19:59.872296Z","end":"2026-03-25T21:19:59.973705Z","steps":["trace[1887183000] 'process raft request' (duration: 85.724482ms)","trace[1887183000] 'compare' (duration: 15.490088ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:20:00.816057Z","caller":"traceutil/trace.go:171","msg":"trace[405842502] linearizableReadLoop","detail":"{readStateIndex:8609; appliedIndex:8608; }","duration":"235.61683ms","start":"2026-03-25T21:20:00.580417Z","end":"2026-03-25T21:20:00.816033Z","steps":["trace[405842502] 'read index received' (duration: 235.406335ms)","trace[405842502] 'applied index is now lower than readState.Index' (duration: 209.675µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:20:00.816193Z","caller":"traceutil/trace.go:171","msg":"trace[1739705000] transaction","detail":"{read_only:false; response_revision:8314; number_of_response:1; }","duration":"235.998739ms","start":"2026-03-25T21:20:00.580141Z","end":"2026-03-25T21:20:00.81614Z","steps":["trace[1739705000] 'process raft request' (duration: 235.735053ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:20:00.816314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.877056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-4hkbz\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-03-25T21:20:00.816356Z","caller":"traceutil/trace.go:171","msg":"trace[1375332926] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-4hkbz; range_end:; response_count:1; response_revision:8314; }","duration":"235.996099ms","start":"2026-03-25T21:20:00.58035Z","end":"2026-03-25T21:20:00.816346Z","steps":["trace[1375332926] 'agreement among raft nodes before linearized reading' (duration: 235.808035ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:20:00.816378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.072937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:20:00.816479Z","caller":"traceutil/trace.go:171","msg":"trace[1437080284] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8314; }","duration":"193.218211ms","start":"2026-03-25T21:20:00.623235Z","end":"2026-03-25T21:20:00.816453Z","steps":["trace[1437080284] 'agreement among raft nodes before linearized reading' (duration: 193.039066ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:20:02.293453Z","caller":"traceutil/trace.go:171","msg":"trace[367376843] transaction","detail":"{read_only:false; response_revision:8329; number_of_response:1; }","duration":"126.481571ms","start":"2026-03-25T21:20:02.166938Z","end":"2026-03-25T21:20:02.293419Z","steps":["trace[367376843] 'process raft request' (duration: 126.264246ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:20:53.077425Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023c5.wal"} {"level":"info","ts":"2026-03-25T21:22:34.236027Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a11c20306510c1f7","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-25T21:22:34.240818Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-25T21:22:34.240899Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-25T21:24:35.529709Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8089} {"level":"info","ts":"2026-03-25T21:24:35.594571Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8089,"took":"62.918838ms","hash":2383317428,"current-db-size-bytes":58454016,"current-db-size":"58 MB","current-db-size-in-use-bytes":29429760,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-03-25T21:24:35.594659Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2383317428,"revision":8089,"compact-revision":5919} {"level":"info","ts":"2026-03-25T21:29:35.535788Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10525} {"level":"info","ts":"2026-03-25T21:29:35.617389Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10525,"took":"78.259692ms","hash":1346760834,"current-db-size-bytes":58454016,"current-db-size":"58 MB","current-db-size-in-use-bytes":20017152,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-03-25T21:29:35.617446Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1346760834,"revision":10525,"compact-revision":8089}