{"level":"warn","ts":"2026-04-11T21:45:49.334399Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-11T21:45:49.334507Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.25:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.25:2380","--initial-cluster=instance=https://199.204.45.25:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.25:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.25: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-04-11T21:45:49.334587Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-11T21:45:49.334605Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-04-11T21:45:49.334622Z","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-04-11T21:45:49.335261Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"]} {"level":"info","ts":"2026-04-11T21:45:49.335351Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.25: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-04-11T21:45:49.338898Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.31042ms"} {"level":"info","ts":"2026-04-11T21:45:49.344126Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ba52c55cf4193752","cluster-id":"4468ed52294bc271"} {"level":"info","ts":"2026-04-11T21:45:49.344229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=()"} {"level":"info","ts":"2026-04-11T21:45:49.344254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 0"} {"level":"info","ts":"2026-04-11T21:45:49.344265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ba52c55cf4193752 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-11T21:45:49.344275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 1"} {"level":"info","ts":"2026-04-11T21:45:49.344318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"warn","ts":"2026-04-11T21:45:49.350263Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-11T21:45:49.353118Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-11T21:45:49.354521Z","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-04-11T21:45:49.357365Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ba52c55cf4193752","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-11T21:45:49.357602Z","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-04-11T21:45:49.357693Z","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-04-11T21:45:49.357708Z","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-04-11T21:45:49.357526Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ba52c55cf4193752","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-11T21:45:49.359982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"info","ts":"2026-04-11T21:45:49.360554Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","added-peer-id":"ba52c55cf4193752","added-peer-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-04-11T21:45:49.363163Z","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-04-11T21:45:49.363213Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-04-11T21:45:49.36327Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-04-11T21:45:49.363505Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ba52c55cf4193752","initial-advertise-peer-urls":["https://199.204.45.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-11T21:45:49.363566Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-11T21:45:49.845209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 is starting a new election at term 1"} {"level":"info","ts":"2026-04-11T21:45:49.84536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-11T21:45:49.845441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgPreVoteResp from ba52c55cf4193752 at term 1"} {"level":"info","ts":"2026-04-11T21:45:49.845531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became candidate at term 2"} {"level":"info","ts":"2026-04-11T21:45:49.845592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgVoteResp from ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-04-11T21:45:49.845662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became leader at term 2"} {"level":"info","ts":"2026-04-11T21:45:49.845712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ba52c55cf4193752 elected leader ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-04-11T21:45:49.846788Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T21:45:49.847782Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T21:45:49.847779Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ba52c55cf4193752","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.25:2379]}","request-path":"/0/members/ba52c55cf4193752/attributes","cluster-id":"4468ed52294bc271","publish-timeout":"7s"} {"level":"info","ts":"2026-04-11T21:45:49.847916Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T21:45:49.848066Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-11T21:45:49.848124Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-11T21:45:49.84852Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T21:45:49.848621Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T21:45:49.848656Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T21:45:49.851369Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-11T21:45:49.851572Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.25:2379"} {"level":"info","ts":"2026-04-11T21:46:00.720823Z","caller":"traceutil/trace.go:171","msg":"trace[387333518] linearizableReadLoop","detail":"{readStateIndex:371; appliedIndex:370; }","duration":"101.718161ms","start":"2026-04-11T21:46:00.619077Z","end":"2026-04-11T21:46:00.720795Z","steps":["trace[387333518] 'read index received' (duration: 101.562228ms)","trace[387333518] 'applied index is now lower than readState.Index' (duration: 154.813µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:46:00.720934Z","caller":"traceutil/trace.go:171","msg":"trace[1997524017] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"170.850561ms","start":"2026-04-11T21:46:00.550063Z","end":"2026-04-11T21:46:00.720913Z","steps":["trace[1997524017] 'process raft request' (duration: 170.568304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:46:00.721077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.960638ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T21:46:00.721219Z","caller":"traceutil/trace.go:171","msg":"trace[1708185960] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:356; }","duration":"102.132842ms","start":"2026-04-11T21:46:00.619067Z","end":"2026-04-11T21:46:00.7212Z","steps":["trace[1708185960] 'agreement among raft nodes before linearized reading' (duration: 101.860105ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:46:00.892911Z","caller":"traceutil/trace.go:171","msg":"trace[1995350168] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"160.41963ms","start":"2026-04-11T21:46:00.732459Z","end":"2026-04-11T21:46:00.892879Z","steps":["trace[1995350168] 'process raft request' (duration: 145.993844ms)","trace[1995350168] 'compare' (duration: 14.258572ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T21:46:25.955111Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986421786850632917,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-11T21:46:26.026257Z","caller":"traceutil/trace.go:171","msg":"trace[1351145153] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"574.227746ms","start":"2026-04-11T21:46:25.452005Z","end":"2026-04-11T21:46:26.026233Z","steps":["trace[1351145153] 'process raft request' (duration: 574.068022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:46:26.026805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:46:25.45198Z","time spent":"574.35354ms","remote":"127.0.0.1:47154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4424,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T21:46:26.046964Z","caller":"traceutil/trace.go:171","msg":"trace[1100496958] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:603; }","duration":"592.624905ms","start":"2026-04-11T21:46:25.45431Z","end":"2026-04-11T21:46:26.046935Z","steps":["trace[1100496958] 'read index received' (duration: 571.825437ms)","trace[1100496958] 'applied index is now lower than readState.Index' (duration: 20.798358ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:46:26.047048Z","caller":"traceutil/trace.go:171","msg":"trace[1170893138] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"461.377977ms","start":"2026-04-11T21:46:25.585639Z","end":"2026-04-11T21:46:26.047017Z","steps":["trace[1170893138] 'process raft request' (duration: 460.758461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:46:26.047108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.769789ms","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-04-11T21:46:26.047169Z","caller":"traceutil/trace.go:171","msg":"trace[444510231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:581; }","duration":"592.876921ms","start":"2026-04-11T21:46:25.454277Z","end":"2026-04-11T21:46:26.047154Z","steps":["trace[444510231] 'agreement among raft nodes before linearized reading' (duration: 592.749628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:46:26.047173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.386939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T21:46:26.047208Z","caller":"traceutil/trace.go:171","msg":"trace[693042064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"106.449331ms","start":"2026-04-11T21:46:25.940748Z","end":"2026-04-11T21:46:26.047197Z","steps":["trace[693042064] 'agreement among raft nodes before linearized reading' (duration: 106.40765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:46:26.04722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:46:25.585612Z","time spent":"461.514431ms","remote":"127.0.0.1:47064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T21:46:26.047208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:46:25.454265Z","time spent":"592.934033ms","remote":"127.0.0.1:47234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-11T21:46:26.047317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.90238ms","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-04-11T21:46:26.0474Z","caller":"traceutil/trace.go:171","msg":"trace[1062981103] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:581; }","duration":"221.041043ms","start":"2026-04-11T21:46:25.826339Z","end":"2026-04-11T21:46:26.04738Z","steps":["trace[1062981103] 'agreement among raft nodes before linearized reading' (duration: 220.846768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:46:26.047127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.078359ms","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-04-11T21:46:26.047484Z","caller":"traceutil/trace.go:171","msg":"trace[1436628942] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:581; }","duration":"414.472979ms","start":"2026-04-11T21:46:25.633001Z","end":"2026-04-11T21:46:26.047474Z","steps":["trace[1436628942] 'agreement among raft nodes before linearized reading' (duration: 414.08582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:46:26.047522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:46:25.632983Z","time spent":"414.53064ms","remote":"127.0.0.1:47234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-11T21:46:50.469383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.285671ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T21:46:50.469498Z","caller":"traceutil/trace.go:171","msg":"trace[2113868725] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"298.924163ms","start":"2026-04-11T21:46:50.17056Z","end":"2026-04-11T21:46:50.469484Z","steps":["trace[2113868725] 'process raft request' (duration: 138.101668ms)","trace[2113868725] 'compare' (duration: 159.908772ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:46:50.501635Z","caller":"traceutil/trace.go:171","msg":"trace[1135212407] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"329.454363ms","start":"2026-04-11T21:46:50.172149Z","end":"2026-04-11T21:46:50.501603Z","steps":["trace[1135212407] 'process raft request' (duration: 329.278179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:46:50.501797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:46:50.172135Z","time spent":"329.580726ms","remote":"127.0.0.1:47234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T21:46:50.501847Z","caller":"traceutil/trace.go:171","msg":"trace[1164444792] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"328.109019ms","start":"2026-04-11T21:46:50.173702Z","end":"2026-04-11T21:46:50.501811Z","steps":["trace[1164444792] 'process raft request' (duration: 327.858073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:46:50.502066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:46:50.173688Z","time spent":"328.259602ms","remote":"127.0.0.1:47234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T21:47:21.675141Z","caller":"traceutil/trace.go:171","msg":"trace[1974486461] linearizableReadLoop","detail":"{readStateIndex:1205; appliedIndex:1204; }","duration":"138.437806ms","start":"2026-04-11T21:47:21.536681Z","end":"2026-04-11T21:47:21.675118Z","steps":["trace[1974486461] 'read index received' (duration: 138.339263ms)","trace[1974486461] 'applied index is now lower than readState.Index' (duration: 97.623µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:47:21.675206Z","caller":"traceutil/trace.go:171","msg":"trace[933348617] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"189.98979ms","start":"2026-04-11T21:47:21.485183Z","end":"2026-04-11T21:47:21.675173Z","steps":["trace[933348617] 'process raft request' (duration: 189.790495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:47:21.675348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.632521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-11T21:47:21.67538Z","caller":"traceutil/trace.go:171","msg":"trace[2056425763] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1168; }","duration":"138.720793ms","start":"2026-04-11T21:47:21.536651Z","end":"2026-04-11T21:47:21.675372Z","steps":["trace[2056425763] 'agreement among raft nodes before linearized reading' (duration: 138.554609ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:47:45.148834Z","caller":"traceutil/trace.go:171","msg":"trace[287793107] transaction","detail":"{read_only:false; response_revision:1434; number_of_response:1; }","duration":"120.447168ms","start":"2026-04-11T21:47:45.028358Z","end":"2026-04-11T21:47:45.148806Z","steps":["trace[287793107] 'process raft request' (duration: 120.287214ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:48:10.436902Z","caller":"traceutil/trace.go:171","msg":"trace[993998017] linearizableReadLoop","detail":"{readStateIndex:1926; appliedIndex:1925; }","duration":"254.183448ms","start":"2026-04-11T21:48:10.182695Z","end":"2026-04-11T21:48:10.436878Z","steps":["trace[993998017] 'read index received' (duration: 254.016564ms)","trace[993998017] 'applied index is now lower than readState.Index' (duration: 163.214µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:48:10.437014Z","caller":"traceutil/trace.go:171","msg":"trace[204487111] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"318.204482ms","start":"2026-04-11T21:48:10.118768Z","end":"2026-04-11T21:48:10.436973Z","steps":["trace[204487111] 'process raft request' (duration: 317.944605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:48:10.437058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.348922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f5ea32f6-6701-4861-8f6f-aeb035507500\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-04-11T21:48:10.437109Z","caller":"traceutil/trace.go:171","msg":"trace[408218604] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f5ea32f6-6701-4861-8f6f-aeb035507500; range_end:; response_count:1; response_revision:1853; }","duration":"254.469494ms","start":"2026-04-11T21:48:10.182628Z","end":"2026-04-11T21:48:10.437097Z","steps":["trace[408218604] 'agreement among raft nodes before linearized reading' (duration: 254.354052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:48:10.437195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:48:10.118742Z","time spent":"318.361266ms","remote":"127.0.0.1:47158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T21:48:10.847498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.187111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-11T21:48:10.847629Z","caller":"traceutil/trace.go:171","msg":"trace[919481567] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1858; }","duration":"153.389036ms","start":"2026-04-11T21:48:10.694211Z","end":"2026-04-11T21:48:10.8476Z","steps":["trace[919481567] 'range keys from in-memory index tree' (duration: 152.935956ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:48:24.645075Z","caller":"traceutil/trace.go:171","msg":"trace[1398957039] transaction","detail":"{read_only:false; response_revision:1972; number_of_response:1; }","duration":"285.792016ms","start":"2026-04-11T21:48:24.359253Z","end":"2026-04-11T21:48:24.645045Z","steps":["trace[1398957039] 'process raft request' (duration: 285.600022ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:49:56.678886Z","caller":"traceutil/trace.go:171","msg":"trace[949874214] transaction","detail":"{read_only:false; response_revision:2655; number_of_response:1; }","duration":"133.209785ms","start":"2026-04-11T21:49:56.545644Z","end":"2026-04-11T21:49:56.678854Z","steps":["trace[949874214] 'process raft request' (duration: 133.011871ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:50:31.71528Z","caller":"traceutil/trace.go:171","msg":"trace[1233892297] linearizableReadLoop","detail":"{readStateIndex:2972; appliedIndex:2971; }","duration":"100.72314ms","start":"2026-04-11T21:50:31.614347Z","end":"2026-04-11T21:50:31.71507Z","steps":["trace[1233892297] 'read index received' (duration: 100.441973ms)","trace[1233892297] 'applied index is now lower than readState.Index' (duration: 280.387µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:50:31.715429Z","caller":"traceutil/trace.go:171","msg":"trace[878164476] transaction","detail":"{read_only:false; response_revision:2861; number_of_response:1; }","duration":"110.396013ms","start":"2026-04-11T21:50:31.604983Z","end":"2026-04-11T21:50:31.715379Z","steps":["trace[878164476] 'process raft request' (duration: 109.892601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:50:31.715482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.125949ms","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-04-11T21:50:31.715663Z","caller":"traceutil/trace.go:171","msg":"trace[1426282673] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2861; }","duration":"101.365495ms","start":"2026-04-11T21:50:31.614284Z","end":"2026-04-11T21:50:31.715649Z","steps":["trace[1426282673] 'agreement among raft nodes before linearized reading' (duration: 101.095758ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:54:30.460622Z","caller":"traceutil/trace.go:171","msg":"trace[215947549] transaction","detail":"{read_only:false; response_revision:4939; number_of_response:1; }","duration":"143.922533ms","start":"2026-04-11T21:54:30.316658Z","end":"2026-04-11T21:54:30.460581Z","steps":["trace[215947549] 'process raft request' (duration: 82.068136ms)","trace[215947549] 'compare' (duration: 61.52763ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:54:30.460656Z","caller":"traceutil/trace.go:171","msg":"trace[900807328] linearizableReadLoop","detail":"{readStateIndex:5132; appliedIndex:5128; }","duration":"141.5218ms","start":"2026-04-11T21:54:30.319107Z","end":"2026-04-11T21:54:30.460629Z","steps":["trace[900807328] 'read index received' (duration: 79.633553ms)","trace[900807328] 'applied index is now lower than readState.Index' (duration: 61.887167ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:54:30.460638Z","caller":"traceutil/trace.go:171","msg":"trace[294363515] transaction","detail":"{read_only:false; response_revision:4940; number_of_response:1; }","duration":"143.037593ms","start":"2026-04-11T21:54:30.317574Z","end":"2026-04-11T21:54:30.460612Z","steps":["trace[294363515] 'process raft request' (duration: 142.87288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:54:30.461019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.882628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8012e48e-3eda-4a47-bbf1-d3f4905dbe3a\" ","response":"range_response_count:1 size:4081"} {"level":"info","ts":"2026-04-11T21:54:30.461052Z","caller":"traceutil/trace.go:171","msg":"trace[1232280870] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8012e48e-3eda-4a47-bbf1-d3f4905dbe3a; range_end:; response_count:1; response_revision:4942; }","duration":"141.948931ms","start":"2026-04-11T21:54:30.319094Z","end":"2026-04-11T21:54:30.461043Z","steps":["trace[1232280870] 'agreement among raft nodes before linearized reading' (duration: 141.766407ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:54:30.461028Z","caller":"traceutil/trace.go:171","msg":"trace[2065821955] transaction","detail":"{read_only:false; response_revision:4941; number_of_response:1; }","duration":"143.361662ms","start":"2026-04-11T21:54:30.317635Z","end":"2026-04-11T21:54:30.460996Z","steps":["trace[2065821955] 'process raft request' (duration: 142.88414ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:54:30.461079Z","caller":"traceutil/trace.go:171","msg":"trace[1252598528] transaction","detail":"{read_only:false; response_revision:4942; number_of_response:1; }","duration":"142.586394ms","start":"2026-04-11T21:54:30.318426Z","end":"2026-04-11T21:54:30.461012Z","steps":["trace[1252598528] 'process raft request' (duration: 142.146213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:54:33.507374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.6573ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T21:54:33.507654Z","caller":"traceutil/trace.go:171","msg":"trace[1384469730] transaction","detail":"{read_only:false; response_revision:5079; number_of_response:1; }","duration":"588.738845ms","start":"2026-04-11T21:54:32.918891Z","end":"2026-04-11T21:54:33.50763Z","steps":["trace[1384469730] 'process raft request' (duration: 12.684437ms)","trace[1384469730] 'compare' (duration: 575.151339ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T21:54:33.507724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:54:32.918852Z","time spent":"588.838808ms","remote":"127.0.0.1:47234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T21:54:33.508252Z","caller":"traceutil/trace.go:171","msg":"trace[853965411] linearizableReadLoop","detail":"{readStateIndex:5275; appliedIndex:5273; }","duration":"568.827922ms","start":"2026-04-11T21:54:32.939379Z","end":"2026-04-11T21:54:33.508207Z","steps":["trace[853965411] 'read index received' (duration: 494.535525ms)","trace[853965411] 'applied index is now lower than readState.Index' (duration: 74.291057ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:54:33.508289Z","caller":"traceutil/trace.go:171","msg":"trace[276435750] transaction","detail":"{read_only:false; response_revision:5080; number_of_response:1; }","duration":"574.382432ms","start":"2026-04-11T21:54:32.933886Z","end":"2026-04-11T21:54:33.508268Z","steps":["trace[276435750] 'process raft request' (duration: 573.641206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:54:33.508412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.039616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T21:54:33.508446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:54:32.933866Z","time spent":"574.485385ms","remote":"127.0.0.1:47234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T21:54:33.508456Z","caller":"traceutil/trace.go:171","msg":"trace[1271732825] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5080; }","duration":"569.130778ms","start":"2026-04-11T21:54:32.939315Z","end":"2026-04-11T21:54:33.508446Z","steps":["trace[1271732825] 'agreement among raft nodes before linearized reading' (duration: 569.056246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:54:33.508483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:54:32.939295Z","time spent":"569.183809ms","remote":"127.0.0.1:46988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-11T21:54:33.508748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.447573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e4b55672-e8d0-44f8-8c4b-c0600054ef4a\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-04-11T21:54:33.508783Z","caller":"traceutil/trace.go:171","msg":"trace[853484265] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e4b55672-e8d0-44f8-8c4b-c0600054ef4a; range_end:; response_count:1; response_revision:5080; }","duration":"420.516715ms","start":"2026-04-11T21:54:33.088257Z","end":"2026-04-11T21:54:33.508774Z","steps":["trace[853484265] 'agreement among raft nodes before linearized reading' (duration: 420.408523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:54:33.5088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:54:33.088237Z","time spent":"420.559385ms","remote":"127.0.0.1:47158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3623,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e4b55672-e8d0-44f8-8c4b-c0600054ef4a\" "} {"level":"warn","ts":"2026-04-11T21:54:33.508918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.522209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:777426"} {"level":"info","ts":"2026-04-11T21:54:33.508978Z","caller":"traceutil/trace.go:171","msg":"trace[2000095614] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5080; }","duration":"565.610402ms","start":"2026-04-11T21:54:32.943354Z","end":"2026-04-11T21:54:33.508965Z","steps":["trace[2000095614] 'agreement among raft nodes before linearized reading' (duration: 565.038449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:54:33.509023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:54:32.943344Z","time spent":"565.668513ms","remote":"127.0.0.1:47064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":25,"response size":777449,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"warn","ts":"2026-04-11T21:54:33.50906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.244809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-6vs9w-tls\" ","response":"range_response_count:1 size:1966"} {"level":"info","ts":"2026-04-11T21:54:33.50917Z","caller":"traceutil/trace.go:171","msg":"trace[648451536] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-6vs9w-tls; range_end:; response_count:1; response_revision:5080; }","duration":"268.394693ms","start":"2026-04-11T21:54:33.240747Z","end":"2026-04-11T21:54:33.509141Z","steps":["trace[648451536] 'agreement among raft nodes before linearized reading' (duration: 267.695627ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:54:35.318805Z","caller":"traceutil/trace.go:171","msg":"trace[1503564308] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5100; }","duration":"116.019344ms","start":"2026-04-11T21:54:35.20276Z","end":"2026-04-11T21:54:35.318779Z","steps":["trace[1503564308] 'process raft request' (duration: 115.901061ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:55:07.276459Z","caller":"traceutil/trace.go:171","msg":"trace[901652239] linearizableReadLoop","detail":"{readStateIndex:5835; appliedIndex:5834; }","duration":"108.233067ms","start":"2026-04-11T21:55:07.168201Z","end":"2026-04-11T21:55:07.276434Z","steps":["trace[901652239] 'read index received' (duration: 108.140955ms)","trace[901652239] 'applied index is now lower than readState.Index' (duration: 91.172µs)"],"step_count":2} {"level":"warn","ts":"2026-04-11T21:55:07.276659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.437032ms","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-04-11T21:55:07.276702Z","caller":"traceutil/trace.go:171","msg":"trace[680339647] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5625; }","duration":"108.515694ms","start":"2026-04-11T21:55:07.168174Z","end":"2026-04-11T21:55:07.27669Z","steps":["trace[680339647] 'agreement among raft nodes before linearized reading' (duration: 108.364151ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:55:07.276514Z","caller":"traceutil/trace.go:171","msg":"trace[107935819] transaction","detail":"{read_only:false; response_revision:5625; number_of_response:1; }","duration":"110.922742ms","start":"2026-04-11T21:55:07.165564Z","end":"2026-04-11T21:55:07.276487Z","steps":["trace[107935819] 'process raft request' (duration: 110.736466ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:55:07.301269Z","caller":"traceutil/trace.go:171","msg":"trace[481741607] transaction","detail":"{read_only:false; response_revision:5626; number_of_response:1; }","duration":"133.000906ms","start":"2026-04-11T21:55:07.168248Z","end":"2026-04-11T21:55:07.301249Z","steps":["trace[481741607] 'process raft request' (duration: 132.884534ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:55:49.926796Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2965} {"level":"info","ts":"2026-04-11T21:55:49.998364Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2965,"took":"70.289194ms","hash":3794770229,"current-db-size-bytes":58216448,"current-db-size":"58 MB","current-db-size-in-use-bytes":33423360,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-11T21:55:49.998437Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3794770229,"revision":2965,"compact-revision":-1} {"level":"info","ts":"2026-04-11T22:00:49.933132Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6003} {"level":"info","ts":"2026-04-11T22:00:50.024944Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6003,"took":"89.936173ms","hash":919363739,"current-db-size-bytes":58216448,"current-db-size":"58 MB","current-db-size-in-use-bytes":21753856,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-11T22:00:50.025027Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":919363739,"revision":6003,"compact-revision":2965} {"level":"warn","ts":"2026-04-11T22:01:03.409218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.924146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T22:01:03.409357Z","caller":"traceutil/trace.go:171","msg":"trace[650638672] linearizableReadLoop","detail":"{readStateIndex:8615; appliedIndex:8614; }","duration":"340.397626ms","start":"2026-04-11T22:01:03.06894Z","end":"2026-04-11T22:01:03.409338Z","steps":["trace[650638672] 'read index received' (duration: 228.305616ms)","trace[650638672] 'applied index is now lower than readState.Index' (duration: 112.09037ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T22:01:03.409384Z","caller":"traceutil/trace.go:171","msg":"trace[123077756] transaction","detail":"{read_only:false; response_revision:8318; number_of_response:1; }","duration":"376.577204ms","start":"2026-04-11T22:01:03.032773Z","end":"2026-04-11T22:01:03.40935Z","steps":["trace[123077756] 'process raft request' (duration: 264.404413ms)","trace[123077756] 'compare' (duration: 111.68606ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T22:01:03.409538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.6072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-11T22:01:03.409537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T22:01:03.032754Z","time spent":"376.700346ms","remote":"127.0.0.1:47234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T22:01:03.409579Z","caller":"traceutil/trace.go:171","msg":"trace[754686883] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8318; }","duration":"340.684102ms","start":"2026-04-11T22:01:03.068885Z","end":"2026-04-11T22:01:03.409569Z","steps":["trace[754686883] 'agreement among raft nodes before linearized reading' (duration: 340.529058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T22:01:03.409613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T22:01:03.068857Z","time spent":"340.747874ms","remote":"127.0.0.1:47234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-11T22:01:03.471507Z","caller":"traceutil/trace.go:171","msg":"trace[2116656574] transaction","detail":"{read_only:false; response_revision:8319; number_of_response:1; }","duration":"172.81202ms","start":"2026-04-11T22:01:03.298659Z","end":"2026-04-11T22:01:03.471471Z","steps":["trace[2116656574] 'process raft request' (duration: 172.587945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T22:01:03.471639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.050694ms","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-04-11T22:01:03.471733Z","caller":"traceutil/trace.go:171","msg":"trace[1277178888] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8319; }","duration":"390.204558ms","start":"2026-04-11T22:01:03.081507Z","end":"2026-04-11T22:01:03.471712Z","steps":["trace[1277178888] 'agreement among raft nodes before linearized reading' (duration: 389.911171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T22:01:03.471789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T22:01:03.081486Z","time spent":"390.29353ms","remote":"127.0.0.1:47234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-11T22:01:03.471838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.793907ms","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-04-11T22:01:03.471963Z","caller":"traceutil/trace.go:171","msg":"trace[1445514992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8319; }","duration":"378.960621ms","start":"2026-04-11T22:01:03.092975Z","end":"2026-04-11T22:01:03.471935Z","steps":["trace[1445514992] 'agreement among raft nodes before linearized reading' (duration: 378.532441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T22:01:03.472044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T22:01:03.092961Z","time spent":"379.074674ms","remote":"127.0.0.1:47234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-11T22:01:43.144448Z","caller":"traceutil/trace.go:171","msg":"trace[680062796] linearizableReadLoop","detail":"{readStateIndex:8890; appliedIndex:8890; }","duration":"160.190842ms","start":"2026-04-11T22:01:42.984238Z","end":"2026-04-11T22:01:43.144429Z","steps":["trace[680062796] 'read index received' (duration: 160.185221ms)","trace[680062796] 'applied index is now lower than readState.Index' (duration: 4.751µs)"],"step_count":2} {"level":"warn","ts":"2026-04-11T22:01:43.144582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.326935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6h2pc\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-11T22:01:43.144616Z","caller":"traceutil/trace.go:171","msg":"trace[429350267] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6h2pc; range_end:; response_count:1; response_revision:8582; }","duration":"160.413397ms","start":"2026-04-11T22:01:42.984187Z","end":"2026-04-11T22:01:43.1446Z","steps":["trace[429350267] 'agreement among raft nodes before linearized reading' (duration: 160.329015ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T22:01:43.33404Z","caller":"traceutil/trace.go:171","msg":"trace[1462633450] transaction","detail":"{read_only:false; response_revision:8583; number_of_response:1; }","duration":"216.304622ms","start":"2026-04-11T22:01:43.11771Z","end":"2026-04-11T22:01:43.334014Z","steps":["trace[1462633450] 'process raft request' (duration: 165.24964ms)","trace[1462633450] 'compare' (duration: 50.796046ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T22:01:43.33434Z","caller":"traceutil/trace.go:171","msg":"trace[1447774491] linearizableReadLoop","detail":"{readStateIndex:8892; appliedIndex:8890; }","duration":"184.746913ms","start":"2026-04-11T22:01:43.149553Z","end":"2026-04-11T22:01:43.3343Z","steps":["trace[1447774491] 'read index received' (duration: 133.428456ms)","trace[1447774491] 'applied index is now lower than readState.Index' (duration: 51.315317ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T22:01:43.334431Z","caller":"traceutil/trace.go:171","msg":"trace[1945968170] transaction","detail":"{read_only:false; response_revision:8584; number_of_response:1; }","duration":"188.985553ms","start":"2026-04-11T22:01:43.145404Z","end":"2026-04-11T22:01:43.33439Z","steps":["trace[1945968170] 'process raft request' (duration: 188.551693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T22:01:43.334903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.330988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228878"} {"level":"info","ts":"2026-04-11T22:01:43.33496Z","caller":"traceutil/trace.go:171","msg":"trace[1186793480] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8584; }","duration":"185.43682ms","start":"2026-04-11T22:01:43.14951Z","end":"2026-04-11T22:01:43.334947Z","steps":["trace[1186793480] 'agreement among raft nodes before linearized reading' (duration: 184.923307ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T22:01:46.447518Z","caller":"traceutil/trace.go:171","msg":"trace[161594183] transaction","detail":"{read_only:false; response_revision:8599; number_of_response:1; }","duration":"108.829862ms","start":"2026-04-11T22:01:46.33866Z","end":"2026-04-11T22:01:46.44749Z","steps":["trace[161594183] 'process raft request' (duration: 108.646438ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T22:01:46.456971Z","caller":"traceutil/trace.go:171","msg":"trace[1894485096] transaction","detail":"{read_only:false; response_revision:8600; number_of_response:1; }","duration":"116.495874ms","start":"2026-04-11T22:01:46.34045Z","end":"2026-04-11T22:01:46.456946Z","steps":["trace[1894485096] 'process raft request' (duration: 116.012513ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T22:01:46.57159Z","caller":"traceutil/trace.go:171","msg":"trace[942072670] transaction","detail":"{read_only:false; response_revision:8601; number_of_response:1; }","duration":"109.846565ms","start":"2026-04-11T22:01:46.46171Z","end":"2026-04-11T22:01:46.571557Z","steps":["trace[942072670] 'process raft request' (duration: 53.321654ms)","trace[942072670] 'compare' (duration: 56.290186ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T22:01:55.878095Z","caller":"traceutil/trace.go:171","msg":"trace[1134992555] transaction","detail":"{read_only:false; response_revision:8691; number_of_response:1; }","duration":"107.781127ms","start":"2026-04-11T22:01:55.770273Z","end":"2026-04-11T22:01:55.878054Z","steps":["trace[1134992555] 'process raft request' (duration: 107.601713ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T22:01:56.074952Z","caller":"traceutil/trace.go:171","msg":"trace[2007666128] linearizableReadLoop","detail":"{readStateIndex:9005; appliedIndex:9004; }","duration":"185.285336ms","start":"2026-04-11T22:01:55.889634Z","end":"2026-04-11T22:01:56.07492Z","steps":["trace[2007666128] 'read index received' (duration: 125.380245ms)","trace[2007666128] 'applied index is now lower than readState.Index' (duration: 59.904071ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T22:01:56.075115Z","caller":"traceutil/trace.go:171","msg":"trace[1560862916] transaction","detail":"{read_only:false; response_revision:8692; number_of_response:1; }","duration":"299.902605ms","start":"2026-04-11T22:01:55.775197Z","end":"2026-04-11T22:01:56.0751Z","steps":["trace[1560862916] 'process raft request' (duration: 239.81264ms)","trace[1560862916] 'compare' (duration: 59.767048ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T22:01:56.075372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.579824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-11T22:01:56.075466Z","caller":"traceutil/trace.go:171","msg":"trace[1415413327] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8692; }","duration":"156.746207ms","start":"2026-04-11T22:01:55.918699Z","end":"2026-04-11T22:01:56.075446Z","steps":["trace[1415413327] 'agreement among raft nodes before linearized reading' (duration: 156.485051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T22:01:56.075629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.00999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T22:01:56.075831Z","caller":"traceutil/trace.go:171","msg":"trace[1500131085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8692; }","duration":"137.295258ms","start":"2026-04-11T22:01:55.938511Z","end":"2026-04-11T22:01:56.075806Z","steps":["trace[1500131085] 'agreement among raft nodes before linearized reading' (duration: 136.901998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T22:01:56.075666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.007554ms","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-04-11T22:01:56.076102Z","caller":"traceutil/trace.go:171","msg":"trace[264553411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8692; }","duration":"186.438803ms","start":"2026-04-11T22:01:55.8896Z","end":"2026-04-11T22:01:56.076039Z","steps":["trace[264553411] 'agreement among raft nodes before linearized reading' (duration: 185.940942ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T22:02:13.474532Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023dd.wal"} {"level":"info","ts":"2026-04-11T22:03:42.887502Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ba52c55cf4193752","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-11T22:03:42.89295Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-11T22:03:42.89303Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-11T22:05:49.94369Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8110} {"level":"info","ts":"2026-04-11T22:05:50.00559Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8110,"took":"60.183911ms","hash":3133346128,"current-db-size-bytes":58216448,"current-db-size":"58 MB","current-db-size-in-use-bytes":29220864,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-11T22:05:50.005666Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3133346128,"revision":8110,"compact-revision":6003} {"level":"info","ts":"2026-04-11T22:10:49.950463Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10640} {"level":"info","ts":"2026-04-11T22:10:50.038266Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10640,"took":"84.866514ms","hash":3975183859,"current-db-size-bytes":58216448,"current-db-size":"58 MB","current-db-size-in-use-bytes":21336064,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-11T22:10:50.038355Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3975183859,"revision":10640,"compact-revision":8110}