{"level":"warn","ts":"2026-10-15T18:58:05.438424Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T18:58:05.438529Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.6: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.6:2380","--initial-cluster=instance=https://162.253.55.6:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.6:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.6: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-10-15T18:58:05.438617Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T18:58:05.438638Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.6:2380"]} {"level":"info","ts":"2026-10-15T18:58:05.438674Z","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-10-15T18:58:05.439565Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.6:2379"]} {"level":"info","ts":"2026-10-15T18:58:05.439692Z","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.6:2380"],"listen-peer-urls":["https://162.253.55.6:2380"],"advertise-client-urls":["https://162.253.55.6:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.6:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.6: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-10-15T18:58:05.444343Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.288831ms"} {"level":"info","ts":"2026-10-15T18:58:05.454641Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e4145bf1b1a19cd","cluster-id":"e02e9056d18cef61"} {"level":"info","ts":"2026-10-15T18:58:05.454724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd switched to configuration voters=()"} {"level":"info","ts":"2026-10-15T18:58:05.454748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd became follower at term 0"} {"level":"info","ts":"2026-10-15T18:58:05.454767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e4145bf1b1a19cd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-15T18:58:05.454775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd became follower at term 1"} {"level":"info","ts":"2026-10-15T18:58:05.454815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd switched to configuration voters=(5638864895540337101)"} {"level":"warn","ts":"2026-10-15T18:58:05.461996Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-15T18:58:05.465675Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-15T18:58:05.468459Z","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-10-15T18:58:05.471822Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e4145bf1b1a19cd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-15T18:58:05.473336Z","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-10-15T18:58:05.473438Z","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-10-15T18:58:05.473489Z","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-10-15T18:58:05.473383Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e4145bf1b1a19cd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-15T18:58:05.473987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd switched to configuration voters=(5638864895540337101)"} {"level":"info","ts":"2026-10-15T18:58:05.474174Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e02e9056d18cef61","local-member-id":"4e4145bf1b1a19cd","added-peer-id":"4e4145bf1b1a19cd","added-peer-peer-urls":["https://162.253.55.6:2380"]} {"level":"info","ts":"2026-10-15T18:58:05.476475Z","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-10-15T18:58:05.476718Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.6:2380"} {"level":"info","ts":"2026-10-15T18:58:05.47678Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.6:2380"} {"level":"info","ts":"2026-10-15T18:58:05.476804Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e4145bf1b1a19cd","initial-advertise-peer-urls":["https://162.253.55.6:2380"],"listen-peer-urls":["https://162.253.55.6:2380"],"advertise-client-urls":["https://162.253.55.6:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.6:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-15T18:58:05.476849Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-15T18:58:05.55615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd is starting a new election at term 1"} {"level":"info","ts":"2026-10-15T18:58:05.556397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd became pre-candidate at term 1"} {"level":"info","ts":"2026-10-15T18:58:05.556459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd received MsgPreVoteResp from 4e4145bf1b1a19cd at term 1"} {"level":"info","ts":"2026-10-15T18:58:05.556488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd became candidate at term 2"} {"level":"info","ts":"2026-10-15T18:58:05.556543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd received MsgVoteResp from 4e4145bf1b1a19cd at term 2"} {"level":"info","ts":"2026-10-15T18:58:05.556568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd became leader at term 2"} {"level":"info","ts":"2026-10-15T18:58:05.556582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e4145bf1b1a19cd elected leader 4e4145bf1b1a19cd at term 2"} {"level":"info","ts":"2026-10-15T18:58:05.557784Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T18:58:05.558777Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T18:58:05.558774Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e4145bf1b1a19cd","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.6:2379]}","request-path":"/0/members/4e4145bf1b1a19cd/attributes","cluster-id":"e02e9056d18cef61","publish-timeout":"7s"} {"level":"info","ts":"2026-10-15T18:58:05.558816Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T18:58:05.559126Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-15T18:58:05.55928Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-15T18:58:05.559548Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e02e9056d18cef61","local-member-id":"4e4145bf1b1a19cd","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T18:58:05.559754Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T18:58:05.559806Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T18:58:05.562342Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.6:2379"} {"level":"info","ts":"2026-10-15T18:58:05.563233Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-15T18:58:58.218144Z","caller":"traceutil/trace.go:171","msg":"trace[1060200543] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"142.142383ms","start":"2026-10-15T18:58:58.075975Z","end":"2026-10-15T18:58:58.218117Z","steps":["trace[1060200543] 'process raft request' (duration: 67.464103ms)","trace[1060200543] 'compare' (duration: 74.561768ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T18:58:58.353904Z","caller":"traceutil/trace.go:171","msg":"trace[1993087137] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"264.998533ms","start":"2026-10-15T18:58:58.08886Z","end":"2026-10-15T18:58:58.353858Z","steps":["trace[1993087137] 'process raft request' (duration: 246.8317ms)","trace[1993087137] 'compare' (duration: 17.933688ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T18:58:58.354678Z","caller":"traceutil/trace.go:171","msg":"trace[523983461] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:673; }","duration":"253.373293ms","start":"2026-10-15T18:58:58.101281Z","end":"2026-10-15T18:58:58.354654Z","steps":["trace[523983461] 'read index received' (duration: 42.248768ms)","trace[523983461] 'applied index is now lower than readState.Index' (duration: 211.123365ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T18:58:58.354762Z","caller":"traceutil/trace.go:171","msg":"trace[1569806325] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"259.511469ms","start":"2026-10-15T18:58:58.095227Z","end":"2026-10-15T18:58:58.354739Z","steps":["trace[1569806325] 'process raft request' (duration: 258.721745ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:58:58.354867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.575877ms","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-10-15T18:58:58.354952Z","caller":"traceutil/trace.go:171","msg":"trace[443386370] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:653; }","duration":"253.71506ms","start":"2026-10-15T18:58:58.101226Z","end":"2026-10-15T18:58:58.354941Z","steps":["trace[443386370] 'agreement among raft nodes before linearized reading' (duration: 253.523156ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:58:58.81562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.35324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T18:58:58.815836Z","caller":"traceutil/trace.go:171","msg":"trace[1939280613] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"378.428915ms","start":"2026-10-15T18:58:58.43739Z","end":"2026-10-15T18:58:58.815819Z","steps":["trace[1939280613] 'process raft request' (duration: 378.366013ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:58:58.815904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T18:58:58.437364Z","time spent":"378.515657ms","remote":"127.0.0.1:57540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-15T18:58:58.815967Z","caller":"traceutil/trace.go:171","msg":"trace[352674973] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"455.775626ms","start":"2026-10-15T18:58:58.360113Z","end":"2026-10-15T18:58:58.815889Z","steps":["trace[352674973] 'process raft request' (duration: 313.970688ms)","trace[352674973] 'compare' (duration: 140.213027ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T18:58:58.816265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T18:58:58.360091Z","time spent":"456.006799ms","remote":"127.0.0.1:57418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:00:23.464606Z","caller":"traceutil/trace.go:171","msg":"trace[458869383] transaction","detail":"{read_only:false; response_revision:1779; number_of_response:1; }","duration":"153.052754ms","start":"2026-10-15T19:00:23.311513Z","end":"2026-10-15T19:00:23.464566Z","steps":["trace[458869383] 'process raft request' (duration: 100.198185ms)","trace[458869383] 'compare' (duration: 52.692576ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:00:23.49349Z","caller":"traceutil/trace.go:171","msg":"trace[1678591204] linearizableReadLoop","detail":"{readStateIndex:1849; appliedIndex:1847; }","duration":"151.247411ms","start":"2026-10-15T19:00:23.342174Z","end":"2026-10-15T19:00:23.493421Z","steps":["trace[1678591204] 'read index received' (duration: 69.474473ms)","trace[1678591204] 'applied index is now lower than readState.Index' (duration: 81.771328ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:00:23.493668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.461715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:00:23.493724Z","caller":"traceutil/trace.go:171","msg":"trace[439139784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1780; }","duration":"151.564086ms","start":"2026-10-15T19:00:23.342141Z","end":"2026-10-15T19:00:23.493706Z","steps":["trace[439139784] 'agreement among raft nodes before linearized reading' (duration: 151.446674ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:00:23.493698Z","caller":"traceutil/trace.go:171","msg":"trace[484470167] transaction","detail":"{read_only:false; response_revision:1780; number_of_response:1; }","duration":"164.373439ms","start":"2026-10-15T19:00:23.32927Z","end":"2026-10-15T19:00:23.493643Z","steps":["trace[484470167] 'process raft request' (duration: 163.923581ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:00:39.050863Z","caller":"traceutil/trace.go:171","msg":"trace[1303514570] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"122.325753ms","start":"2026-10-15T19:00:38.92848Z","end":"2026-10-15T19:00:39.050806Z","steps":["trace[1303514570] 'process raft request' (duration: 122.037149ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:21.951277Z","caller":"traceutil/trace.go:171","msg":"trace[927236545] linearizableReadLoop","detail":"{readStateIndex:2297; appliedIndex:2296; }","duration":"138.458309ms","start":"2026-10-15T19:01:21.81279Z","end":"2026-10-15T19:01:21.951249Z","steps":["trace[927236545] 'read index received' (duration: 138.220544ms)","trace[927236545] 'applied index is now lower than readState.Index' (duration: 236.175µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:01:21.951443Z","caller":"traceutil/trace.go:171","msg":"trace[1436477485] transaction","detail":"{read_only:false; response_revision:2215; number_of_response:1; }","duration":"179.663326ms","start":"2026-10-15T19:01:21.771767Z","end":"2026-10-15T19:01:21.95143Z","steps":["trace[1436477485] 'process raft request' (duration: 179.266278ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:21.951796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.978269ms","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-10-15T19:01:21.951895Z","caller":"traceutil/trace.go:171","msg":"trace[1082211191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2215; }","duration":"139.100331ms","start":"2026-10-15T19:01:21.812767Z","end":"2026-10-15T19:01:21.951867Z","steps":["trace[1082211191] 'agreement among raft nodes before linearized reading' (duration: 138.851016ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:02:07.003716Z","caller":"traceutil/trace.go:171","msg":"trace[1172465434] transaction","detail":"{read_only:false; response_revision:2505; number_of_response:1; }","duration":"179.616307ms","start":"2026-10-15T19:02:06.824069Z","end":"2026-10-15T19:02:07.003686Z","steps":["trace[1172465434] 'process raft request' (duration: 163.689566ms)","trace[1172465434] 'compare' (duration: 15.536034ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:02:44.515419Z","caller":"traceutil/trace.go:171","msg":"trace[842607040] linearizableReadLoop","detail":"{readStateIndex:2807; appliedIndex:2806; }","duration":"173.670785ms","start":"2026-10-15T19:02:44.341719Z","end":"2026-10-15T19:02:44.51539Z","steps":["trace[842607040] 'read index received' (duration: 127.058293ms)","trace[842607040] 'applied index is now lower than readState.Index' (duration: 46.609642ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:02:44.515744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.669365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-15T19:02:44.51579Z","caller":"traceutil/trace.go:171","msg":"trace[1853424502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2707; }","duration":"109.778667ms","start":"2026-10-15T19:02:44.406001Z","end":"2026-10-15T19:02:44.515779Z","steps":["trace[1853424502] 'agreement among raft nodes before linearized reading' (duration: 109.586713ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:02:44.515723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.951291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T19:02:44.515806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.657104ms","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-10-15T19:02:44.515893Z","caller":"traceutil/trace.go:171","msg":"trace[2035019228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2707; }","duration":"109.802887ms","start":"2026-10-15T19:02:44.40607Z","end":"2026-10-15T19:02:44.515873Z","steps":["trace[2035019228] 'agreement among raft nodes before linearized reading' (duration: 109.571783ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:02:44.515895Z","caller":"traceutil/trace.go:171","msg":"trace[188916511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2707; }","duration":"174.190845ms","start":"2026-10-15T19:02:44.341674Z","end":"2026-10-15T19:02:44.515865Z","steps":["trace[188916511] 'agreement among raft nodes before linearized reading' (duration: 173.831568ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:07:33.088601Z","caller":"traceutil/trace.go:171","msg":"trace[279139568] transaction","detail":"{read_only:false; response_revision:4930; number_of_response:1; }","duration":"103.669611ms","start":"2026-10-15T19:07:32.984891Z","end":"2026-10-15T19:07:33.088561Z","steps":["trace[279139568] 'process raft request' (duration: 97.459144ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:07:35.082718Z","caller":"traceutil/trace.go:171","msg":"trace[110215430] linearizableReadLoop","detail":"{readStateIndex:5140; appliedIndex:5139; }","duration":"108.024213ms","start":"2026-10-15T19:07:34.974668Z","end":"2026-10-15T19:07:35.082692Z","steps":["trace[110215430] 'read index received' (duration: 107.87512ms)","trace[110215430] 'applied index is now lower than readState.Index' (duration: 148.103µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:07:35.083117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.4423ms","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-10-15T19:07:35.083148Z","caller":"traceutil/trace.go:171","msg":"trace[1557005094] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4953; }","duration":"108.536472ms","start":"2026-10-15T19:07:34.974602Z","end":"2026-10-15T19:07:35.083138Z","steps":["trace[1557005094] 'agreement among raft nodes before linearized reading' (duration: 108.185256ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:07:35.084144Z","caller":"traceutil/trace.go:171","msg":"trace[1604651687] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"110.00102ms","start":"2026-10-15T19:07:34.974111Z","end":"2026-10-15T19:07:35.084112Z","steps":["trace[1604651687] 'process raft request' (duration: 108.433731ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:07:41.380944Z","caller":"traceutil/trace.go:171","msg":"trace[1281076635] linearizableReadLoop","detail":"{readStateIndex:5371; appliedIndex:5370; }","duration":"112.223172ms","start":"2026-10-15T19:07:41.2687Z","end":"2026-10-15T19:07:41.380923Z","steps":["trace[1281076635] 'read index received' (duration: 112.039468ms)","trace[1281076635] 'applied index is now lower than readState.Index' (duration: 182.944µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:07:41.381058Z","caller":"traceutil/trace.go:171","msg":"trace[1027101773] transaction","detail":"{read_only:false; response_revision:5175; number_of_response:1; }","duration":"114.090937ms","start":"2026-10-15T19:07:41.266938Z","end":"2026-10-15T19:07:41.381028Z","steps":["trace[1027101773] 'process raft request' (duration: 113.849272ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:07:41.381239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.518407ms","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-10-15T19:07:41.381342Z","caller":"traceutil/trace.go:171","msg":"trace[1377069] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5175; }","duration":"112.648031ms","start":"2026-10-15T19:07:41.268674Z","end":"2026-10-15T19:07:41.381322Z","steps":["trace[1377069] 'agreement among raft nodes before linearized reading' (duration: 112.401496ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:07:41.381258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.406337ms","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-10-15T19:07:41.381442Z","caller":"traceutil/trace.go:171","msg":"trace[1468479818] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5175; }","duration":"110.638281ms","start":"2026-10-15T19:07:41.270786Z","end":"2026-10-15T19:07:41.381425Z","steps":["trace[1468479818] 'agreement among raft nodes before linearized reading' (duration: 110.307525ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:07:41.38144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.360336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-10-15T19:07:41.381549Z","caller":"traceutil/trace.go:171","msg":"trace[724403375] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:5175; }","duration":"102.505408ms","start":"2026-10-15T19:07:41.279021Z","end":"2026-10-15T19:07:41.381527Z","steps":["trace[724403375] 'agreement among raft nodes before linearized reading' (duration: 102.221763ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:07:41.561755Z","caller":"traceutil/trace.go:171","msg":"trace[2119373826] transaction","detail":"{read_only:false; response_revision:5176; number_of_response:1; }","duration":"176.133145ms","start":"2026-10-15T19:07:41.385584Z","end":"2026-10-15T19:07:41.561718Z","steps":["trace[2119373826] 'process raft request' (duration: 175.943742ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:07:41.570721Z","caller":"traceutil/trace.go:171","msg":"trace[1407411779] transaction","detail":"{read_only:false; response_revision:5177; number_of_response:1; }","duration":"185.048323ms","start":"2026-10-15T19:07:41.385642Z","end":"2026-10-15T19:07:41.570691Z","steps":["trace[1407411779] 'process raft request' (duration: 184.90146ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:03.687815Z","caller":"traceutil/trace.go:171","msg":"trace[146728607] transaction","detail":"{read_only:false; response_revision:5580; number_of_response:1; }","duration":"146.867924ms","start":"2026-10-15T19:08:03.540906Z","end":"2026-10-15T19:08:03.687774Z","steps":["trace[146728607] 'process raft request' (duration: 127.360667ms)","trace[146728607] 'compare' (duration: 19.372054ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:08:03.900367Z","caller":"traceutil/trace.go:171","msg":"trace[115055365] transaction","detail":"{read_only:false; response_revision:5582; number_of_response:1; }","duration":"204.04334ms","start":"2026-10-15T19:08:03.6963Z","end":"2026-10-15T19:08:03.900343Z","steps":["trace[115055365] 'process raft request' (duration: 203.873277ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:03.900299Z","caller":"traceutil/trace.go:171","msg":"trace[1935649759] transaction","detail":"{read_only:false; response_revision:5581; number_of_response:1; }","duration":"207.2238ms","start":"2026-10-15T19:08:03.693042Z","end":"2026-10-15T19:08:03.900266Z","steps":["trace[1935649759] 'process raft request' (duration: 112.272373ms)","trace[1935649759] 'compare' (duration: 94.695212ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:08:04.092523Z","caller":"traceutil/trace.go:171","msg":"trace[1777026533] transaction","detail":"{read_only:false; response_revision:5585; number_of_response:1; }","duration":"112.199112ms","start":"2026-10-15T19:08:03.980279Z","end":"2026-10-15T19:08:04.092478Z","steps":["trace[1777026533] 'process raft request' (duration: 111.935066ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:04.092585Z","caller":"traceutil/trace.go:171","msg":"trace[1771704826] transaction","detail":"{read_only:false; response_revision:5586; number_of_response:1; }","duration":"109.832497ms","start":"2026-10-15T19:08:03.982726Z","end":"2026-10-15T19:08:04.092559Z","steps":["trace[1771704826] 'process raft request' (duration: 109.674694ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:05.721603Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2823} {"level":"info","ts":"2026-10-15T19:08:05.788913Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2823,"took":"65.845769ms","hash":1356271344,"current-db-size-bytes":54575104,"current-db-size":"55 MB","current-db-size-in-use-bytes":30887936,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T19:08:05.788989Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1356271344,"revision":2823,"compact-revision":-1} {"level":"info","ts":"2026-10-15T19:13:05.728707Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5601} {"level":"info","ts":"2026-10-15T19:13:05.815414Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5601,"took":"84.635741ms","hash":2204947517,"current-db-size-bytes":54575104,"current-db-size":"55 MB","current-db-size-in-use-bytes":26079232,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-15T19:13:05.815488Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2204947517,"revision":5601,"compact-revision":2823} {"level":"warn","ts":"2026-10-15T19:14:00.585383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.150187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:14:00.585513Z","caller":"traceutil/trace.go:171","msg":"trace[288630556] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:8377; }","duration":"134.331051ms","start":"2026-10-15T19:14:00.451159Z","end":"2026-10-15T19:14:00.58549Z","steps":["trace[288630556] 'count revisions from in-memory index tree' (duration: 133.987653ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:14:00.585603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.183505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8zpfg\" ","response":"range_response_count:1 size:6516"} {"level":"info","ts":"2026-10-15T19:14:00.585691Z","caller":"traceutil/trace.go:171","msg":"trace[571679684] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8zpfg; range_end:; response_count:1; response_revision:8377; }","duration":"191.336668ms","start":"2026-10-15T19:14:00.394335Z","end":"2026-10-15T19:14:00.585672Z","steps":["trace[571679684] 'range keys from in-memory index tree' (duration: 190.90357ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:14:00.58581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.045694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:14:00.585838Z","caller":"traceutil/trace.go:171","msg":"trace[488421306] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8377; }","duration":"244.132256ms","start":"2026-10-15T19:14:00.341696Z","end":"2026-10-15T19:14:00.585829Z","steps":["trace[488421306] 'range keys from in-memory index tree' (duration: 243.85537ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:14:12.197482Z","caller":"traceutil/trace.go:171","msg":"trace[437117678] linearizableReadLoop","detail":"{readStateIndex:8739; appliedIndex:8738; }","duration":"162.072405ms","start":"2026-10-15T19:14:12.035354Z","end":"2026-10-15T19:14:12.197426Z","steps":["trace[437117678] 'read index received' (duration: 131.432676ms)","trace[437117678] 'applied index is now lower than readState.Index' (duration: 30.638649ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:14:12.197613Z","caller":"traceutil/trace.go:171","msg":"trace[1848860481] transaction","detail":"{read_only:false; response_revision:8439; number_of_response:1; }","duration":"173.829337ms","start":"2026-10-15T19:14:12.023772Z","end":"2026-10-15T19:14:12.197601Z","steps":["trace[1848860481] 'process raft request' (duration: 143.021415ms)","trace[1848860481] 'compare' (duration: 30.477586ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:14:12.19789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.529414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-10-15T19:14:12.197968Z","caller":"traceutil/trace.go:171","msg":"trace[1832858126] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8439; }","duration":"162.653256ms","start":"2026-10-15T19:14:12.035296Z","end":"2026-10-15T19:14:12.197949Z","steps":["trace[1832858126] 'agreement among raft nodes before linearized reading' (duration: 162.497473ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:14:12.743569Z","caller":"traceutil/trace.go:171","msg":"trace[407892911] transaction","detail":"{read_only:false; response_revision:8443; number_of_response:1; }","duration":"184.512089ms","start":"2026-10-15T19:14:12.559013Z","end":"2026-10-15T19:14:12.743525Z","steps":["trace[407892911] 'process raft request' (duration: 184.283105ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:14:40.504616Z","caller":"traceutil/trace.go:171","msg":"trace[1904837946] linearizableReadLoop","detail":"{readStateIndex:8949; appliedIndex:8948; }","duration":"164.643564ms","start":"2026-10-15T19:14:40.339951Z","end":"2026-10-15T19:14:40.504594Z","steps":["trace[1904837946] 'read index received' (duration: 164.4386ms)","trace[1904837946] 'applied index is now lower than readState.Index' (duration: 203.714µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:14:40.504737Z","caller":"traceutil/trace.go:171","msg":"trace[1017137675] transaction","detail":"{read_only:false; response_revision:8642; number_of_response:1; }","duration":"197.290701ms","start":"2026-10-15T19:14:40.307417Z","end":"2026-10-15T19:14:40.504708Z","steps":["trace[1017137675] 'process raft request' (duration: 197.018085ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:14:40.504782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.823287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:14:40.504815Z","caller":"traceutil/trace.go:171","msg":"trace[1502196611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8642; }","duration":"164.912189ms","start":"2026-10-15T19:14:40.339893Z","end":"2026-10-15T19:14:40.504805Z","steps":["trace[1502196611] 'agreement among raft nodes before linearized reading' (duration: 164.842387ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:14:40.505002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.855983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-15T19:14:40.505043Z","caller":"traceutil/trace.go:171","msg":"trace[1384360953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8642; }","duration":"112.937755ms","start":"2026-10-15T19:14:40.392095Z","end":"2026-10-15T19:14:40.505032Z","steps":["trace[1384360953] 'agreement among raft nodes before linearized reading' (duration: 112.817803ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:14:40.505046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.749047ms","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-10-15T19:14:40.505113Z","caller":"traceutil/trace.go:171","msg":"trace[1349814689] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8642; }","duration":"117.830288ms","start":"2026-10-15T19:14:40.387267Z","end":"2026-10-15T19:14:40.505097Z","steps":["trace[1349814689] 'agreement among raft nodes before linearized reading' (duration: 117.692206ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:14:40.50489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.62068ms","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-10-15T19:14:40.50538Z","caller":"traceutil/trace.go:171","msg":"trace[1430748789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8642; }","duration":"131.158841ms","start":"2026-10-15T19:14:40.37421Z","end":"2026-10-15T19:14:40.505369Z","steps":["trace[1430748789] 'agreement among raft nodes before linearized reading' (duration: 130.546729ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:14:40.658923Z","caller":"traceutil/trace.go:171","msg":"trace[1833861945] transaction","detail":"{read_only:false; response_revision:8645; number_of_response:1; }","duration":"144.712947ms","start":"2026-10-15T19:14:40.514191Z","end":"2026-10-15T19:14:40.658904Z","steps":["trace[1833861945] 'process raft request' (duration: 144.620235ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:14:40.658932Z","caller":"traceutil/trace.go:171","msg":"trace[584347541] transaction","detail":"{read_only:false; response_revision:8644; number_of_response:1; }","duration":"146.371667ms","start":"2026-10-15T19:14:40.512538Z","end":"2026-10-15T19:14:40.658909Z","steps":["trace[584347541] 'process raft request' (duration: 145.844157ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:14:54.993818Z","caller":"traceutil/trace.go:171","msg":"trace[1706734494] transaction","detail":"{read_only:false; response_revision:8765; number_of_response:1; }","duration":"217.163101ms","start":"2026-10-15T19:14:54.776625Z","end":"2026-10-15T19:14:54.993788Z","steps":["trace[1706734494] 'process raft request' (duration: 216.981397ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:14:55.157519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.65441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:211594"} {"level":"info","ts":"2026-10-15T19:14:55.157612Z","caller":"traceutil/trace.go:171","msg":"trace[1283248509] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8765; }","duration":"105.779222ms","start":"2026-10-15T19:14:55.051811Z","end":"2026-10-15T19:14:55.15759Z","steps":["trace[1283248509] 'range keys from in-memory index tree' (duration: 105.260652ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:14:55.788428Z","caller":"traceutil/trace.go:171","msg":"trace[1288527611] linearizableReadLoop","detail":"{readStateIndex:9080; appliedIndex:9079; }","duration":"229.582385ms","start":"2026-10-15T19:14:55.55882Z","end":"2026-10-15T19:14:55.788403Z","steps":["trace[1288527611] 'read index received' (duration: 229.462693ms)","trace[1288527611] 'applied index is now lower than readState.Index' (duration: 118.722µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:14:55.788453Z","caller":"traceutil/trace.go:171","msg":"trace[342176223] transaction","detail":"{read_only:false; response_revision:8766; number_of_response:1; }","duration":"244.969056ms","start":"2026-10-15T19:14:55.543465Z","end":"2026-10-15T19:14:55.788434Z","steps":["trace[342176223] 'process raft request' (duration: 244.781083ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:14:55.788636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.795459ms","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-10-15T19:14:55.788674Z","caller":"traceutil/trace.go:171","msg":"trace[1477792340] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8766; }","duration":"229.885821ms","start":"2026-10-15T19:14:55.558779Z","end":"2026-10-15T19:14:55.788664Z","steps":["trace[1477792340] 'agreement among raft nodes before linearized reading' (duration: 229.716128ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:14:55.854811Z","caller":"traceutil/trace.go:171","msg":"trace[826390808] transaction","detail":"{read_only:false; response_revision:8767; number_of_response:1; }","duration":"293.599657ms","start":"2026-10-15T19:14:55.56119Z","end":"2026-10-15T19:14:55.85479Z","steps":["trace[826390808] 'process raft request' (duration: 293.469814ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:14:56.329884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.886706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-10-15T19:14:56.329974Z","caller":"traceutil/trace.go:171","msg":"trace[2019550437] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8772; }","duration":"105.033188ms","start":"2026-10-15T19:14:56.22492Z","end":"2026-10-15T19:14:56.329953Z","steps":["trace[2019550437] 'range keys from in-memory index tree' (duration: 104.532068ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:14:56.506426Z","caller":"traceutil/trace.go:171","msg":"trace[900529738] transaction","detail":"{read_only:false; response_revision:8773; number_of_response:1; }","duration":"159.830564ms","start":"2026-10-15T19:14:56.346571Z","end":"2026-10-15T19:14:56.506401Z","steps":["trace[900529738] 'process raft request' (duration: 159.627381ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:15:26.00726Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024ba.wal"} {"level":"info","ts":"2026-10-15T19:16:30.480779Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e4145bf1b1a19cd","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T19:16:30.48795Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-15T19:16:30.488073Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-15T19:18:05.735361Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7863} {"level":"info","ts":"2026-10-15T19:18:05.80082Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7863,"took":"63.214258ms","hash":70448531,"current-db-size-bytes":54575104,"current-db-size":"55 MB","current-db-size-in-use-bytes":28811264,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-15T19:18:05.80095Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":70448531,"revision":7863,"compact-revision":5601} {"level":"info","ts":"2026-10-15T19:23:05.742074Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10371} {"level":"info","ts":"2026-10-15T19:23:05.831247Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10371,"took":"86.448449ms","hash":3595936449,"current-db-size-bytes":54575104,"current-db-size":"55 MB","current-db-size-in-use-bytes":27066368,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-15T19:23:05.831313Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3595936449,"revision":10371,"compact-revision":7863} {"level":"info","ts":"2026-10-15T19:26:14.562347Z","caller":"traceutil/trace.go:171","msg":"trace[2129686222] transaction","detail":"{read_only:false; response_revision:13958; number_of_response:1; }","duration":"130.683468ms","start":"2026-10-15T19:26:14.431634Z","end":"2026-10-15T19:26:14.562317Z","steps":["trace[2129686222] 'process raft request' (duration: 130.532865ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:26:25.968479Z","caller":"traceutil/trace.go:171","msg":"trace[1733496337] linearizableReadLoop","detail":"{readStateIndex:14613; appliedIndex:14612; }","duration":"388.049188ms","start":"2026-10-15T19:26:25.5804Z","end":"2026-10-15T19:26:25.968449Z","steps":["trace[1733496337] 'read index received' (duration: 290.260114ms)","trace[1733496337] 'applied index is now lower than readState.Index' (duration: 97.787904ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:26:25.968554Z","caller":"traceutil/trace.go:171","msg":"trace[1706538389] transaction","detail":"{read_only:false; response_revision:14126; number_of_response:1; }","duration":"443.277474ms","start":"2026-10-15T19:26:25.525228Z","end":"2026-10-15T19:26:25.968505Z","steps":["trace[1706538389] 'process raft request' (duration: 345.554322ms)","trace[1706538389] 'compare' (duration: 97.492468ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:26:25.968741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:26:25.525196Z","time spent":"443.449348ms","remote":"127.0.0.1:57252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":854,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-15T19:26:25.968862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.121502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-15T19:26:25.968906Z","caller":"traceutil/trace.go:171","msg":"trace[352362514] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14126; }","duration":"375.237805ms","start":"2026-10-15T19:26:25.593658Z","end":"2026-10-15T19:26:25.968895Z","steps":["trace[352362514] 'agreement among raft nodes before linearized reading' (duration: 374.963409ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:26:25.968883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.448786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-66a6e5b8-0151-4f75-90ce-fd6ec5501699\" ","response":"range_response_count:1 size:3605"} {"level":"warn","ts":"2026-10-15T19:26:25.968916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.385064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T19:26:25.968975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:26:25.593635Z","time spent":"375.306156ms","remote":"127.0.0.1:57418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-15T19:26:25.969068Z","caller":"traceutil/trace.go:171","msg":"trace[1071033824] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:14126; }","duration":"263.590308ms","start":"2026-10-15T19:26:25.705433Z","end":"2026-10-15T19:26:25.969024Z","steps":["trace[1071033824] 'agreement among raft nodes before linearized reading' (duration: 263.346894ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:26:25.969197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.84051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-10-15T19:26:25.969234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.769184ms","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-10-15T19:26:25.96928Z","caller":"traceutil/trace.go:171","msg":"trace[575829910] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14126; }","duration":"323.975613ms","start":"2026-10-15T19:26:25.645278Z","end":"2026-10-15T19:26:25.969253Z","steps":["trace[575829910] 'agreement among raft nodes before linearized reading' (duration: 323.771529ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:26:25.969306Z","caller":"traceutil/trace.go:171","msg":"trace[688756910] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14126; }","duration":"357.899926ms","start":"2026-10-15T19:26:25.611385Z","end":"2026-10-15T19:26:25.969285Z","steps":["trace[688756910] 'agreement among raft nodes before linearized reading' (duration: 357.685992ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:26:25.96934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:26:25.645242Z","time spent":"324.085725ms","remote":"127.0.0.1:57418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-15T19:26:25.969363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:26:25.611354Z","time spent":"358.001178ms","remote":"127.0.0.1:57418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-15T19:26:25.969179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.89165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:26:25.969504Z","caller":"traceutil/trace.go:171","msg":"trace[571211414] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:14126; }","duration":"238.249277ms","start":"2026-10-15T19:26:25.731172Z","end":"2026-10-15T19:26:25.969422Z","steps":["trace[571211414] 'agreement among raft nodes before linearized reading' (duration: 237.921251ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:26:25.969802Z","caller":"traceutil/trace.go:171","msg":"trace[461952055] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-66a6e5b8-0151-4f75-90ce-fd6ec5501699; range_end:; response_count:1; response_revision:14126; }","duration":"388.602878ms","start":"2026-10-15T19:26:25.580352Z","end":"2026-10-15T19:26:25.968955Z","steps":["trace[461952055] 'agreement among raft nodes before linearized reading' (duration: 388.249291ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:26:25.970025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:26:25.580327Z","time spent":"389.584757ms","remote":"127.0.0.1:57348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3628,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-66a6e5b8-0151-4f75-90ce-fd6ec5501699\" "} {"level":"info","ts":"2026-10-15T19:28:05.748853Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12645} {"level":"info","ts":"2026-10-15T19:28:05.832235Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12645,"took":"80.314233ms","hash":409810468,"current-db-size-bytes":54575104,"current-db-size":"55 MB","current-db-size-in-use-bytes":30691328,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T19:28:05.832301Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":409810468,"revision":12645,"compact-revision":10371} {"level":"info","ts":"2026-10-15T19:28:06.076795Z","caller":"traceutil/trace.go:171","msg":"trace[830572903] transaction","detail":"{read_only:false; response_revision:15217; number_of_response:1; }","duration":"132.90926ms","start":"2026-10-15T19:28:05.943859Z","end":"2026-10-15T19:28:06.076768Z","steps":["trace[830572903] 'process raft request' (duration: 132.740637ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:28:07.012658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.37819ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T19:28:07.012881Z","caller":"traceutil/trace.go:171","msg":"trace[1114878752] transaction","detail":"{read_only:false; response_revision:15220; number_of_response:1; }","duration":"283.031657ms","start":"2026-10-15T19:28:06.729821Z","end":"2026-10-15T19:28:07.012853Z","steps":["trace[1114878752] 'process raft request' (duration: 141.33549ms)","trace[1114878752] 'compare' (duration: 141.174127ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:28:07.013081Z","caller":"traceutil/trace.go:171","msg":"trace[1004248925] linearizableReadLoop","detail":"{readStateIndex:15744; appliedIndex:15742; }","duration":"246.476283ms","start":"2026-10-15T19:28:06.766567Z","end":"2026-10-15T19:28:07.013043Z","steps":["trace[1004248925] 'read index received' (duration: 104.596774ms)","trace[1004248925] 'applied index is now lower than readState.Index' (duration: 141.878089ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:28:07.01334Z","caller":"traceutil/trace.go:171","msg":"trace[388615907] transaction","detail":"{read_only:false; response_revision:15221; number_of_response:1; }","duration":"283.225001ms","start":"2026-10-15T19:28:06.730097Z","end":"2026-10-15T19:28:07.013322Z","steps":["trace[388615907] 'process raft request' (duration: 282.710301ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:28:07.013557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.997524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-rlqkh\" ","response":"range_response_count:1 size:20645"} {"level":"info","ts":"2026-10-15T19:28:07.013606Z","caller":"traceutil/trace.go:171","msg":"trace[1869545089] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-rlqkh; range_end:; response_count:1; response_revision:15221; }","duration":"247.099746ms","start":"2026-10-15T19:28:06.766493Z","end":"2026-10-15T19:28:07.013593Z","steps":["trace[1869545089] 'agreement among raft nodes before linearized reading' (duration: 246.870702ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:28:07.131161Z","caller":"traceutil/trace.go:171","msg":"trace[286507992] linearizableReadLoop","detail":"{readStateIndex:15745; appliedIndex:15744; }","duration":"117.973367ms","start":"2026-10-15T19:28:07.013162Z","end":"2026-10-15T19:28:07.131135Z","steps":["trace[286507992] 'read index received' (duration: 116.938777ms)","trace[286507992] 'applied index is now lower than readState.Index' (duration: 1.03319ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:28:07.131493Z","caller":"traceutil/trace.go:171","msg":"trace[1106456136] transaction","detail":"{read_only:false; response_revision:15222; number_of_response:1; }","duration":"136.792414ms","start":"2026-10-15T19:28:06.994686Z","end":"2026-10-15T19:28:07.131478Z","steps":["trace[1106456136] 'process raft request' (duration: 135.513309ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:28:07.131653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.894709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-15T19:28:07.131776Z","caller":"traceutil/trace.go:171","msg":"trace[519551431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15222; }","duration":"231.095662ms","start":"2026-10-15T19:28:06.900658Z","end":"2026-10-15T19:28:07.131754Z","steps":["trace[519551431] 'agreement among raft nodes before linearized reading' (duration: 230.640703ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:28:07.132031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.975714ms","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-10-15T19:28:07.13208Z","caller":"traceutil/trace.go:171","msg":"trace[1472844428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15222; }","duration":"236.096516ms","start":"2026-10-15T19:28:06.89597Z","end":"2026-10-15T19:28:07.132067Z","steps":["trace[1472844428] 'agreement among raft nodes before linearized reading' (duration: 235.944224ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:28:07.13239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.367509ms","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-10-15T19:28:07.132443Z","caller":"traceutil/trace.go:171","msg":"trace[1391837715] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:15222; }","duration":"229.481941ms","start":"2026-10-15T19:28:06.902943Z","end":"2026-10-15T19:28:07.132425Z","steps":["trace[1391837715] 'agreement among raft nodes before linearized reading' (duration: 229.220006ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:28:07.136064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.378468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:78 size:752393"} {"level":"info","ts":"2026-10-15T19:28:07.136143Z","caller":"traceutil/trace.go:171","msg":"trace[1017766847] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:78; response_revision:15222; }","duration":"115.586692ms","start":"2026-10-15T19:28:07.020538Z","end":"2026-10-15T19:28:07.136125Z","steps":["trace[1017766847] 'agreement among raft nodes before linearized reading' (duration: 110.879413ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:33:05.756193Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15216} {"level":"info","ts":"2026-10-15T19:33:05.860716Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15216,"took":"101.429944ms","hash":3509154757,"current-db-size-bytes":54575104,"current-db-size":"55 MB","current-db-size-in-use-bytes":31625216,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T19:33:05.8608Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3509154757,"revision":15216,"compact-revision":12645} {"level":"info","ts":"2026-10-15T19:33:44.072577Z","caller":"traceutil/trace.go:171","msg":"trace[1290283619] transaction","detail":"{read_only:false; response_revision:17779; number_of_response:1; }","duration":"180.391211ms","start":"2026-10-15T19:33:43.89216Z","end":"2026-10-15T19:33:44.072551Z","steps":["trace[1290283619] 'process raft request' (duration: 180.038424ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:33:48.981729Z","caller":"traceutil/trace.go:171","msg":"trace[1724787762] transaction","detail":"{read_only:false; response_revision:17807; number_of_response:1; }","duration":"146.918826ms","start":"2026-10-15T19:33:48.83478Z","end":"2026-10-15T19:33:48.981698Z","steps":["trace[1724787762] 'process raft request' (duration: 116.660872ms)","trace[1724787762] 'compare' (duration: 30.134851ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:33:48.993114Z","caller":"traceutil/trace.go:171","msg":"trace[1503524022] transaction","detail":"{read_only:false; response_revision:17808; number_of_response:1; }","duration":"116.219634ms","start":"2026-10-15T19:33:48.876872Z","end":"2026-10-15T19:33:48.993092Z","steps":["trace[1503524022] 'process raft request' (duration: 115.902108ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:33:49.589477Z","caller":"traceutil/trace.go:171","msg":"trace[2099671202] linearizableReadLoop","detail":"{readStateIndex:18420; appliedIndex:18419; }","duration":"246.958613ms","start":"2026-10-15T19:33:49.342471Z","end":"2026-10-15T19:33:49.589429Z","steps":["trace[2099671202] 'read index received' (duration: 246.83423ms)","trace[2099671202] 'applied index is now lower than readState.Index' (duration: 122.803µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:33:49.589867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.379821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:33:49.5899Z","caller":"traceutil/trace.go:171","msg":"trace[1987251857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17811; }","duration":"247.444333ms","start":"2026-10-15T19:33:49.342446Z","end":"2026-10-15T19:33:49.58989Z","steps":["trace[1987251857] 'agreement among raft nodes before linearized reading' (duration: 247.367401ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:33:49.692718Z","caller":"traceutil/trace.go:171","msg":"trace[526860904] transaction","detail":"{read_only:false; response_revision:17812; number_of_response:1; }","duration":"101.471225ms","start":"2026-10-15T19:33:49.591219Z","end":"2026-10-15T19:33:49.69269Z","steps":["trace[526860904] 'process raft request' (duration: 43.69292ms)","trace[526860904] 'compare' (duration: 57.599521ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:36:12.066903Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e4145bf1b1a19cd","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T19:36:12.076015Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-15T19:36:12.076139Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-15T19:38:05.76396Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17451} {"level":"info","ts":"2026-10-15T19:38:05.868113Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17451,"took":"100.747312ms","hash":383336861,"current-db-size-bytes":54575104,"current-db-size":"55 MB","current-db-size-in-use-bytes":35160064,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-15T19:38:05.868184Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":383336861,"revision":17451,"compact-revision":15216} {"level":"warn","ts":"2026-10-15T19:40:48.359099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.932868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T19:40:48.359197Z","caller":"traceutil/trace.go:171","msg":"trace[1964007219] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:21303; }","duration":"196.095301ms","start":"2026-10-15T19:40:48.163079Z","end":"2026-10-15T19:40:48.359175Z","steps":["trace[1964007219] 'count revisions from in-memory index tree' (duration: 195.830825ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:40:48.655997Z","caller":"traceutil/trace.go:171","msg":"trace[311043496] transaction","detail":"{read_only:false; response_revision:21304; number_of_response:1; }","duration":"129.665561ms","start":"2026-10-15T19:40:48.526276Z","end":"2026-10-15T19:40:48.655941Z","steps":["trace[311043496] 'process raft request' (duration: 129.501378ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:40:48.761908Z","caller":"traceutil/trace.go:171","msg":"trace[1863064134] transaction","detail":"{read_only:false; response_revision:21305; number_of_response:1; }","duration":"157.084581ms","start":"2026-10-15T19:40:48.604799Z","end":"2026-10-15T19:40:48.761884Z","steps":["trace[1863064134] 'process raft request' (duration: 100.604809ms)","trace[1863064134] 'compare' (duration: 56.320259ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:43:05.771122Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20137} {"level":"info","ts":"2026-10-15T19:43:05.896796Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20137,"took":"121.658498ms","hash":361172974,"current-db-size-bytes":54575104,"current-db-size":"55 MB","current-db-size-in-use-bytes":31924224,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T19:43:05.896873Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":361172974,"revision":20137,"compact-revision":17451} {"level":"info","ts":"2026-10-15T19:44:35.019452Z","caller":"traceutil/trace.go:171","msg":"trace[1663214364] linearizableReadLoop","detail":"{readStateIndex:24491; appliedIndex:24490; }","duration":"160.959182ms","start":"2026-10-15T19:44:34.858466Z","end":"2026-10-15T19:44:35.019425Z","steps":["trace[1663214364] 'read index received' (duration: 160.685837ms)","trace[1663214364] 'applied index is now lower than readState.Index' (duration: 272.125µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:44:35.019505Z","caller":"traceutil/trace.go:171","msg":"trace[1212882188] transaction","detail":"{read_only:false; response_revision:23696; number_of_response:1; }","duration":"174.752814ms","start":"2026-10-15T19:44:34.844721Z","end":"2026-10-15T19:44:35.019474Z","steps":["trace[1212882188] 'process raft request' (duration: 174.500889ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:44:35.019683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.201987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-manager\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-10-15T19:44:35.019747Z","caller":"traceutil/trace.go:171","msg":"trace[1669567528] range","detail":"{range_begin:/registry/serviceaccounts/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-manager; range_end:; response_count:1; response_revision:23696; }","duration":"161.330879ms","start":"2026-10-15T19:44:34.858401Z","end":"2026-10-15T19:44:35.019732Z","steps":["trace[1669567528] 'agreement among raft nodes before linearized reading' (duration: 161.104875ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:44:35.346624Z","caller":"traceutil/trace.go:171","msg":"trace[1180937164] linearizableReadLoop","detail":"{readStateIndex:24492; appliedIndex:24491; }","duration":"231.085872ms","start":"2026-10-15T19:44:35.115517Z","end":"2026-10-15T19:44:35.346603Z","steps":["trace[1180937164] 'read index received' (duration: 231.050522ms)","trace[1180937164] 'applied index is now lower than readState.Index' (duration: 33.95µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:44:35.346899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.352238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/openstackclusters.infrastructure.cluster.x-k8s.io\" ","response":"range_response_count:1 size:87332"} {"level":"info","ts":"2026-10-15T19:44:35.346991Z","caller":"traceutil/trace.go:171","msg":"trace[686833293] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/openstackclusters.infrastructure.cluster.x-k8s.io; range_end:; response_count:1; response_revision:23697; }","duration":"231.50812ms","start":"2026-10-15T19:44:35.115463Z","end":"2026-10-15T19:44:35.346971Z","steps":["trace[686833293] 'agreement among raft nodes before linearized reading' (duration: 231.221405ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:44:35.347614Z","caller":"traceutil/trace.go:171","msg":"trace[1594502176] transaction","detail":"{read_only:false; response_revision:23697; number_of_response:1; }","duration":"298.699354ms","start":"2026-10-15T19:44:35.048895Z","end":"2026-10-15T19:44:35.347594Z","steps":["trace[1594502176] 'process raft request' (duration: 297.564142ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:44:35.350875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.452379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T19:44:35.350973Z","caller":"traceutil/trace.go:171","msg":"trace[2122562445] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:23698; }","duration":"135.563602ms","start":"2026-10-15T19:44:35.215369Z","end":"2026-10-15T19:44:35.350933Z","steps":["trace[2122562445] 'agreement among raft nodes before linearized reading' (duration: 135.405729ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:44:35.351389Z","caller":"traceutil/trace.go:171","msg":"trace[1060127549] transaction","detail":"{read_only:false; response_revision:23698; number_of_response:1; }","duration":"141.887972ms","start":"2026-10-15T19:44:35.209479Z","end":"2026-10-15T19:44:35.351367Z","steps":["trace[1060127549] 'process raft request' (duration: 141.125317ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:47:18.823597Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000665f.wal"} {"level":"info","ts":"2026-10-15T19:47:52.333821Z","caller":"traceutil/trace.go:171","msg":"trace[703967586] linearizableReadLoop","detail":"{readStateIndex:26531; appliedIndex:26530; }","duration":"334.611995ms","start":"2026-10-15T19:47:51.999165Z","end":"2026-10-15T19:47:52.333777Z","steps":["trace[703967586] 'read index received' (duration: 334.437062ms)","trace[703967586] 'applied index is now lower than readState.Index' (duration: 173.783µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:47:52.333973Z","caller":"traceutil/trace.go:171","msg":"trace[474637188] transaction","detail":"{read_only:false; response_revision:25686; number_of_response:1; }","duration":"358.502349ms","start":"2026-10-15T19:47:51.975424Z","end":"2026-10-15T19:47:52.333926Z","steps":["trace[474637188] 'process raft request' (duration: 358.187573ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:47:52.334159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:47:51.9754Z","time spent":"358.660142ms","remote":"127.0.0.1:57418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:47:52.334238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.043904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:521"} {"level":"info","ts":"2026-10-15T19:47:52.334334Z","caller":"traceutil/trace.go:171","msg":"trace[202449380] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:25686; }","duration":"335.194667ms","start":"2026-10-15T19:47:51.999118Z","end":"2026-10-15T19:47:52.334313Z","steps":["trace[202449380] 'agreement among raft nodes before linearized reading' (duration: 334.82725ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:47:52.334382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:47:51.999095Z","time spent":"335.278339ms","remote":"127.0.0.1:57364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":544,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"info","ts":"2026-10-15T19:47:52.465272Z","caller":"traceutil/trace.go:171","msg":"trace[814092118] transaction","detail":"{read_only:false; response_revision:25687; number_of_response:1; }","duration":"406.755035ms","start":"2026-10-15T19:47:52.058486Z","end":"2026-10-15T19:47:52.465241Z","steps":["trace[814092118] 'process raft request' (duration: 356.037243ms)","trace[814092118] 'compare' (duration: 50.495887ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:47:52.465548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:47:52.058469Z","time spent":"406.881807ms","remote":"127.0.0.1:57418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:47:52.46555Z","caller":"traceutil/trace.go:171","msg":"trace[1323541919] linearizableReadLoop","detail":"{readStateIndex:26533; appliedIndex:26531; }","duration":"131.616146ms","start":"2026-10-15T19:47:52.333912Z","end":"2026-10-15T19:47:52.465529Z","steps":["trace[1323541919] 'read index received' (duration: 80.533717ms)","trace[1323541919] 'applied index is now lower than readState.Index' (duration: 51.081539ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:47:52.465625Z","caller":"traceutil/trace.go:171","msg":"trace[1835393230] transaction","detail":"{read_only:false; response_revision:25688; number_of_response:1; }","duration":"322.608749ms","start":"2026-10-15T19:47:52.142954Z","end":"2026-10-15T19:47:52.465563Z","steps":["trace[1835393230] 'process raft request' (duration: 322.232582ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:47:52.465938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:47:52.142937Z","time spent":"322.831553ms","remote":"127.0.0.1:57418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:47:52.465988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.002779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:47:52.466258Z","caller":"traceutil/trace.go:171","msg":"trace[1760076547] transaction","detail":"{read_only:false; response_revision:25689; number_of_response:1; }","duration":"118.760603ms","start":"2026-10-15T19:47:52.347482Z","end":"2026-10-15T19:47:52.466242Z","steps":["trace[1760076547] 'process raft request' (duration: 117.936517ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:47:52.466227Z","caller":"traceutil/trace.go:171","msg":"trace[664675055] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25688; }","duration":"349.309694ms","start":"2026-10-15T19:47:52.116889Z","end":"2026-10-15T19:47:52.466198Z","steps":["trace[664675055] 'agreement among raft nodes before linearized reading' (duration: 348.719023ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:47:52.466359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.750012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-10-15T19:47:52.46641Z","caller":"traceutil/trace.go:171","msg":"trace[1359759688] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25689; }","duration":"428.811693ms","start":"2026-10-15T19:47:52.037582Z","end":"2026-10-15T19:47:52.466393Z","steps":["trace[1359759688] 'agreement among raft nodes before linearized reading' (duration: 428.079469ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:47:52.466453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:47:52.037528Z","time spent":"428.913415ms","remote":"127.0.0.1:57418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":584,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-10-15T19:47:52.466469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:47:52.116877Z","time spent":"349.510809ms","remote":"127.0.0.1:55432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"warn","ts":"2026-10-15T19:47:52.466991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.37202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-15T19:47:52.466999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.729497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T19:47:52.467033Z","caller":"traceutil/trace.go:171","msg":"trace[1616396875] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25690; }","duration":"112.432991ms","start":"2026-10-15T19:47:52.354589Z","end":"2026-10-15T19:47:52.467022Z","steps":["trace[1616396875] 'agreement among raft nodes before linearized reading' (duration: 112.281048ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:47:52.467041Z","caller":"traceutil/trace.go:171","msg":"trace[1152993269] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:25690; }","duration":"112.774968ms","start":"2026-10-15T19:47:52.354255Z","end":"2026-10-15T19:47:52.46703Z","steps":["trace[1152993269] 'agreement among raft nodes before linearized reading' (duration: 112.627055ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:47:52.467092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.671495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-15T19:47:52.467133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.570475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4bvdz\" ","response":"range_response_count:1 size:18518"} {"level":"info","ts":"2026-10-15T19:47:52.467184Z","caller":"traceutil/trace.go:171","msg":"trace[1729404863] transaction","detail":"{read_only:false; response_revision:25690; number_of_response:1; }","duration":"118.528917ms","start":"2026-10-15T19:47:52.348643Z","end":"2026-10-15T19:47:52.467172Z","steps":["trace[1729404863] 'process raft request' (duration: 117.510728ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:47:52.467194Z","caller":"traceutil/trace.go:171","msg":"trace[1658502890] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4bvdz; range_end:; response_count:1; response_revision:25690; }","duration":"131.657947ms","start":"2026-10-15T19:47:52.335522Z","end":"2026-10-15T19:47:52.46718Z","steps":["trace[1658502890] 'agreement among raft nodes before linearized reading' (duration: 131.312211ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:47:52.467147Z","caller":"traceutil/trace.go:171","msg":"trace[482404788] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:25689; }","duration":"384.761116ms","start":"2026-10-15T19:47:52.08237Z","end":"2026-10-15T19:47:52.467132Z","steps":["trace[482404788] 'agreement among raft nodes before linearized reading' (duration: 383.275969ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:47:52.467318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.808066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:47:52.46735Z","caller":"traceutil/trace.go:171","msg":"trace[2039042995] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25690; }","duration":"125.860197ms","start":"2026-10-15T19:47:52.34148Z","end":"2026-10-15T19:47:52.46734Z","steps":["trace[2039042995] 'agreement among raft nodes before linearized reading' (duration: 125.760285ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:47:52.467353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:47:52.082356Z","time spent":"384.982601ms","remote":"127.0.0.1:50916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-10-15T19:47:52.847498Z","caller":"traceutil/trace.go:171","msg":"trace[1671737671] transaction","detail":"{read_only:false; response_revision:25691; number_of_response:1; }","duration":"375.725906ms","start":"2026-10-15T19:47:52.471746Z","end":"2026-10-15T19:47:52.847472Z","steps":["trace[1671737671] 'process raft request' (duration: 375.41342ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:47:52.847665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:47:52.471725Z","time spent":"375.849958ms","remote":"127.0.0.1:57418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:47:52.861704Z","caller":"traceutil/trace.go:171","msg":"trace[1286701705] linearizableReadLoop","detail":"{readStateIndex:26537; appliedIndex:26535; }","duration":"176.303724ms","start":"2026-10-15T19:47:52.685379Z","end":"2026-10-15T19:47:52.861682Z","steps":["trace[1286701705] 'read index received' (duration: 161.757619ms)","trace[1286701705] 'applied index is now lower than readState.Index' (duration: 14.545095ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:47:52.861785Z","caller":"traceutil/trace.go:171","msg":"trace[1642261794] transaction","detail":"{read_only:false; response_revision:25692; number_of_response:1; }","duration":"389.054009ms","start":"2026-10-15T19:47:52.47271Z","end":"2026-10-15T19:47:52.861764Z","steps":["trace[1642261794] 'process raft request' (duration: 388.865516ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:47:52.86186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.452147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-15T19:47:52.861918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:47:52.472693Z","time spent":"389.13507ms","remote":"127.0.0.1:57418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:47:52.861926Z","caller":"traceutil/trace.go:171","msg":"trace[167348011] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:25692; }","duration":"176.553669ms","start":"2026-10-15T19:47:52.685338Z","end":"2026-10-15T19:47:52.861892Z","steps":["trace[167348011] 'agreement among raft nodes before linearized reading' (duration: 176.457538ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:47:52.862133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.930085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-api-78cbc7fccb-8jn2g\" ","response":"range_response_count:1 size:8650"} {"level":"info","ts":"2026-10-15T19:47:52.862208Z","caller":"traceutil/trace.go:171","msg":"trace[2066865486] range","detail":"{range_begin:/registry/pods/openstack/magnum-api-78cbc7fccb-8jn2g; range_end:; response_count:1; response_revision:25692; }","duration":"110.049106ms","start":"2026-10-15T19:47:52.752142Z","end":"2026-10-15T19:47:52.862191Z","steps":["trace[2066865486] 'agreement among raft nodes before linearized reading' (duration: 109.834572ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:48:05.778325Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22386} {"level":"info","ts":"2026-10-15T19:48:05.897149Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22386,"took":"114.589314ms","hash":2065583649,"current-db-size-bytes":54996992,"current-db-size":"55 MB","current-db-size-in-use-bytes":46247936,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-10-15T19:48:05.89724Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2065583649,"revision":22386,"compact-revision":20137} {"level":"warn","ts":"2026-10-15T19:48:52.90207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.371342ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T19:48:52.902681Z","caller":"traceutil/trace.go:171","msg":"trace[274210754] transaction","detail":"{read_only:false; response_revision:26237; number_of_response:1; }","duration":"213.417948ms","start":"2026-10-15T19:48:52.68924Z","end":"2026-10-15T19:48:52.902658Z","steps":["trace[274210754] 'process raft request' (duration: 100.372644ms)","trace[274210754] 'compare' (duration: 111.897132ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:48:52.902831Z","caller":"traceutil/trace.go:171","msg":"trace[1915742688] transaction","detail":"{read_only:false; response_revision:26238; number_of_response:1; }","duration":"203.225925ms","start":"2026-10-15T19:48:52.699596Z","end":"2026-10-15T19:48:52.902821Z","steps":["trace[1915742688] 'process raft request' (duration: 202.570983ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:48:52.902976Z","caller":"traceutil/trace.go:171","msg":"trace[1774055845] transaction","detail":"{read_only:false; response_revision:26239; number_of_response:1; }","duration":"200.425882ms","start":"2026-10-15T19:48:52.702541Z","end":"2026-10-15T19:48:52.902967Z","steps":["trace[1774055845] 'process raft request' (duration: 199.678518ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:48:53.21821Z","caller":"traceutil/trace.go:171","msg":"trace[844392290] transaction","detail":"{read_only:false; response_revision:26240; number_of_response:1; }","duration":"305.77996ms","start":"2026-10-15T19:48:52.912403Z","end":"2026-10-15T19:48:53.218183Z","steps":["trace[844392290] 'process raft request' (duration: 305.634307ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:48:53.218353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:48:52.912377Z","time spent":"305.902002ms","remote":"127.0.0.1:57418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:48:53.218696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.021338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-10-15T19:48:53.218774Z","caller":"traceutil/trace.go:171","msg":"trace[1910465374] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:26240; }","duration":"156.142231ms","start":"2026-10-15T19:48:53.062615Z","end":"2026-10-15T19:48:53.218757Z","steps":["trace[1910465374] 'agreement among raft nodes before linearized reading' (duration: 155.890516ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:48:53.218481Z","caller":"traceutil/trace.go:171","msg":"trace[1735991551] linearizableReadLoop","detail":"{readStateIndex:27099; appliedIndex:27099; }","duration":"155.774704ms","start":"2026-10-15T19:48:53.062665Z","end":"2026-10-15T19:48:53.21844Z","steps":["trace[1735991551] 'read index received' (duration: 155.730473ms)","trace[1735991551] 'applied index is now lower than readState.Index' (duration: 42.251µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:48:53.679879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.310978ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T19:48:53.680307Z","caller":"traceutil/trace.go:171","msg":"trace[401815380] linearizableReadLoop","detail":"{readStateIndex:27102; appliedIndex:27101; }","duration":"242.994978ms","start":"2026-10-15T19:48:53.437276Z","end":"2026-10-15T19:48:53.680271Z","steps":["trace[401815380] 'read index received' (duration: 39.59µs)","trace[401815380] 'applied index is now lower than readState.Index' (duration: 242.953528ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:48:53.680583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.300184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-15T19:48:53.680722Z","caller":"traceutil/trace.go:171","msg":"trace[798460118] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26243; }","duration":"243.477317ms","start":"2026-10-15T19:48:53.437233Z","end":"2026-10-15T19:48:53.680711Z","steps":["trace[798460118] 'agreement among raft nodes before linearized reading' (duration: 243.156481ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:48:53.681142Z","caller":"traceutil/trace.go:171","msg":"trace[1117640429] transaction","detail":"{read_only:false; response_revision:26243; number_of_response:1; }","duration":"411.440473ms","start":"2026-10-15T19:48:53.269687Z","end":"2026-10-15T19:48:53.681128Z","steps":["trace[1117640429] 'process raft request' (duration: 121.774479ms)","trace[1117640429] 'compare' (duration: 287.463952ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:48:53.681307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:48:53.269647Z","time spent":"411.587355ms","remote":"127.0.0.1:57418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:49:42.063366Z","caller":"traceutil/trace.go:171","msg":"trace[763539253] transaction","detail":"{read_only:false; response_revision:26771; number_of_response:1; }","duration":"108.10856ms","start":"2026-10-15T19:49:41.955233Z","end":"2026-10-15T19:49:42.063341Z","steps":["trace[763539253] 'process raft request' (duration: 108.031058ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:53:05.790432Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25790} {"level":"info","ts":"2026-10-15T19:53:05.959255Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25790,"took":"164.131192ms","hash":2827690619,"current-db-size-bytes":56684544,"current-db-size":"57 MB","current-db-size-in-use-bytes":37031936,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-15T19:53:05.959322Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2827690619,"revision":25790,"compact-revision":22386} {"level":"info","ts":"2026-10-15T19:53:43.875114Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e4145bf1b1a19cd","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T19:53:43.88513Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-15T19:53:43.885236Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-15T19:58:05.797624Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28783} {"level":"info","ts":"2026-10-15T19:58:05.950206Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28783,"took":"148.455965ms","hash":1935184538,"current-db-size-bytes":56684544,"current-db-size":"57 MB","current-db-size-in-use-bytes":32235520,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T19:58:05.95028Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1935184538,"revision":28783,"compact-revision":25790} {"level":"info","ts":"2026-10-15T20:03:05.805282Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31054} {"level":"info","ts":"2026-10-15T20:03:05.939151Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31054,"took":"129.592424ms","hash":2728008375,"current-db-size-bytes":56684544,"current-db-size":"57 MB","current-db-size-in-use-bytes":31260672,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T20:03:05.939222Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2728008375,"revision":31054,"compact-revision":28783} {"level":"info","ts":"2026-10-15T20:08:05.81217Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33415} {"level":"info","ts":"2026-10-15T20:08:05.968712Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33415,"took":"149.283036ms","hash":3311036640,"current-db-size-bytes":56684544,"current-db-size":"57 MB","current-db-size-in-use-bytes":31903744,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T20:08:05.9688Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3311036640,"revision":33415,"compact-revision":31054} {"level":"info","ts":"2026-10-15T20:13:05.819334Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35781} {"level":"info","ts":"2026-10-15T20:13:05.977416Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35781,"took":"153.622321ms","hash":136841897,"current-db-size-bytes":56684544,"current-db-size":"57 MB","current-db-size-in-use-bytes":32526336,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T20:13:05.977539Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":136841897,"revision":35781,"compact-revision":33415} {"level":"info","ts":"2026-10-15T20:14:41.044979Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e4145bf1b1a19cd","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T20:14:41.051407Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-15T20:14:41.051504Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-15T20:18:05.826652Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38046} {"level":"info","ts":"2026-10-15T20:18:05.989434Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38046,"took":"154.083639ms","hash":2430439028,"current-db-size-bytes":57536512,"current-db-size":"58 MB","current-db-size-in-use-bytes":31879168,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T20:18:05.989612Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2430439028,"revision":38046,"compact-revision":35781}