{"level":"warn","ts":"2026-03-25T21:05:00.237604Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-25T21:05:00.237687Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.25:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.25:2380","--initial-cluster=instance=https://199.204.45.25:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.25:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.25:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-25T21:05:00.237754Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-25T21:05:00.237768Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-03-25T21:05:00.237788Z","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-03-25T21:05:00.238409Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"]} {"level":"info","ts":"2026-03-25T21:05:00.238496Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.25:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-25T21:05:00.24198Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.285126ms"} {"level":"info","ts":"2026-03-25T21:05:00.249212Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ba52c55cf4193752","cluster-id":"4468ed52294bc271"} {"level":"info","ts":"2026-03-25T21:05:00.249334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=()"} {"level":"info","ts":"2026-03-25T21:05:00.24936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 0"} {"level":"info","ts":"2026-03-25T21:05:00.249374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ba52c55cf4193752 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-25T21:05:00.249386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 1"} {"level":"info","ts":"2026-03-25T21:05:00.249435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"warn","ts":"2026-03-25T21:05:00.256642Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-25T21:05:00.2582Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-25T21:05:00.259566Z","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-03-25T21:05:00.26244Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ba52c55cf4193752","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-25T21:05:00.262957Z","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-03-25T21:05:00.263001Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ba52c55cf4193752","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-25T21:05:00.263285Z","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-03-25T21:05:00.263348Z","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-03-25T21:05:00.263759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"info","ts":"2026-03-25T21:05:00.264591Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","added-peer-id":"ba52c55cf4193752","added-peer-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-03-25T21:05:00.269618Z","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-03-25T21:05:00.269933Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ba52c55cf4193752","initial-advertise-peer-urls":["https://199.204.45.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-25T21:05:00.269933Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-03-25T21:05:00.269988Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-25T21:05:00.270015Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-03-25T21:05:01.149769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 is starting a new election at term 1"} {"level":"info","ts":"2026-03-25T21:05:01.149918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-25T21:05:01.149991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgPreVoteResp from ba52c55cf4193752 at term 1"} {"level":"info","ts":"2026-03-25T21:05:01.150012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became candidate at term 2"} {"level":"info","ts":"2026-03-25T21:05:01.150038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgVoteResp from ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-03-25T21:05:01.150061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became leader at term 2"} {"level":"info","ts":"2026-03-25T21:05:01.150082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ba52c55cf4193752 elected leader ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-03-25T21:05:01.151167Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ba52c55cf4193752","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.25:2379]}","request-path":"/0/members/ba52c55cf4193752/attributes","cluster-id":"4468ed52294bc271","publish-timeout":"7s"} {"level":"info","ts":"2026-03-25T21:05:01.151321Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T21:05:01.151547Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T21:05:01.151951Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:05:01.152Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-25T21:05:01.152033Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-25T21:05:01.154623Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.25:2379"} {"level":"info","ts":"2026-03-25T21:05:01.15592Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-25T21:05:01.158461Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:05:01.158597Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:05:01.158653Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:05:17.989261Z","caller":"traceutil/trace.go:171","msg":"trace[459909948] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"112.174713ms","start":"2026-03-25T21:05:17.877016Z","end":"2026-03-25T21:05:17.989191Z","steps":["trace[459909948] 'process raft request' (duration: 50.644079ms)","trace[459909948] 'compare' (duration: 61.296138ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:05:17.989345Z","caller":"traceutil/trace.go:171","msg":"trace[767098903] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"109.361381ms","start":"2026-03-25T21:05:17.879952Z","end":"2026-03-25T21:05:17.989314Z","steps":["trace[767098903] 'process raft request' (duration: 109.267968ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:17.989379Z","caller":"traceutil/trace.go:171","msg":"trace[1507587009] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"109.860252ms","start":"2026-03-25T21:05:17.879476Z","end":"2026-03-25T21:05:17.989336Z","steps":["trace[1507587009] 'process raft request' (duration: 109.644246ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:17.989576Z","caller":"traceutil/trace.go:171","msg":"trace[1588684133] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"105.272564ms","start":"2026-03-25T21:05:17.884245Z","end":"2026-03-25T21:05:17.989518Z","steps":["trace[1588684133] 'process raft request' (duration: 105.017537ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:18.20771Z","caller":"traceutil/trace.go:171","msg":"trace[1615194330] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"117.670317ms","start":"2026-03-25T21:05:18.090012Z","end":"2026-03-25T21:05:18.207683Z","steps":["trace[1615194330] 'process raft request' (duration: 38.860623ms)","trace[1615194330] 'compare' (duration: 78.693151ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:05:18.216296Z","caller":"traceutil/trace.go:171","msg":"trace[505202442] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"124.894515ms","start":"2026-03-25T21:05:18.091382Z","end":"2026-03-25T21:05:18.216276Z","steps":["trace[505202442] 'process raft request' (duration: 124.762102ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:18.216567Z","caller":"traceutil/trace.go:171","msg":"trace[981663652] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"114.3495ms","start":"2026-03-25T21:05:18.102186Z","end":"2026-03-25T21:05:18.216536Z","steps":["trace[981663652] 'process raft request' (duration: 114.041402ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:18.217237Z","caller":"traceutil/trace.go:171","msg":"trace[998758439] linearizableReadLoop","detail":"{readStateIndex:416; appliedIndex:414; }","duration":"115.261304ms","start":"2026-03-25T21:05:18.101956Z","end":"2026-03-25T21:05:18.217218Z","steps":["trace[998758439] 'read index received' (duration: 26.887361ms)","trace[998758439] 'applied index is now lower than readState.Index' (duration: 88.372642ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:05:18.217393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.407148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" ","response":"range_response_count:1 size:3819"} {"level":"info","ts":"2026-03-25T21:05:18.217515Z","caller":"traceutil/trace.go:171","msg":"trace[1975143658] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-7c96b6546b; range_end:; response_count:1; response_revision:403; }","duration":"115.578561ms","start":"2026-03-25T21:05:18.101918Z","end":"2026-03-25T21:05:18.217497Z","steps":["trace[1975143658] 'agreement among raft nodes before linearized reading' (duration: 115.377116ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:18.217721Z","caller":"traceutil/trace.go:171","msg":"trace[1530714338] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"115.366016ms","start":"2026-03-25T21:05:18.102336Z","end":"2026-03-25T21:05:18.217702Z","steps":["trace[1530714338] 'process raft request' (duration: 114.125274ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:18.217772Z","caller":"traceutil/trace.go:171","msg":"trace[89268447] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"111.788684ms","start":"2026-03-25T21:05:18.105967Z","end":"2026-03-25T21:05:18.217755Z","steps":["trace[89268447] 'process raft request' (duration: 111.165277ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:31.65759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.948751ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:05:31.657739Z","caller":"traceutil/trace.go:171","msg":"trace[893267153] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"401.343219ms","start":"2026-03-25T21:05:31.256376Z","end":"2026-03-25T21:05:31.65772Z","steps":["trace[893267153] 'process raft request' (duration: 162.682159ms)","trace[893267153] 'compare' (duration: 237.828638ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:05:31.65785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:31.256357Z","time spent":"401.41941ms","remote":"127.0.0.1:58198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:05:31.659886Z","caller":"traceutil/trace.go:171","msg":"trace[1650628765] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"403.335751ms","start":"2026-03-25T21:05:31.256521Z","end":"2026-03-25T21:05:31.659857Z","steps":["trace[1650628765] 'process raft request' (duration: 403.238528ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:31.659928Z","caller":"traceutil/trace.go:171","msg":"trace[1336763584] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"403.273179ms","start":"2026-03-25T21:05:31.256622Z","end":"2026-03-25T21:05:31.659895Z","steps":["trace[1336763584] 'process raft request' (duration: 403.179717ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:31.660022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:31.256515Z","time spent":"403.437773ms","remote":"127.0.0.1:58198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T21:05:31.660037Z","caller":"traceutil/trace.go:171","msg":"trace[1055718800] transaction","detail":"{read_only:false; number_of_response:1; response_revision:477; }","duration":"403.543696ms","start":"2026-03-25T21:05:31.256455Z","end":"2026-03-25T21:05:31.659999Z","steps":["trace[1055718800] 'process raft request' (duration: 403.261239ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:31.660146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:31.256608Z","time spent":"403.430014ms","remote":"127.0.0.1:58696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T21:05:31.660182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:31.25644Z","time spent":"403.66491ms","remote":"127.0.0.1:58198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7366,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:05:31.660196Z","caller":"traceutil/trace.go:171","msg":"trace[1047876650] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"399.559033ms","start":"2026-03-25T21:05:31.260599Z","end":"2026-03-25T21:05:31.660158Z","steps":["trace[1047876650] 'process raft request' (duration: 399.309896ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:31.6603Z","caller":"traceutil/trace.go:171","msg":"trace[291305597] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"400.754014ms","start":"2026-03-25T21:05:31.259523Z","end":"2026-03-25T21:05:31.660277Z","steps":["trace[291305597] 'process raft request' (duration: 400.357433ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:31.660319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:31.260583Z","time spent":"399.665985ms","remote":"127.0.0.1:58198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:05:31.660329Z","caller":"traceutil/trace.go:171","msg":"trace[2089850785] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"400.811916ms","start":"2026-03-25T21:05:31.259464Z","end":"2026-03-25T21:05:31.660276Z","steps":["trace[2089850785] 'process raft request' (duration: 400.385244ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:31.660348Z","caller":"traceutil/trace.go:171","msg":"trace[2127200545] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"395.169798ms","start":"2026-03-25T21:05:31.265161Z","end":"2026-03-25T21:05:31.66033Z","steps":["trace[2127200545] 'process raft request' (duration: 394.874621ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:31.660361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:31.259518Z","time spent":"400.816305ms","remote":"127.0.0.1:58198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T21:05:31.660418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:31.265138Z","time spent":"395.24502ms","remote":"127.0.0.1:58198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T21:05:31.660395Z","caller":"traceutil/trace.go:171","msg":"trace[1286187497] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"403.70215ms","start":"2026-03-25T21:05:31.25663Z","end":"2026-03-25T21:05:31.660333Z","steps":["trace[1286187497] 'process raft request' (duration: 403.192217ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:31.660482Z","caller":"traceutil/trace.go:171","msg":"trace[17728177] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"395.322882ms","start":"2026-03-25T21:05:31.265119Z","end":"2026-03-25T21:05:31.660442Z","steps":["trace[17728177] 'process raft request' (duration: 394.88303ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:31.660562Z","caller":"traceutil/trace.go:171","msg":"trace[1629267618] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"368.764039ms","start":"2026-03-25T21:05:31.29179Z","end":"2026-03-25T21:05:31.660554Z","steps":["trace[1629267618] 'process raft request' (duration: 368.312298ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:31.660585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:31.256622Z","time spent":"403.874595ms","remote":"127.0.0.1:58696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T21:05:31.660591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:31.265104Z","time spent":"395.416414ms","remote":"127.0.0.1:58198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T21:05:31.660603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:31.291766Z","time spent":"368.821451ms","remote":"127.0.0.1:58198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T21:05:31.660438Z","caller":"traceutil/trace.go:171","msg":"trace[964465000] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"388.059523ms","start":"2026-03-25T21:05:31.272367Z","end":"2026-03-25T21:05:31.660427Z","steps":["trace[964465000] 'process raft request' (duration: 387.699044ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:31.66075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:31.272349Z","time spent":"388.387291ms","remote":"127.0.0.1:58198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T21:05:31.660423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:31.259456Z","time spent":"400.923408ms","remote":"127.0.0.1:58198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T21:05:31.660679Z","caller":"traceutil/trace.go:171","msg":"trace[651314782] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"339.769123ms","start":"2026-03-25T21:05:31.320901Z","end":"2026-03-25T21:05:31.66067Z","steps":["trace[651314782] 'process raft request' (duration: 339.2393ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:31.660884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:31.320877Z","time spent":"339.99223ms","remote":"127.0.0.1:58198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T21:05:31.666276Z","caller":"traceutil/trace.go:171","msg":"trace[1085902423] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"242.358325ms","start":"2026-03-25T21:05:31.423907Z","end":"2026-03-25T21:05:31.666265Z","steps":["trace[1085902423] 'process raft request' (duration: 242.268213ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:32.516292Z","caller":"traceutil/trace.go:171","msg":"trace[899253694] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"292.327768ms","start":"2026-03-25T21:05:32.223942Z","end":"2026-03-25T21:05:32.51627Z","steps":["trace[899253694] 'process raft request' (duration: 292.162223ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:32.677596Z","caller":"traceutil/trace.go:171","msg":"trace[535522331] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"301.23145ms","start":"2026-03-25T21:05:32.375729Z","end":"2026-03-25T21:05:32.676961Z","steps":["trace[535522331] 'process raft request' (duration: 295.278265ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:32.677713Z","caller":"traceutil/trace.go:171","msg":"trace[235065627] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"143.675414ms","start":"2026-03-25T21:05:32.53398Z","end":"2026-03-25T21:05:32.677656Z","steps":["trace[235065627] 'process raft request' (duration: 142.840702ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:32.677917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:32.375706Z","time spent":"302.067041ms","remote":"127.0.0.1:58198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T21:05:38.154165Z","caller":"traceutil/trace.go:171","msg":"trace[1914907960] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"190.573737ms","start":"2026-03-25T21:05:37.963542Z","end":"2026-03-25T21:05:38.154116Z","steps":["trace[1914907960] 'process raft request' (duration: 188.74883ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:38.465758Z","caller":"traceutil/trace.go:171","msg":"trace[381719644] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"292.373299ms","start":"2026-03-25T21:05:38.173351Z","end":"2026-03-25T21:05:38.465725Z","steps":["trace[381719644] 'process raft request' (duration: 273.416695ms)","trace[381719644] 'compare' (duration: 18.642935ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:05:38.526496Z","caller":"traceutil/trace.go:171","msg":"trace[445171378] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"313.974002ms","start":"2026-03-25T21:05:38.21249Z","end":"2026-03-25T21:05:38.526464Z","steps":["trace[445171378] 'process raft request' (duration: 313.87512ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:38.526613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:38.21246Z","time spent":"314.101345ms","remote":"127.0.0.1:58686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T21:05:39.200162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.787718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T21:05:39.200088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.194315ms","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-03-25T21:05:39.20025Z","caller":"traceutil/trace.go:171","msg":"trace[641676407] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:582; }","duration":"313.917801ms","start":"2026-03-25T21:05:38.886315Z","end":"2026-03-25T21:05:39.200233Z","steps":["trace[641676407] 'range keys from in-memory index tree' (duration: 313.739886ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:39.200296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:38.886306Z","time spent":"313.979703ms","remote":"127.0.0.1:50706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 "} {"level":"warn","ts":"2026-03-25T21:05:39.200299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.173102ms","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-03-25T21:05:39.200313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.848339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:05:39.200353Z","caller":"traceutil/trace.go:171","msg":"trace[869486959] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:582; }","duration":"493.269915ms","start":"2026-03-25T21:05:38.707068Z","end":"2026-03-25T21:05:39.200338Z","steps":["trace[869486959] 'range keys from in-memory index tree' (duration: 493.018908ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:39.200386Z","caller":"traceutil/trace.go:171","msg":"trace[531445047] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:582; }","duration":"313.931151ms","start":"2026-03-25T21:05:38.886432Z","end":"2026-03-25T21:05:39.200363Z","steps":["trace[531445047] 'count revisions from in-memory index tree' (duration: 313.818258ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:39.200397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.884284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T21:05:39.20042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:38.886428Z","time spent":"313.984192ms","remote":"127.0.0.1:50706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"info","ts":"2026-03-25T21:05:39.200443Z","caller":"traceutil/trace.go:171","msg":"trace[2002530281] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:582; }","duration":"312.964725ms","start":"2026-03-25T21:05:38.887468Z","end":"2026-03-25T21:05:39.200432Z","steps":["trace[2002530281] 'range keys from in-memory index tree' (duration: 312.777121ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:39.20047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.518763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T21:05:39.200415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:38.707041Z","time spent":"493.367637ms","remote":"127.0.0.1:58446","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-03-25T21:05:39.20049Z","caller":"traceutil/trace.go:171","msg":"trace[704410888] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:582; }","duration":"340.538964ms","start":"2026-03-25T21:05:38.859944Z","end":"2026-03-25T21:05:39.200483Z","steps":["trace[704410888] 'range keys from in-memory index tree' (duration: 340.432661ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:39.200486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:38.887462Z","time spent":"313.013336ms","remote":"127.0.0.1:50706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 "} {"level":"warn","ts":"2026-03-25T21:05:39.200515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:38.859919Z","time spent":"340.590655ms","remote":"127.0.0.1:50704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"warn","ts":"2026-03-25T21:05:39.200448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.710443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:05:39.200307Z","caller":"traceutil/trace.go:171","msg":"trace[445731138] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:582; }","duration":"340.441322ms","start":"2026-03-25T21:05:38.859838Z","end":"2026-03-25T21:05:39.200279Z","steps":["trace[445731138] 'count revisions from in-memory index tree' (duration: 340.072443ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:39.200634Z","caller":"traceutil/trace.go:171","msg":"trace[1383280795] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:582; }","duration":"339.893117ms","start":"2026-03-25T21:05:38.860731Z","end":"2026-03-25T21:05:39.200624Z","steps":["trace[1383280795] 'range keys from in-memory index tree' (duration: 339.639251ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:39.200654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:38.859821Z","time spent":"340.82135ms","remote":"127.0.0.1:50704","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-03-25T21:05:39.200669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:38.860723Z","time spent":"339.935198ms","remote":"127.0.0.1:50704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"warn","ts":"2026-03-25T21:05:39.200771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.247386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-25T21:05:39.2008Z","caller":"traceutil/trace.go:171","msg":"trace[355682532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:582; }","duration":"490.295017ms","start":"2026-03-25T21:05:38.710498Z","end":"2026-03-25T21:05:39.200793Z","steps":["trace[355682532] 'range keys from in-memory index tree' (duration: 490.183245ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:39.200818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:38.710488Z","time spent":"490.326109ms","remote":"127.0.0.1:58446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-25T21:05:39.201049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.362247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-25T21:05:39.201171Z","caller":"traceutil/trace.go:171","msg":"trace[1302344486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:582; }","duration":"493.564022ms","start":"2026-03-25T21:05:38.707552Z","end":"2026-03-25T21:05:39.201116Z","steps":["trace[1302344486] 'range keys from in-memory index tree' (duration: 493.085161ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:39.201251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:38.707528Z","time spent":"493.709235ms","remote":"127.0.0.1:58446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-25T21:05:39.202925Z","caller":"traceutil/trace.go:171","msg":"trace[1606582892] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"267.818929ms","start":"2026-03-25T21:05:38.935094Z","end":"2026-03-25T21:05:39.202913Z","steps":["trace[1606582892] 'read index received' (duration: 267.129551ms)","trace[1606582892] 'applied index is now lower than readState.Index' (duration: 688.828µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:05:39.203022Z","caller":"traceutil/trace.go:171","msg":"trace[92235130] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"278.226631ms","start":"2026-03-25T21:05:38.924755Z","end":"2026-03-25T21:05:39.202982Z","steps":["trace[92235130] 'process raft request' (duration: 277.664786ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:39.20305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.949802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:05:39.203678Z","caller":"traceutil/trace.go:171","msg":"trace[1752254699] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:583; }","duration":"268.56321ms","start":"2026-03-25T21:05:38.935085Z","end":"2026-03-25T21:05:39.203648Z","steps":["trace[1752254699] 'agreement among raft nodes before linearized reading' (duration: 267.901342ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:39.203702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.338203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:05:39.203734Z","caller":"traceutil/trace.go:171","msg":"trace[530820762] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:583; }","duration":"268.470936ms","start":"2026-03-25T21:05:38.935254Z","end":"2026-03-25T21:05:39.203725Z","steps":["trace[530820762] 'agreement among raft nodes before linearized reading' (duration: 268.329603ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:39.203831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.841957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T21:05:39.203866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.091322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:05:39.203869Z","caller":"traceutil/trace.go:171","msg":"trace[336335467] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:583; }","duration":"174.921769ms","start":"2026-03-25T21:05:39.028936Z","end":"2026-03-25T21:05:39.203858Z","steps":["trace[336335467] 'agreement among raft nodes before linearized reading' (duration: 174.846897ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:39.203892Z","caller":"traceutil/trace.go:171","msg":"trace[1729546304] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:583; }","duration":"226.139853ms","start":"2026-03-25T21:05:38.977746Z","end":"2026-03-25T21:05:39.203886Z","steps":["trace[1729546304] 'agreement among raft nodes before linearized reading' (duration: 226.105273ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:39.204061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.93753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-25T21:05:39.204064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.359999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:05:39.204089Z","caller":"traceutil/trace.go:171","msg":"trace[381013677] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:583; }","duration":"129.011101ms","start":"2026-03-25T21:05:39.075071Z","end":"2026-03-25T21:05:39.204082Z","steps":["trace[381013677] 'agreement among raft nodes before linearized reading' (duration: 128.916049ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:39.204093Z","caller":"traceutil/trace.go:171","msg":"trace[232167615] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:583; }","duration":"226.41693ms","start":"2026-03-25T21:05:38.977669Z","end":"2026-03-25T21:05:39.204086Z","steps":["trace[232167615] 'agreement among raft nodes before linearized reading' (duration: 226.374039ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:39.203838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.335272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T21:05:39.204197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.61903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:05:39.204199Z","caller":"traceutil/trace.go:171","msg":"trace[678711553] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:583; }","duration":"225.717202ms","start":"2026-03-25T21:05:38.978474Z","end":"2026-03-25T21:05:39.204192Z","steps":["trace[678711553] 'agreement among raft nodes before linearized reading' (duration: 225.343613ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:39.204216Z","caller":"traceutil/trace.go:171","msg":"trace[184546172] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:583; }","duration":"174.670651ms","start":"2026-03-25T21:05:39.029539Z","end":"2026-03-25T21:05:39.20421Z","steps":["trace[184546172] 'agreement among raft nodes before linearized reading' (duration: 174.645031ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:39.204173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.157854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:05:39.204306Z","caller":"traceutil/trace.go:171","msg":"trace[1409306551] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:583; }","duration":"175.30808ms","start":"2026-03-25T21:05:39.028991Z","end":"2026-03-25T21:05:39.204299Z","steps":["trace[1409306551] 'agreement among raft nodes before linearized reading' (duration: 175.142024ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:55.355632Z","caller":"traceutil/trace.go:171","msg":"trace[1011499464] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"126.705714ms","start":"2026-03-25T21:05:55.228886Z","end":"2026-03-25T21:05:55.355591Z","steps":["trace[1011499464] 'process raft request' (duration: 126.245672ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:06:17.47164Z","caller":"traceutil/trace.go:171","msg":"trace[1694908279] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"123.116531ms","start":"2026-03-25T21:06:17.348498Z","end":"2026-03-25T21:06:17.471614Z","steps":["trace[1694908279] 'process raft request' (duration: 122.894385ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:06.650377Z","caller":"traceutil/trace.go:171","msg":"trace[382364257] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"119.531578ms","start":"2026-03-25T21:07:06.530814Z","end":"2026-03-25T21:07:06.650345Z","steps":["trace[382364257] 'process raft request' (duration: 95.126588ms)","trace[382364257] 'compare' (duration: 24.094092ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:07:06.883187Z","caller":"traceutil/trace.go:171","msg":"trace[568658468] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"226.615709ms","start":"2026-03-25T21:07:06.656545Z","end":"2026-03-25T21:07:06.883161Z","steps":["trace[568658468] 'process raft request' (duration: 226.443015ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:07.184893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.51254ms","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-03-25T21:07:07.184984Z","caller":"traceutil/trace.go:171","msg":"trace[1009551851] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1790; }","duration":"142.668244ms","start":"2026-03-25T21:07:07.042294Z","end":"2026-03-25T21:07:07.184962Z","steps":["trace[1009551851] 'range keys from in-memory index tree' (duration: 142.312026ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:07.185117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.794702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-03-25T21:07:07.185217Z","caller":"traceutil/trace.go:171","msg":"trace[1950164555] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1790; }","duration":"237.986398ms","start":"2026-03-25T21:07:06.947206Z","end":"2026-03-25T21:07:07.185192Z","steps":["trace[1950164555] 'range keys from in-memory index tree' (duration: 237.588657ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:56.998412Z","caller":"traceutil/trace.go:171","msg":"trace[950026556] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"152.143374ms","start":"2026-03-25T21:07:56.846241Z","end":"2026-03-25T21:07:56.998384Z","steps":["trace[950026556] 'process raft request' (duration: 151.985409ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:08:41.262802Z","caller":"traceutil/trace.go:171","msg":"trace[2053748893] transaction","detail":"{read_only:false; response_revision:2491; number_of_response:1; }","duration":"100.258089ms","start":"2026-03-25T21:08:41.162516Z","end":"2026-03-25T21:08:41.262774Z","steps":["trace[2053748893] 'process raft request' (duration: 44.235677ms)","trace[2053748893] 'compare' (duration: 55.872528ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:08:45.694631Z","caller":"traceutil/trace.go:171","msg":"trace[1229697479] transaction","detail":"{read_only:false; response_revision:2555; number_of_response:1; }","duration":"141.62883ms","start":"2026-03-25T21:08:45.552978Z","end":"2026-03-25T21:08:45.694606Z","steps":["trace[1229697479] 'process raft request' (duration: 141.2095ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:46.467152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.001843ms","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-03-25T21:08:46.4672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.540114ms","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-03-25T21:08:46.467373Z","caller":"traceutil/trace.go:171","msg":"trace[1580691222] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2559; }","duration":"331.700798ms","start":"2026-03-25T21:08:46.135605Z","end":"2026-03-25T21:08:46.467305Z","steps":["trace[1580691222] 'range keys from in-memory index tree' (duration: 331.345189ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:08:46.467434Z","caller":"traceutil/trace.go:171","msg":"trace[277142760] linearizableReadLoop","detail":"{readStateIndex:2657; appliedIndex:2656; }","duration":"115.953324ms","start":"2026-03-25T21:08:46.351468Z","end":"2026-03-25T21:08:46.467421Z","steps":["trace[277142760] 'read index received' (duration: 89.095369ms)","trace[277142760] 'applied index is now lower than readState.Index' (duration: 26.856845ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:08:46.467254Z","caller":"traceutil/trace.go:171","msg":"trace[2067484900] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2559; }","duration":"377.171827ms","start":"2026-03-25T21:08:46.090062Z","end":"2026-03-25T21:08:46.467234Z","steps":["trace[2067484900] 'count revisions from in-memory index tree' (duration: 376.90624ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:46.467455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:46.13557Z","time spent":"331.861232ms","remote":"127.0.0.1:58362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-03-25T21:08:46.467508Z","caller":"traceutil/trace.go:171","msg":"trace[2069965313] transaction","detail":"{read_only:false; response_revision:2560; number_of_response:1; }","duration":"158.424596ms","start":"2026-03-25T21:08:46.309075Z","end":"2026-03-25T21:08:46.4675Z","steps":["trace[2069965313] 'process raft request' (duration: 131.589421ms)","trace[2069965313] 'compare' (duration: 26.469926ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:08:46.467523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:46.090036Z","time spent":"377.448703ms","remote":"127.0.0.1:50924","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-03-25T21:08:46.467712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.225823ms","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-03-25T21:08:46.467786Z","caller":"traceutil/trace.go:171","msg":"trace[2093227239] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2560; }","duration":"116.331515ms","start":"2026-03-25T21:08:46.351432Z","end":"2026-03-25T21:08:46.467764Z","steps":["trace[2093227239] 'agreement among raft nodes before linearized reading' (duration: 116.125749ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:46.468027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.720974ms","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-03-25T21:08:46.46803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.365394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-25T21:08:46.468081Z","caller":"traceutil/trace.go:171","msg":"trace[1757687569] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2560; }","duration":"114.798276ms","start":"2026-03-25T21:08:46.353265Z","end":"2026-03-25T21:08:46.468063Z","steps":["trace[1757687569] 'agreement among raft nodes before linearized reading' (duration: 114.670613ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:08:46.468088Z","caller":"traceutil/trace.go:171","msg":"trace[652226584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2560; }","duration":"109.438186ms","start":"2026-03-25T21:08:46.358636Z","end":"2026-03-25T21:08:46.468074Z","steps":["trace[652226584] 'agreement among raft nodes before linearized reading' (duration: 109.303173ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:46.468251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.655381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-25T21:08:46.468293Z","caller":"traceutil/trace.go:171","msg":"trace[528194576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2560; }","duration":"109.725683ms","start":"2026-03-25T21:08:46.358556Z","end":"2026-03-25T21:08:46.468281Z","steps":["trace[528194576] 'agreement among raft nodes before linearized reading' (duration: 109.614531ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:20.306697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.492959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:09:20.306831Z","caller":"traceutil/trace.go:171","msg":"trace[597622792] linearizableReadLoop","detail":"{readStateIndex:2868; appliedIndex:2867; }","duration":"292.515802ms","start":"2026-03-25T21:09:20.014296Z","end":"2026-03-25T21:09:20.306812Z","steps":["trace[597622792] 'read index received' (duration: 77.687994ms)","trace[597622792] 'applied index is now lower than readState.Index' (duration: 214.826197ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:09:20.30709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.775738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-03-25T21:09:20.307379Z","caller":"traceutil/trace.go:171","msg":"trace[366086511] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2763; }","duration":"293.114097ms","start":"2026-03-25T21:09:20.014243Z","end":"2026-03-25T21:09:20.307357Z","steps":["trace[366086511] 'agreement among raft nodes before linearized reading' (duration: 292.658375ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:20.307399Z","caller":"traceutil/trace.go:171","msg":"trace[944977584] transaction","detail":"{read_only:false; response_revision:2763; number_of_response:1; }","duration":"398.042187ms","start":"2026-03-25T21:09:19.909322Z","end":"2026-03-25T21:09:20.307364Z","steps":["trace[944977584] 'process raft request' (duration: 182.769767ms)","trace[944977584] 'compare' (duration: 214.059018ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:09:20.307558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:19.909297Z","time spent":"398.17297ms","remote":"127.0.0.1:58446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:09:20.307628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.95644ms","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-03-25T21:09:20.307676Z","caller":"traceutil/trace.go:171","msg":"trace[1596325554] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2763; }","duration":"171.006561ms","start":"2026-03-25T21:09:20.136656Z","end":"2026-03-25T21:09:20.307662Z","steps":["trace[1596325554] 'agreement among raft nodes before linearized reading' (duration: 170.251722ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:11:45.551601Z","caller":"traceutil/trace.go:171","msg":"trace[670491379] linearizableReadLoop","detail":"{readStateIndex:3871; appliedIndex:3870; }","duration":"169.723861ms","start":"2026-03-25T21:11:45.38185Z","end":"2026-03-25T21:11:45.551574Z","steps":["trace[670491379] 'read index received' (duration: 169.498936ms)","trace[670491379] 'applied index is now lower than readState.Index' (duration: 223.945µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:11:45.551744Z","caller":"traceutil/trace.go:171","msg":"trace[466563765] transaction","detail":"{read_only:false; response_revision:3730; number_of_response:1; }","duration":"170.295827ms","start":"2026-03-25T21:11:45.381397Z","end":"2026-03-25T21:11:45.551693Z","steps":["trace[466563765] 'process raft request' (duration: 170.015079ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:11:45.551852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.655535ms","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-03-25T21:11:45.55191Z","caller":"traceutil/trace.go:171","msg":"trace[395854131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3730; }","duration":"168.771028ms","start":"2026-03-25T21:11:45.383128Z","end":"2026-03-25T21:11:45.551899Z","steps":["trace[395854131] 'agreement among raft nodes before linearized reading' (duration: 168.591884ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:11:45.551929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.05523ms","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-03-25T21:11:45.552027Z","caller":"traceutil/trace.go:171","msg":"trace[1585260535] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3730; }","duration":"170.184293ms","start":"2026-03-25T21:11:45.381819Z","end":"2026-03-25T21:11:45.552003Z","steps":["trace[1585260535] 'agreement among raft nodes before linearized reading' (duration: 169.906537ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:11:45.552076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.482131ms","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-03-25T21:11:45.552199Z","caller":"traceutil/trace.go:171","msg":"trace[627558531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3730; }","duration":"168.666846ms","start":"2026-03-25T21:11:45.383514Z","end":"2026-03-25T21:11:45.552181Z","steps":["trace[627558531] 'agreement among raft nodes before linearized reading' (duration: 168.318927ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:02.505285Z","caller":"traceutil/trace.go:171","msg":"trace[1903447550] transaction","detail":"{read_only:false; response_revision:4602; number_of_response:1; }","duration":"166.800829ms","start":"2026-03-25T21:13:02.338452Z","end":"2026-03-25T21:13:02.505252Z","steps":["trace[1903447550] 'process raft request' (duration: 77.171372ms)","trace[1903447550] 'compare' (duration: 89.403411ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:02.505286Z","caller":"traceutil/trace.go:171","msg":"trace[1909687745] linearizableReadLoop","detail":"{readStateIndex:4778; appliedIndex:4777; }","duration":"129.851781ms","start":"2026-03-25T21:13:02.375399Z","end":"2026-03-25T21:13:02.505251Z","steps":["trace[1909687745] 'read index received' (duration: 40.240225ms)","trace[1909687745] 'applied index is now lower than readState.Index' (duration: 89.609746ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:13:02.505505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.089797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-03-25T21:13:02.505588Z","caller":"traceutil/trace.go:171","msg":"trace[258013667] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4602; }","duration":"130.255522ms","start":"2026-03-25T21:13:02.375319Z","end":"2026-03-25T21:13:02.505574Z","steps":["trace[258013667] 'agreement among raft nodes before linearized reading' (duration: 129.974804ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:02.521753Z","caller":"traceutil/trace.go:171","msg":"trace[1475270565] transaction","detail":"{read_only:false; response_revision:4604; number_of_response:1; }","duration":"129.509411ms","start":"2026-03-25T21:13:02.392222Z","end":"2026-03-25T21:13:02.521732Z","steps":["trace[1475270565] 'process raft request' (duration: 129.369918ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:02.521865Z","caller":"traceutil/trace.go:171","msg":"trace[1143683243] transaction","detail":"{read_only:false; response_revision:4603; number_of_response:1; }","duration":"131.849462ms","start":"2026-03-25T21:13:02.389991Z","end":"2026-03-25T21:13:02.52184Z","steps":["trace[1143683243] 'process raft request' (duration: 131.431921ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:02.522305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.507577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-03-25T21:13:02.522584Z","caller":"traceutil/trace.go:171","msg":"trace[60997553] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4604; }","duration":"140.714662ms","start":"2026-03-25T21:13:02.381686Z","end":"2026-03-25T21:13:02.522401Z","steps":["trace[60997553] 'agreement among raft nodes before linearized reading' (duration: 140.2576ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:07.809558Z","caller":"traceutil/trace.go:171","msg":"trace[508879641] linearizableReadLoop","detail":"{readStateIndex:4951; appliedIndex:4950; }","duration":"109.286536ms","start":"2026-03-25T21:13:07.70025Z","end":"2026-03-25T21:13:07.809537Z","steps":["trace[508879641] 'read index received' (duration: 109.085411ms)","trace[508879641] 'applied index is now lower than readState.Index' (duration: 199.775µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:07.809701Z","caller":"traceutil/trace.go:171","msg":"trace[1515691433] transaction","detail":"{read_only:false; response_revision:4767; number_of_response:1; }","duration":"130.972699ms","start":"2026-03-25T21:13:07.678683Z","end":"2026-03-25T21:13:07.809656Z","steps":["trace[1515691433] 'process raft request' (duration: 130.695832ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:07.809779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.519822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-03-25T21:13:07.809844Z","caller":"traceutil/trace.go:171","msg":"trace[2118012378] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4767; }","duration":"109.595854ms","start":"2026-03-25T21:13:07.700233Z","end":"2026-03-25T21:13:07.809829Z","steps":["trace[2118012378] 'agreement among raft nodes before linearized reading' (duration: 109.441901ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:08.629628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.774725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-25T21:13:08.629743Z","caller":"traceutil/trace.go:171","msg":"trace[697766623] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4779; }","duration":"162.969539ms","start":"2026-03-25T21:13:08.466748Z","end":"2026-03-25T21:13:08.629718Z","steps":["trace[697766623] 'count revisions from in-memory index tree' (duration: 162.661471ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:08.629772Z","caller":"traceutil/trace.go:171","msg":"trace[806759819] transaction","detail":"{read_only:false; response_revision:4780; number_of_response:1; }","duration":"150.890856ms","start":"2026-03-25T21:13:08.478832Z","end":"2026-03-25T21:13:08.629723Z","steps":["trace[806759819] 'process raft request' (duration: 149.587733ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:11.013583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.001733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:13:11.013745Z","caller":"traceutil/trace.go:171","msg":"trace[1649957931] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4900; }","duration":"217.238459ms","start":"2026-03-25T21:13:10.796476Z","end":"2026-03-25T21:13:11.013715Z","steps":["trace[1649957931] 'range keys from in-memory index tree' (duration: 216.833748ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:11.013967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.654842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-25T21:13:11.014067Z","caller":"traceutil/trace.go:171","msg":"trace[1745342386] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:4900; }","duration":"503.773105ms","start":"2026-03-25T21:13:10.510268Z","end":"2026-03-25T21:13:11.014041Z","steps":["trace[1745342386] 'count revisions from in-memory index tree' (duration: 503.539299ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:11.01412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:10.510182Z","time spent":"503.92592ms","remote":"127.0.0.1:58312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":4,"response size":30,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-03-25T21:13:11.014122Z","caller":"traceutil/trace.go:171","msg":"trace[687329279] transaction","detail":"{read_only:false; response_revision:4901; number_of_response:1; }","duration":"201.311294ms","start":"2026-03-25T21:13:10.812782Z","end":"2026-03-25T21:13:11.014093Z","steps":["trace[687329279] 'process raft request' (duration: 112.955231ms)","trace[687329279] 'compare' (duration: 88.107687ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:13:11.0145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.946305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:719257"} {"level":"info","ts":"2026-03-25T21:13:11.014577Z","caller":"traceutil/trace.go:171","msg":"trace[542449382] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4900; }","duration":"310.072748ms","start":"2026-03-25T21:13:10.704486Z","end":"2026-03-25T21:13:11.014559Z","steps":["trace[542449382] 'range keys from in-memory index tree' (duration: 309.510784ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:11.015739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:10.704466Z","time spent":"311.250609ms","remote":"127.0.0.1:58242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":24,"response size":719280,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"warn","ts":"2026-03-25T21:13:15.240952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.350368ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:13:15.241279Z","caller":"traceutil/trace.go:171","msg":"trace[292835224] transaction","detail":"{read_only:false; response_revision:4984; number_of_response:1; }","duration":"273.348325ms","start":"2026-03-25T21:13:14.967906Z","end":"2026-03-25T21:13:15.241254Z","steps":["trace[292835224] 'process raft request' (duration: 138.590058ms)","trace[292835224] 'compare' (duration: 134.136211ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:15.241325Z","caller":"traceutil/trace.go:171","msg":"trace[1773226130] transaction","detail":"{read_only:false; response_revision:4985; number_of_response:1; }","duration":"216.810087ms","start":"2026-03-25T21:13:15.024495Z","end":"2026-03-25T21:13:15.241305Z","steps":["trace[1773226130] 'process raft request' (duration: 216.702465ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:15.241378Z","caller":"traceutil/trace.go:171","msg":"trace[1788520951] linearizableReadLoop","detail":"{readStateIndex:5174; appliedIndex:5173; }","duration":"222.786433ms","start":"2026-03-25T21:13:15.018528Z","end":"2026-03-25T21:13:15.241314Z","steps":["trace[1788520951] 'read index received' (duration: 87.989324ms)","trace[1788520951] 'applied index is now lower than readState.Index' (duration: 134.792498ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:13:15.241724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.186904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:413130"} {"level":"info","ts":"2026-03-25T21:13:15.241894Z","caller":"traceutil/trace.go:171","msg":"trace[606616045] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:4985; }","duration":"223.403849ms","start":"2026-03-25T21:13:15.018473Z","end":"2026-03-25T21:13:15.241876Z","steps":["trace[606616045] 'agreement among raft nodes before linearized reading' (duration: 223.01031ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:15.666501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.657677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:13:15.666689Z","caller":"traceutil/trace.go:171","msg":"trace[86709929] linearizableReadLoop","detail":"{readStateIndex:5178; appliedIndex:5175; }","duration":"418.604935ms","start":"2026-03-25T21:13:15.248067Z","end":"2026-03-25T21:13:15.666672Z","steps":["trace[86709929] 'read index received' (duration: 20.484991ms)","trace[86709929] 'applied index is now lower than readState.Index' (duration: 398.118974ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:15.666839Z","caller":"traceutil/trace.go:171","msg":"trace[1903613904] transaction","detail":"{read_only:false; response_revision:4987; number_of_response:1; }","duration":"419.14528ms","start":"2026-03-25T21:13:15.247679Z","end":"2026-03-25T21:13:15.666824Z","steps":["trace[1903613904] 'process raft request' (duration: 297.038921ms)","trace[1903613904] 'compare' (duration: 121.552585ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:15.666838Z","caller":"traceutil/trace.go:171","msg":"trace[1128596588] transaction","detail":"{read_only:false; response_revision:4988; number_of_response:1; }","duration":"418.8051ms","start":"2026-03-25T21:13:15.247994Z","end":"2026-03-25T21:13:15.6668Z","steps":["trace[1128596588] 'process raft request' (duration: 418.610526ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:15.666902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:15.247653Z","time spent":"419.219861ms","remote":"127.0.0.1:58356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4687,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:13:15.667001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:15.247986Z","time spent":"418.938094ms","remote":"127.0.0.1:58446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:13:15.667025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.929443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62865"} {"level":"info","ts":"2026-03-25T21:13:15.667093Z","caller":"traceutil/trace.go:171","msg":"trace[1535240265] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:4988; }","duration":"419.032606ms","start":"2026-03-25T21:13:15.248043Z","end":"2026-03-25T21:13:15.667075Z","steps":["trace[1535240265] 'agreement among raft nodes before linearized reading' (duration: 418.743199ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:15.667147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:15.248036Z","time spent":"419.102049ms","remote":"127.0.0.1:58356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":7,"response size":62888,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-03-25T21:13:15.672099Z","caller":"traceutil/trace.go:171","msg":"trace[1264686367] transaction","detail":"{read_only:false; response_revision:4991; number_of_response:1; }","duration":"205.628938ms","start":"2026-03-25T21:13:15.466453Z","end":"2026-03-25T21:13:15.672082Z","steps":["trace[1264686367] 'process raft request' (duration: 205.585057ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:15.672101Z","caller":"traceutil/trace.go:171","msg":"trace[969801307] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"395.034984ms","start":"2026-03-25T21:13:15.277043Z","end":"2026-03-25T21:13:15.672078Z","steps":["trace[969801307] 'process raft request' (duration: 394.803927ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:15.672483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:15.277018Z","time spent":"395.431934ms","remote":"127.0.0.1:58650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2657,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:13:15.672226Z","caller":"traceutil/trace.go:171","msg":"trace[523119210] transaction","detail":"{read_only:false; response_revision:4990; number_of_response:1; }","duration":"303.050336ms","start":"2026-03-25T21:13:15.369158Z","end":"2026-03-25T21:13:15.672208Z","steps":["trace[523119210] 'process raft request' (duration: 302.826659ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:15.672635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:15.369135Z","time spent":"303.424195ms","remote":"127.0.0.1:58446","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-03-25T21:13:15.680316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.277183ms","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":"info","ts":"2026-03-25T21:13:15.680366Z","caller":"traceutil/trace.go:171","msg":"trace[532268852] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:4992; }","duration":"226.370065ms","start":"2026-03-25T21:13:15.453984Z","end":"2026-03-25T21:13:15.680354Z","steps":["trace[532268852] 'agreement among raft nodes before linearized reading' (duration: 226.264642ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:15.680372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.256589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-03-25T21:13:15.680415Z","caller":"traceutil/trace.go:171","msg":"trace[1301768467] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4992; }","duration":"108.333232ms","start":"2026-03-25T21:13:15.572069Z","end":"2026-03-25T21:13:15.680402Z","steps":["trace[1301768467] 'agreement among raft nodes before linearized reading' (duration: 108.214268ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:15.680758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.63438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-03-25T21:13:15.680782Z","caller":"traceutil/trace.go:171","msg":"trace[1555845335] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4992; }","duration":"165.65995ms","start":"2026-03-25T21:13:15.515113Z","end":"2026-03-25T21:13:15.680773Z","steps":["trace[1555845335] 'agreement among raft nodes before linearized reading' (duration: 165.576598ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:15.680913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.410923ms","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-03-25T21:13:15.680934Z","caller":"traceutil/trace.go:171","msg":"trace[653799469] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4992; }","duration":"190.433283ms","start":"2026-03-25T21:13:15.490496Z","end":"2026-03-25T21:13:15.680929Z","steps":["trace[653799469] 'agreement among raft nodes before linearized reading' (duration: 190.376722ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:15.681073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.424753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T21:13:15.681105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.374746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12973"} {"level":"info","ts":"2026-03-25T21:13:15.681146Z","caller":"traceutil/trace.go:171","msg":"trace[1219181392] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:4992; }","duration":"222.595198ms","start":"2026-03-25T21:13:15.458534Z","end":"2026-03-25T21:13:15.681129Z","steps":["trace[1219181392] 'agreement among raft nodes before linearized reading' (duration: 222.417003ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:15.68116Z","caller":"traceutil/trace.go:171","msg":"trace[242056737] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4992; }","duration":"216.457519ms","start":"2026-03-25T21:13:15.464692Z","end":"2026-03-25T21:13:15.681149Z","steps":["trace[242056737] 'agreement among raft nodes before linearized reading' (duration: 216.315575ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:22.172874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.742114ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:13:22.173041Z","caller":"traceutil/trace.go:171","msg":"trace[102504166] transaction","detail":"{read_only:false; response_revision:5124; number_of_response:1; }","duration":"123.363984ms","start":"2026-03-25T21:13:22.049658Z","end":"2026-03-25T21:13:22.173022Z","steps":["trace[102504166] 'process raft request' (duration: 14.397814ms)","trace[102504166] 'compare' (duration: 108.56902ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:30.635784Z","caller":"traceutil/trace.go:171","msg":"trace[1552618548] transaction","detail":"{read_only:false; response_revision:5263; number_of_response:1; }","duration":"317.481987ms","start":"2026-03-25T21:13:30.318257Z","end":"2026-03-25T21:13:30.635739Z","steps":["trace[1552618548] 'process raft request' (duration: 314.240393ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:30.635946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:30.31823Z","time spent":"317.637752ms","remote":"127.0.0.1:58446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:13:30.640335Z","caller":"traceutil/trace.go:171","msg":"trace[1379573343] transaction","detail":"{read_only:false; response_revision:5266; number_of_response:1; }","duration":"227.163861ms","start":"2026-03-25T21:13:30.413112Z","end":"2026-03-25T21:13:30.640276Z","steps":["trace[1379573343] 'process raft request' (duration: 227.060828ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:30.640358Z","caller":"traceutil/trace.go:171","msg":"trace[783725417] transaction","detail":"{read_only:false; response_revision:5264; number_of_response:1; }","duration":"321.887882ms","start":"2026-03-25T21:13:30.318425Z","end":"2026-03-25T21:13:30.640313Z","steps":["trace[783725417] 'process raft request' (duration: 321.581583ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:30.640527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:30.318416Z","time spent":"322.026715ms","remote":"127.0.0.1:58446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:13:30.640833Z","caller":"traceutil/trace.go:171","msg":"trace[1277080535] transaction","detail":"{read_only:false; response_revision:5265; number_of_response:1; }","duration":"322.322174ms","start":"2026-03-25T21:13:30.318467Z","end":"2026-03-25T21:13:30.640789Z","steps":["trace[1277080535] 'process raft request' (duration: 321.666226ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:30.640967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:30.31845Z","time spent":"322.438106ms","remote":"127.0.0.1:58446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:13:30.838267Z","caller":"traceutil/trace.go:171","msg":"trace[757365087] linearizableReadLoop","detail":"{readStateIndex:5463; appliedIndex:5462; }","duration":"166.149026ms","start":"2026-03-25T21:13:30.67209Z","end":"2026-03-25T21:13:30.838239Z","steps":["trace[757365087] 'read index received' (duration: 118.436186ms)","trace[757365087] 'applied index is now lower than readState.Index' (duration: 47.71112ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:30.838448Z","caller":"traceutil/trace.go:171","msg":"trace[232055246] transaction","detail":"{read_only:false; response_revision:5267; number_of_response:1; }","duration":"188.602619ms","start":"2026-03-25T21:13:30.649822Z","end":"2026-03-25T21:13:30.838424Z","steps":["trace[232055246] 'process raft request' (duration: 140.89489ms)","trace[232055246] 'compare' (duration: 47.263567ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:13:30.838577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.5214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-03-25T21:13:30.838637Z","caller":"traceutil/trace.go:171","msg":"trace[1479654201] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5267; }","duration":"155.618312ms","start":"2026-03-25T21:13:30.683002Z","end":"2026-03-25T21:13:30.83862Z","steps":["trace[1479654201] 'agreement among raft nodes before linearized reading' (duration: 155.411337ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:30.838998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.455534ms","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-03-25T21:13:30.839126Z","caller":"traceutil/trace.go:171","msg":"trace[2016896114] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5267; }","duration":"167.05762ms","start":"2026-03-25T21:13:30.672038Z","end":"2026-03-25T21:13:30.839095Z","steps":["trace[2016896114] 'agreement among raft nodes before linearized reading' (duration: 166.361102ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:31.134899Z","caller":"traceutil/trace.go:171","msg":"trace[81828016] transaction","detail":"{read_only:false; response_revision:5269; number_of_response:1; }","duration":"291.114191ms","start":"2026-03-25T21:13:30.843752Z","end":"2026-03-25T21:13:31.134866Z","steps":["trace[81828016] 'process raft request' (duration: 256.285756ms)","trace[81828016] 'compare' (duration: 34.602089ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:31.135138Z","caller":"traceutil/trace.go:171","msg":"trace[1280458330] transaction","detail":"{read_only:false; response_revision:5270; number_of_response:1; }","duration":"291.256026ms","start":"2026-03-25T21:13:30.843856Z","end":"2026-03-25T21:13:31.135112Z","steps":["trace[1280458330] 'process raft request' (duration: 290.947937ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:31.135396Z","caller":"traceutil/trace.go:171","msg":"trace[1401892878] transaction","detail":"{read_only:false; response_revision:5271; number_of_response:1; }","duration":"291.071801ms","start":"2026-03-25T21:13:30.844308Z","end":"2026-03-25T21:13:31.13538Z","steps":["trace[1401892878] 'process raft request' (duration: 290.711662ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:31.135657Z","caller":"traceutil/trace.go:171","msg":"trace[69308346] transaction","detail":"{read_only:false; response_revision:5272; number_of_response:1; }","duration":"291.273625ms","start":"2026-03-25T21:13:30.844359Z","end":"2026-03-25T21:13:31.135632Z","steps":["trace[69308346] 'process raft request' (duration: 290.975188ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:31.135717Z","caller":"traceutil/trace.go:171","msg":"trace[393347812] transaction","detail":"{read_only:false; response_revision:5273; number_of_response:1; }","duration":"291.298507ms","start":"2026-03-25T21:13:30.844378Z","end":"2026-03-25T21:13:31.135676Z","steps":["trace[393347812] 'process raft request' (duration: 291.119822ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:31.135912Z","caller":"traceutil/trace.go:171","msg":"trace[1805622892] linearizableReadLoop","detail":"{readStateIndex:5471; appliedIndex:5464; }","duration":"160.276414ms","start":"2026-03-25T21:13:30.975616Z","end":"2026-03-25T21:13:31.135893Z","steps":["trace[1805622892] 'read index received' (duration: 124.385621ms)","trace[1805622892] 'applied index is now lower than readState.Index' (duration: 35.888953ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:31.136137Z","caller":"traceutil/trace.go:171","msg":"trace[1318385049] transaction","detail":"{read_only:false; response_revision:5274; number_of_response:1; }","duration":"290.637799ms","start":"2026-03-25T21:13:30.845483Z","end":"2026-03-25T21:13:31.136121Z","steps":["trace[1318385049] 'process raft request' (duration: 290.107405ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:31.13611Z","caller":"traceutil/trace.go:171","msg":"trace[344714250] transaction","detail":"{read_only:false; response_revision:5275; number_of_response:1; }","duration":"288.682198ms","start":"2026-03-25T21:13:30.84739Z","end":"2026-03-25T21:13:31.136072Z","steps":["trace[344714250] 'process raft request' (duration: 288.401721ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:31.136154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.521989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8b2db1f8-b2cd-47dc-b307-663faa035d41\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-03-25T21:13:31.136298Z","caller":"traceutil/trace.go:171","msg":"trace[480127873] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8b2db1f8-b2cd-47dc-b307-663faa035d41; range_end:; response_count:1; response_revision:5275; }","duration":"160.710824ms","start":"2026-03-25T21:13:30.975561Z","end":"2026-03-25T21:13:31.136272Z","steps":["trace[480127873] 'agreement among raft nodes before linearized reading' (duration: 160.513729ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:32.875712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.298837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:13:32.875828Z","caller":"traceutil/trace.go:171","msg":"trace[316198388] linearizableReadLoop","detail":"{readStateIndex:5488; appliedIndex:5487; }","duration":"138.320782ms","start":"2026-03-25T21:13:32.737492Z","end":"2026-03-25T21:13:32.875812Z","steps":["trace[316198388] 'read index received' (duration: 25.803299ms)","trace[316198388] 'applied index is now lower than readState.Index' (duration: 112.516413ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:32.875869Z","caller":"traceutil/trace.go:171","msg":"trace[296177818] transaction","detail":"{read_only:false; response_revision:5292; number_of_response:1; }","duration":"230.20402ms","start":"2026-03-25T21:13:32.645632Z","end":"2026-03-25T21:13:32.875836Z","steps":["trace[296177818] 'process raft request' (duration: 117.698317ms)","trace[296177818] 'compare' (duration: 112.186885ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:13:32.875969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.136099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-25T21:13:32.876004Z","caller":"traceutil/trace.go:171","msg":"trace[1046572609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5292; }","duration":"232.18167ms","start":"2026-03-25T21:13:32.643813Z","end":"2026-03-25T21:13:32.875995Z","steps":["trace[1046572609] 'agreement among raft nodes before linearized reading' (duration: 232.065038ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:32.87791Z","caller":"traceutil/trace.go:171","msg":"trace[567693032] transaction","detail":"{read_only:false; response_revision:5293; number_of_response:1; }","duration":"136.204808ms","start":"2026-03-25T21:13:32.741686Z","end":"2026-03-25T21:13:32.877891Z","steps":["trace[567693032] 'process raft request' (duration: 136.045894ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:45.170872Z","caller":"traceutil/trace.go:171","msg":"trace[1910269751] linearizableReadLoop","detail":"{readStateIndex:5671; appliedIndex:5670; }","duration":"426.61902ms","start":"2026-03-25T21:13:44.744229Z","end":"2026-03-25T21:13:45.170848Z","steps":["trace[1910269751] 'read index received' (duration: 426.496777ms)","trace[1910269751] 'applied index is now lower than readState.Index' (duration: 121.203µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:45.170941Z","caller":"traceutil/trace.go:171","msg":"trace[1458140658] transaction","detail":"{read_only:false; response_revision:5469; number_of_response:1; }","duration":"747.829984ms","start":"2026-03-25T21:13:44.423087Z","end":"2026-03-25T21:13:45.170917Z","steps":["trace[1458140658] 'process raft request' (duration: 747.578608ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:45.171083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.847287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10394"} {"level":"info","ts":"2026-03-25T21:13:45.171119Z","caller":"traceutil/trace.go:171","msg":"trace[1246112133] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5469; }","duration":"426.933489ms","start":"2026-03-25T21:13:44.744175Z","end":"2026-03-25T21:13:45.171108Z","steps":["trace[1246112133] 'agreement among raft nodes before linearized reading' (duration: 426.788845ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:45.17115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:44.744153Z","time spent":"426.98924ms","remote":"127.0.0.1:58356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10417,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-03-25T21:13:45.171207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:44.423065Z","time spent":"747.935137ms","remote":"127.0.0.1:58446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:13:45.179005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.078614ms","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-03-25T21:13:45.179025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.189864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:13:45.179217Z","caller":"traceutil/trace.go:171","msg":"trace[1636108508] transaction","detail":"{read_only:false; response_revision:5470; number_of_response:1; }","duration":"262.604571ms","start":"2026-03-25T21:13:44.91657Z","end":"2026-03-25T21:13:45.179175Z","steps":["trace[1636108508] 'process raft request' (duration: 262.125878ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:45.17927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.242412ms","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-03-25T21:13:45.179285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.506646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-25T21:13:45.179333Z","caller":"traceutil/trace.go:171","msg":"trace[1616808541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5470; }","duration":"257.348384ms","start":"2026-03-25T21:13:44.921966Z","end":"2026-03-25T21:13:45.179315Z","steps":["trace[1616808541] 'agreement among raft nodes before linearized reading' (duration: 257.18228ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:45.17935Z","caller":"traceutil/trace.go:171","msg":"trace[409661978] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:5470; }","duration":"337.63658ms","start":"2026-03-25T21:13:44.841699Z","end":"2026-03-25T21:13:45.179335Z","steps":["trace[409661978] 'agreement among raft nodes before linearized reading' (duration: 337.493676ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:45.179364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.673739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-03-25T21:13:45.179391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:44.841684Z","time spent":"337.697711ms","remote":"127.0.0.1:58686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":3,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-03-25T21:13:45.179403Z","caller":"traceutil/trace.go:171","msg":"trace[1026498617] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5470; }","duration":"382.747231ms","start":"2026-03-25T21:13:44.796646Z","end":"2026-03-25T21:13:45.179393Z","steps":["trace[1026498617] 'agreement among raft nodes before linearized reading' (duration: 382.556307ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:45.179459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:44.796629Z","time spent":"382.820683ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-03-25T21:13:45.179621Z","caller":"traceutil/trace.go:171","msg":"trace[1663112651] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5470; }","duration":"383.515582ms","start":"2026-03-25T21:13:44.795756Z","end":"2026-03-25T21:13:45.179272Z","steps":["trace[1663112651] 'agreement among raft nodes before linearized reading' (duration: 383.108001ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:45.179746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.40081ms","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-03-25T21:13:45.179794Z","caller":"traceutil/trace.go:171","msg":"trace[31147135] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5470; }","duration":"348.478022ms","start":"2026-03-25T21:13:44.831306Z","end":"2026-03-25T21:13:45.179784Z","steps":["trace[31147135] 'agreement among raft nodes before linearized reading' (duration: 347.812545ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:45.179823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:44.831291Z","time spent":"348.523083ms","remote":"127.0.0.1:58446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-25T21:13:45.179698Z","caller":"traceutil/trace.go:171","msg":"trace[786021753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5470; }","duration":"258.176686ms","start":"2026-03-25T21:13:44.920878Z","end":"2026-03-25T21:13:45.179055Z","steps":["trace[786021753] 'agreement among raft nodes before linearized reading' (duration: 258.000962ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:45.180418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:44.795668Z","time spent":"384.725224ms","remote":"127.0.0.1:58154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-25T21:13:45.346829Z","caller":"traceutil/trace.go:171","msg":"trace[1302469574] transaction","detail":"{read_only:false; response_revision:5472; number_of_response:1; }","duration":"163.058526ms","start":"2026-03-25T21:13:45.183733Z","end":"2026-03-25T21:13:45.346791Z","steps":["trace[1302469574] 'process raft request' (duration: 89.615288ms)","trace[1302469574] 'compare' (duration: 72.986966ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:45.347028Z","caller":"traceutil/trace.go:171","msg":"trace[1457851354] transaction","detail":"{read_only:false; response_revision:5473; number_of_response:1; }","duration":"162.933953ms","start":"2026-03-25T21:13:45.184082Z","end":"2026-03-25T21:13:45.347016Z","steps":["trace[1457851354] 'process raft request' (duration: 162.622974ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:45.347077Z","caller":"traceutil/trace.go:171","msg":"trace[343898719] transaction","detail":"{read_only:false; response_revision:5474; number_of_response:1; }","duration":"161.482505ms","start":"2026-03-25T21:13:45.185569Z","end":"2026-03-25T21:13:45.347051Z","steps":["trace[343898719] 'process raft request' (duration: 161.284499ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:45.347176Z","caller":"traceutil/trace.go:171","msg":"trace[1573119575] linearizableReadLoop","detail":"{readStateIndex:5676; appliedIndex:5673; }","duration":"154.865262ms","start":"2026-03-25T21:13:45.192244Z","end":"2026-03-25T21:13:45.34711Z","steps":["trace[1573119575] 'read index received' (duration: 81.119236ms)","trace[1573119575] 'applied index is now lower than readState.Index' (duration: 73.742786ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:45.347164Z","caller":"traceutil/trace.go:171","msg":"trace[1398826418] transaction","detail":"{read_only:false; response_revision:5475; number_of_response:1; }","duration":"150.362786ms","start":"2026-03-25T21:13:45.196763Z","end":"2026-03-25T21:13:45.347125Z","steps":["trace[1398826418] 'process raft request' (duration: 150.161181ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:45.34744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.879573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-8b2db1f8-b2cd-47dc-b307-663faa035d41\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-03-25T21:13:45.347521Z","caller":"traceutil/trace.go:171","msg":"trace[757551287] range","detail":"{range_begin:/registry/persistentvolumes/pvc-8b2db1f8-b2cd-47dc-b307-663faa035d41; range_end:; response_count:1; response_revision:5476; }","duration":"165.002335ms","start":"2026-03-25T21:13:45.182503Z","end":"2026-03-25T21:13:45.347505Z","steps":["trace[757551287] 'agreement among raft nodes before linearized reading' (duration: 164.750469ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:45.347526Z","caller":"traceutil/trace.go:171","msg":"trace[1186427492] transaction","detail":"{read_only:false; response_revision:5478; number_of_response:1; }","duration":"146.121405ms","start":"2026-03-25T21:13:45.20138Z","end":"2026-03-25T21:13:45.347502Z","steps":["trace[1186427492] 'process raft request' (duration: 146.042073ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:45.347611Z","caller":"traceutil/trace.go:171","msg":"trace[1323716970] transaction","detail":"{read_only:false; response_revision:5477; number_of_response:1; }","duration":"149.529574ms","start":"2026-03-25T21:13:45.198071Z","end":"2026-03-25T21:13:45.347601Z","steps":["trace[1323716970] 'process raft request' (duration: 149.242796ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:45.347561Z","caller":"traceutil/trace.go:171","msg":"trace[218636936] transaction","detail":"{read_only:false; response_revision:5476; number_of_response:1; }","duration":"150.423076ms","start":"2026-03-25T21:13:45.19712Z","end":"2026-03-25T21:13:45.347543Z","steps":["trace[218636936] 'process raft request' (duration: 149.908603ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:46.134279Z","caller":"traceutil/trace.go:171","msg":"trace[1358528763] linearizableReadLoop","detail":"{readStateIndex:5687; appliedIndex:5686; }","duration":"178.61115ms","start":"2026-03-25T21:13:45.955634Z","end":"2026-03-25T21:13:46.134245Z","steps":["trace[1358528763] 'read index received' (duration: 163.189009ms)","trace[1358528763] 'applied index is now lower than readState.Index' (duration: 15.420861ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:46.134302Z","caller":"traceutil/trace.go:171","msg":"trace[389937380] transaction","detail":"{read_only:false; response_revision:5485; number_of_response:1; }","duration":"185.363995ms","start":"2026-03-25T21:13:45.948892Z","end":"2026-03-25T21:13:46.134256Z","steps":["trace[389937380] 'process raft request' (duration: 169.984936ms)","trace[389937380] 'compare' (duration: 15.190715ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:13:46.134638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.98314ms","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-03-25T21:13:46.134704Z","caller":"traceutil/trace.go:171","msg":"trace[487420031] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5485; }","duration":"179.111123ms","start":"2026-03-25T21:13:45.955577Z","end":"2026-03-25T21:13:46.134689Z","steps":["trace[487420031] 'agreement among raft nodes before linearized reading' (duration: 178.832195ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:46.134844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.211531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:13:46.134915Z","caller":"traceutil/trace.go:171","msg":"trace[462504843] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:5485; }","duration":"108.323804ms","start":"2026-03-25T21:13:46.026575Z","end":"2026-03-25T21:13:46.134899Z","steps":["trace[462504843] 'agreement among raft nodes before linearized reading' (duration: 108.15875ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:14:19.200735Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986421410210876401,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T21:14:19.701183Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986421410210876401,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T21:14:20.201821Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986421410210876401,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T21:14:20.30375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.989850026s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-25T21:14:20.303881Z","caller":"traceutil/trace.go:171","msg":"trace[2089141215] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.99000798s","start":"2026-03-25T21:14:18.313838Z","end":"2026-03-25T21:14:20.303846Z","steps":["trace[2089141215] 'agreement among raft nodes before linearized reading' (duration: 1.989844376s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:14:20.303987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:14:18.313735Z","time spent":"1.990212206s","remote":"127.0.0.1:58154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/25 21:14:20 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-25T21:14:20.63409Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.331292066s","expected-duration":"1s"} {"level":"info","ts":"2026-03-25T21:14:20.634739Z","caller":"traceutil/trace.go:171","msg":"trace[113432051] transaction","detail":"{read_only:false; response_revision:5686; number_of_response:1; }","duration":"2.332026165s","start":"2026-03-25T21:14:18.302684Z","end":"2026-03-25T21:14:20.63471Z","steps":["trace[113432051] 'process raft request' (duration: 2.331847991s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:14:20.634906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:14:18.302665Z","time spent":"2.332123767s","remote":"127.0.0.1:58446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:14:20.636603Z","caller":"traceutil/trace.go:171","msg":"trace[390851185] transaction","detail":"{read_only:false; response_revision:5687; number_of_response:1; }","duration":"2.277700554s","start":"2026-03-25T21:14:18.35889Z","end":"2026-03-25T21:14:20.636591Z","steps":["trace[390851185] 'process raft request' (duration: 2.277257702s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:14:20.63667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:14:18.358868Z","time spent":"2.277768065s","remote":"127.0.0.1:58446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:14:20.638522Z","caller":"traceutil/trace.go:171","msg":"trace[383045435] transaction","detail":"{read_only:false; response_revision:5688; number_of_response:1; }","duration":"1.279129595s","start":"2026-03-25T21:14:19.359382Z","end":"2026-03-25T21:14:20.638512Z","steps":["trace[383045435] 'process raft request' (duration: 1.27892011s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:14:20.638582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:14:19.359343Z","time spent":"1.279209467s","remote":"127.0.0.1:58446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:14:20.638778Z","caller":"traceutil/trace.go:171","msg":"trace[3455721] linearizableReadLoop","detail":"{readStateIndex:5898; appliedIndex:5893; }","duration":"2.325063324s","start":"2026-03-25T21:14:18.313705Z","end":"2026-03-25T21:14:20.638768Z","steps":["trace[3455721] 'read index received' (duration: 2.320757762s)","trace[3455721] 'applied index is now lower than readState.Index' (duration: 4.304702ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:14:20.638939Z","caller":"traceutil/trace.go:171","msg":"trace[1265658755] transaction","detail":"{read_only:false; response_revision:5689; number_of_response:1; }","duration":"1.129576062s","start":"2026-03-25T21:14:19.509352Z","end":"2026-03-25T21:14:20.638929Z","steps":["trace[1265658755] 'process raft request' (duration: 1.12912517s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:14:20.639026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:14:19.509313Z","time spent":"1.129685614s","remote":"127.0.0.1:58446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:14:20.639318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.325607168s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:81810"} {"level":"info","ts":"2026-03-25T21:14:20.639356Z","caller":"traceutil/trace.go:171","msg":"trace[1669977306] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5689; }","duration":"2.32566515s","start":"2026-03-25T21:14:18.313683Z","end":"2026-03-25T21:14:20.639348Z","steps":["trace[1669977306] 'agreement among raft nodes before linearized reading' (duration: 2.325447044s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:14:20.639379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:14:18.31367Z","time spent":"2.325703851s","remote":"127.0.0.1:58356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":9,"response size":81833,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-03-25T21:14:20.639606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.325861615s","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-03-25T21:14:20.639682Z","caller":"traceutil/trace.go:171","msg":"trace[1825709262] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5689; }","duration":"2.325959307s","start":"2026-03-25T21:14:18.313715Z","end":"2026-03-25T21:14:20.639675Z","steps":["trace[1825709262] 'agreement among raft nodes before linearized reading' (duration: 2.325839624s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:14:20.639704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:14:18.313703Z","time spent":"2.325994818s","remote":"127.0.0.1:58446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-25T21:14:20.639819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.646658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:14:20.639858Z","caller":"traceutil/trace.go:171","msg":"trace[1359695299] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5689; }","duration":"335.801982ms","start":"2026-03-25T21:14:20.304044Z","end":"2026-03-25T21:14:20.639846Z","steps":["trace[1359695299] 'agreement among raft nodes before linearized reading' (duration: 335.739871ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:14:20.639879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:14:20.303997Z","time spent":"335.877275ms","remote":"127.0.0.1:58158","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-03-25T21:14:20.639951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.12606379s","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-03-25T21:14:20.639979Z","caller":"traceutil/trace.go:171","msg":"trace[1896034555] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5689; }","duration":"1.126124661s","start":"2026-03-25T21:14:19.513848Z","end":"2026-03-25T21:14:20.639972Z","steps":["trace[1896034555] 'agreement among raft nodes before linearized reading' (duration: 1.126052479s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:14:20.639996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:14:19.513821Z","time spent":"1.126171642s","remote":"127.0.0.1:58446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-25T21:14:20.640058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.280562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-25T21:14:20.640081Z","caller":"traceutil/trace.go:171","msg":"trace[1250483433] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:5689; }","duration":"195.350034ms","start":"2026-03-25T21:14:20.444724Z","end":"2026-03-25T21:14:20.640074Z","steps":["trace[1250483433] 'agreement among raft nodes before linearized reading' (duration: 195.298461ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:14:20.640136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.126938853s","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-03-25T21:14:20.640169Z","caller":"traceutil/trace.go:171","msg":"trace[408870906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5689; }","duration":"1.127012245s","start":"2026-03-25T21:14:19.513147Z","end":"2026-03-25T21:14:20.640159Z","steps":["trace[408870906] 'agreement among raft nodes before linearized reading' (duration: 1.126935313s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:14:20.640202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:14:19.513128Z","time spent":"1.127069316s","remote":"127.0.0.1:58446","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-03-25T21:14:20.640375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127750914s","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-03-25T21:14:20.64041Z","caller":"traceutil/trace.go:171","msg":"trace[1368666917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5689; }","duration":"1.127848056s","start":"2026-03-25T21:14:19.512552Z","end":"2026-03-25T21:14:20.6404Z","steps":["trace[1368666917] 'agreement among raft nodes before linearized reading' (duration: 1.127760684s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:14:20.640438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:14:19.512517Z","time spent":"1.127913697s","remote":"127.0.0.1:58446","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-03-25T21:14:20.64084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.323772075s","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-03-25T21:14:20.640876Z","caller":"traceutil/trace.go:171","msg":"trace[442922546] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5689; }","duration":"1.323869498s","start":"2026-03-25T21:14:19.316997Z","end":"2026-03-25T21:14:20.640866Z","steps":["trace[442922546] 'agreement among raft nodes before linearized reading' (duration: 1.323780546s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:14:20.640901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:14:19.31697Z","time spent":"1.323926169s","remote":"127.0.0.1:58446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-25T21:14:20.641075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.056753835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-03-25T21:14:20.641062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.431607307s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-03-25T21:14:20.641163Z","caller":"traceutil/trace.go:171","msg":"trace[1506720703] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5689; }","duration":"1.431768241s","start":"2026-03-25T21:14:19.209373Z","end":"2026-03-25T21:14:20.641141Z","steps":["trace[1506720703] 'agreement among raft nodes before linearized reading' (duration: 1.431458183s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:14:20.641212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:14:19.209354Z","time spent":"1.431850232s","remote":"127.0.0.1:58356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-03-25T21:14:20.641101Z","caller":"traceutil/trace.go:171","msg":"trace[2058575690] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:5689; }","duration":"2.056813346s","start":"2026-03-25T21:14:18.584281Z","end":"2026-03-25T21:14:20.641095Z","steps":["trace[2058575690] 'agreement among raft nodes before linearized reading' (duration: 2.056044736s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:14:20.641403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:14:18.584265Z","time spent":"2.057124914s","remote":"127.0.0.1:58692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":645,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-03-25T21:15:01.189971Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2991} {"level":"info","ts":"2026-03-25T21:15:01.272924Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2991,"took":"81.700454ms","hash":3961753715,"current-db-size-bytes":58396672,"current-db-size":"58 MB","current-db-size-in-use-bytes":33853440,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-03-25T21:15:01.272984Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3961753715,"revision":2991,"compact-revision":-1} {"level":"info","ts":"2026-03-25T21:18:45.459107Z","caller":"traceutil/trace.go:171","msg":"trace[786647301] transaction","detail":"{read_only:false; response_revision:7657; number_of_response:1; }","duration":"103.402563ms","start":"2026-03-25T21:18:45.355686Z","end":"2026-03-25T21:18:45.459089Z","steps":["trace[786647301] 'process raft request' (duration: 103.25684ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:19:32.893839Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986421410210882824,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-25T21:19:33.015454Z","caller":"traceutil/trace.go:171","msg":"trace[664049348] linearizableReadLoop","detail":"{readStateIndex:8393; appliedIndex:8392; }","duration":"622.59106ms","start":"2026-03-25T21:19:32.392829Z","end":"2026-03-25T21:19:33.01542Z","steps":["trace[664049348] 'read index received' (duration: 622.408195ms)","trace[664049348] 'applied index is now lower than readState.Index' (duration: 182.035µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:19:33.015596Z","caller":"traceutil/trace.go:171","msg":"trace[113909593] transaction","detail":"{read_only:false; response_revision:8109; number_of_response:1; }","duration":"638.999268ms","start":"2026-03-25T21:19:32.37656Z","end":"2026-03-25T21:19:33.015559Z","steps":["trace[113909593] 'process raft request' (duration: 638.731352ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:19:33.015672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.830916ms","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-03-25T21:19:33.015701Z","caller":"traceutil/trace.go:171","msg":"trace[810933693] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8109; }","duration":"622.880398ms","start":"2026-03-25T21:19:32.392812Z","end":"2026-03-25T21:19:33.015693Z","steps":["trace[810933693] 'agreement among raft nodes before linearized reading' (duration: 622.729484ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:19:33.015699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.33914ms","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":"info","ts":"2026-03-25T21:19:33.015728Z","caller":"traceutil/trace.go:171","msg":"trace[823050241] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:8109; }","duration":"534.393052ms","start":"2026-03-25T21:19:32.481327Z","end":"2026-03-25T21:19:33.01572Z","steps":["trace[823050241] 'agreement among raft nodes before linearized reading' (duration: 534.315ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:19:33.015729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:19:32.392769Z","time spent":"622.952879ms","remote":"127.0.0.1:58446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-25T21:19:33.01575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:19:32.481315Z","time spent":"534.425953ms","remote":"127.0.0.1:58608","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-03-25T21:19:33.015772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:19:32.376542Z","time spent":"639.143591ms","remote":"127.0.0.1:58446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:19:33.015828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.446483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-03-25T21:19:33.01586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.450917ms","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-03-25T21:19:33.015761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.289142ms","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-03-25T21:19:33.015902Z","caller":"traceutil/trace.go:171","msg":"trace[1112100409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8109; }","duration":"561.520859ms","start":"2026-03-25T21:19:32.454365Z","end":"2026-03-25T21:19:33.015886Z","steps":["trace[1112100409] 'agreement among raft nodes before linearized reading' (duration: 561.443066ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:19:33.015935Z","caller":"traceutil/trace.go:171","msg":"trace[921778382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8109; }","duration":"564.538488ms","start":"2026-03-25T21:19:32.451385Z","end":"2026-03-25T21:19:33.015924Z","steps":["trace[921778382] 'agreement among raft nodes before linearized reading' (duration: 564.220029ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:19:33.01594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:19:32.454345Z","time spent":"561.587671ms","remote":"127.0.0.1:58446","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-03-25T21:19:33.015953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.720921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T21:19:33.015972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:19:32.451364Z","time spent":"564.59825ms","remote":"127.0.0.1:58446","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-03-25T21:19:33.015979Z","caller":"traceutil/trace.go:171","msg":"trace[218295013] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8109; }","duration":"220.781932ms","start":"2026-03-25T21:19:32.79519Z","end":"2026-03-25T21:19:33.015972Z","steps":["trace[218295013] 'agreement among raft nodes before linearized reading' (duration: 220.738861ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:19:33.015979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.335527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-gpt9c\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-03-25T21:19:33.01601Z","caller":"traceutil/trace.go:171","msg":"trace[713440208] range","detail":"{range_begin:/registry/pods/openstack/keepalived-gpt9c; range_end:; response_count:1; response_revision:8109; }","duration":"535.390939ms","start":"2026-03-25T21:19:32.480611Z","end":"2026-03-25T21:19:33.016002Z","steps":["trace[713440208] 'agreement among raft nodes before linearized reading' (duration: 535.302517ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:19:33.015889Z","caller":"traceutil/trace.go:171","msg":"trace[1368842420] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8109; }","duration":"557.524486ms","start":"2026-03-25T21:19:32.458344Z","end":"2026-03-25T21:19:33.015869Z","steps":["trace[1368842420] 'agreement among raft nodes before linearized reading' (duration: 557.396232ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:19:33.016038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:19:32.4806Z","time spent":"535.43152ms","remote":"127.0.0.1:58356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6543,"request content":"key:\"/registry/pods/openstack/keepalived-gpt9c\" "} {"level":"warn","ts":"2026-03-25T21:19:33.016049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:19:32.458333Z","time spent":"557.7083ms","remote":"127.0.0.1:58446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-03-25T21:19:33.016059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.020007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:19:33.016088Z","caller":"traceutil/trace.go:171","msg":"trace[2051760087] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8109; }","duration":"424.048408ms","start":"2026-03-25T21:19:32.59203Z","end":"2026-03-25T21:19:33.016079Z","steps":["trace[2051760087] 'agreement among raft nodes before linearized reading' (duration: 424.007227ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:19:33.016109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:19:32.591977Z","time spent":"424.12579ms","remote":"127.0.0.1:50716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-03-25T21:19:46.533386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.683623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-03-25T21:19:46.533488Z","caller":"traceutil/trace.go:171","msg":"trace[1602543635] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8181; }","duration":"103.866037ms","start":"2026-03-25T21:19:46.4296Z","end":"2026-03-25T21:19:46.533466Z","steps":["trace[1602543635] 'range keys from in-memory index tree' (duration: 103.430144ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:19:46.533533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.033454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-03-25T21:19:46.53363Z","caller":"traceutil/trace.go:171","msg":"trace[1620445026] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8181; }","duration":"108.189149ms","start":"2026-03-25T21:19:46.425422Z","end":"2026-03-25T21:19:46.533611Z","steps":["trace[1620445026] 'range keys from in-memory index tree' (duration: 107.820788ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:19:46.666792Z","caller":"traceutil/trace.go:171","msg":"trace[2102362743] transaction","detail":"{read_only:false; response_revision:8183; number_of_response:1; }","duration":"128.674702ms","start":"2026-03-25T21:19:46.538077Z","end":"2026-03-25T21:19:46.666752Z","steps":["trace[2102362743] 'process raft request' (duration: 112.041899ms)","trace[2102362743] 'compare' (duration: 16.401047ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:19:47.227797Z","caller":"traceutil/trace.go:171","msg":"trace[177977056] transaction","detail":"{read_only:false; response_revision:8185; number_of_response:1; }","duration":"132.969104ms","start":"2026-03-25T21:19:47.094794Z","end":"2026-03-25T21:19:47.227764Z","steps":["trace[177977056] 'process raft request' (duration: 132.666265ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:20:01.197474Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6093} {"level":"info","ts":"2026-03-25T21:20:01.29279Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6093,"took":"93.259161ms","hash":2188356062,"current-db-size-bytes":58396672,"current-db-size":"58 MB","current-db-size-in-use-bytes":23281664,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-25T21:20:01.292861Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2188356062,"revision":6093,"compact-revision":2991} {"level":"info","ts":"2026-03-25T21:20:13.66448Z","caller":"traceutil/trace.go:171","msg":"trace[1471431157] linearizableReadLoop","detail":"{readStateIndex:8671; appliedIndex:8670; }","duration":"278.976988ms","start":"2026-03-25T21:20:13.385477Z","end":"2026-03-25T21:20:13.664454Z","steps":["trace[1471431157] 'read index received' (duration: 278.775373ms)","trace[1471431157] 'applied index is now lower than readState.Index' (duration: 200.845µs)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:20:13.664834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.584296ms","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-03-25T21:20:13.664861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.353458ms","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-03-25T21:20:13.664928Z","caller":"traceutil/trace.go:171","msg":"trace[120649816] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8375; }","duration":"279.45858ms","start":"2026-03-25T21:20:13.385456Z","end":"2026-03-25T21:20:13.664914Z","steps":["trace[120649816] 'agreement among raft nodes before linearized reading' (duration: 279.230694ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:20:13.664931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.296774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:20:13.664881Z","caller":"traceutil/trace.go:171","msg":"trace[1478957598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8375; }","duration":"276.661007ms","start":"2026-03-25T21:20:13.388209Z","end":"2026-03-25T21:20:13.66487Z","steps":["trace[1478957598] 'agreement among raft nodes before linearized reading' (duration: 276.516634ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:20:13.665013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.481604ms","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-03-25T21:20:13.665044Z","caller":"traceutil/trace.go:171","msg":"trace[1650575001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8375; }","duration":"276.525734ms","start":"2026-03-25T21:20:13.38851Z","end":"2026-03-25T21:20:13.665036Z","steps":["trace[1650575001] 'agreement among raft nodes before linearized reading' (duration: 276.447532ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:20:13.665011Z","caller":"traceutil/trace.go:171","msg":"trace[342504579] transaction","detail":"{read_only:false; response_revision:8375; number_of_response:1; }","duration":"282.020668ms","start":"2026-03-25T21:20:13.382932Z","end":"2026-03-25T21:20:13.664952Z","steps":["trace[342504579] 'process raft request' (duration: 281.387852ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:20:13.665082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.528705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-gpt9c\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-03-25T21:20:13.665175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.293782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-03-25T21:20:13.665228Z","caller":"traceutil/trace.go:171","msg":"trace[2061937707] range","detail":"{range_begin:/registry/pods/openstack/keepalived-gpt9c; range_end:; response_count:1; response_revision:8375; }","duration":"263.69043ms","start":"2026-03-25T21:20:13.401524Z","end":"2026-03-25T21:20:13.665214Z","steps":["trace[2061937707] 'agreement among raft nodes before linearized reading' (duration: 263.485025ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:20:13.665272Z","caller":"traceutil/trace.go:171","msg":"trace[1377952486] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8375; }","duration":"275.426735ms","start":"2026-03-25T21:20:13.38982Z","end":"2026-03-25T21:20:13.665247Z","steps":["trace[1377952486] 'agreement among raft nodes before linearized reading' (duration: 275.134088ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:20:13.665152Z","caller":"traceutil/trace.go:171","msg":"trace[791429991] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:8375; }","duration":"249.332365ms","start":"2026-03-25T21:20:13.415622Z","end":"2026-03-25T21:20:13.664955Z","steps":["trace[791429991] 'agreement among raft nodes before linearized reading' (duration: 249.282284ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:20:13.965884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.051887ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:20:13.966133Z","caller":"traceutil/trace.go:171","msg":"trace[563698616] linearizableReadLoop","detail":"{readStateIndex:8675; appliedIndex:8671; }","duration":"294.034ms","start":"2026-03-25T21:20:13.672081Z","end":"2026-03-25T21:20:13.966115Z","steps":["trace[563698616] 'read index received' (duration: 58.411051ms)","trace[563698616] 'applied index is now lower than readState.Index' (duration: 235.622049ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:20:13.966171Z","caller":"traceutil/trace.go:171","msg":"trace[1412155836] transaction","detail":"{read_only:false; response_revision:8377; number_of_response:1; }","duration":"295.767285ms","start":"2026-03-25T21:20:13.670337Z","end":"2026-03-25T21:20:13.966104Z","steps":["trace[1412155836] 'process raft request' (duration: 190.358908ms)","trace[1412155836] 'compare' (duration: 104.628797ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:20:13.966177Z","caller":"traceutil/trace.go:171","msg":"trace[1850425705] transaction","detail":"{read_only:false; response_revision:8378; number_of_response:1; }","duration":"295.098588ms","start":"2026-03-25T21:20:13.671042Z","end":"2026-03-25T21:20:13.96614Z","steps":["trace[1850425705] 'process raft request' (duration: 294.945654ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:20:13.966427Z","caller":"traceutil/trace.go:171","msg":"trace[34603001] transaction","detail":"{read_only:false; response_revision:8379; number_of_response:1; }","duration":"295.313463ms","start":"2026-03-25T21:20:13.671085Z","end":"2026-03-25T21:20:13.966398Z","steps":["trace[34603001] 'process raft request' (duration: 294.990445ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:20:13.966496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.40965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228539"} {"level":"info","ts":"2026-03-25T21:20:13.96653Z","caller":"traceutil/trace.go:171","msg":"trace[197062922] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8379; }","duration":"294.465132ms","start":"2026-03-25T21:20:13.672055Z","end":"2026-03-25T21:20:13.96652Z","steps":["trace[197062922] 'agreement among raft nodes before linearized reading' (duration: 294.116073ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:20:26.478516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.263748ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:20:26.478653Z","caller":"traceutil/trace.go:171","msg":"trace[209075522] transaction","detail":"{read_only:false; response_revision:8490; number_of_response:1; }","duration":"160.724317ms","start":"2026-03-25T21:20:26.317905Z","end":"2026-03-25T21:20:26.478629Z","steps":["trace[209075522] 'process raft request' (duration: 11.247653ms)","trace[209075522] 'compare' (duration: 149.080464ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:20:26.672795Z","caller":"traceutil/trace.go:171","msg":"trace[1627620623] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8491; }","duration":"183.647814ms","start":"2026-03-25T21:20:26.489127Z","end":"2026-03-25T21:20:26.672774Z","steps":["trace[1627620623] 'process raft request' (duration: 178.746397ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:20:26.699854Z","caller":"traceutil/trace.go:171","msg":"trace[962262120] linearizableReadLoop","detail":"{readStateIndex:8794; appliedIndex:8792; }","duration":"189.404085ms","start":"2026-03-25T21:20:26.510435Z","end":"2026-03-25T21:20:26.699839Z","steps":["trace[962262120] 'read index received' (duration: 157.445182ms)","trace[962262120] 'applied index is now lower than readState.Index' (duration: 31.958173ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:20:26.699988Z","caller":"traceutil/trace.go:171","msg":"trace[1116093792] transaction","detail":"{read_only:false; response_revision:8492; number_of_response:1; }","duration":"209.254442ms","start":"2026-03-25T21:20:26.490721Z","end":"2026-03-25T21:20:26.699975Z","steps":["trace[1116093792] 'process raft request' (duration: 209.037296ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:20:26.700089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.644931ms","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-03-25T21:20:26.700161Z","caller":"traceutil/trace.go:171","msg":"trace[606051126] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8492; }","duration":"189.750543ms","start":"2026-03-25T21:20:26.510394Z","end":"2026-03-25T21:20:26.700144Z","steps":["trace[606051126] 'agreement among raft nodes before linearized reading' (duration: 189.556619ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:20:27.461006Z","caller":"traceutil/trace.go:171","msg":"trace[47781154] transaction","detail":"{read_only:false; response_revision:8495; number_of_response:1; }","duration":"211.549151ms","start":"2026-03-25T21:20:27.249352Z","end":"2026-03-25T21:20:27.460901Z","steps":["trace[47781154] 'process raft request' (duration: 148.091407ms)","trace[47781154] 'compare' (duration: 63.244067ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:20:28.331054Z","caller":"traceutil/trace.go:171","msg":"trace[205871064] transaction","detail":"{read_only:false; response_revision:8505; number_of_response:1; }","duration":"135.160822ms","start":"2026-03-25T21:20:28.195855Z","end":"2026-03-25T21:20:28.331016Z","steps":["trace[205871064] 'process raft request' (duration: 97.932601ms)","trace[205871064] 'compare' (duration: 37.084046ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:20:28.331238Z","caller":"traceutil/trace.go:171","msg":"trace[1630205653] linearizableReadLoop","detail":"{readStateIndex:8807; appliedIndex:8806; }","duration":"133.347975ms","start":"2026-03-25T21:20:28.197866Z","end":"2026-03-25T21:20:28.331214Z","steps":["trace[1630205653] 'read index received' (duration: 96.032803ms)","trace[1630205653] 'applied index is now lower than readState.Index' (duration: 37.313722ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:20:28.331389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.509418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:20:28.331433Z","caller":"traceutil/trace.go:171","msg":"trace[1989481011] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:8506; }","duration":"133.641052ms","start":"2026-03-25T21:20:28.19778Z","end":"2026-03-25T21:20:28.331421Z","steps":["trace[1989481011] 'agreement among raft nodes before linearized reading' (duration: 133.55127ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:20:28.331701Z","caller":"traceutil/trace.go:171","msg":"trace[1230166204] transaction","detail":"{read_only:false; response_revision:8506; number_of_response:1; }","duration":"132.512122ms","start":"2026-03-25T21:20:28.199173Z","end":"2026-03-25T21:20:28.331685Z","steps":["trace[1230166204] 'process raft request' (duration: 131.832626ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:21:06.872768Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023e3.wal"} {"level":"warn","ts":"2026-03-25T21:21:32.816084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.32364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-25T21:21:32.816251Z","caller":"traceutil/trace.go:171","msg":"trace[1230158030] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:9126; }","duration":"137.547056ms","start":"2026-03-25T21:21:32.678635Z","end":"2026-03-25T21:21:32.816182Z","steps":["trace[1230158030] 'count revisions from in-memory index tree' (duration: 137.145295ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:22:34.881371Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ba52c55cf4193752","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-25T21:22:34.886332Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-25T21:22:34.886436Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-25T21:24:06.624589Z","caller":"traceutil/trace.go:171","msg":"trace[1128165068] transaction","detail":"{read_only:false; response_revision:10394; number_of_response:1; }","duration":"151.111362ms","start":"2026-03-25T21:24:06.473442Z","end":"2026-03-25T21:24:06.624553Z","steps":["trace[1128165068] 'process raft request' (duration: 150.866856ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:25:01.204516Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8262} {"level":"info","ts":"2026-03-25T21:25:01.285301Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8262,"took":"78.989191ms","hash":2869164368,"current-db-size-bytes":58396672,"current-db-size":"58 MB","current-db-size-in-use-bytes":25055232,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-03-25T21:25:01.285377Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2869164368,"revision":8262,"compact-revision":6093} {"level":"info","ts":"2026-03-25T21:30:01.21772Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10697} {"level":"info","ts":"2026-03-25T21:30:01.296869Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10697,"took":"77.533753ms","hash":2948289619,"current-db-size-bytes":58396672,"current-db-size":"58 MB","current-db-size-in-use-bytes":20312064,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-03-25T21:30:01.296928Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2948289619,"revision":10697,"compact-revision":8262}