{"level":"warn","ts":"2026-04-11T02:12:07.638346Z","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-11T02:12:07.63846Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.153: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.153:2380","--initial-cluster=instance=https://199.204.45.153:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.153:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.153: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-11T02:12:07.63855Z","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-11T02:12:07.638567Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-04-11T02:12:07.638644Z","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-11T02:12:07.639422Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"]} {"level":"info","ts":"2026-04-11T02:12:07.639586Z","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.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.153: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-11T02:12:07.644158Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.32537ms"} {"level":"info","ts":"2026-04-11T02:12:07.653321Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"355195d5aae85944","cluster-id":"e424441517bc0b32"} {"level":"info","ts":"2026-04-11T02:12:07.653517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=()"} {"level":"info","ts":"2026-04-11T02:12:07.653551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 0"} {"level":"info","ts":"2026-04-11T02:12:07.653608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 355195d5aae85944 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-11T02:12:07.653617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 1"} {"level":"info","ts":"2026-04-11T02:12:07.65368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"warn","ts":"2026-04-11T02:12:07.665602Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-11T02:12:07.667615Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-11T02:12:07.668879Z","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-11T02:12:07.671084Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"355195d5aae85944","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-11T02:12:07.671297Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"355195d5aae85944","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-11T02:12:07.671396Z","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-11T02:12:07.671524Z","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-11T02:12:07.67156Z","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-11T02:12:07.672796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"info","ts":"2026-04-11T02:12:07.673238Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","added-peer-id":"355195d5aae85944","added-peer-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-04-11T02:12:07.675549Z","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-11T02:12:07.675674Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-04-11T02:12:07.675745Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-04-11T02:12:07.675886Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"355195d5aae85944","initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-11T02:12:07.67595Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-11T02:12:07.855723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 is starting a new election at term 1"} {"level":"info","ts":"2026-04-11T02:12:07.855779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-11T02:12:07.855812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgPreVoteResp from 355195d5aae85944 at term 1"} {"level":"info","ts":"2026-04-11T02:12:07.855839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became candidate at term 2"} {"level":"info","ts":"2026-04-11T02:12:07.855856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgVoteResp from 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-04-11T02:12:07.855878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became leader at term 2"} {"level":"info","ts":"2026-04-11T02:12:07.855896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 355195d5aae85944 elected leader 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-04-11T02:12:07.857012Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T02:12:07.857889Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"355195d5aae85944","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.153:2379]}","request-path":"/0/members/355195d5aae85944/attributes","cluster-id":"e424441517bc0b32","publish-timeout":"7s"} {"level":"info","ts":"2026-04-11T02:12:07.857938Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T02:12:07.857977Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T02:12:07.85833Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-11T02:12:07.858415Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-11T02:12:07.858705Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T02:12:07.858808Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T02:12:07.858849Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T02:12:07.861264Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-11T02:12:07.861739Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.153:2379"} {"level":"info","ts":"2026-04-11T02:12:22.757683Z","caller":"traceutil/trace.go:171","msg":"trace[2106618336] linearizableReadLoop","detail":"{readStateIndex:379; appliedIndex:378; }","duration":"196.796977ms","start":"2026-04-11T02:12:22.56085Z","end":"2026-04-11T02:12:22.757647Z","steps":["trace[2106618336] 'read index received' (duration: 196.44223ms)","trace[2106618336] 'applied index is now lower than readState.Index' (duration: 352.987µs)"],"step_count":2} {"level":"warn","ts":"2026-04-11T02:12:22.757912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.003231ms","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-11T02:12:22.757978Z","caller":"traceutil/trace.go:171","msg":"trace[1649113925] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:362; }","duration":"197.137423ms","start":"2026-04-11T02:12:22.56083Z","end":"2026-04-11T02:12:22.757968Z","steps":["trace[1649113925] 'agreement among raft nodes before linearized reading' (duration: 196.97969ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:12:22.75806Z","caller":"traceutil/trace.go:171","msg":"trace[559471446] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"203.941978ms","start":"2026-04-11T02:12:22.554091Z","end":"2026-04-11T02:12:22.758032Z","steps":["trace[559471446] 'process raft request' (duration: 203.359077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:12:23.102053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.932886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:12:23.102175Z","caller":"traceutil/trace.go:171","msg":"trace[1264202931] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:363; }","duration":"205.12639ms","start":"2026-04-11T02:12:22.897022Z","end":"2026-04-11T02:12:23.102148Z","steps":["trace[1264202931] 'range keys from in-memory index tree' (duration: 204.732752ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:12:45.642757Z","caller":"traceutil/trace.go:171","msg":"trace[435265538] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"157.931622ms","start":"2026-04-11T02:12:45.484777Z","end":"2026-04-11T02:12:45.642709Z","steps":["trace[435265538] 'process raft request' (duration: 157.707528ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:12:45.678322Z","caller":"traceutil/trace.go:171","msg":"trace[471016829] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"153.288163ms","start":"2026-04-11T02:12:45.524997Z","end":"2026-04-11T02:12:45.678285Z","steps":["trace[471016829] 'process raft request' (duration: 152.59893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:12:46.468098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.298894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T02:12:46.468148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.234486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-11T02:12:46.468157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.532933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-11T02:12:46.468231Z","caller":"traceutil/trace.go:171","msg":"trace[1028735264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:580; }","duration":"567.341418ms","start":"2026-04-11T02:12:45.900869Z","end":"2026-04-11T02:12:46.468211Z","steps":["trace[1028735264] 'range keys from in-memory index tree' (duration: 567.060423ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:12:46.468247Z","caller":"traceutil/trace.go:171","msg":"trace[1025457473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"571.495888ms","start":"2026-04-11T02:12:45.896709Z","end":"2026-04-11T02:12:46.468205Z","steps":["trace[1025457473] 'range keys from in-memory index tree' (duration: 571.13422ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:12:46.468266Z","caller":"traceutil/trace.go:171","msg":"trace[1062624810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:580; }","duration":"565.662846ms","start":"2026-04-11T02:12:45.90258Z","end":"2026-04-11T02:12:46.468243Z","steps":["trace[1062624810] 'range keys from in-memory index tree' (duration: 565.389981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:12:46.468267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:12:45.90086Z","time spent":"567.400209ms","remote":"127.0.0.1:59326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-11T02:12:46.468303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:12:45.896687Z","time spent":"571.598769ms","remote":"127.0.0.1:59082","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-11T02:12:46.468319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:12:45.902574Z","time spent":"565.736187ms","remote":"127.0.0.1:59326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-11T02:12:46.468448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.489501ms","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-11T02:12:46.469289Z","caller":"traceutil/trace.go:171","msg":"trace[1600671492] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"394.655123ms","start":"2026-04-11T02:12:46.074584Z","end":"2026-04-11T02:12:46.469239Z","steps":["trace[1600671492] 'range keys from in-memory index tree' (duration: 393.201295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:12:46.469562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:12:46.07456Z","time spent":"394.952598ms","remote":"127.0.0.1:59326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-11T02:12:46.470777Z","caller":"traceutil/trace.go:171","msg":"trace[655167997] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"277.724465ms","start":"2026-04-11T02:12:46.193008Z","end":"2026-04-11T02:12:46.470733Z","steps":["trace[655167997] 'process raft request' (duration: 276.41528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:39.256809Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439316438282073,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T02:13:39.757291Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439316438282073,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T02:13:40.258389Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439316438282073,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T02:13:40.759053Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439316438282073,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T02:13:40.896164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999991258s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-11T02:13:40.89622Z","caller":"traceutil/trace.go:171","msg":"trace[4404714] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000092339s","start":"2026-04-11T02:13:38.896115Z","end":"2026-04-11T02:13:40.896208Z","steps":["trace[4404714] 'agreement among raft nodes before linearized reading' (duration: 2.000024398s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:40.896249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:38.896093Z","time spent":"2.000150641s","remote":"127.0.0.1:59082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-11T02:13:41.260123Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439316438282073,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T02:13:41.760969Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439316438282073,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T02:13:42.261169Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439316438282073,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T02:13:42.689255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000045298s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-11T02:13:42.689337Z","caller":"traceutil/trace.go:171","msg":"trace[2005428108] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000171591s","start":"2026-04-11T02:13:40.689146Z","end":"2026-04-11T02:13:42.689318Z","steps":["trace[2005428108] 'agreement among raft nodes before linearized reading' (duration: 2.000075759s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:42.689383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:40.689125Z","time spent":"2.000249692s","remote":"127.0.0.1:59082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/11 02:13:42 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-11T02:13:42.761683Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439316438282073,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T02:13:42.812772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:37.81376Z","time spent":"4.999003153s","remote":"127.0.0.1:59326","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/04/11 02:13:42 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-11T02:13:42.897577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000547148s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-11T02:13:42.897667Z","caller":"traceutil/trace.go:171","msg":"trace[615324212] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00068397s","start":"2026-04-11T02:13:40.896963Z","end":"2026-04-11T02:13:42.897647Z","steps":["trace[615324212] 'agreement among raft nodes before linearized reading' (duration: 2.000564497s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:42.897714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:40.896953Z","time spent":"2.000753081s","remote":"127.0.0.1:59096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-11T02:13:43.174297Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"5.360383099s","expected-duration":"1s"} {"level":"info","ts":"2026-04-11T02:13:43.178753Z","caller":"traceutil/trace.go:171","msg":"trace[1725026709] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"4.338354491s","start":"2026-04-11T02:13:38.840352Z","end":"2026-04-11T02:13:43.178706Z","steps":["trace[1725026709] 'process raft request' (duration: 4.338128706s)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:13:43.178776Z","caller":"traceutil/trace.go:171","msg":"trace[1106093514] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"1.913223026s","start":"2026-04-11T02:13:41.265524Z","end":"2026-04-11T02:13:43.178747Z","steps":["trace[1106093514] 'process raft request' (duration: 1.913101334s)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:13:43.178801Z","caller":"traceutil/trace.go:171","msg":"trace[2064633558] linearizableReadLoop","detail":"{readStateIndex:1204; appliedIndex:1200; }","duration":"4.422121814s","start":"2026-04-11T02:13:38.756658Z","end":"2026-04-11T02:13:43.17878Z","steps":["trace[2064633558] 'read index received' (duration: 4.418001315s)","trace[2064633558] 'applied index is now lower than readState.Index' (duration: 4.119299ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T02:13:43.178981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.422304608s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-11T02:13:43.179028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.422331138s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-11T02:13:43.17903Z","caller":"traceutil/trace.go:171","msg":"trace[1071041711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1167; }","duration":"4.422410599s","start":"2026-04-11T02:13:38.75661Z","end":"2026-04-11T02:13:43.179021Z","steps":["trace[1071041711] 'agreement among raft nodes before linearized reading' (duration: 4.422231566s)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:13:43.178927Z","caller":"traceutil/trace.go:171","msg":"trace[1126778956] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"486.074703ms","start":"2026-04-11T02:13:42.69282Z","end":"2026-04-11T02:13:43.178895Z","steps":["trace[1126778956] 'process raft request' (duration: 485.869829ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:13:43.179059Z","caller":"traceutil/trace.go:171","msg":"trace[1328833075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1167; }","duration":"4.42240477s","start":"2026-04-11T02:13:38.756645Z","end":"2026-04-11T02:13:43.17905Z","steps":["trace[1328833075] 'agreement among raft nodes before linearized reading' (duration: 4.422292348s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.179069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:38.75659Z","time spent":"4.422468971s","remote":"127.0.0.1:59326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-11T02:13:43.179087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:38.756619Z","time spent":"4.422461061s","remote":"127.0.0.1:59326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-11T02:13:43.179211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.333886378s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-11T02:13:43.179203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.435636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:13:43.179245Z","caller":"traceutil/trace.go:171","msg":"trace[163331071] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1167; }","duration":"2.333949038s","start":"2026-04-11T02:13:40.845288Z","end":"2026-04-11T02:13:43.179237Z","steps":["trace[163331071] 'agreement among raft nodes before linearized reading' (duration: 2.333884937s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.179279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:40.845272Z","time spent":"2.33399524s","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-11T02:13:43.179278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.461037822s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-11T02:13:43.179298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.957779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-04-11T02:13:43.179351Z","caller":"traceutil/trace.go:171","msg":"trace[1993904900] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1167; }","duration":"1.461179725s","start":"2026-04-11T02:13:41.718153Z","end":"2026-04-11T02:13:43.179332Z","steps":["trace[1993904900] 'agreement among raft nodes before linearized reading' (duration: 1.461006782s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.179345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.881175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-11T02:13:43.179387Z","caller":"traceutil/trace.go:171","msg":"trace[696278616] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:1167; }","duration":"175.118202ms","start":"2026-04-11T02:13:43.004244Z","end":"2026-04-11T02:13:43.179362Z","steps":["trace[696278616] 'agreement among raft nodes before linearized reading' (duration: 174.895818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.179392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:41.71813Z","time spent":"1.461254906s","remote":"127.0.0.1:59122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":37,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-04-11T02:13:43.179412Z","caller":"traceutil/trace.go:171","msg":"trace[1839698912] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1167; }","duration":"120.16349ms","start":"2026-04-11T02:13:43.059224Z","end":"2026-04-11T02:13:43.179388Z","steps":["trace[1839698912] 'agreement among raft nodes before linearized reading' (duration: 119.885875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.179412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.978067445s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-11T02:13:43.179438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.475498538s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:13:43.179471Z","caller":"traceutil/trace.go:171","msg":"trace[18270793] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1167; }","duration":"3.978165267s","start":"2026-04-11T02:13:39.20129Z","end":"2026-04-11T02:13:43.179455Z","steps":["trace[18270793] 'agreement among raft nodes before linearized reading' (duration: 3.977923102s)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:13:43.179478Z","caller":"traceutil/trace.go:171","msg":"trace[1129808299] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1167; }","duration":"2.47559766s","start":"2026-04-11T02:13:40.703868Z","end":"2026-04-11T02:13:43.179465Z","steps":["trace[1129808299] 'agreement among raft nodes before linearized reading' (duration: 2.475501298s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.179459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16569159s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T02:13:43.1795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:39.201275Z","time spent":"3.978218758s","remote":"127.0.0.1:59326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-11T02:13:43.179501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:40.703836Z","time spent":"2.475658351s","remote":"127.0.0.1:59364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-11T02:13:43.179507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.296699063s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-11T02:13:43.179522Z","caller":"traceutil/trace.go:171","msg":"trace[737046549] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1167; }","duration":"1.165804301s","start":"2026-04-11T02:13:42.0137Z","end":"2026-04-11T02:13:43.179504Z","steps":["trace[737046549] 'agreement among raft nodes before linearized reading' (duration: 1.165648679s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.179489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.605825313s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:13:43.179562Z","caller":"traceutil/trace.go:171","msg":"trace[331599568] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1167; }","duration":"4.296795715s","start":"2026-04-11T02:13:38.882753Z","end":"2026-04-11T02:13:43.179549Z","steps":["trace[331599568] 'agreement among raft nodes before linearized reading' (duration: 4.296669503s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.179576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:42.013678Z","time spent":"1.165887274s","remote":"127.0.0.1:59212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-04-11T02:13:43.179591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:38.882735Z","time spent":"4.296849916s","remote":"127.0.0.1:59196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":7,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-04-11T02:13:43.179606Z","caller":"traceutil/trace.go:171","msg":"trace[613879075] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1167; }","duration":"1.606018037s","start":"2026-04-11T02:13:41.573561Z","end":"2026-04-11T02:13:43.179579Z","steps":["trace[613879075] 'agreement among raft nodes before linearized reading' (duration: 1.605738142s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.179625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.581081218s","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":"warn","ts":"2026-04-11T02:13:43.179652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:41.573539Z","time spent":"1.606104418s","remote":"127.0.0.1:59518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-04-11T02:13:43.179667Z","caller":"traceutil/trace.go:171","msg":"trace[1019048519] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1167; }","duration":"3.581146759s","start":"2026-04-11T02:13:39.598495Z","end":"2026-04-11T02:13:43.179641Z","steps":["trace[1019048519] 'agreement among raft nodes before linearized reading' (duration: 3.581105728s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.179692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:39.598472Z","time spent":"3.58121511s","remote":"127.0.0.1:59284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-04-11T02:13:43.179695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.42294013s","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-11T02:13:43.179755Z","caller":"traceutil/trace.go:171","msg":"trace[1384932810] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1167; }","duration":"4.423037422s","start":"2026-04-11T02:13:38.756697Z","end":"2026-04-11T02:13:43.179734Z","steps":["trace[1384932810] 'agreement among raft nodes before linearized reading' (duration: 4.422784687s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.179781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:38.756673Z","time spent":"4.423101973s","remote":"127.0.0.1:59326","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":"info","ts":"2026-04-11T02:13:43.179303Z","caller":"traceutil/trace.go:171","msg":"trace[601411763] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1167; }","duration":"270.585779ms","start":"2026-04-11T02:13:42.908691Z","end":"2026-04-11T02:13:43.179276Z","steps":["trace[601411763] 'agreement among raft nodes before linearized reading' (duration: 270.407576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.180056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:41.265489Z","time spent":"1.913365319s","remote":"127.0.0.1:59326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T02:13:43.180104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.8392489s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.153\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-11T02:13:43.180144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:38.840313Z","time spent":"4.338529664s","remote":"127.0.0.1:59326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T02:13:43.180136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:42.692797Z","time spent":"486.281638ms","remote":"127.0.0.1:59160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T02:13:43.18019Z","caller":"traceutil/trace.go:171","msg":"trace[727786547] range","detail":"{range_begin:/registry/masterleases/199.204.45.153; range_end:; response_count:1; response_revision:1167; }","duration":"1.839382303s","start":"2026-04-11T02:13:41.340788Z","end":"2026-04-11T02:13:43.18017Z","steps":["trace[727786547] 'agreement among raft nodes before linearized reading' (duration: 1.838280982s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.180286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:41.340765Z","time spent":"1.839513175s","remote":"127.0.0.1:59104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.153\" "} {"level":"warn","ts":"2026-04-11T02:13:43.180359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.18734243s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-11T02:13:43.180406Z","caller":"traceutil/trace.go:171","msg":"trace[1631162167] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1167; }","duration":"4.187401251s","start":"2026-04-11T02:13:38.99299Z","end":"2026-04-11T02:13:43.180391Z","steps":["trace[1631162167] 'agreement among raft nodes before linearized reading' (duration: 4.186466754s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.180433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:38.992947Z","time spent":"4.187478142s","remote":"127.0.0.1:59174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":12,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-04-11T02:13:43.463782Z","caller":"traceutil/trace.go:171","msg":"trace[1925576686] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"278.689873ms","start":"2026-04-11T02:13:43.185064Z","end":"2026-04-11T02:13:43.463754Z","steps":["trace[1925576686] 'process raft request' (duration: 259.823923ms)","trace[1925576686] 'compare' (duration: 18.561325ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:13:43.464196Z","caller":"traceutil/trace.go:171","msg":"trace[774227109] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"278.357348ms","start":"2026-04-11T02:13:43.185818Z","end":"2026-04-11T02:13:43.464175Z","steps":["trace[774227109] 'process raft request' (duration: 278.207305ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:13:43.464275Z","caller":"traceutil/trace.go:171","msg":"trace[753504894] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"278.42168ms","start":"2026-04-11T02:13:43.18583Z","end":"2026-04-11T02:13:43.464252Z","steps":["trace[753504894] 'process raft request' (duration: 278.301967ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:13:43.464197Z","caller":"traceutil/trace.go:171","msg":"trace[742433503] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"278.423399ms","start":"2026-04-11T02:13:43.185745Z","end":"2026-04-11T02:13:43.464168Z","steps":["trace[742433503] 'process raft request' (duration: 277.959929ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:13:43.470823Z","caller":"traceutil/trace.go:171","msg":"trace[686486875] linearizableReadLoop","detail":"{readStateIndex:1211; appliedIndex:1206; }","duration":"280.365946ms","start":"2026-04-11T02:13:43.190237Z","end":"2026-04-11T02:13:43.470603Z","steps":["trace[686486875] 'read index received' (duration: 254.668324ms)","trace[686486875] 'applied index is now lower than readState.Index' (duration: 25.695922ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:13:43.470869Z","caller":"traceutil/trace.go:171","msg":"trace[848576213] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"284.601537ms","start":"2026-04-11T02:13:43.186236Z","end":"2026-04-11T02:13:43.470837Z","steps":["trace[848576213] 'process raft request' (duration: 283.527867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.471171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.906896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:2897"} {"level":"info","ts":"2026-04-11T02:13:43.471231Z","caller":"traceutil/trace.go:171","msg":"trace[148994563] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1173; }","duration":"281.003638ms","start":"2026-04-11T02:13:43.190212Z","end":"2026-04-11T02:13:43.471216Z","steps":["trace[148994563] 'agreement among raft nodes before linearized reading' (duration: 280.748764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:09.443626Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439316438282663,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T02:14:09.944631Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439316438282663,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T02:14:10.445146Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439316438282663,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T02:14:10.946358Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439316438282663,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T02:14:11.446882Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439316438282663,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T02:14:11.947582Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439316438282663,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T02:14:12.447932Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439316438282663,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T02:14:12.690234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000663339s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-11T02:14:12.690359Z","caller":"traceutil/trace.go:171","msg":"trace[1586657133] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000861623s","start":"2026-04-11T02:14:10.689469Z","end":"2026-04-11T02:14:12.690331Z","steps":["trace[1586657133] 'agreement among raft nodes before linearized reading' (duration: 2.00070007s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:12.690433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:10.689445Z","time spent":"2.000977116s","remote":"127.0.0.1:59082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/11 02:14:12 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-11T02:14:12.897518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000531466s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-11T02:14:12.897784Z","caller":"traceutil/trace.go:171","msg":"trace[96565154] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000869284s","start":"2026-04-11T02:14:10.896884Z","end":"2026-04-11T02:14:12.897754Z","steps":["trace[96565154] 'agreement among raft nodes before linearized reading' (duration: 2.000562797s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:12.898044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:10.896864Z","time spent":"2.001105498s","remote":"127.0.0.1:59096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/11 02:14:12 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-11T02:14:12.94908Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439316438282663,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T02:14:13.449302Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439316438282663,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T02:14:13.942169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999054735s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-11T02:14:13.942253Z","caller":"traceutil/trace.go:171","msg":"trace[1655948525] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.999182387s","start":"2026-04-11T02:14:08.943051Z","end":"2026-04-11T02:14:13.942233Z","steps":["trace[1655948525] 'agreement among raft nodes before linearized reading' (duration: 4.999084556s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:13.942301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:08.943033Z","time spent":"4.999255558s","remote":"127.0.0.1:59326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/04/11 02:14:13 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-11T02:14:13.950372Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439316438282663,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T02:14:14.278105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:09.280123Z","time spent":"4.997975014s","remote":"127.0.0.1:59326","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/04/11 02:14:14 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-11T02:14:14.45154Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439316438282663,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T02:14:14.601784Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"5.678620721s","expected-duration":"1s"} {"level":"info","ts":"2026-04-11T02:14:14.602332Z","caller":"traceutil/trace.go:171","msg":"trace[550477376] transaction","detail":"{read_only:false; response_revision:1467; number_of_response:1; }","duration":"5.679264613s","start":"2026-04-11T02:14:08.92304Z","end":"2026-04-11T02:14:14.602304Z","steps":["trace[550477376] 'process raft request' (duration: 5.679065179s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:14.602569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:08.923023Z","time spent":"5.679386876s","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T02:14:14.672855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.000101755s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-11T02:14:14.672935Z","caller":"traceutil/trace.go:171","msg":"trace[2089161041] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"5.000210137s","start":"2026-04-11T02:14:09.672707Z","end":"2026-04-11T02:14:14.672917Z","steps":["trace[2089161041] 'agreement among raft nodes before linearized reading' (duration: 5.000121225s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:14.672976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:09.672696Z","time spent":"5.000271739s","remote":"127.0.0.1:59326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/04/11 02:14:14 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-11T02:14:14.691057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999653787s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-11T02:14:14.691115Z","caller":"traceutil/trace.go:171","msg":"trace[1917505574] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999743579s","start":"2026-04-11T02:14:09.691359Z","end":"2026-04-11T02:14:14.691102Z","steps":["trace[1917505574] 'agreement among raft nodes before linearized reading' (duration: 4.999678597s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:14.691152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:09.691345Z","time spent":"4.999798389s","remote":"127.0.0.1:59326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-11T02:14:14.905648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00009927s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-11T02:14:14.905764Z","caller":"traceutil/trace.go:171","msg":"trace[1489079881] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000305133s","start":"2026-04-11T02:14:12.905437Z","end":"2026-04-11T02:14:14.905742Z","steps":["trace[1489079881] 'agreement among raft nodes before linearized reading' (duration: 2.00014697s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:14.905821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:12.905412Z","time spent":"2.000399215s","remote":"127.0.0.1:59082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/11 02:14:14 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-04-11T02:14:15.218373Z","caller":"traceutil/trace.go:171","msg":"trace[994004651] linearizableReadLoop","detail":"{readStateIndex:1520; appliedIndex:1516; }","duration":"6.275239729s","start":"2026-04-11T02:14:08.9431Z","end":"2026-04-11T02:14:15.21834Z","steps":["trace[994004651] 'read index received' (duration: 5.658954455s)","trace[994004651] 'applied index is now lower than readState.Index' (duration: 616.283794ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:14:15.218535Z","caller":"traceutil/trace.go:171","msg":"trace[1075596255] transaction","detail":"{read_only:false; response_revision:1468; number_of_response:1; }","duration":"6.109900465s","start":"2026-04-11T02:14:09.108623Z","end":"2026-04-11T02:14:15.218524Z","steps":["trace[1075596255] 'process raft request' (duration: 6.109401755s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:15.218665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:09.108594Z","time spent":"6.109973786s","remote":"127.0.0.1:59160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T02:14:15.218705Z","caller":"traceutil/trace.go:171","msg":"trace[331385121] transaction","detail":"{read_only:false; response_revision:1470; number_of_response:1; }","duration":"1.715918031s","start":"2026-04-11T02:14:13.502762Z","end":"2026-04-11T02:14:15.21868Z","steps":["trace[331385121] 'process raft request' (duration: 1.715529523s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:15.218932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:13.502731Z","time spent":"1.716130194s","remote":"127.0.0.1:59326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T02:14:15.318879Z","caller":"traceutil/trace.go:171","msg":"trace[1835092328] transaction","detail":"{read_only:false; response_revision:1471; number_of_response:1; }","duration":"710.742052ms","start":"2026-04-11T02:14:14.608115Z","end":"2026-04-11T02:14:15.318857Z","steps":["trace[1835092328] 'process raft request' (duration: 710.537678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:15.319045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:14.608089Z","time spent":"710.863535ms","remote":"127.0.0.1:59248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1165,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T02:14:15.319071Z","caller":"traceutil/trace.go:171","msg":"trace[2112728569] linearizableReadLoop","detail":"{readStateIndex:1522; appliedIndex:1520; }","duration":"100.563815ms","start":"2026-04-11T02:14:15.218478Z","end":"2026-04-11T02:14:15.319042Z","steps":["trace[2112728569] 'read index received' (duration: 100.117716ms)","trace[2112728569] 'applied index is now lower than readState.Index' (duration: 443.669µs)"],"step_count":2} {"level":"warn","ts":"2026-04-11T02:14:15.319223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.628649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-11T02:14:15.319259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.276682738s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T02:14:15.31926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.190256024s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-11T02:14:15.319267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.551332149s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:14:15.319269Z","caller":"traceutil/trace.go:171","msg":"trace[797549155] transaction","detail":"{read_only:false; response_revision:1472; number_of_response:1; }","duration":"710.06565ms","start":"2026-04-11T02:14:14.609168Z","end":"2026-04-11T02:14:15.319234Z","steps":["trace[797549155] 'process raft request' (duration: 709.637972ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:15.319324Z","caller":"traceutil/trace.go:171","msg":"trace[310109949] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1472; }","duration":"5.190415237s","start":"2026-04-11T02:14:10.128893Z","end":"2026-04-11T02:14:15.319308Z","steps":["trace[310109949] 'agreement among raft nodes before linearized reading' (duration: 5.190214363s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:15.319355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.226778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:14:15.319357Z","caller":"traceutil/trace.go:171","msg":"trace[131218289] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1472; }","duration":"2.551511112s","start":"2026-04-11T02:14:12.767825Z","end":"2026-04-11T02:14:15.319336Z","steps":["trace[131218289] 'agreement among raft nodes before linearized reading' (duration: 2.551241887s)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:15.319386Z","caller":"traceutil/trace.go:171","msg":"trace[148126301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1472; }","duration":"413.30138ms","start":"2026-04-11T02:14:14.906076Z","end":"2026-04-11T02:14:15.319378Z","steps":["trace[148126301] 'agreement among raft nodes before linearized reading' (duration: 413.253729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:15.319399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:10.128848Z","time spent":"5.190535019s","remote":"127.0.0.1:38994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":11,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-04-11T02:14:15.319415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:12.767803Z","time spent":"2.551599984s","remote":"127.0.0.1:38768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-04-11T02:14:15.319422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:14.906051Z","time spent":"413.364402ms","remote":"127.0.0.1:59096","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-11T02:14:15.319435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.62636673s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-11T02:14:15.31947Z","caller":"traceutil/trace.go:171","msg":"trace[1960270085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1472; }","duration":"5.626423581s","start":"2026-04-11T02:14:09.693034Z","end":"2026-04-11T02:14:15.319458Z","steps":["trace[1960270085] 'agreement among raft nodes before linearized reading' (duration: 5.626299339s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:15.319457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:14.609133Z","time spent":"710.229853ms","remote":"127.0.0.1:59330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1578,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T02:14:15.319472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.434667957s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-11T02:14:15.319503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.012599995s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-11T02:14:15.319511Z","caller":"traceutil/trace.go:171","msg":"trace[1598013590] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1472; }","duration":"1.434739558s","start":"2026-04-11T02:14:13.884759Z","end":"2026-04-11T02:14:15.319499Z","steps":["trace[1598013590] 'agreement among raft nodes before linearized reading' (duration: 1.434674897s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:15.319549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:13.884742Z","time spent":"1.434801589s","remote":"127.0.0.1:59430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-04-11T02:14:15.319557Z","caller":"traceutil/trace.go:171","msg":"trace[680314271] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1472; }","duration":"4.012690397s","start":"2026-04-11T02:14:11.30685Z","end":"2026-04-11T02:14:15.31954Z","steps":["trace[680314271] 'agreement among raft nodes before linearized reading' (duration: 4.012610726s)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:15.319293Z","caller":"traceutil/trace.go:171","msg":"trace[1194055946] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1472; }","duration":"746.717671ms","start":"2026-04-11T02:14:14.572559Z","end":"2026-04-11T02:14:15.319276Z","steps":["trace[1194055946] 'agreement among raft nodes before linearized reading' (duration: 746.564098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:15.319597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:11.306825Z","time spent":"4.012762238s","remote":"127.0.0.1:59330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":9,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-04-11T02:14:15.319606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.625463677s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18a52ba69e6ea766\" ","response":"range_response_count:1 size:773"} {"level":"info","ts":"2026-04-11T02:14:15.319637Z","caller":"traceutil/trace.go:171","msg":"trace[959025034] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18a52ba69e6ea766; range_end:; response_count:1; response_revision:1472; }","duration":"2.625540759s","start":"2026-04-11T02:14:12.694087Z","end":"2026-04-11T02:14:15.319627Z","steps":["trace[959025034] 'agreement among raft nodes before linearized reading' (duration: 2.625344125s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:15.319665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:12.694048Z","time spent":"2.62561017s","remote":"127.0.0.1:59160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":796,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18a52ba69e6ea766\" "} {"level":"warn","ts":"2026-04-11T02:14:15.319409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.66266349s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-11T02:14:15.319652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:14.572491Z","time spent":"747.120918ms","remote":"127.0.0.1:38950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-11T02:14:15.319505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:09.693023Z","time spent":"5.626475482s","remote":"127.0.0.1:59326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-11T02:14:15.319722Z","caller":"traceutil/trace.go:171","msg":"trace[962867494] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1472; }","duration":"2.663017917s","start":"2026-04-11T02:14:12.656687Z","end":"2026-04-11T02:14:15.319705Z","steps":["trace[962867494] 'agreement among raft nodes before linearized reading' (duration: 2.662675291s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:15.319741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.811685643s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-11T02:14:15.319303Z","caller":"traceutil/trace.go:171","msg":"trace[368718994] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1472; }","duration":"5.27677771s","start":"2026-04-11T02:14:10.04251Z","end":"2026-04-11T02:14:15.319287Z","steps":["trace[368718994] 'agreement among raft nodes before linearized reading' (duration: 5.276696579s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:15.31978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:12.656654Z","time spent":"2.663106268s","remote":"127.0.0.1:59490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-11T02:14:15.319269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.414762876s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T02:14:15.319815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:10.042481Z","time spent":"5.27731861s","remote":"127.0.0.1:38760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-04-11T02:14:15.319826Z","caller":"traceutil/trace.go:171","msg":"trace[535086522] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1472; }","duration":"1.415383308s","start":"2026-04-11T02:14:13.90443Z","end":"2026-04-11T02:14:15.319814Z","steps":["trace[535086522] 'agreement among raft nodes before linearized reading' (duration: 1.414734196s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:15.319848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.820030087s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/bindings.rabbitmq.com\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T02:14:15.319869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:13.904408Z","time spent":"1.415447079s","remote":"127.0.0.1:38918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-11T02:14:15.319877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.997686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-defaultbackend-6987ff55cf-2twrz\" ","response":"range_response_count:1 size:4532"} {"level":"warn","ts":"2026-04-11T02:14:15.319911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.121482226s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-11T02:14:15.319918Z","caller":"traceutil/trace.go:171","msg":"trace[1781978878] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-defaultbackend-6987ff55cf-2twrz; range_end:; response_count:1; response_revision:1472; }","duration":"712.093368ms","start":"2026-04-11T02:14:14.607813Z","end":"2026-04-11T02:14:15.319907Z","steps":["trace[1781978878] 'agreement among raft nodes before linearized reading' (duration: 711.793512ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:15.319943Z","caller":"traceutil/trace.go:171","msg":"trace[1736344173] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1472; }","duration":"6.121553767s","start":"2026-04-11T02:14:09.198376Z","end":"2026-04-11T02:14:15.31993Z","steps":["trace[1736344173] 'agreement among raft nodes before linearized reading' (duration: 6.121505926s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:15.319951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:14.607791Z","time spent":"712.151929ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":4555,"request content":"key:\"/registry/pods/ingress-nginx/ingress-nginx-defaultbackend-6987ff55cf-2twrz\" "} {"level":"warn","ts":"2026-04-11T02:14:15.319965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:09.198353Z","time spent":"6.121607259s","remote":"127.0.0.1:59534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-04-11T02:14:15.319783Z","caller":"traceutil/trace.go:171","msg":"trace[536622432] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1472; }","duration":"1.811764444s","start":"2026-04-11T02:14:13.508002Z","end":"2026-04-11T02:14:15.319767Z","steps":["trace[536622432] 'agreement among raft nodes before linearized reading' (duration: 1.811697993s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:15.320006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.650180436s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-11T02:14:15.320024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:13.507975Z","time spent":"1.81203774s","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-04-11T02:14:15.320035Z","caller":"traceutil/trace.go:171","msg":"trace[1786207741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1472; }","duration":"5.650244047s","start":"2026-04-11T02:14:09.669781Z","end":"2026-04-11T02:14:15.320025Z","steps":["trace[1786207741] 'agreement among raft nodes before linearized reading' (duration: 5.649748808s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:15.320046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.977644106s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.153\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-11T02:14:15.320062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:09.669758Z","time spent":"5.650297629s","remote":"127.0.0.1:59326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-11T02:14:15.319952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040133376s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:550"} {"level":"warn","ts":"2026-04-11T02:14:15.32008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.247482788s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:14:15.320086Z","caller":"traceutil/trace.go:171","msg":"trace[1970108469] range","detail":"{range_begin:/registry/masterleases/199.204.45.153; range_end:; response_count:1; response_revision:1472; }","duration":"3.977730988s","start":"2026-04-11T02:14:11.342344Z","end":"2026-04-11T02:14:15.320075Z","steps":["trace[1970108469] 'agreement among raft nodes before linearized reading' (duration: 3.977385942s)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:15.320102Z","caller":"traceutil/trace.go:171","msg":"trace[1110118750] range","detail":"{range_begin:/registry/leases/ingress-nginx/ingress-nginx-leader; range_end:; response_count:0; response_revision:1472; }","duration":"6.247504008s","start":"2026-04-11T02:14:09.072592Z","end":"2026-04-11T02:14:15.320096Z","steps":["trace[1110118750] 'agreement among raft nodes before linearized reading' (duration: 6.247463258s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:15.320117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:11.342324Z","time spent":"3.97778657s","remote":"127.0.0.1:59104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.153\" "} {"level":"warn","ts":"2026-04-11T02:14:15.320146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:09.07253Z","time spent":"6.2476129s","remote":"127.0.0.1:59326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" "} {"level":"info","ts":"2026-04-11T02:14:15.319881Z","caller":"traceutil/trace.go:171","msg":"trace[1384346832] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/bindings.rabbitmq.com; range_end:; response_count:0; response_revision:1472; }","duration":"5.820098308s","start":"2026-04-11T02:14:09.499773Z","end":"2026-04-11T02:14:15.319871Z","steps":["trace[1384346832] 'agreement among raft nodes before linearized reading' (duration: 5.820046527s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:15.320452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:09.499754Z","time spent":"5.820680599s","remote":"127.0.0.1:59122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/bindings.rabbitmq.com\" "} {"level":"info","ts":"2026-04-11T02:14:15.320127Z","caller":"traceutil/trace.go:171","msg":"trace[1164678852] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:1472; }","duration":"1.040321619s","start":"2026-04-11T02:14:14.279775Z","end":"2026-04-11T02:14:15.320097Z","steps":["trace[1164678852] 'agreement among raft nodes before linearized reading' (duration: 1.039549035s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:15.320837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:14:14.279757Z","time spent":"1.041059163s","remote":"127.0.0.1:59326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":573,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-04-11T02:14:15.499822Z","caller":"traceutil/trace.go:171","msg":"trace[1254238079] transaction","detail":"{read_only:false; response_revision:1474; number_of_response:1; }","duration":"174.858326ms","start":"2026-04-11T02:14:15.324918Z","end":"2026-04-11T02:14:15.499776Z","steps":["trace[1254238079] 'process raft request' (duration: 174.723894ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:15.499821Z","caller":"traceutil/trace.go:171","msg":"trace[398316194] transaction","detail":"{read_only:false; response_revision:1473; number_of_response:1; }","duration":"175.235853ms","start":"2026-04-11T02:14:15.324524Z","end":"2026-04-11T02:14:15.499759Z","steps":["trace[398316194] 'process raft request' (duration: 174.969378ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:15.499909Z","caller":"traceutil/trace.go:171","msg":"trace[727317521] transaction","detail":"{read_only:false; response_revision:1475; number_of_response:1; }","duration":"174.256055ms","start":"2026-04-11T02:14:15.325632Z","end":"2026-04-11T02:14:15.499888Z","steps":["trace[727317521] 'process raft request' (duration: 174.062712ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:15.500123Z","caller":"traceutil/trace.go:171","msg":"trace[1740024022] transaction","detail":"{read_only:false; response_revision:1476; number_of_response:1; }","duration":"173.555531ms","start":"2026-04-11T02:14:15.326519Z","end":"2026-04-11T02:14:15.500074Z","steps":["trace[1740024022] 'process raft request' (duration: 173.216074ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:15.501076Z","caller":"traceutil/trace.go:171","msg":"trace[556842894] transaction","detail":"{read_only:false; response_revision:1477; number_of_response:1; }","duration":"171.988282ms","start":"2026-04-11T02:14:15.329078Z","end":"2026-04-11T02:14:15.501066Z","steps":["trace[556842894] 'process raft request' (duration: 170.924051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:15.501316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.52803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/bindings.rabbitmq.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:14:15.501391Z","caller":"traceutil/trace.go:171","msg":"trace[913793297] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/bindings.rabbitmq.com; range_end:; response_count:0; response_revision:1477; }","duration":"174.623582ms","start":"2026-04-11T02:14:15.326746Z","end":"2026-04-11T02:14:15.50137Z","steps":["trace[913793297] 'agreement among raft nodes before linearized reading' (duration: 174.466869ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:15.501001Z","caller":"traceutil/trace.go:171","msg":"trace[1658231298] linearizableReadLoop","detail":"{readStateIndex:1528; appliedIndex:1523; }","duration":"174.218414ms","start":"2026-04-11T02:14:15.326766Z","end":"2026-04-11T02:14:15.500985Z","steps":["trace[1658231298] 'read index received' (duration: 40.903422ms)","trace[1658231298] 'applied index is now lower than readState.Index' (duration: 133.313812ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:14:15.579929Z","caller":"traceutil/trace.go:171","msg":"trace[1215508028] transaction","detail":"{read_only:false; response_revision:1478; number_of_response:1; }","duration":"211.10132ms","start":"2026-04-11T02:14:15.36873Z","end":"2026-04-11T02:14:15.579831Z","steps":["trace[1215508028] 'process raft request' (duration: 210.863685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:15.580014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.884011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:550"} {"level":"info","ts":"2026-04-11T02:14:15.580058Z","caller":"traceutil/trace.go:171","msg":"trace[353589868] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:1479; }","duration":"251.961363ms","start":"2026-04-11T02:14:15.328084Z","end":"2026-04-11T02:14:15.580045Z","steps":["trace[353589868] 'agreement among raft nodes before linearized reading' (duration: 251.777129ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:15.580014Z","caller":"traceutil/trace.go:171","msg":"trace[274452787] transaction","detail":"{read_only:false; response_revision:1479; number_of_response:1; }","duration":"211.189921ms","start":"2026-04-11T02:14:15.368773Z","end":"2026-04-11T02:14:15.579963Z","steps":["trace[274452787] 'process raft request' (duration: 210.959167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:15.580043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.271559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:14:15.580135Z","caller":"traceutil/trace.go:171","msg":"trace[1216290471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1479; }","duration":"251.392602ms","start":"2026-04-11T02:14:15.328723Z","end":"2026-04-11T02:14:15.580115Z","steps":["trace[1216290471] 'agreement among raft nodes before linearized reading' (duration: 251.113807ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:15.739286Z","caller":"traceutil/trace.go:171","msg":"trace[66006252] transaction","detail":"{read_only:false; response_revision:1480; number_of_response:1; }","duration":"233.404447ms","start":"2026-04-11T02:14:15.505852Z","end":"2026-04-11T02:14:15.739257Z","steps":["trace[66006252] 'process raft request' (duration: 233.104561ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:15.739507Z","caller":"traceutil/trace.go:171","msg":"trace[1212088581] transaction","detail":"{read_only:false; response_revision:1481; number_of_response:1; }","duration":"233.488918ms","start":"2026-04-11T02:14:15.505973Z","end":"2026-04-11T02:14:15.739462Z","steps":["trace[1212088581] 'process raft request' (duration: 233.159592ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:15.739549Z","caller":"traceutil/trace.go:171","msg":"trace[1895597785] linearizableReadLoop","detail":"{readStateIndex:1535; appliedIndex:1531; }","duration":"159.736217ms","start":"2026-04-11T02:14:15.579792Z","end":"2026-04-11T02:14:15.739528Z","steps":["trace[1895597785] 'read index received' (duration: 159.131886ms)","trace[1895597785] 'applied index is now lower than readState.Index' (duration: 602.951µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:14:15.739572Z","caller":"traceutil/trace.go:171","msg":"trace[1248371041] transaction","detail":"{read_only:false; response_revision:1482; number_of_response:1; }","duration":"233.447387ms","start":"2026-04-11T02:14:15.506087Z","end":"2026-04-11T02:14:15.739534Z","steps":["trace[1248371041] 'process raft request' (duration: 233.249473ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:15.739625Z","caller":"traceutil/trace.go:171","msg":"trace[953365469] transaction","detail":"{read_only:false; response_revision:1483; number_of_response:1; }","duration":"231.640013ms","start":"2026-04-11T02:14:15.507962Z","end":"2026-04-11T02:14:15.739602Z","steps":["trace[953365469] 'process raft request' (duration: 231.48454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:15.739697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.145153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/bindings.rabbitmq.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:14:15.739785Z","caller":"traceutil/trace.go:171","msg":"trace[1547380957] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/bindings.rabbitmq.com; range_end:; response_count:0; response_revision:1483; }","duration":"232.255495ms","start":"2026-04-11T02:14:15.50749Z","end":"2026-04-11T02:14:15.739745Z","steps":["trace[1547380957] 'agreement among raft nodes before linearized reading' (duration: 232.134423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:15.739883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.277352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-04-11T02:14:15.739949Z","caller":"traceutil/trace.go:171","msg":"trace[685258875] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:1483; }","duration":"235.433156ms","start":"2026-04-11T02:14:15.504497Z","end":"2026-04-11T02:14:15.73993Z","steps":["trace[685258875] 'agreement among raft nodes before linearized reading' (duration: 235.14151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:15.739993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.502014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-11T02:14:15.740036Z","caller":"traceutil/trace.go:171","msg":"trace[2084603987] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1483; }","duration":"157.571985ms","start":"2026-04-11T02:14:15.582453Z","end":"2026-04-11T02:14:15.740025Z","steps":["trace[2084603987] 'agreement among raft nodes before linearized reading' (duration: 157.486234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:15.740049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.961595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18a52ba6aaf6a4c4\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-04-11T02:14:15.740112Z","caller":"traceutil/trace.go:171","msg":"trace[1145914385] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18a52ba6aaf6a4c4; range_end:; response_count:1; response_revision:1483; }","duration":"156.063866ms","start":"2026-04-11T02:14:15.584024Z","end":"2026-04-11T02:14:15.740088Z","steps":["trace[1145914385] 'agreement among raft nodes before linearized reading' (duration: 155.919114ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:15.85856Z","caller":"traceutil/trace.go:171","msg":"trace[1347850148] transaction","detail":"{read_only:false; response_revision:1485; number_of_response:1; }","duration":"110.81293ms","start":"2026-04-11T02:14:15.747728Z","end":"2026-04-11T02:14:15.858541Z","steps":["trace[1347850148] 'process raft request' (duration: 110.520385ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:15.858675Z","caller":"traceutil/trace.go:171","msg":"trace[536059731] transaction","detail":"{read_only:false; response_revision:1486; number_of_response:1; }","duration":"103.742525ms","start":"2026-04-11T02:14:15.754885Z","end":"2026-04-11T02:14:15.858627Z","steps":["trace[536059731] 'process raft request' (duration: 103.525621ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:15.929948Z","caller":"traceutil/trace.go:171","msg":"trace[1068936227] transaction","detail":"{read_only:false; response_revision:1487; number_of_response:1; }","duration":"147.054655ms","start":"2026-04-11T02:14:15.782835Z","end":"2026-04-11T02:14:15.92989Z","steps":["trace[1068936227] 'process raft request' (duration: 146.728269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:15.930138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.07713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18a52ba6aaf6a4c4\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-04-11T02:14:15.930241Z","caller":"traceutil/trace.go:171","msg":"trace[2065931888] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18a52ba6aaf6a4c4; range_end:; response_count:1; response_revision:1487; }","duration":"152.204032ms","start":"2026-04-11T02:14:15.77801Z","end":"2026-04-11T02:14:15.930214Z","steps":["trace[2065931888] 'agreement among raft nodes before linearized reading' (duration: 151.900887ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:16.078011Z","caller":"traceutil/trace.go:171","msg":"trace[1056449085] linearizableReadLoop","detail":"{readStateIndex:1541; appliedIndex:1539; }","duration":"148.214447ms","start":"2026-04-11T02:14:15.929768Z","end":"2026-04-11T02:14:16.077982Z","steps":["trace[1056449085] 'read index received' (duration: 129.07627ms)","trace[1056449085] 'applied index is now lower than readState.Index' (duration: 19.136797ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:14:16.078187Z","caller":"traceutil/trace.go:171","msg":"trace[1063974161] transaction","detail":"{read_only:false; response_revision:1489; number_of_response:1; }","duration":"211.881435ms","start":"2026-04-11T02:14:15.866279Z","end":"2026-04-11T02:14:16.07816Z","steps":["trace[1063974161] 'process raft request' (duration: 211.569929ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:16.078174Z","caller":"traceutil/trace.go:171","msg":"trace[159206457] transaction","detail":"{read_only:false; response_revision:1488; number_of_response:1; }","duration":"214.964995ms","start":"2026-04-11T02:14:15.863172Z","end":"2026-04-11T02:14:16.078137Z","steps":["trace[159206457] 'process raft request' (duration: 195.657455ms)","trace[159206457] 'compare' (duration: 18.739899ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T02:14:16.078218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.779762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/exchanges.rabbitmq.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:14:16.078274Z","caller":"traceutil/trace.go:171","msg":"trace[177226253] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/exchanges.rabbitmq.com; range_end:; response_count:0; response_revision:1489; }","duration":"212.872604ms","start":"2026-04-11T02:14:15.865385Z","end":"2026-04-11T02:14:16.078257Z","steps":["trace[177226253] 'agreement among raft nodes before linearized reading' (duration: 212.768652ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:16.109796Z","caller":"traceutil/trace.go:171","msg":"trace[461203793] transaction","detail":"{read_only:false; response_revision:1490; number_of_response:1; }","duration":"176.120501ms","start":"2026-04-11T02:14:15.933654Z","end":"2026-04-11T02:14:16.109774Z","steps":["trace[461203793] 'process raft request' (duration: 175.955428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:16.110128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.139981ms","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-04-11T02:14:16.110191Z","caller":"traceutil/trace.go:171","msg":"trace[461353635] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1490; }","duration":"165.258582ms","start":"2026-04-11T02:14:15.944919Z","end":"2026-04-11T02:14:16.110178Z","steps":["trace[461353635] 'agreement among raft nodes before linearized reading' (duration: 165.018058ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:16.247642Z","caller":"traceutil/trace.go:171","msg":"trace[569911931] linearizableReadLoop","detail":"{readStateIndex:1543; appliedIndex:1542; }","duration":"137.865169ms","start":"2026-04-11T02:14:16.10974Z","end":"2026-04-11T02:14:16.247605Z","steps":["trace[569911931] 'read index received' (duration: 76.392482ms)","trace[569911931] 'applied index is now lower than readState.Index' (duration: 61.471187ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:14:16.247653Z","caller":"traceutil/trace.go:171","msg":"trace[1876296244] transaction","detail":"{read_only:false; response_revision:1491; number_of_response:1; }","duration":"161.741355ms","start":"2026-04-11T02:14:16.085862Z","end":"2026-04-11T02:14:16.247604Z","steps":["trace[1876296244] 'process raft request' (duration: 100.308379ms)","trace[1876296244] 'compare' (duration: 61.186071ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T02:14:16.247918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.863766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/exchanges.rabbitmq.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:14:16.247958Z","caller":"traceutil/trace.go:171","msg":"trace[398176487] transaction","detail":"{read_only:false; response_revision:1493; number_of_response:1; }","duration":"131.945555ms","start":"2026-04-11T02:14:16.116001Z","end":"2026-04-11T02:14:16.247947Z","steps":["trace[398176487] 'process raft request' (duration: 131.804112ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:16.247971Z","caller":"traceutil/trace.go:171","msg":"trace[1140825344] transaction","detail":"{read_only:false; response_revision:1492; number_of_response:1; }","duration":"133.26076ms","start":"2026-04-11T02:14:16.114687Z","end":"2026-04-11T02:14:16.247947Z","steps":["trace[1140825344] 'process raft request' (duration: 132.840092ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:16.247992Z","caller":"traceutil/trace.go:171","msg":"trace[274827494] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/exchanges.rabbitmq.com; range_end:; response_count:0; response_revision:1492; }","duration":"163.975077ms","start":"2026-04-11T02:14:16.083996Z","end":"2026-04-11T02:14:16.247971Z","steps":["trace[274827494] 'agreement among raft nodes before linearized reading' (duration: 163.807324ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:16.615979Z","caller":"traceutil/trace.go:171","msg":"trace[2010824736] transaction","detail":"{read_only:false; response_revision:1497; number_of_response:1; }","duration":"193.553994ms","start":"2026-04-11T02:14:16.422383Z","end":"2026-04-11T02:14:16.615937Z","steps":["trace[2010824736] 'process raft request' (duration: 106.910456ms)","trace[2010824736] 'compare' (duration: 86.369773ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:14:16.668642Z","caller":"traceutil/trace.go:171","msg":"trace[861680122] transaction","detail":"{read_only:false; response_revision:1498; number_of_response:1; }","duration":"202.934905ms","start":"2026-04-11T02:14:16.465677Z","end":"2026-04-11T02:14:16.668612Z","steps":["trace[861680122] 'process raft request' (duration: 202.787272ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:16.796013Z","caller":"traceutil/trace.go:171","msg":"trace[724676118] linearizableReadLoop","detail":"{readStateIndex:1551; appliedIndex:1550; }","duration":"116.739075ms","start":"2026-04-11T02:14:16.679227Z","end":"2026-04-11T02:14:16.795966Z","steps":["trace[724676118] 'read index received' (duration: 116.5136ms)","trace[724676118] 'applied index is now lower than readState.Index' (duration: 224.045µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:14:16.796131Z","caller":"traceutil/trace.go:171","msg":"trace[482389136] transaction","detail":"{read_only:false; response_revision:1499; number_of_response:1; }","duration":"119.357654ms","start":"2026-04-11T02:14:16.676741Z","end":"2026-04-11T02:14:16.796099Z","steps":["trace[482389136] 'process raft request' (duration: 119.021168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:16.796194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.939508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/operatorpolicies.rabbitmq.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:14:16.796244Z","caller":"traceutil/trace.go:171","msg":"trace[251695682] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/operatorpolicies.rabbitmq.com; range_end:; response_count:0; response_revision:1499; }","duration":"117.053661ms","start":"2026-04-11T02:14:16.679174Z","end":"2026-04-11T02:14:16.796227Z","steps":["trace[251695682] 'agreement among raft nodes before linearized reading' (duration: 116.944869ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:16.817778Z","caller":"traceutil/trace.go:171","msg":"trace[761458928] transaction","detail":"{read_only:false; response_revision:1500; number_of_response:1; }","duration":"138.519482ms","start":"2026-04-11T02:14:16.679236Z","end":"2026-04-11T02:14:16.817756Z","steps":["trace[761458928] 'process raft request' (duration: 138.368959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:16.817891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.28559ms","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-11T02:14:16.817932Z","caller":"traceutil/trace.go:171","msg":"trace[983824250] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1500; }","duration":"123.378161ms","start":"2026-04-11T02:14:16.694544Z","end":"2026-04-11T02:14:16.817923Z","steps":["trace[983824250] 'agreement among raft nodes before linearized reading' (duration: 123.213158ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:16.960939Z","caller":"traceutil/trace.go:171","msg":"trace[52983671] linearizableReadLoop","detail":"{readStateIndex:1553; appliedIndex:1552; }","duration":"143.19025ms","start":"2026-04-11T02:14:16.817725Z","end":"2026-04-11T02:14:16.960915Z","steps":["trace[52983671] 'read index received' (duration: 136.660256ms)","trace[52983671] 'applied index is now lower than readState.Index' (duration: 6.528944ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:14:16.96102Z","caller":"traceutil/trace.go:171","msg":"trace[10733575] transaction","detail":"{read_only:false; response_revision:1501; number_of_response:1; }","duration":"158.449083ms","start":"2026-04-11T02:14:16.802536Z","end":"2026-04-11T02:14:16.960985Z","steps":["trace[10733575] 'process raft request' (duration: 151.781956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:16.961129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.847769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/operatorpolicies.rabbitmq.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:14:16.961187Z","caller":"traceutil/trace.go:171","msg":"trace[275214175] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/operatorpolicies.rabbitmq.com; range_end:; response_count:0; response_revision:1501; }","duration":"159.998602ms","start":"2026-04-11T02:14:16.801174Z","end":"2026-04-11T02:14:16.961172Z","steps":["trace[275214175] 'agreement among raft nodes before linearized reading' (duration: 159.86325ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:16.990975Z","caller":"traceutil/trace.go:171","msg":"trace[1999787239] transaction","detail":"{read_only:false; response_revision:1502; number_of_response:1; }","duration":"168.753341ms","start":"2026-04-11T02:14:16.822195Z","end":"2026-04-11T02:14:16.990949Z","steps":["trace[1999787239] 'process raft request' (duration: 168.531266ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:17.085165Z","caller":"traceutil/trace.go:171","msg":"trace[901819373] linearizableReadLoop","detail":"{readStateIndex:1555; appliedIndex:1553; }","duration":"124.138046ms","start":"2026-04-11T02:14:16.961009Z","end":"2026-04-11T02:14:17.085147Z","steps":["trace[901819373] 'read index received' (duration: 29.784591ms)","trace[901819373] 'applied index is now lower than readState.Index' (duration: 94.352265ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T02:14:17.085309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.614939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:14:17.085388Z","caller":"traceutil/trace.go:171","msg":"trace[1586302960] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1502; }","duration":"188.761472ms","start":"2026-04-11T02:14:16.896608Z","end":"2026-04-11T02:14:17.085369Z","steps":["trace[1586302960] 'agreement among raft nodes before linearized reading' (duration: 188.609409ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:17.085454Z","caller":"traceutil/trace.go:171","msg":"trace[1837293313] transaction","detail":"{read_only:false; response_revision:1503; number_of_response:1; }","duration":"118.881354ms","start":"2026-04-11T02:14:16.96655Z","end":"2026-04-11T02:14:17.085432Z","steps":["trace[1837293313] 'process raft request' (duration: 118.506537ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:17.336721Z","caller":"traceutil/trace.go:171","msg":"trace[1339503342] transaction","detail":"{read_only:false; response_revision:1505; number_of_response:1; }","duration":"138.558521ms","start":"2026-04-11T02:14:17.198131Z","end":"2026-04-11T02:14:17.33669Z","steps":["trace[1339503342] 'process raft request' (duration: 83.831724ms)","trace[1339503342] 'compare' (duration: 54.610855ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:14:17.360392Z","caller":"traceutil/trace.go:171","msg":"trace[196770172] transaction","detail":"{read_only:false; response_revision:1506; number_of_response:1; }","duration":"159.539283ms","start":"2026-04-11T02:14:17.200828Z","end":"2026-04-11T02:14:17.360367Z","steps":["trace[196770172] 'process raft request' (duration: 159.43442ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:17.523251Z","caller":"traceutil/trace.go:171","msg":"trace[2063494535] linearizableReadLoop","detail":"{readStateIndex:1561; appliedIndex:1560; }","duration":"100.655227ms","start":"2026-04-11T02:14:17.422563Z","end":"2026-04-11T02:14:17.523218Z","steps":["trace[2063494535] 'read index received' (duration: 68.180695ms)","trace[2063494535] 'applied index is now lower than readState.Index' (duration: 32.472821ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:14:17.523324Z","caller":"traceutil/trace.go:171","msg":"trace[1450939026] transaction","detail":"{read_only:false; response_revision:1508; number_of_response:1; }","duration":"105.316816ms","start":"2026-04-11T02:14:17.41796Z","end":"2026-04-11T02:14:17.523276Z","steps":["trace[1450939026] 'process raft request' (duration: 72.863114ms)","trace[1450939026] 'compare' (duration: 32.257598ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T02:14:17.52345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.86261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/policies.rabbitmq.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:14:17.523537Z","caller":"traceutil/trace.go:171","msg":"trace[1773146274] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/policies.rabbitmq.com; range_end:; response_count:0; response_revision:1508; }","duration":"100.994983ms","start":"2026-04-11T02:14:17.422522Z","end":"2026-04-11T02:14:17.523517Z","steps":["trace[1773146274] 'agreement among raft nodes before linearized reading' (duration: 100.83622ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:17.683885Z","caller":"traceutil/trace.go:171","msg":"trace[331669753] transaction","detail":"{read_only:false; response_revision:1511; number_of_response:1; }","duration":"153.967256ms","start":"2026-04-11T02:14:17.529896Z","end":"2026-04-11T02:14:17.683863Z","steps":["trace[331669753] 'process raft request' (duration: 153.884855ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:17.684069Z","caller":"traceutil/trace.go:171","msg":"trace[716853480] transaction","detail":"{read_only:false; response_revision:1510; number_of_response:1; }","duration":"154.431376ms","start":"2026-04-11T02:14:17.529496Z","end":"2026-04-11T02:14:17.683928Z","steps":["trace[716853480] 'process raft request' (duration: 141.817495ms)","trace[716853480] 'compare' (duration: 12.349356ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:14:17.767865Z","caller":"traceutil/trace.go:171","msg":"trace[925259627] transaction","detail":"{read_only:false; response_revision:1513; number_of_response:1; }","duration":"139.981299ms","start":"2026-04-11T02:14:17.627858Z","end":"2026-04-11T02:14:17.767839Z","steps":["trace[925259627] 'process raft request' (duration: 139.820366ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:17.912765Z","caller":"traceutil/trace.go:171","msg":"trace[293139362] transaction","detail":"{read_only:false; response_revision:1514; number_of_response:1; }","duration":"137.764736ms","start":"2026-04-11T02:14:17.77498Z","end":"2026-04-11T02:14:17.912745Z","steps":["trace[293139362] 'process raft request' (duration: 137.619603ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:18.032178Z","caller":"traceutil/trace.go:171","msg":"trace[990275573] transaction","detail":"{read_only:false; response_revision:1515; number_of_response:1; }","duration":"255.284825ms","start":"2026-04-11T02:14:17.776848Z","end":"2026-04-11T02:14:18.032133Z","steps":["trace[990275573] 'process raft request' (duration: 255.043401ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:18.200097Z","caller":"traceutil/trace.go:171","msg":"trace[1057743405] linearizableReadLoop","detail":"{readStateIndex:1569; appliedIndex:1568; }","duration":"162.199105ms","start":"2026-04-11T02:14:18.037875Z","end":"2026-04-11T02:14:18.200075Z","steps":["trace[1057743405] 'read index received' (duration: 95.340635ms)","trace[1057743405] 'applied index is now lower than readState.Index' (duration: 66.85756ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T02:14:18.200205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.322997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/schemareplications.rabbitmq.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:14:18.200244Z","caller":"traceutil/trace.go:171","msg":"trace[870137046] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/schemareplications.rabbitmq.com; range_end:; response_count:0; response_revision:1516; }","duration":"162.378538ms","start":"2026-04-11T02:14:18.037854Z","end":"2026-04-11T02:14:18.200232Z","steps":["trace[870137046] 'agreement among raft nodes before linearized reading' (duration: 162.301076ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:18.200318Z","caller":"traceutil/trace.go:171","msg":"trace[1035910168] transaction","detail":"{read_only:false; response_revision:1516; number_of_response:1; }","duration":"162.773505ms","start":"2026-04-11T02:14:18.037517Z","end":"2026-04-11T02:14:18.200291Z","steps":["trace[1035910168] 'process raft request' (duration: 95.750812ms)","trace[1035910168] 'compare' (duration: 66.697677ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:14:18.358684Z","caller":"traceutil/trace.go:171","msg":"trace[1946442925] linearizableReadLoop","detail":"{readStateIndex:1570; appliedIndex:1569; }","duration":"148.895239ms","start":"2026-04-11T02:14:18.209764Z","end":"2026-04-11T02:14:18.358659Z","steps":["trace[1946442925] 'read index received' (duration: 116.887196ms)","trace[1946442925] 'applied index is now lower than readState.Index' (duration: 32.006813ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:14:18.358801Z","caller":"traceutil/trace.go:171","msg":"trace[1211501885] transaction","detail":"{read_only:false; response_revision:1517; number_of_response:1; }","duration":"150.965449ms","start":"2026-04-11T02:14:18.207792Z","end":"2026-04-11T02:14:18.358757Z","steps":["trace[1211501885] 'process raft request' (duration: 118.909385ms)","trace[1211501885] 'compare' (duration: 31.82615ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T02:14:18.358846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.057763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/schemareplications.rabbitmq.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:14:18.359011Z","caller":"traceutil/trace.go:171","msg":"trace[1945715082] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/schemareplications.rabbitmq.com; range_end:; response_count:0; response_revision:1517; }","duration":"149.281287ms","start":"2026-04-11T02:14:18.209705Z","end":"2026-04-11T02:14:18.358986Z","steps":["trace[1945715082] 'agreement among raft nodes before linearized reading' (duration: 149.084414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:18.359614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.449366ms","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":"warn","ts":"2026-04-11T02:14:18.35962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.061962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:14:18.359761Z","caller":"traceutil/trace.go:171","msg":"trace[1378986449] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1517; }","duration":"138.261655ms","start":"2026-04-11T02:14:18.221473Z","end":"2026-04-11T02:14:18.359734Z","steps":["trace[1378986449] 'agreement among raft nodes before linearized reading' (duration: 137.99228ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:18.359718Z","caller":"traceutil/trace.go:171","msg":"trace[1983652508] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1517; }","duration":"107.591509ms","start":"2026-04-11T02:14:18.252107Z","end":"2026-04-11T02:14:18.359699Z","steps":["trace[1983652508] 'agreement among raft nodes before linearized reading' (duration: 107.379515ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:18.528141Z","caller":"traceutil/trace.go:171","msg":"trace[1238259651] transaction","detail":"{read_only:false; response_revision:1519; number_of_response:1; }","duration":"163.472268ms","start":"2026-04-11T02:14:18.364636Z","end":"2026-04-11T02:14:18.528108Z","steps":["trace[1238259651] 'process raft request' (duration: 107.012897ms)","trace[1238259651] 'compare' (duration: 56.271907ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T02:14:18.734248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.473697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:14:18.73433Z","caller":"traceutil/trace.go:171","msg":"trace[725973457] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:1522; }","duration":"107.589859ms","start":"2026-04-11T02:14:18.626722Z","end":"2026-04-11T02:14:18.734312Z","steps":["trace[725973457] 'agreement among raft nodes before linearized reading' (duration: 107.447986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:18.734314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.981027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:14:18.734425Z","caller":"traceutil/trace.go:171","msg":"trace[1974840246] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:1522; }","duration":"108.131919ms","start":"2026-04-11T02:14:18.626271Z","end":"2026-04-11T02:14:18.734403Z","steps":["trace[1974840246] 'agreement among raft nodes before linearized reading' (duration: 107.941136ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:20.555794Z","caller":"traceutil/trace.go:171","msg":"trace[1516990561] linearizableReadLoop","detail":"{readStateIndex:1596; appliedIndex:1595; }","duration":"171.107034ms","start":"2026-04-11T02:14:20.384659Z","end":"2026-04-11T02:14:20.555766Z","steps":["trace[1516990561] 'read index received' (duration: 170.985092ms)","trace[1516990561] 'applied index is now lower than readState.Index' (duration: 120.252µs)"],"step_count":2} {"level":"warn","ts":"2026-04-11T02:14:20.55625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.539432ms","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-11T02:14:20.556385Z","caller":"traceutil/trace.go:171","msg":"trace[599510892] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1543; }","duration":"171.744386ms","start":"2026-04-11T02:14:20.384613Z","end":"2026-04-11T02:14:20.556357Z","steps":["trace[599510892] 'agreement among raft nodes before linearized reading' (duration: 171.292248ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:20.556486Z","caller":"traceutil/trace.go:171","msg":"trace[1797787959] transaction","detail":"{read_only:false; response_revision:1543; number_of_response:1; }","duration":"244.749384ms","start":"2026-04-11T02:14:20.311118Z","end":"2026-04-11T02:14:20.555867Z","steps":["trace[1797787959] 'process raft request' (duration: 244.492909ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:20.818191Z","caller":"traceutil/trace.go:171","msg":"trace[1546921808] linearizableReadLoop","detail":"{readStateIndex:1598; appliedIndex:1597; }","duration":"129.932688ms","start":"2026-04-11T02:14:20.688236Z","end":"2026-04-11T02:14:20.818168Z","steps":["trace[1546921808] 'read index received' (duration: 117.264185ms)","trace[1546921808] 'applied index is now lower than readState.Index' (duration: 12.667333ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T02:14:20.81833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.08432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:14:20.818369Z","caller":"traceutil/trace.go:171","msg":"trace[1741045840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1545; }","duration":"130.185472ms","start":"2026-04-11T02:14:20.688171Z","end":"2026-04-11T02:14:20.818357Z","steps":["trace[1741045840] 'agreement among raft nodes before linearized reading' (duration: 130.106281ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:20.818395Z","caller":"traceutil/trace.go:171","msg":"trace[188219063] transaction","detail":"{read_only:false; response_revision:1545; number_of_response:1; }","duration":"201.004498ms","start":"2026-04-11T02:14:20.617324Z","end":"2026-04-11T02:14:20.818329Z","steps":["trace[188219063] 'process raft request' (duration: 188.304394ms)","trace[188219063] 'compare' (duration: 12.405398ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:14:21.126535Z","caller":"traceutil/trace.go:171","msg":"trace[432468625] linearizableReadLoop","detail":"{readStateIndex:1600; appliedIndex:1599; }","duration":"230.065493ms","start":"2026-04-11T02:14:20.896445Z","end":"2026-04-11T02:14:21.12651Z","steps":["trace[432468625] 'read index received' (duration: 142.880634ms)","trace[432468625] 'applied index is now lower than readState.Index' (duration: 87.183789ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:14:21.126635Z","caller":"traceutil/trace.go:171","msg":"trace[272711198] transaction","detail":"{read_only:false; response_revision:1547; number_of_response:1; }","duration":"232.427778ms","start":"2026-04-11T02:14:20.894156Z","end":"2026-04-11T02:14:21.126584Z","steps":["trace[272711198] 'process raft request' (duration: 145.353682ms)","trace[272711198] 'compare' (duration: 86.798401ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T02:14:21.126804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.328978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:14:21.126888Z","caller":"traceutil/trace.go:171","msg":"trace[128265098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1547; }","duration":"230.460751ms","start":"2026-04-11T02:14:20.896409Z","end":"2026-04-11T02:14:21.12687Z","steps":["trace[128265098] 'agreement among raft nodes before linearized reading' (duration: 230.234336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:21.126514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.598311ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:14:21.12832Z","caller":"traceutil/trace.go:171","msg":"trace[277643458] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1546; }","duration":"154.560168ms","start":"2026-04-11T02:14:20.973742Z","end":"2026-04-11T02:14:21.128302Z","steps":["trace[277643458] 'range keys from in-memory index tree' (duration: 152.5607ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:37.068148Z","caller":"traceutil/trace.go:171","msg":"trace[2034530595] linearizableReadLoop","detail":"{readStateIndex:1903; appliedIndex:1902; }","duration":"101.815959ms","start":"2026-04-11T02:14:36.966291Z","end":"2026-04-11T02:14:37.068107Z","steps":["trace[2034530595] 'read index received' (duration: 101.521563ms)","trace[2034530595] 'applied index is now lower than readState.Index' (duration: 292.696µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:14:37.068225Z","caller":"traceutil/trace.go:171","msg":"trace[1926086535] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"166.874295ms","start":"2026-04-11T02:14:36.901296Z","end":"2026-04-11T02:14:37.06817Z","steps":["trace[1926086535] 'process raft request' (duration: 166.594719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:37.068567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.254787ms","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-11T02:14:37.068673Z","caller":"traceutil/trace.go:171","msg":"trace[1564271449] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1825; }","duration":"102.410211ms","start":"2026-04-11T02:14:36.966241Z","end":"2026-04-11T02:14:37.068651Z","steps":["trace[1564271449] 'agreement among raft nodes before linearized reading' (duration: 102.031784ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:14:52.284846Z","caller":"traceutil/trace.go:171","msg":"trace[654823837] linearizableReadLoop","detail":"{readStateIndex:2044; appliedIndex:2043; }","duration":"160.365029ms","start":"2026-04-11T02:14:52.124402Z","end":"2026-04-11T02:14:52.284767Z","steps":["trace[654823837] 'read index received' (duration: 160.134694ms)","trace[654823837] 'applied index is now lower than readState.Index' (duration: 228.595µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:14:52.28491Z","caller":"traceutil/trace.go:171","msg":"trace[1477584631] transaction","detail":"{read_only:false; response_revision:1963; number_of_response:1; }","duration":"280.464259ms","start":"2026-04-11T02:14:52.004411Z","end":"2026-04-11T02:14:52.284875Z","steps":["trace[1477584631] 'process raft request' (duration: 280.118171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:52.285244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.791848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-11T02:14:52.285301Z","caller":"traceutil/trace.go:171","msg":"trace[402664790] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1963; }","duration":"160.93029ms","start":"2026-04-11T02:14:52.124352Z","end":"2026-04-11T02:14:52.285282Z","steps":["trace[402664790] 'agreement among raft nodes before linearized reading' (duration: 160.618375ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:15:28.531746Z","caller":"traceutil/trace.go:171","msg":"trace[1550945526] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"105.554727ms","start":"2026-04-11T02:15:28.426141Z","end":"2026-04-11T02:15:28.531696Z","steps":["trace[1550945526] 'process raft request' (duration: 105.279022ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:15:29.562991Z","caller":"traceutil/trace.go:171","msg":"trace[1691496588] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"158.648473ms","start":"2026-04-11T02:15:29.404288Z","end":"2026-04-11T02:15:29.562937Z","steps":["trace[1691496588] 'process raft request' (duration: 158.380758ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:16:54.486979Z","caller":"traceutil/trace.go:171","msg":"trace[443478450] transaction","detail":"{read_only:false; response_revision:2830; number_of_response:1; }","duration":"105.87285ms","start":"2026-04-11T02:16:54.381076Z","end":"2026-04-11T02:16:54.486949Z","steps":["trace[443478450] 'process raft request' (duration: 105.690816ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:16:54.75421Z","caller":"traceutil/trace.go:171","msg":"trace[827113949] transaction","detail":"{read_only:false; response_revision:2831; number_of_response:1; }","duration":"259.243166ms","start":"2026-04-11T02:16:54.494923Z","end":"2026-04-11T02:16:54.754167Z","steps":["trace[827113949] 'process raft request' (duration: 219.693888ms)","trace[827113949] 'compare' (duration: 38.821454ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:16:54.793721Z","caller":"traceutil/trace.go:171","msg":"trace[437999733] linearizableReadLoop","detail":"{readStateIndex:2947; appliedIndex:2945; }","duration":"254.180354ms","start":"2026-04-11T02:16:54.539512Z","end":"2026-04-11T02:16:54.793693Z","steps":["trace[437999733] 'read index received' (duration: 175.075248ms)","trace[437999733] 'applied index is now lower than readState.Index' (duration: 79.103916ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:16:54.793873Z","caller":"traceutil/trace.go:171","msg":"trace[1493366290] transaction","detail":"{read_only:false; response_revision:2832; number_of_response:1; }","duration":"298.274454ms","start":"2026-04-11T02:16:54.495557Z","end":"2026-04-11T02:16:54.793831Z","steps":["trace[1493366290] 'process raft request' (duration: 298.012639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:16:54.794078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.520652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:16:54.794222Z","caller":"traceutil/trace.go:171","msg":"trace[1848616699] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2832; }","duration":"254.747597ms","start":"2026-04-11T02:16:54.539443Z","end":"2026-04-11T02:16:54.794191Z","steps":["trace[1848616699] 'agreement among raft nodes before linearized reading' (duration: 254.352488ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:19:25.059366Z","caller":"traceutil/trace.go:171","msg":"trace[664500702] transaction","detail":"{read_only:false; response_revision:3823; number_of_response:1; }","duration":"143.696776ms","start":"2026-04-11T02:19:24.915644Z","end":"2026-04-11T02:19:25.059341Z","steps":["trace[664500702] 'process raft request' (duration: 143.573784ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:19:30.456283Z","caller":"traceutil/trace.go:171","msg":"trace[1149530734] transaction","detail":"{read_only:false; response_revision:3847; number_of_response:1; }","duration":"152.284377ms","start":"2026-04-11T02:19:30.303962Z","end":"2026-04-11T02:19:30.456246Z","steps":["trace[1149530734] 'process raft request' (duration: 151.990852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:20:50.716393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.957135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:719272"} {"level":"info","ts":"2026-04-11T02:20:50.716485Z","caller":"traceutil/trace.go:171","msg":"trace[595099345] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4993; }","duration":"120.121728ms","start":"2026-04-11T02:20:50.596345Z","end":"2026-04-11T02:20:50.716467Z","steps":["trace[595099345] 'range keys from in-memory index tree' (duration: 119.459595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:21:01.687061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.882035ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-11T02:21:01.687152Z","caller":"traceutil/trace.go:171","msg":"trace[616121395] linearizableReadLoop","detail":"{readStateIndex:5414; appliedIndex:5413; }","duration":"160.020357ms","start":"2026-04-11T02:21:01.527119Z","end":"2026-04-11T02:21:01.687139Z","steps":["trace[616121395] 'read index received' (duration: 48.804985ms)","trace[616121395] 'applied index is now lower than readState.Index' (duration: 111.214632ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:21:01.687216Z","caller":"traceutil/trace.go:171","msg":"trace[1193225939] transaction","detail":"{read_only:false; response_revision:5210; number_of_response:1; }","duration":"228.627807ms","start":"2026-04-11T02:21:01.458558Z","end":"2026-04-11T02:21:01.687185Z","steps":["trace[1193225939] 'process raft request' (duration: 117.443286ms)","trace[1193225939] 'compare' (duration: 110.754442ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T02:21:01.687277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.726061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-11T02:21:01.6873Z","caller":"traceutil/trace.go:171","msg":"trace[1909001398] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:5210; }","duration":"119.798633ms","start":"2026-04-11T02:21:01.567494Z","end":"2026-04-11T02:21:01.687292Z","steps":["trace[1909001398] 'agreement among raft nodes before linearized reading' (duration: 119.716181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:21:01.687361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.246611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-11T02:21:01.687425Z","caller":"traceutil/trace.go:171","msg":"trace[917495854] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5210; }","duration":"160.349753ms","start":"2026-04-11T02:21:01.527056Z","end":"2026-04-11T02:21:01.687405Z","steps":["trace[917495854] 'agreement among raft nodes before linearized reading' (duration: 160.144049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:21:10.03995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.894106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:21:10.040031Z","caller":"traceutil/trace.go:171","msg":"trace[868350585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5354; }","duration":"109.041488ms","start":"2026-04-11T02:21:09.930972Z","end":"2026-04-11T02:21:10.040014Z","steps":["trace[868350585] 'range keys from in-memory index tree' (duration: 108.826784ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:21:10.392501Z","caller":"traceutil/trace.go:171","msg":"trace[1962958531] transaction","detail":"{read_only:false; response_revision:5357; number_of_response:1; }","duration":"225.28971ms","start":"2026-04-11T02:21:10.167177Z","end":"2026-04-11T02:21:10.392466Z","steps":["trace[1962958531] 'process raft request' (duration: 147.288692ms)","trace[1962958531] 'compare' (duration: 77.858595ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:21:12.384443Z","caller":"traceutil/trace.go:171","msg":"trace[1086782299] linearizableReadLoop","detail":"{readStateIndex:5614; appliedIndex:5613; }","duration":"164.638748ms","start":"2026-04-11T02:21:12.219776Z","end":"2026-04-11T02:21:12.384414Z","steps":["trace[1086782299] 'read index received' (duration: 71.437387ms)","trace[1086782299] 'applied index is now lower than readState.Index' (duration: 93.200001ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:21:12.384587Z","caller":"traceutil/trace.go:171","msg":"trace[1583575054] transaction","detail":"{read_only:false; response_revision:5405; number_of_response:1; }","duration":"169.73366ms","start":"2026-04-11T02:21:12.214821Z","end":"2026-04-11T02:21:12.384554Z","steps":["trace[1583575054] 'process raft request' (duration: 76.322205ms)","trace[1583575054] 'compare' (duration: 93.120249ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T02:21:12.384811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.998465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-11T02:21:12.384869Z","caller":"traceutil/trace.go:171","msg":"trace[840938519] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5405; }","duration":"165.089328ms","start":"2026-04-11T02:21:12.21976Z","end":"2026-04-11T02:21:12.384849Z","steps":["trace[840938519] 'agreement among raft nodes before linearized reading' (duration: 164.828133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:21:12.385136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.634397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:110395"} {"level":"info","ts":"2026-04-11T02:21:12.385227Z","caller":"traceutil/trace.go:171","msg":"trace[531405958] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:5405; }","duration":"164.795511ms","start":"2026-04-11T02:21:12.220413Z","end":"2026-04-11T02:21:12.385208Z","steps":["trace[531405958] 'agreement among raft nodes before linearized reading' (duration: 164.272451ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:21:49.608316Z","caller":"traceutil/trace.go:171","msg":"trace[1569047368] linearizableReadLoop","detail":"{readStateIndex:5945; appliedIndex:5944; }","duration":"302.130587ms","start":"2026-04-11T02:21:49.306158Z","end":"2026-04-11T02:21:49.608289Z","steps":["trace[1569047368] 'read index received' (duration: 301.993374ms)","trace[1569047368] 'applied index is now lower than readState.Index' (duration: 135.992µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:21:49.608461Z","caller":"traceutil/trace.go:171","msg":"trace[719286905] transaction","detail":"{read_only:false; response_revision:5729; number_of_response:1; }","duration":"441.259596ms","start":"2026-04-11T02:21:49.167164Z","end":"2026-04-11T02:21:49.608424Z","steps":["trace[719286905] 'process raft request' (duration: 440.983241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:21:49.608612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.070829ms","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-11T02:21:49.60866Z","caller":"traceutil/trace.go:171","msg":"trace[1750987147] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5729; }","duration":"100.183452ms","start":"2026-04-11T02:21:49.508464Z","end":"2026-04-11T02:21:49.608648Z","steps":["trace[1750987147] 'agreement among raft nodes before linearized reading' (duration: 100.001548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:21:49.608638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.459883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/openstack/keystone-api\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:21:49.608736Z","caller":"traceutil/trace.go:171","msg":"trace[1869878019] range","detail":"{range_begin:/registry/poddisruptionbudgets/openstack/keystone-api; range_end:; response_count:0; response_revision:5729; }","duration":"302.612276ms","start":"2026-04-11T02:21:49.306102Z","end":"2026-04-11T02:21:49.608714Z","steps":["trace[1869878019] 'agreement among raft nodes before linearized reading' (duration: 302.428662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:21:49.608746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:21:49.167149Z","time spent":"441.440969ms","remote":"127.0.0.1:59326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T02:21:49.608797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:21:49.306075Z","time spent":"302.709597ms","remote":"127.0.0.1:59378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/openstack/keystone-api\" "} {"level":"info","ts":"2026-04-11T02:22:01.870836Z","caller":"traceutil/trace.go:171","msg":"trace[1758693465] transaction","detail":"{read_only:false; response_revision:5898; number_of_response:1; }","duration":"102.616599ms","start":"2026-04-11T02:22:01.768197Z","end":"2026-04-11T02:22:01.870813Z","steps":["trace[1758693465] 'process raft request' (duration: 92.707552ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:22:08.293769Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2905} {"level":"info","ts":"2026-04-11T02:22:08.366586Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2905,"took":"71.227392ms","hash":2187093375,"current-db-size-bytes":57815040,"current-db-size":"58 MB","current-db-size-in-use-bytes":33570816,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-11T02:22:08.366671Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2187093375,"revision":2905,"compact-revision":-1} {"level":"info","ts":"2026-04-11T02:27:08.302209Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5961} {"level":"info","ts":"2026-04-11T02:27:08.398069Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5961,"took":"93.390209ms","hash":4396966,"current-db-size-bytes":57815040,"current-db-size":"58 MB","current-db-size-in-use-bytes":23314432,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-11T02:27:08.398152Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4396966,"revision":5961,"compact-revision":2905} {"level":"info","ts":"2026-04-11T02:27:09.296506Z","caller":"traceutil/trace.go:171","msg":"trace[1677446054] transaction","detail":"{read_only:false; response_revision:8185; number_of_response:1; }","duration":"269.857953ms","start":"2026-04-11T02:27:09.026625Z","end":"2026-04-11T02:27:09.296483Z","steps":["trace[1677446054] 'process raft request' (duration: 269.70851ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:27:09.451327Z","caller":"traceutil/trace.go:171","msg":"trace[2007733241] linearizableReadLoop","detail":"{readStateIndex:8479; appliedIndex:8478; }","duration":"285.72361ms","start":"2026-04-11T02:27:09.165532Z","end":"2026-04-11T02:27:09.451256Z","steps":["trace[2007733241] 'read index received' (duration: 130.991052ms)","trace[2007733241] 'applied index is now lower than readState.Index' (duration: 154.731078ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:27:09.451355Z","caller":"traceutil/trace.go:171","msg":"trace[1344991774] transaction","detail":"{read_only:false; response_revision:8186; number_of_response:1; }","duration":"361.386955ms","start":"2026-04-11T02:27:09.089941Z","end":"2026-04-11T02:27:09.451328Z","steps":["trace[1344991774] 'process raft request' (duration: 350.085059ms)","trace[1344991774] 'compare' (duration: 10.865017ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T02:27:09.451575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:27:09.089904Z","time spent":"361.564739ms","remote":"127.0.0.1:59326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T02:27:09.451596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.053347ms","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-11T02:27:09.451663Z","caller":"traceutil/trace.go:171","msg":"trace[870089328] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8186; }","duration":"286.169439ms","start":"2026-04-11T02:27:09.165476Z","end":"2026-04-11T02:27:09.451645Z","steps":["trace[870089328] 'agreement among raft nodes before linearized reading' (duration: 285.959685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:27:09.458996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.21009ms","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-11T02:27:09.458989Z","caller":"traceutil/trace.go:171","msg":"trace[1038120872] transaction","detail":"{read_only:false; response_revision:8187; number_of_response:1; }","duration":"119.284499ms","start":"2026-04-11T02:27:09.339666Z","end":"2026-04-11T02:27:09.458951Z","steps":["trace[1038120872] 'process raft request' (duration: 119.068894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:27:09.45903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.825762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-11T02:27:09.459058Z","caller":"traceutil/trace.go:171","msg":"trace[362127402] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8187; }","duration":"292.317173ms","start":"2026-04-11T02:27:09.166727Z","end":"2026-04-11T02:27:09.459044Z","steps":["trace[362127402] 'agreement among raft nodes before linearized reading' (duration: 292.135689ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:27:09.459102Z","caller":"traceutil/trace.go:171","msg":"trace[326484011] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:8187; }","duration":"255.923444ms","start":"2026-04-11T02:27:09.203157Z","end":"2026-04-11T02:27:09.45908Z","steps":["trace[326484011] 'agreement among raft nodes before linearized reading' (duration: 255.794742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:27:09.459211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.684629ms","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-04-11T02:27:09.459275Z","caller":"traceutil/trace.go:171","msg":"trace[6051736] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8187; }","duration":"262.773941ms","start":"2026-04-11T02:27:09.196484Z","end":"2026-04-11T02:27:09.459258Z","steps":["trace[6051736] 'agreement among raft nodes before linearized reading' (duration: 262.560787ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:27:21.720994Z","caller":"traceutil/trace.go:171","msg":"trace[2060984863] linearizableReadLoop","detail":"{readStateIndex:8548; appliedIndex:8547; }","duration":"196.907552ms","start":"2026-04-11T02:27:21.524057Z","end":"2026-04-11T02:27:21.720965Z","steps":["trace[2060984863] 'read index received' (duration: 196.709248ms)","trace[2060984863] 'applied index is now lower than readState.Index' (duration: 197.473µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:27:21.721147Z","caller":"traceutil/trace.go:171","msg":"trace[1096266754] transaction","detail":"{read_only:false; response_revision:8251; number_of_response:1; }","duration":"218.734729ms","start":"2026-04-11T02:27:21.50235Z","end":"2026-04-11T02:27:21.721084Z","steps":["trace[1096266754] 'process raft request' (duration: 218.436023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:27:21.721335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.256798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-11T02:27:21.721417Z","caller":"traceutil/trace.go:171","msg":"trace[2095460645] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8251; }","duration":"197.392801ms","start":"2026-04-11T02:27:21.524004Z","end":"2026-04-11T02:27:21.721397Z","steps":["trace[2095460645] 'agreement among raft nodes before linearized reading' (duration: 197.120186ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:27:21.770332Z","caller":"traceutil/trace.go:171","msg":"trace[2094079925] transaction","detail":"{read_only:false; response_revision:8252; number_of_response:1; }","duration":"222.970082ms","start":"2026-04-11T02:27:21.547331Z","end":"2026-04-11T02:27:21.770301Z","steps":["trace[2094079925] 'process raft request' (duration: 222.741218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:27:21.770441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.062856ms","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-11T02:27:21.770498Z","caller":"traceutil/trace.go:171","msg":"trace[1239041320] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8252; }","duration":"242.173877ms","start":"2026-04-11T02:27:21.528312Z","end":"2026-04-11T02:27:21.770486Z","steps":["trace[1239041320] 'agreement among raft nodes before linearized reading' (duration: 242.016834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:27:21.770518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.400662ms","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-11T02:27:21.770633Z","caller":"traceutil/trace.go:171","msg":"trace[111234901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8252; }","duration":"246.565375ms","start":"2026-04-11T02:27:21.524037Z","end":"2026-04-11T02:27:21.770602Z","steps":["trace[111234901] 'agreement among raft nodes before linearized reading' (duration: 246.230318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:28:00.396345Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439316438300526,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-11T02:28:00.488146Z","caller":"traceutil/trace.go:171","msg":"trace[362295487] linearizableReadLoop","detail":"{readStateIndex:8846; appliedIndex:8845; }","duration":"592.093423ms","start":"2026-04-11T02:27:59.896031Z","end":"2026-04-11T02:28:00.488124Z","steps":["trace[362295487] 'read index received' (duration: 591.897179ms)","trace[362295487] 'applied index is now lower than readState.Index' (duration: 195.004µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:28:00.488195Z","caller":"traceutil/trace.go:171","msg":"trace[318923487] transaction","detail":"{read_only:false; response_revision:8537; number_of_response:1; }","duration":"614.949551ms","start":"2026-04-11T02:27:59.873211Z","end":"2026-04-11T02:28:00.488161Z","steps":["trace[318923487] 'process raft request' (duration: 614.737416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:28:00.488344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:27:59.873188Z","time spent":"615.062943ms","remote":"127.0.0.1:59326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T02:28:00.48836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.307098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T02:28:00.488559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.873966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-11T02:28:00.488595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.326815ms","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-11T02:28:00.488608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.144602ms","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-04-11T02:28:00.488684Z","caller":"traceutil/trace.go:171","msg":"trace[250185768] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8537; }","duration":"419.003729ms","start":"2026-04-11T02:28:00.069626Z","end":"2026-04-11T02:28:00.488629Z","steps":["trace[250185768] 'agreement among raft nodes before linearized reading' (duration: 418.749264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:28:00.488761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:28:00.069607Z","time spent":"419.142431ms","remote":"127.0.0.1:59326","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":"info","ts":"2026-04-11T02:28:00.488688Z","caller":"traceutil/trace.go:171","msg":"trace[1962492580] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8537; }","duration":"592.446391ms","start":"2026-04-11T02:27:59.895981Z","end":"2026-04-11T02:28:00.488427Z","steps":["trace[1962492580] 'agreement among raft nodes before linearized reading' (duration: 592.293208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:28:00.488843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:27:59.895956Z","time spent":"592.876339ms","remote":"127.0.0.1:59082","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-11T02:28:00.488884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.631241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:28:00.488928Z","caller":"traceutil/trace.go:171","msg":"trace[1275751534] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:8537; }","duration":"430.718232ms","start":"2026-04-11T02:28:00.058195Z","end":"2026-04-11T02:28:00.488913Z","steps":["trace[1275751534] 'agreement among raft nodes before linearized reading' (duration: 430.10159ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:28:00.488927Z","caller":"traceutil/trace.go:171","msg":"trace[1186362740] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8537; }","duration":"418.232103ms","start":"2026-04-11T02:28:00.070418Z","end":"2026-04-11T02:28:00.48865Z","steps":["trace[1186362740] 'agreement among raft nodes before linearized reading' (duration: 418.017799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:28:00.488987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:28:00.070409Z","time spent":"418.559219ms","remote":"127.0.0.1:59326","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-04-11T02:28:00.48897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:28:00.058177Z","time spent":"430.781594ms","remote":"127.0.0.1:38760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-04-11T02:28:00.488777Z","caller":"traceutil/trace.go:171","msg":"trace[1461850221] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8537; }","duration":"423.413656ms","start":"2026-04-11T02:28:00.065226Z","end":"2026-04-11T02:28:00.48864Z","steps":["trace[1461850221] 'agreement among raft nodes before linearized reading' (duration: 423.232282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:28:00.48943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:28:00.065206Z","time spent":"424.211422ms","remote":"127.0.0.1:59326","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-11T02:28:00.707354Z","caller":"traceutil/trace.go:171","msg":"trace[1886711938] transaction","detail":"{read_only:false; response_revision:8538; number_of_response:1; }","duration":"214.345512ms","start":"2026-04-11T02:28:00.492984Z","end":"2026-04-11T02:28:00.70733Z","steps":["trace[1886711938] 'process raft request' (duration: 142.81296ms)","trace[1886711938] 'compare' (duration: 71.368569ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:28:00.710059Z","caller":"traceutil/trace.go:171","msg":"trace[1392788124] transaction","detail":"{read_only:false; response_revision:8540; number_of_response:1; }","duration":"216.214128ms","start":"2026-04-11T02:28:00.493819Z","end":"2026-04-11T02:28:00.710034Z","steps":["trace[1392788124] 'process raft request' (duration: 216.137277ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:28:00.710095Z","caller":"traceutil/trace.go:171","msg":"trace[533321939] transaction","detail":"{read_only:false; response_revision:8539; number_of_response:1; }","duration":"216.816052ms","start":"2026-04-11T02:28:00.493236Z","end":"2026-04-11T02:28:00.710052Z","steps":["trace[533321939] 'process raft request' (duration: 216.622908ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:28:00.71038Z","caller":"traceutil/trace.go:171","msg":"trace[746166343] transaction","detail":"{read_only:false; response_revision:8541; number_of_response:1; }","duration":"216.173627ms","start":"2026-04-11T02:28:00.494179Z","end":"2026-04-11T02:28:00.710353Z","steps":["trace[746166343] 'process raft request' (duration: 215.80943ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:28:00.71051Z","caller":"traceutil/trace.go:171","msg":"trace[1485687865] linearizableReadLoop","detail":"{readStateIndex:8851; appliedIndex:8846; }","duration":"210.537064ms","start":"2026-04-11T02:28:00.499955Z","end":"2026-04-11T02:28:00.710492Z","steps":["trace[1485687865] 'read index received' (duration: 135.823108ms)","trace[1485687865] 'applied index is now lower than readState.Index' (duration: 74.712466ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:28:00.710556Z","caller":"traceutil/trace.go:171","msg":"trace[1652592951] transaction","detail":"{read_only:false; response_revision:8542; number_of_response:1; }","duration":"216.32936ms","start":"2026-04-11T02:28:00.494215Z","end":"2026-04-11T02:28:00.710544Z","steps":["trace[1652592951] 'process raft request' (duration: 216.035274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:28:00.710701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.726869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-gbbfj\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-11T02:28:00.710752Z","caller":"traceutil/trace.go:171","msg":"trace[1276067016] range","detail":"{range_begin:/registry/pods/openstack/keepalived-gbbfj; range_end:; response_count:1; response_revision:8542; }","duration":"210.81026ms","start":"2026-04-11T02:28:00.499929Z","end":"2026-04-11T02:28:00.71074Z","steps":["trace[1276067016] 'agreement among raft nodes before linearized reading' (duration: 210.665937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:28:11.146077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.342011ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:28:11.146224Z","caller":"traceutil/trace.go:171","msg":"trace[262792205] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8596; }","duration":"172.554505ms","start":"2026-04-11T02:28:10.973645Z","end":"2026-04-11T02:28:11.146199Z","steps":["trace[262792205] 'range keys from in-memory index tree' (duration: 172.321651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:28:11.146299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.331019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T02:28:11.146434Z","caller":"traceutil/trace.go:171","msg":"trace[94018960] linearizableReadLoop","detail":"{readStateIndex:8908; appliedIndex:8907; }","duration":"188.712308ms","start":"2026-04-11T02:28:10.957698Z","end":"2026-04-11T02:28:11.14641Z","steps":["trace[94018960] 'read index received' (duration: 78.097754ms)","trace[94018960] 'applied index is now lower than readState.Index' (duration: 110.612564ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:28:11.146492Z","caller":"traceutil/trace.go:171","msg":"trace[488094679] transaction","detail":"{read_only:false; response_revision:8597; number_of_response:1; }","duration":"228.846712ms","start":"2026-04-11T02:28:10.917584Z","end":"2026-04-11T02:28:11.146431Z","steps":["trace[488094679] 'process raft request' (duration: 118.267748ms)","trace[488094679] 'compare' (duration: 110.146175ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T02:28:11.146526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.81776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:28:11.146584Z","caller":"traceutil/trace.go:171","msg":"trace[1771571530] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8597; }","duration":"188.909002ms","start":"2026-04-11T02:28:10.957664Z","end":"2026-04-11T02:28:11.146573Z","steps":["trace[1771571530] 'agreement among raft nodes before linearized reading' (duration: 188.80797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:28:12.790081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.90954ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T02:28:12.790251Z","caller":"traceutil/trace.go:171","msg":"trace[341480081] transaction","detail":"{read_only:false; response_revision:8603; number_of_response:1; }","duration":"133.227248ms","start":"2026-04-11T02:28:12.657004Z","end":"2026-04-11T02:28:12.790232Z","steps":["trace[341480081] 'process raft request' (duration: 28.113163ms)","trace[341480081] 'compare' (duration: 104.758728ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:28:12.96108Z","caller":"traceutil/trace.go:171","msg":"trace[175344008] transaction","detail":"{read_only:false; response_revision:8606; number_of_response:1; }","duration":"113.574204ms","start":"2026-04-11T02:28:12.847474Z","end":"2026-04-11T02:28:12.961048Z","steps":["trace[175344008] 'process raft request' (duration: 77.254187ms)","trace[175344008] 'compare' (duration: 36.070393ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:28:12.961083Z","caller":"traceutil/trace.go:171","msg":"trace[1529740513] transaction","detail":"{read_only:false; response_revision:8607; number_of_response:1; }","duration":"113.219426ms","start":"2026-04-11T02:28:12.847834Z","end":"2026-04-11T02:28:12.961053Z","steps":["trace[1529740513] 'process raft request' (duration: 113.135765ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:28:12.961271Z","caller":"traceutil/trace.go:171","msg":"trace[780644118] transaction","detail":"{read_only:false; response_revision:8608; number_of_response:1; }","duration":"113.392349ms","start":"2026-04-11T02:28:12.84784Z","end":"2026-04-11T02:28:12.961232Z","steps":["trace[780644118] 'process raft request' (duration: 113.174705ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:28:12.961277Z","caller":"traceutil/trace.go:171","msg":"trace[160927379] linearizableReadLoop","detail":"{readStateIndex:8921; appliedIndex:8918; }","duration":"111.37533ms","start":"2026-04-11T02:28:12.849864Z","end":"2026-04-11T02:28:12.961239Z","steps":["trace[160927379] 'read index received' (duration: 74.87977ms)","trace[160927379] 'applied index is now lower than readState.Index' (duration: 36.49277ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T02:28:12.961673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.781438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-gbbfj\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-11T02:28:12.961752Z","caller":"traceutil/trace.go:171","msg":"trace[1940549689] range","detail":"{range_begin:/registry/pods/openstack/keepalived-gbbfj; range_end:; response_count:1; response_revision:8608; }","duration":"111.939221ms","start":"2026-04-11T02:28:12.849791Z","end":"2026-04-11T02:28:12.96173Z","steps":["trace[1940549689] 'agreement among raft nodes before linearized reading' (duration: 111.577414ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:28:46.984098Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024a5.wal"} {"level":"info","ts":"2026-04-11T02:30:01.72226Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"355195d5aae85944","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-11T02:30:01.727983Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-11T02:30:01.728082Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-11T02:31:23.760076Z","caller":"traceutil/trace.go:171","msg":"trace[247634594] transaction","detail":"{read_only:false; response_revision:10293; number_of_response:1; }","duration":"103.670752ms","start":"2026-04-11T02:31:23.65637Z","end":"2026-04-11T02:31:23.76004Z","steps":["trace[247634594] 'process raft request' (duration: 103.355706ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:32:08.309276Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8182} {"level":"info","ts":"2026-04-11T02:32:08.38969Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8182,"took":"78.259104ms","hash":3134632865,"current-db-size-bytes":57815040,"current-db-size":"58 MB","current-db-size-in-use-bytes":25907200,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-11T02:32:08.38984Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3134632865,"revision":8182,"compact-revision":5961} {"level":"info","ts":"2026-04-11T02:35:01.353976Z","caller":"traceutil/trace.go:171","msg":"trace[117127023] transaction","detail":"{read_only:false; response_revision:11479; number_of_response:1; }","duration":"260.109618ms","start":"2026-04-11T02:35:01.093833Z","end":"2026-04-11T02:35:01.353942Z","steps":["trace[117127023] 'process raft request' (duration: 232.233671ms)","trace[117127023] 'compare' (duration: 27.686723ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:35:01.354063Z","caller":"traceutil/trace.go:171","msg":"trace[1937023903] transaction","detail":"{read_only:false; response_revision:11480; number_of_response:1; }","duration":"151.743612ms","start":"2026-04-11T02:35:01.202279Z","end":"2026-04-11T02:35:01.354023Z","steps":["trace[1937023903] 'process raft request' (duration: 151.59842ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:35:01.55059Z","caller":"traceutil/trace.go:171","msg":"trace[2095006112] transaction","detail":"{read_only:false; response_revision:11483; number_of_response:1; }","duration":"135.793065ms","start":"2026-04-11T02:35:01.414766Z","end":"2026-04-11T02:35:01.550559Z","steps":["trace[2095006112] 'process raft request' (duration: 135.495759ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:35:04.930557Z","caller":"traceutil/trace.go:171","msg":"trace[1070163721] transaction","detail":"{read_only:false; response_revision:11499; number_of_response:1; }","duration":"113.295514ms","start":"2026-04-11T02:35:04.817226Z","end":"2026-04-11T02:35:04.930522Z","steps":["trace[1070163721] 'process raft request' (duration: 113.10687ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:37:08.316184Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10580} {"level":"info","ts":"2026-04-11T02:37:08.401985Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10580,"took":"83.946509ms","hash":347931188,"current-db-size-bytes":57815040,"current-db-size":"58 MB","current-db-size-in-use-bytes":20791296,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-11T02:37:08.402036Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":347931188,"revision":10580,"compact-revision":8182}