{"level":"warn","ts":"2026-04-09T01:56:39.659803Z","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-09T01:56:39.659897Z","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-09T01:56:39.660029Z","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-09T01:56:39.660046Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-04-09T01:56:39.660086Z","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-09T01:56:39.660974Z","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-09T01:56:39.661113Z","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":8,"max-cpu-available":8,"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-09T01:56:39.665255Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.844271ms"} {"level":"info","ts":"2026-04-09T01:56:39.670299Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ba52c55cf4193752","cluster-id":"4468ed52294bc271"} {"level":"info","ts":"2026-04-09T01:56:39.670359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T01:56:39.670382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 0"} {"level":"info","ts":"2026-04-09T01:56:39.670391Z","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-09T01:56:39.670398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 1"} {"level":"info","ts":"2026-04-09T01:56:39.670455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"warn","ts":"2026-04-09T01:56:39.676058Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T01:56:39.677209Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T01:56:39.679682Z","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-09T01:56:39.68195Z","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-09T01:56:39.682255Z","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-09T01:56:39.682302Z","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-09T01:56:39.682513Z","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-09T01:56:39.682551Z","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-09T01:56:39.684766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"info","ts":"2026-04-09T01:56:39.687835Z","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-09T01:56:39.688814Z","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-09T01:56:39.688905Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-04-09T01:56:39.688957Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-04-09T01:56:39.689084Z","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-09T01:56:39.689138Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T01:56:40.470615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T01:56:40.470674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T01:56:40.47069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgPreVoteResp from ba52c55cf4193752 at term 1"} {"level":"info","ts":"2026-04-09T01:56:40.470704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became candidate at term 2"} {"level":"info","ts":"2026-04-09T01:56:40.470712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgVoteResp from ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-04-09T01:56:40.47075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became leader at term 2"} {"level":"info","ts":"2026-04-09T01:56:40.47076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ba52c55cf4193752 elected leader ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-04-09T01:56:40.472648Z","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-09T01:56:40.472651Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T01:56:40.472684Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T01:56:40.472879Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T01:56:40.472939Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T01:56:40.472971Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T01:56:40.473336Z","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-09T01:56:40.473465Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T01:56:40.473508Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T01:56:40.476498Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.25:2379"} {"level":"info","ts":"2026-04-09T01:56:40.481964Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-09T01:57:16.050047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.116123ms","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-04-09T01:57:16.050158Z","caller":"traceutil/trace.go:171","msg":"trace[885274910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:613; }","duration":"154.240425ms","start":"2026-04-09T01:57:15.895901Z","end":"2026-04-09T01:57:16.050141Z","steps":["trace[885274910] 'range keys from in-memory index tree' (duration: 153.94009ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:57:16.156824Z","caller":"traceutil/trace.go:171","msg":"trace[525562003] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"176.764809ms","start":"2026-04-09T01:57:15.980032Z","end":"2026-04-09T01:57:16.156797Z","steps":["trace[525562003] 'read index received' (duration: 175.823424ms)","trace[525562003] 'applied index is now lower than readState.Index' (duration: 940.115µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:57:16.156864Z","caller":"traceutil/trace.go:171","msg":"trace[2138341136] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"204.652364ms","start":"2026-04-09T01:57:15.952185Z","end":"2026-04-09T01:57:16.156838Z","steps":["trace[2138341136] 'process raft request' (duration: 204.035194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:57:16.157028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.971412ms","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-09T01:57:16.157083Z","caller":"traceutil/trace.go:171","msg":"trace[235327657] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:614; }","duration":"177.061754ms","start":"2026-04-09T01:57:15.980003Z","end":"2026-04-09T01:57:16.157065Z","steps":["trace[235327657] 'agreement among raft nodes before linearized reading' (duration: 176.900882ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:57:16.174322Z","caller":"traceutil/trace.go:171","msg":"trace[1384077292] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"119.607004ms","start":"2026-04-09T01:57:16.054691Z","end":"2026-04-09T01:57:16.174298Z","steps":["trace[1384077292] 'process raft request' (duration: 119.520773ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:57:16.335205Z","caller":"traceutil/trace.go:171","msg":"trace[884588692] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:631; }","duration":"173.190206ms","start":"2026-04-09T01:57:16.161994Z","end":"2026-04-09T01:57:16.335184Z","steps":["trace[884588692] 'read index received' (duration: 12.167695ms)","trace[884588692] 'applied index is now lower than readState.Index' (duration: 161.021281ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T01:57:16.33537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.355729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T01:57:16.335518Z","caller":"traceutil/trace.go:171","msg":"trace[80630906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"173.592272ms","start":"2026-04-09T01:57:16.161912Z","end":"2026-04-09T01:57:16.335504Z","steps":["trace[80630906] 'agreement among raft nodes before linearized reading' (duration: 173.395969ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:57:16.336004Z","caller":"traceutil/trace.go:171","msg":"trace[881384987] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"174.447336ms","start":"2026-04-09T01:57:16.160812Z","end":"2026-04-09T01:57:16.33526Z","steps":["trace[881384987] 'process raft request' (duration: 174.231833ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:57:16.343654Z","caller":"traceutil/trace.go:171","msg":"trace[1146772678] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"129.670696ms","start":"2026-04-09T01:57:16.213966Z","end":"2026-04-09T01:57:16.343636Z","steps":["trace[1146772678] 'process raft request' (duration: 129.429972ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:57:16.702537Z","caller":"traceutil/trace.go:171","msg":"trace[428790204] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"345.385786ms","start":"2026-04-09T01:57:16.357123Z","end":"2026-04-09T01:57:16.702509Z","steps":["trace[428790204] 'process raft request' (duration: 333.997702ms)","trace[428790204] 'compare' (duration: 10.985937ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T01:57:16.703128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:57:16.3571Z","time spent":"345.506208ms","remote":"127.0.0.1:56370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T01:59:02.727853Z","caller":"traceutil/trace.go:171","msg":"trace[465527459] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"206.073029ms","start":"2026-04-09T01:59:02.52171Z","end":"2026-04-09T01:59:02.727783Z","steps":["trace[465527459] 'process raft request' (duration: 205.774183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:59:03.152163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.657434ms","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-04-09T01:59:03.152259Z","caller":"traceutil/trace.go:171","msg":"trace[1379894734] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1892; }","duration":"118.771717ms","start":"2026-04-09T01:59:03.033465Z","end":"2026-04-09T01:59:03.152237Z","steps":["trace[1379894734] 'range keys from in-memory index tree' (duration: 118.45667ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:59:31.067681Z","caller":"traceutil/trace.go:171","msg":"trace[1134364874] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"139.431112ms","start":"2026-04-09T01:59:30.928206Z","end":"2026-04-09T01:59:31.067637Z","steps":["trace[1134364874] 'process raft request' (duration: 139.072665ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:59:31.06789Z","caller":"traceutil/trace.go:171","msg":"trace[1104653679] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"138.657466ms","start":"2026-04-09T01:59:30.929216Z","end":"2026-04-09T01:59:31.067874Z","steps":["trace[1104653679] 'process raft request' (duration: 138.267629ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:59:31.067964Z","caller":"traceutil/trace.go:171","msg":"trace[1304430926] linearizableReadLoop","detail":"{readStateIndex:2170; appliedIndex:2168; }","duration":"115.426122ms","start":"2026-04-09T01:59:30.952378Z","end":"2026-04-09T01:59:31.067805Z","steps":["trace[1304430926] 'read index received' (duration: 114.932693ms)","trace[1304430926] 'applied index is now lower than readState.Index' (duration: 492.199µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T01:59:31.068068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.652576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T01:59:31.068172Z","caller":"traceutil/trace.go:171","msg":"trace[1684054852] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:2097; }","duration":"115.833079ms","start":"2026-04-09T01:59:30.952313Z","end":"2026-04-09T01:59:31.068146Z","steps":["trace[1684054852] 'agreement among raft nodes before linearized reading' (duration: 115.633766ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:59:36.917559Z","caller":"traceutil/trace.go:171","msg":"trace[1649115452] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"118.336663ms","start":"2026-04-09T01:59:36.7992Z","end":"2026-04-09T01:59:36.917537Z","steps":["trace[1649115452] 'process raft request' (duration: 118.20807ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:59:37.263387Z","caller":"traceutil/trace.go:171","msg":"trace[668880368] linearizableReadLoop","detail":"{readStateIndex:2203; appliedIndex:2201; }","duration":"146.484858ms","start":"2026-04-09T01:59:37.116881Z","end":"2026-04-09T01:59:37.263366Z","steps":["trace[668880368] 'read index received' (duration: 95.581722ms)","trace[668880368] 'applied index is now lower than readState.Index' (duration: 50.901926ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:59:37.263752Z","caller":"traceutil/trace.go:171","msg":"trace[2125415598] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"161.376698ms","start":"2026-04-09T01:59:37.102328Z","end":"2026-04-09T01:59:37.263705Z","steps":["trace[2125415598] 'process raft request' (duration: 110.097374ms)","trace[2125415598] 'compare' (duration: 50.711243ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:59:37.263795Z","caller":"traceutil/trace.go:171","msg":"trace[733730179] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"161.109902ms","start":"2026-04-09T01:59:37.102667Z","end":"2026-04-09T01:59:37.263777Z","steps":["trace[733730179] 'process raft request' (duration: 160.647474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:59:37.264028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.131321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-09T01:59:37.264109Z","caller":"traceutil/trace.go:171","msg":"trace[697392357] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2129; }","duration":"147.247462ms","start":"2026-04-09T01:59:37.116839Z","end":"2026-04-09T01:59:37.264087Z","steps":["trace[697392357] 'agreement among raft nodes before linearized reading' (duration: 147.010978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:59:37.264086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.502459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T01:59:37.264182Z","caller":"traceutil/trace.go:171","msg":"trace[1792505302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2129; }","duration":"101.656552ms","start":"2026-04-09T01:59:37.162503Z","end":"2026-04-09T01:59:37.264159Z","steps":["trace[1792505302] 'agreement among raft nodes before linearized reading' (duration: 101.505159ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:59:37.508745Z","caller":"traceutil/trace.go:171","msg":"trace[1767302292] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"171.47269ms","start":"2026-04-09T01:59:37.337232Z","end":"2026-04-09T01:59:37.508705Z","steps":["trace[1767302292] 'process raft request' (duration: 170.537732ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:01:15.723894Z","caller":"traceutil/trace.go:171","msg":"trace[992983340] transaction","detail":"{read_only:false; response_revision:2760; number_of_response:1; }","duration":"102.354437ms","start":"2026-04-09T02:01:15.621509Z","end":"2026-04-09T02:01:15.723863Z","steps":["trace[992983340] 'process raft request' (duration: 102.151665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:01:15.98201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.282884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:01:15.98213Z","caller":"traceutil/trace.go:171","msg":"trace[764126254] linearizableReadLoop","detail":"{readStateIndex:2858; appliedIndex:2857; }","duration":"197.114225ms","start":"2026-04-09T02:01:15.784993Z","end":"2026-04-09T02:01:15.982107Z","steps":["trace[764126254] 'read index received' (duration: 68.351384ms)","trace[764126254] 'applied index is now lower than readState.Index' (duration: 128.761371ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:01:15.982341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.347909ms","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":"info","ts":"2026-04-09T02:01:15.982268Z","caller":"traceutil/trace.go:171","msg":"trace[450232185] transaction","detail":"{read_only:false; response_revision:2761; number_of_response:1; }","duration":"200.608641ms","start":"2026-04-09T02:01:15.781621Z","end":"2026-04-09T02:01:15.982229Z","steps":["trace[450232185] 'process raft request' (duration: 71.7894ms)","trace[450232185] 'compare' (duration: 128.150182ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:01:15.982381Z","caller":"traceutil/trace.go:171","msg":"trace[228141461] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2761; }","duration":"197.416449ms","start":"2026-04-09T02:01:15.784953Z","end":"2026-04-09T02:01:15.982369Z","steps":["trace[228141461] 'agreement among raft nodes before linearized reading' (duration: 197.203816ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:18.248081Z","caller":"traceutil/trace.go:171","msg":"trace[846113304] linearizableReadLoop","detail":"{readStateIndex:4788; appliedIndex:4786; }","duration":"128.398164ms","start":"2026-04-09T02:05:18.119653Z","end":"2026-04-09T02:05:18.248051Z","steps":["trace[846113304] 'read index received' (duration: 64.620333ms)","trace[846113304] 'applied index is now lower than readState.Index' (duration: 63.776201ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:05:18.24824Z","caller":"traceutil/trace.go:171","msg":"trace[930004417] transaction","detail":"{read_only:false; response_revision:4616; number_of_response:1; }","duration":"130.056274ms","start":"2026-04-09T02:05:18.118171Z","end":"2026-04-09T02:05:18.248227Z","steps":["trace[930004417] 'process raft request' (duration: 129.653734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.248534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.943798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2643"} {"level":"info","ts":"2026-04-09T02:05:18.248696Z","caller":"traceutil/trace.go:171","msg":"trace[1010786475] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4616; }","duration":"129.149923ms","start":"2026-04-09T02:05:18.119533Z","end":"2026-04-09T02:05:18.248682Z","steps":["trace[1010786475] 'agreement among raft nodes before linearized reading' (duration: 128.865726ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:24.527624Z","caller":"traceutil/trace.go:171","msg":"trace[1933890796] transaction","detail":"{read_only:false; response_revision:4836; number_of_response:1; }","duration":"130.652102ms","start":"2026-04-09T02:05:24.396936Z","end":"2026-04-09T02:05:24.527588Z","steps":["trace[1933890796] 'process raft request' (duration: 130.418386ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:29.715954Z","caller":"traceutil/trace.go:171","msg":"trace[1285019211] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"122.389508ms","start":"2026-04-09T02:05:29.59349Z","end":"2026-04-09T02:05:29.71588Z","steps":["trace[1285019211] 'process raft request' (duration: 86.307716ms)","trace[1285019211] 'compare' (duration: 35.683203ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:05:38.968087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.055682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T02:05:38.968187Z","caller":"traceutil/trace.go:171","msg":"trace[459039448] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:5014; }","duration":"388.233506ms","start":"2026-04-09T02:05:38.579935Z","end":"2026-04-09T02:05:38.968168Z","steps":["trace[459039448] 'count revisions from in-memory index tree' (duration: 387.950529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:38.968237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:38.579911Z","time spent":"388.314217ms","remote":"127.0.0.1:56370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":32,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-04-09T02:05:38.968427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.502485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:38.968515Z","caller":"traceutil/trace.go:171","msg":"trace[704775395] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:5014; }","duration":"106.664988ms","start":"2026-04-09T02:05:38.861827Z","end":"2026-04-09T02:05:38.968492Z","steps":["trace[704775395] 'count revisions from in-memory index tree' (duration: 106.386602ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:40.602499Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2896} {"level":"info","ts":"2026-04-09T02:06:40.759029Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2896,"took":"154.666859ms","hash":1966082010,"current-db-size-bytes":53243904,"current-db-size":"53 MB","current-db-size-in-use-bytes":29409280,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-09T02:06:40.759158Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1966082010,"revision":2896,"compact-revision":-1} {"level":"info","ts":"2026-04-09T02:11:40.609247Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5550} {"level":"info","ts":"2026-04-09T02:11:40.689487Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5550,"took":"78.4653ms","hash":378796444,"current-db-size-bytes":53243904,"current-db-size":"53 MB","current-db-size-in-use-bytes":18628608,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-04-09T02:11:40.689562Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":378796444,"revision":5550,"compact-revision":2896}