{"level":"warn","ts":"2026-04-11T21:45:23.524955Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-11T21:45:23.525078Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.65: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.65:2380","--initial-cluster=instance=https://199.204.45.65:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.65:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.65:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-11T21:45:23.525156Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-11T21:45:23.525173Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-04-11T21:45:23.525198Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-11T21:45:23.525855Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"]} {"level":"info","ts":"2026-04-11T21:45:23.52598Z","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.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.65:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-11T21:45:23.530894Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.643073ms"} {"level":"info","ts":"2026-04-11T21:45:23.542661Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cf8c3a138c9a77a2","cluster-id":"c278183e1f634dff"} {"level":"info","ts":"2026-04-11T21:45:23.542814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=()"} {"level":"info","ts":"2026-04-11T21:45:23.542851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 0"} {"level":"info","ts":"2026-04-11T21:45:23.542886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cf8c3a138c9a77a2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-11T21:45:23.542905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 1"} {"level":"info","ts":"2026-04-11T21:45:23.542982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"warn","ts":"2026-04-11T21:45:23.551795Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-11T21:45:23.553434Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-11T21:45:23.554878Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-11T21:45:23.557573Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cf8c3a138c9a77a2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-11T21:45:23.557944Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-11T21:45:23.558059Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-11T21:45:23.558162Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-11T21:45:23.558634Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cf8c3a138c9a77a2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-11T21:45:23.560704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"info","ts":"2026-04-11T21:45:23.560912Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","added-peer-id":"cf8c3a138c9a77a2","added-peer-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-04-11T21:45:23.562622Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-11T21:45:23.562786Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-04-11T21:45:23.562865Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-04-11T21:45:23.562875Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cf8c3a138c9a77a2","initial-advertise-peer-urls":["https://199.204.45.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-11T21:45:23.562924Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-11T21:45:23.744195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 is starting a new election at term 1"} {"level":"info","ts":"2026-04-11T21:45:23.744245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-11T21:45:23.744268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgPreVoteResp from cf8c3a138c9a77a2 at term 1"} {"level":"info","ts":"2026-04-11T21:45:23.74428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became candidate at term 2"} {"level":"info","ts":"2026-04-11T21:45:23.744284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgVoteResp from cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-04-11T21:45:23.744296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became leader at term 2"} {"level":"info","ts":"2026-04-11T21:45:23.744304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cf8c3a138c9a77a2 elected leader cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-04-11T21:45:23.746381Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cf8c3a138c9a77a2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.65:2379]}","request-path":"/0/members/cf8c3a138c9a77a2/attributes","cluster-id":"c278183e1f634dff","publish-timeout":"7s"} {"level":"info","ts":"2026-04-11T21:45:23.746511Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T21:45:23.746652Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-11T21:45:23.746688Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T21:45:23.746559Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T21:45:23.746754Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-11T21:45:23.748029Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T21:45:23.748146Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T21:45:23.748184Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T21:45:23.750085Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.65:2379"} {"level":"info","ts":"2026-04-11T21:45:23.756164Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-11T21:45:29.42971Z","caller":"traceutil/trace.go:171","msg":"trace[725338640] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"121.253253ms","start":"2026-04-11T21:45:29.308429Z","end":"2026-04-11T21:45:29.429682Z","steps":["trace[725338640] 'process raft request' (duration: 114.1847ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:45:39.491992Z","caller":"traceutil/trace.go:171","msg":"trace[818240412] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"274.836488ms","start":"2026-04-11T21:45:39.217129Z","end":"2026-04-11T21:45:39.491966Z","steps":["trace[818240412] 'process raft request' (duration: 274.646993ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:45:39.678578Z","caller":"traceutil/trace.go:171","msg":"trace[239813901] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"177.795921ms","start":"2026-04-11T21:45:39.500726Z","end":"2026-04-11T21:45:39.678522Z","steps":["trace[239813901] 'process raft request' (duration: 177.534476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:45:51.554198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.610869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-04-11T21:45:51.554297Z","caller":"traceutil/trace.go:171","msg":"trace[1678403268] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"100.668399ms","start":"2026-04-11T21:45:51.453586Z","end":"2026-04-11T21:45:51.554254Z","steps":["trace[1678403268] 'process raft request' (duration: 71.538002ms)","trace[1678403268] 'compare' (duration: 28.919022ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T21:45:51.55447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.2112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14463"} {"level":"info","ts":"2026-04-11T21:45:51.5546Z","caller":"traceutil/trace.go:171","msg":"trace[852476420] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:459; }","duration":"107.375234ms","start":"2026-04-11T21:45:51.447204Z","end":"2026-04-11T21:45:51.554579Z","steps":["trace[852476420] 'agreement among raft nodes before linearized reading' (duration: 107.049797ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:45:51.55451Z","caller":"traceutil/trace.go:171","msg":"trace[2120370049] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:458; }","duration":"113.916004ms","start":"2026-04-11T21:45:51.440483Z","end":"2026-04-11T21:45:51.554399Z","steps":["trace[2120370049] 'agreement among raft nodes before linearized reading' (duration: 34.596466ms)","trace[2120370049] 'range keys from in-memory index tree' (duration: 78.94465ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:45:59.16445Z","caller":"traceutil/trace.go:171","msg":"trace[1111876990] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"107.054488ms","start":"2026-04-11T21:45:59.057331Z","end":"2026-04-11T21:45:59.164386Z","steps":["trace[1111876990] 'read index received' (duration: 104.974216ms)","trace[1111876990] 'applied index is now lower than readState.Index' (duration: 2.078532ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:45:59.164479Z","caller":"traceutil/trace.go:171","msg":"trace[2015792770] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"169.984755ms","start":"2026-04-11T21:45:58.994444Z","end":"2026-04-11T21:45:59.164429Z","steps":["trace[2015792770] 'process raft request' (duration: 168.023776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:45:59.164736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.383575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-11T21:45:59.164841Z","caller":"traceutil/trace.go:171","msg":"trace[423309625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:575; }","duration":"107.506927ms","start":"2026-04-11T21:45:59.057281Z","end":"2026-04-11T21:45:59.164788Z","steps":["trace[423309625] 'agreement among raft nodes before linearized reading' (duration: 107.277123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:45:59.63785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.472589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T21:45:59.638122Z","caller":"traceutil/trace.go:171","msg":"trace[231987104] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:595; }","duration":"411.03153ms","start":"2026-04-11T21:45:59.227072Z","end":"2026-04-11T21:45:59.638104Z","steps":["trace[231987104] 'read index received' (duration: 12.558791ms)","trace[231987104] 'applied index is now lower than readState.Index' (duration: 398.471319ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:45:59.638109Z","caller":"traceutil/trace.go:171","msg":"trace[2090364937] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"420.006269ms","start":"2026-04-11T21:45:59.21806Z","end":"2026-04-11T21:45:59.638066Z","steps":["trace[2090364937] 'process raft request' (duration: 21.559781ms)","trace[2090364937] 'compare' (duration: 397.359847ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:45:59.638234Z","caller":"traceutil/trace.go:171","msg":"trace[1267874703] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"418.262834ms","start":"2026-04-11T21:45:59.219939Z","end":"2026-04-11T21:45:59.638202Z","steps":["trace[1267874703] 'process raft request' (duration: 418.020659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:45:59.638265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:45:59.21803Z","time spent":"420.150011ms","remote":"127.0.0.1:34766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T21:45:59.63831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.232954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T21:45:59.638383Z","caller":"traceutil/trace.go:171","msg":"trace[1878088823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"411.340456ms","start":"2026-04-11T21:45:59.227024Z","end":"2026-04-11T21:45:59.638364Z","steps":["trace[1878088823] 'agreement among raft nodes before linearized reading' (duration: 411.203453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:45:59.638385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:45:59.219914Z","time spent":"418.406176ms","remote":"127.0.0.1:34960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-11T21:45:59.638456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:45:59.227002Z","time spent":"411.442977ms","remote":"127.0.0.1:34504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-11T21:45:59.638582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.919304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mcnl9\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-11T21:45:59.638657Z","caller":"traceutil/trace.go:171","msg":"trace[301858382] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mcnl9; range_end:; response_count:1; response_revision:578; }","duration":"155.035866ms","start":"2026-04-11T21:45:59.483603Z","end":"2026-04-11T21:45:59.638639Z","steps":["trace[301858382] 'agreement among raft nodes before linearized reading' (duration: 154.804482ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:46:17.730249Z","caller":"traceutil/trace.go:171","msg":"trace[1647731560] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"133.370373ms","start":"2026-04-11T21:46:17.596855Z","end":"2026-04-11T21:46:17.730225Z","steps":["trace[1647731560] 'process raft request' (duration: 133.228761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:47:13.841169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.221473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T21:47:13.841351Z","caller":"traceutil/trace.go:171","msg":"trace[1541025961] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1235; }","duration":"136.462158ms","start":"2026-04-11T21:47:13.704864Z","end":"2026-04-11T21:47:13.841326Z","steps":["trace[1541025961] 'count revisions from in-memory index tree' (duration: 136.12677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:48:00.044473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.296274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ac12251c-4665-4284-ae71-bc8f2ac87a66\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-11T21:48:00.044597Z","caller":"traceutil/trace.go:171","msg":"trace[532486660] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ac12251c-4665-4284-ae71-bc8f2ac87a66; range_end:; response_count:1; response_revision:1884; }","duration":"137.469577ms","start":"2026-04-11T21:47:59.907106Z","end":"2026-04-11T21:48:00.044575Z","steps":["trace[532486660] 'range keys from in-memory index tree' (duration: 137.09901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:48:00.044633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.896075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-11T21:48:00.044698Z","caller":"traceutil/trace.go:171","msg":"trace[1632529790] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1884; }","duration":"151.009548ms","start":"2026-04-11T21:47:59.893673Z","end":"2026-04-11T21:48:00.044683Z","steps":["trace[1632529790] 'range keys from in-memory index tree' (duration: 150.679911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:48:00.044802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.084589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-11T21:48:00.044938Z","caller":"traceutil/trace.go:171","msg":"trace[806713498] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1884; }","duration":"241.320604ms","start":"2026-04-11T21:47:59.803585Z","end":"2026-04-11T21:48:00.044906Z","steps":["trace[806713498] 'range keys from in-memory index tree' (duration: 240.738192ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:48:00.18908Z","caller":"traceutil/trace.go:171","msg":"trace[234260465] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"137.498038ms","start":"2026-04-11T21:48:00.051552Z","end":"2026-04-11T21:48:00.18905Z","steps":["trace[234260465] 'process raft request' (duration: 133.463588ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:48:51.628173Z","caller":"traceutil/trace.go:171","msg":"trace[848991783] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"116.297347ms","start":"2026-04-11T21:48:51.511837Z","end":"2026-04-11T21:48:51.628134Z","steps":["trace[848991783] 'process raft request' (duration: 116.062813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:48:51.905914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.817129ms","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-11T21:48:51.906022Z","caller":"traceutil/trace.go:171","msg":"trace[549631703] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2221; }","duration":"103.002073ms","start":"2026-04-11T21:48:51.802995Z","end":"2026-04-11T21:48:51.905997Z","steps":["trace[549631703] 'range keys from in-memory index tree' (duration: 102.473653ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:48:52.068133Z","caller":"traceutil/trace.go:171","msg":"trace[1542765389] linearizableReadLoop","detail":"{readStateIndex:2306; appliedIndex:2305; }","duration":"140.236192ms","start":"2026-04-11T21:48:51.927872Z","end":"2026-04-11T21:48:52.068108Z","steps":["trace[1542765389] 'read index received' (duration: 140.105129ms)","trace[1542765389] 'applied index is now lower than readState.Index' (duration: 129.553µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:48:52.068197Z","caller":"traceutil/trace.go:171","msg":"trace[2024208164] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"156.547636ms","start":"2026-04-11T21:48:51.911617Z","end":"2026-04-11T21:48:52.068165Z","steps":["trace[2024208164] 'process raft request' (duration: 156.327152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:48:52.068448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.527838ms","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-11T21:48:52.068609Z","caller":"traceutil/trace.go:171","msg":"trace[971530064] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2222; }","duration":"140.771693ms","start":"2026-04-11T21:48:51.927811Z","end":"2026-04-11T21:48:52.068583Z","steps":["trace[971530064] 'agreement among raft nodes before linearized reading' (duration: 140.394876ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:50:20.028285Z","caller":"traceutil/trace.go:171","msg":"trace[1203602014] transaction","detail":"{read_only:false; response_revision:2891; number_of_response:1; }","duration":"124.284994ms","start":"2026-04-11T21:50:19.903973Z","end":"2026-04-11T21:50:20.028258Z","steps":["trace[1203602014] 'process raft request' (duration: 124.109931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:50:20.481675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.156421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T21:50:20.481777Z","caller":"traceutil/trace.go:171","msg":"trace[79036942] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:2892; }","duration":"246.283643ms","start":"2026-04-11T21:50:20.235467Z","end":"2026-04-11T21:50:20.481751Z","steps":["trace[79036942] 'count revisions from in-memory index tree' (duration: 246.072149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:50:20.482105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.499588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T21:50:20.482197Z","caller":"traceutil/trace.go:171","msg":"trace[1654338482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2892; }","duration":"250.646581ms","start":"2026-04-11T21:50:20.231529Z","end":"2026-04-11T21:50:20.482175Z","steps":["trace[1654338482] 'range keys from in-memory index tree' (duration: 250.364755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:50:20.482267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.135308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-11T21:50:20.482345Z","caller":"traceutil/trace.go:171","msg":"trace[1254900561] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2892; }","duration":"162.258571ms","start":"2026-04-11T21:50:20.320067Z","end":"2026-04-11T21:50:20.482326Z","steps":["trace[1254900561] 'range keys from in-memory index tree' (duration: 161.864623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:50:20.482296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.0608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T21:50:20.482394Z","caller":"traceutil/trace.go:171","msg":"trace[81611870] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:2892; }","duration":"121.200713ms","start":"2026-04-11T21:50:20.361178Z","end":"2026-04-11T21:50:20.482379Z","steps":["trace[81611870] 'count revisions from in-memory index tree' (duration: 120.980138ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:50:42.058318Z","caller":"traceutil/trace.go:171","msg":"trace[1643731888] transaction","detail":"{read_only:false; response_revision:3012; number_of_response:1; }","duration":"195.745158ms","start":"2026-04-11T21:50:41.862542Z","end":"2026-04-11T21:50:42.058287Z","steps":["trace[1643731888] 'process raft request' (duration: 195.532834ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:50:42.059125Z","caller":"traceutil/trace.go:171","msg":"trace[951314238] transaction","detail":"{read_only:false; response_revision:3013; number_of_response:1; }","duration":"162.867054ms","start":"2026-04-11T21:50:41.896207Z","end":"2026-04-11T21:50:42.059074Z","steps":["trace[951314238] 'process raft request' (duration: 162.522236ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:52:51.503933Z","caller":"traceutil/trace.go:171","msg":"trace[1966098132] transaction","detail":"{read_only:false; response_revision:3890; number_of_response:1; }","duration":"101.917712ms","start":"2026-04-11T21:52:51.401985Z","end":"2026-04-11T21:52:51.503903Z","steps":["trace[1966098132] 'process raft request' (duration: 101.480123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:52:56.86791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.795639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xrbbj\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-11T21:52:56.868Z","caller":"traceutil/trace.go:171","msg":"trace[204602758] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xrbbj; range_end:; response_count:1; response_revision:3917; }","duration":"116.941441ms","start":"2026-04-11T21:52:56.751039Z","end":"2026-04-11T21:52:56.86798Z","steps":["trace[204602758] 'range keys from in-memory index tree' (duration: 116.582664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:54:12.949369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.60762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-adab42bc-5d39-48b2-9ba8-f1586eca6d2e\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-04-11T21:54:12.949536Z","caller":"traceutil/trace.go:171","msg":"trace[428829566] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-adab42bc-5d39-48b2-9ba8-f1586eca6d2e; range_end:; response_count:1; response_revision:4880; }","duration":"109.825915ms","start":"2026-04-11T21:54:12.839689Z","end":"2026-04-11T21:54:12.949515Z","steps":["trace[428829566] 'range keys from in-memory index tree' (duration: 109.438967ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:54:12.997383Z","caller":"traceutil/trace.go:171","msg":"trace[1139982110] transaction","detail":"{read_only:false; response_revision:4882; number_of_response:1; }","duration":"113.776794ms","start":"2026-04-11T21:54:12.883583Z","end":"2026-04-11T21:54:12.99736Z","steps":["trace[1139982110] 'process raft request' (duration: 113.666362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:54:19.033638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.428107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T21:54:19.033743Z","caller":"traceutil/trace.go:171","msg":"trace[933540392] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:5091; }","duration":"141.58063ms","start":"2026-04-11T21:54:18.89214Z","end":"2026-04-11T21:54:19.033721Z","steps":["trace[933540392] 'range keys from in-memory index tree' (duration: 141.319795ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:54:19.034071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.884846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-28ea0d93-2d04-49c5-a1ad-64587d92d3a0\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-04-11T21:54:19.034106Z","caller":"traceutil/trace.go:171","msg":"trace[855124821] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-28ea0d93-2d04-49c5-a1ad-64587d92d3a0; range_end:; response_count:1; response_revision:5091; }","duration":"120.956518ms","start":"2026-04-11T21:54:18.913142Z","end":"2026-04-11T21:54:19.034098Z","steps":["trace[855124821] 'range keys from in-memory index tree' (duration: 120.744043ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:54:19.172258Z","caller":"traceutil/trace.go:171","msg":"trace[955324529] linearizableReadLoop","detail":"{readStateIndex:5282; appliedIndex:5281; }","duration":"129.784193ms","start":"2026-04-11T21:54:19.042441Z","end":"2026-04-11T21:54:19.172225Z","steps":["trace[955324529] 'read index received' (duration: 129.63145ms)","trace[955324529] 'applied index is now lower than readState.Index' (duration: 151.653µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:54:19.17232Z","caller":"traceutil/trace.go:171","msg":"trace[1350092828] transaction","detail":"{read_only:false; response_revision:5092; number_of_response:1; }","duration":"130.953487ms","start":"2026-04-11T21:54:19.041327Z","end":"2026-04-11T21:54:19.17228Z","steps":["trace[1350092828] 'process raft request' (duration: 130.690982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:54:19.172439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.969108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T21:54:19.172492Z","caller":"traceutil/trace.go:171","msg":"trace[422785220] range","detail":"{range_begin:/registry/clusterrolebindings/vector; range_end:; response_count:0; response_revision:5092; }","duration":"130.10088ms","start":"2026-04-11T21:54:19.042375Z","end":"2026-04-11T21:54:19.172476Z","steps":["trace[422785220] 'agreement among raft nodes before linearized reading' (duration: 129.975528ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:54:19.176642Z","caller":"traceutil/trace.go:171","msg":"trace[1531602624] transaction","detail":"{read_only:false; response_revision:5093; number_of_response:1; }","duration":"132.658792ms","start":"2026-04-11T21:54:19.043952Z","end":"2026-04-11T21:54:19.176611Z","steps":["trace[1531602624] 'process raft request' (duration: 132.371287ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:54:19.588583Z","caller":"traceutil/trace.go:171","msg":"trace[755984255] transaction","detail":"{read_only:false; response_revision:5105; number_of_response:1; }","duration":"117.206082ms","start":"2026-04-11T21:54:19.471354Z","end":"2026-04-11T21:54:19.58856Z","steps":["trace[755984255] 'process raft request' (duration: 117.09608ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:54:19.588604Z","caller":"traceutil/trace.go:171","msg":"trace[267357811] transaction","detail":"{read_only:false; response_revision:5104; number_of_response:1; }","duration":"126.483657ms","start":"2026-04-11T21:54:19.462085Z","end":"2026-04-11T21:54:19.588569Z","steps":["trace[267357811] 'process raft request' (duration: 85.719443ms)","trace[267357811] 'compare' (duration: 40.483529ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:54:27.22336Z","caller":"traceutil/trace.go:171","msg":"trace[512718898] transaction","detail":"{read_only:false; response_revision:5261; number_of_response:1; }","duration":"152.370715ms","start":"2026-04-11T21:54:27.070958Z","end":"2026-04-11T21:54:27.223329Z","steps":["trace[512718898] 'process raft request' (duration: 152.175711ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:54:27.223568Z","caller":"traceutil/trace.go:171","msg":"trace[939149106] transaction","detail":"{read_only:false; response_revision:5262; number_of_response:1; }","duration":"152.413185ms","start":"2026-04-11T21:54:27.071133Z","end":"2026-04-11T21:54:27.223546Z","steps":["trace[939149106] 'process raft request' (duration: 152.14124ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:54:27.223809Z","caller":"traceutil/trace.go:171","msg":"trace[35339017] transaction","detail":"{read_only:false; response_revision:5264; number_of_response:1; }","duration":"151.366245ms","start":"2026-04-11T21:54:27.072424Z","end":"2026-04-11T21:54:27.22379Z","steps":["trace[35339017] 'process raft request' (duration: 151.276473ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:54:27.223839Z","caller":"traceutil/trace.go:171","msg":"trace[1223578772] transaction","detail":"{read_only:false; response_revision:5263; number_of_response:1; }","duration":"152.001977ms","start":"2026-04-11T21:54:27.071805Z","end":"2026-04-11T21:54:27.223807Z","steps":["trace[1223578772] 'process raft request' (duration: 151.652171ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:54:36.640144Z","caller":"traceutil/trace.go:171","msg":"trace[1104359827] linearizableReadLoop","detail":"{readStateIndex:5641; appliedIndex:5640; }","duration":"115.416746ms","start":"2026-04-11T21:54:36.524696Z","end":"2026-04-11T21:54:36.640113Z","steps":["trace[1104359827] 'read index received' (duration: 32.119067ms)","trace[1104359827] 'applied index is now lower than readState.Index' (duration: 83.296319ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:54:36.640272Z","caller":"traceutil/trace.go:171","msg":"trace[67217455] transaction","detail":"{read_only:false; response_revision:5443; number_of_response:1; }","duration":"117.71038ms","start":"2026-04-11T21:54:36.52251Z","end":"2026-04-11T21:54:36.640221Z","steps":["trace[67217455] 'process raft request' (duration: 92.564983ms)","trace[67217455] 'compare' (duration: 24.900923ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T21:54:36.641518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.777813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-11T21:54:36.641638Z","caller":"traceutil/trace.go:171","msg":"trace[549872098] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5443; }","duration":"116.936326ms","start":"2026-04-11T21:54:36.524681Z","end":"2026-04-11T21:54:36.641617Z","steps":["trace[549872098] 'agreement among raft nodes before linearized reading' (duration: 115.63004ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:54:36.769501Z","caller":"traceutil/trace.go:171","msg":"trace[1061790117] transaction","detail":"{read_only:false; response_revision:5444; number_of_response:1; }","duration":"123.277811ms","start":"2026-04-11T21:54:36.64617Z","end":"2026-04-11T21:54:36.769448Z","steps":["trace[1061790117] 'process raft request' (duration: 107.528919ms)","trace[1061790117] 'compare' (duration: 15.547308ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:54:48.193318Z","caller":"traceutil/trace.go:171","msg":"trace[1894435103] linearizableReadLoop","detail":"{readStateIndex:5821; appliedIndex:5820; }","duration":"107.05212ms","start":"2026-04-11T21:54:48.086245Z","end":"2026-04-11T21:54:48.193297Z","steps":["trace[1894435103] 'read index received' (duration: 106.783885ms)","trace[1894435103] 'applied index is now lower than readState.Index' (duration: 267.245µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:54:48.193378Z","caller":"traceutil/trace.go:171","msg":"trace[385267101] transaction","detail":"{read_only:false; response_revision:5617; number_of_response:1; }","duration":"115.952897ms","start":"2026-04-11T21:54:48.077394Z","end":"2026-04-11T21:54:48.193346Z","steps":["trace[385267101] 'process raft request' (duration: 115.713152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:54:48.193497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.240973ms","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-11T21:54:48.193577Z","caller":"traceutil/trace.go:171","msg":"trace[1058046661] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5617; }","duration":"107.377176ms","start":"2026-04-11T21:54:48.086191Z","end":"2026-04-11T21:54:48.193569Z","steps":["trace[1058046661] 'agreement among raft nodes before linearized reading' (duration: 107.200663ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:54:48.488441Z","caller":"traceutil/trace.go:171","msg":"trace[652352190] transaction","detail":"{read_only:false; response_revision:5621; number_of_response:1; }","duration":"253.59495ms","start":"2026-04-11T21:54:48.234795Z","end":"2026-04-11T21:54:48.48839Z","steps":["trace[652352190] 'process raft request' (duration: 242.151494ms)","trace[652352190] 'compare' (duration: 11.276183ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:54:48.488903Z","caller":"traceutil/trace.go:171","msg":"trace[1273944530] linearizableReadLoop","detail":"{readStateIndex:5828; appliedIndex:5823; }","duration":"205.985238ms","start":"2026-04-11T21:54:48.282887Z","end":"2026-04-11T21:54:48.488873Z","steps":["trace[1273944530] 'read index received' (duration: 36.134905ms)","trace[1273944530] 'applied index is now lower than readState.Index' (duration: 169.835773ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:54:48.488917Z","caller":"traceutil/trace.go:171","msg":"trace[929083721] transaction","detail":"{read_only:false; response_revision:5623; number_of_response:1; }","duration":"253.607371ms","start":"2026-04-11T21:54:48.235273Z","end":"2026-04-11T21:54:48.488881Z","steps":["trace[929083721] 'process raft request' (duration: 253.429978ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:54:48.48892Z","caller":"traceutil/trace.go:171","msg":"trace[43977120] transaction","detail":"{read_only:false; response_revision:5622; number_of_response:1; }","duration":"254.048249ms","start":"2026-04-11T21:54:48.234853Z","end":"2026-04-11T21:54:48.488902Z","steps":["trace[43977120] 'process raft request' (duration: 253.519919ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:54:48.489164Z","caller":"traceutil/trace.go:171","msg":"trace[463220678] transaction","detail":"{read_only:false; response_revision:5624; number_of_response:1; }","duration":"253.847085ms","start":"2026-04-11T21:54:48.235303Z","end":"2026-04-11T21:54:48.48915Z","steps":["trace[463220678] 'process raft request' (duration: 253.460998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:54:48.489341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.434647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-04-11T21:54:48.489392Z","caller":"traceutil/trace.go:171","msg":"trace[484126596] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5624; }","duration":"206.541959ms","start":"2026-04-11T21:54:48.282838Z","end":"2026-04-11T21:54:48.48938Z","steps":["trace[484126596] 'agreement among raft nodes before linearized reading' (duration: 206.149381ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:55:23.779553Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2915} {"level":"info","ts":"2026-04-11T21:55:23.850825Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2915,"took":"69.795912ms","hash":2958842308,"current-db-size-bytes":56156160,"current-db-size":"56 MB","current-db-size-in-use-bytes":32681984,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-11T21:55:23.850885Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2958842308,"revision":2915,"compact-revision":-1} {"level":"info","ts":"2026-04-11T22:00:23.786369Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5967} {"level":"info","ts":"2026-04-11T22:00:23.876942Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5967,"took":"88.946389ms","hash":2290807109,"current-db-size-bytes":56156160,"current-db-size":"56 MB","current-db-size-in-use-bytes":18608128,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-04-11T22:00:23.877046Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2290807109,"revision":5967,"compact-revision":2915}