{"level":"warn","ts":"2026-06-05T11:24:01.063903Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-05T11:24:01.064018Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53: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.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53: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-06-05T11:24:01.064132Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-05T11:24:01.06415Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-06-05T11:24:01.064176Z","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-06-05T11:24:01.065103Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-06-05T11:24:01.065223Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53: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-06-05T11:24:01.068272Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.781748ms"} {"level":"info","ts":"2026-06-05T11:24:01.073863Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-06-05T11:24:01.073942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-06-05T11:24:01.073973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-06-05T11:24:01.073987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-05T11:24:01.074002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-06-05T11:24:01.074041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-06-05T11:24:01.079229Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-05T11:24:01.080755Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-05T11:24:01.082231Z","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-06-05T11:24:01.084158Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-05T11:24:01.087828Z","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-06-05T11:24:01.087918Z","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-06-05T11:24:01.087925Z","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-06-05T11:24:01.08981Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-05T11:24:01.090151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-06-05T11:24:01.09023Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-06-05T11:24:01.090832Z","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-06-05T11:24:01.090938Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-06-05T11:24:01.090962Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-06-05T11:24:01.091178Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-05T11:24:01.091206Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-05T11:24:01.274747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-06-05T11:24:01.27479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-06-05T11:24:01.274849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-06-05T11:24:01.274878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-06-05T11:24:01.274889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-06-05T11:24:01.274898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-06-05T11:24:01.274928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-06-05T11:24:01.275854Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T11:24:01.276526Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-06-05T11:24:01.276536Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-05T11:24:01.276603Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-05T11:24:01.27686Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-05T11:24:01.276915Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-05T11:24:01.277051Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T11:24:01.277183Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T11:24:01.277239Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T11:24:01.279711Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-06-05T11:24:01.280238Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-05T11:24:33.850265Z","caller":"traceutil/trace.go:171","msg":"trace[500069334] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"263.10932ms","start":"2026-06-05T11:24:33.587125Z","end":"2026-06-05T11:24:33.850234Z","steps":["trace[500069334] 'process raft request' (duration: 262.961317ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:24:37.158208Z","caller":"traceutil/trace.go:171","msg":"trace[866438216] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"409.975007ms","start":"2026-06-05T11:24:36.748203Z","end":"2026-06-05T11:24:37.158178Z","steps":["trace[866438216] 'process raft request' (duration: 409.774003ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:24:37.158807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:24:36.748179Z","time spent":"410.121101ms","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-05T11:24:37.168329Z","caller":"traceutil/trace.go:171","msg":"trace[181109288] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"406.225706ms","start":"2026-06-05T11:24:36.762086Z","end":"2026-06-05T11:24:37.168312Z","steps":["trace[181109288] 'process raft request' (duration: 406.066242ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:24:37.168447Z","caller":"traceutil/trace.go:171","msg":"trace[2060003196] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"414.282882ms","start":"2026-06-05T11:24:36.754145Z","end":"2026-06-05T11:24:37.168428Z","steps":["trace[2060003196] 'process raft request' (duration: 413.839231ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:24:37.168513Z","caller":"traceutil/trace.go:171","msg":"trace[1143718260] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"368.348185ms","start":"2026-06-05T11:24:36.800159Z","end":"2026-06-05T11:24:37.168507Z","steps":["trace[1143718260] 'process raft request' (duration: 368.160031ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:24:37.168548Z","caller":"traceutil/trace.go:171","msg":"trace[987737545] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"378.043901ms","start":"2026-06-05T11:24:36.7905Z","end":"2026-06-05T11:24:37.168544Z","steps":["trace[987737545] 'process raft request' (duration: 377.777045ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:24:37.168584Z","caller":"traceutil/trace.go:171","msg":"trace[904287585] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"366.776448ms","start":"2026-06-05T11:24:36.801803Z","end":"2026-06-05T11:24:37.168579Z","steps":["trace[904287585] 'process raft request' (duration: 366.551593ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:24:37.168618Z","caller":"traceutil/trace.go:171","msg":"trace[478609901] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"363.347824ms","start":"2026-06-05T11:24:36.805266Z","end":"2026-06-05T11:24:37.168614Z","steps":["trace[478609901] 'process raft request' (duration: 363.134429ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:24:37.16865Z","caller":"traceutil/trace.go:171","msg":"trace[1062611409] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"363.339394ms","start":"2026-06-05T11:24:36.805307Z","end":"2026-06-05T11:24:37.168646Z","steps":["trace[1062611409] 'process raft request' (duration: 363.137199ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:24:37.168618Z","caller":"traceutil/trace.go:171","msg":"trace[893020678] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"400.355483ms","start":"2026-06-05T11:24:36.768183Z","end":"2026-06-05T11:24:37.168539Z","steps":["trace[893020678] 'process raft request' (duration: 400.019315ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:24:37.168684Z","caller":"traceutil/trace.go:171","msg":"trace[251940575] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"358.584689ms","start":"2026-06-05T11:24:36.810095Z","end":"2026-06-05T11:24:37.16868Z","steps":["trace[251940575] 'process raft request' (duration: 358.392964ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:24:37.168807Z","caller":"traceutil/trace.go:171","msg":"trace[255352526] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"341.875705ms","start":"2026-06-05T11:24:36.826924Z","end":"2026-06-05T11:24:37.1688Z","steps":["trace[255352526] 'process raft request' (duration: 341.692769ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:24:37.168843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:24:36.754126Z","time spent":"414.356424ms","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T11:24:37.16891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:24:36.800137Z","time spent":"368.390647ms","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T11:24:37.168919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:24:36.826903Z","time spent":"341.926505ms","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T11:24:37.168953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:24:36.79048Z","time spent":"378.078293ms","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T11:24:37.168987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:24:36.801785Z","time spent":"366.813269ms","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T11:24:37.169022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:24:36.805259Z","time spent":"363.372404ms","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T11:24:37.169063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:24:36.805291Z","time spent":"363.374494ms","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-05T11:24:37.169099Z","caller":"traceutil/trace.go:171","msg":"trace[1377620091] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"221.466959ms","start":"2026-06-05T11:24:36.947604Z","end":"2026-06-05T11:24:37.16907Z","steps":["trace[1377620091] 'process raft request' (duration: 221.13676ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:24:37.169171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:24:36.768164Z","time spent":"400.553569ms","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T11:24:37.169152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:24:36.810087Z","time spent":"358.60734ms","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T11:24:37.168761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:24:36.762064Z","time spent":"406.315958ms","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-05T11:24:43.619158Z","caller":"traceutil/trace.go:171","msg":"trace[72486140] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"291.519ms","start":"2026-06-05T11:24:43.327612Z","end":"2026-06-05T11:24:43.619131Z","steps":["trace[72486140] 'read index received' (duration: 290.497986ms)","trace[72486140] 'applied index is now lower than readState.Index' (duration: 1.019914ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T11:24:43.61931Z","caller":"traceutil/trace.go:171","msg":"trace[1178949504] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"419.58105ms","start":"2026-06-05T11:24:43.199687Z","end":"2026-06-05T11:24:43.619268Z","steps":["trace[1178949504] 'process raft request' (duration: 418.749831ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:24:43.619481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.855709ms","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-06-05T11:24:43.619589Z","caller":"traceutil/trace.go:171","msg":"trace[1011369254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:626; }","duration":"292.022243ms","start":"2026-06-05T11:24:43.327548Z","end":"2026-06-05T11:24:43.61957Z","steps":["trace[1011369254] 'agreement among raft nodes before linearized reading' (duration: 291.732196ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:24:43.619872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:24:43.199655Z","time spent":"419.894758ms","remote":"127.0.0.1:53128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T11:24:44.344296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.778168ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T11:24:44.34453Z","caller":"traceutil/trace.go:171","msg":"trace[1504972798] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"651.597005ms","start":"2026-06-05T11:24:43.692916Z","end":"2026-06-05T11:24:44.344513Z","steps":["trace[1504972798] 'process raft request' (duration: 651.555704ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:24:44.344555Z","caller":"traceutil/trace.go:171","msg":"trace[1087001169] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"725.243723ms","start":"2026-06-05T11:24:43.619277Z","end":"2026-06-05T11:24:44.344521Z","steps":["trace[1087001169] 'read index received' (duration: 77.939363ms)","trace[1087001169] 'applied index is now lower than readState.Index' (duration: 647.30107ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T11:24:44.344587Z","caller":"traceutil/trace.go:171","msg":"trace[131011855] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"720.370565ms","start":"2026-06-05T11:24:43.62418Z","end":"2026-06-05T11:24:44.344551Z","steps":["trace[131011855] 'process raft request' (duration: 720.214931ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:24:44.344685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:24:43.624165Z","time spent":"720.473588ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T11:24:44.344592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:24:43.69289Z","time spent":"651.675807ms","remote":"127.0.0.1:53488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T11:24:44.344799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.476285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T11:24:44.344856Z","caller":"traceutil/trace.go:171","msg":"trace[1919802997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"829.557547ms","start":"2026-06-05T11:24:43.515274Z","end":"2026-06-05T11:24:44.344832Z","steps":["trace[1919802997] 'agreement among raft nodes before linearized reading' (duration: 829.438624ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:24:44.344887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:24:43.515247Z","time spent":"829.633609ms","remote":"127.0.0.1:32876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-05T11:24:44.345051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.67132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-05T11:24:44.345068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.398487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-05T11:24:44.345087Z","caller":"traceutil/trace.go:171","msg":"trace[462186766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:629; }","duration":"752.749073ms","start":"2026-06-05T11:24:43.592326Z","end":"2026-06-05T11:24:44.345075Z","steps":["trace[462186766] 'agreement among raft nodes before linearized reading' (duration: 752.614119ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:24:44.345096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.871803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8zvsb\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-06-05T11:24:44.345116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:24:43.592295Z","time spent":"752.814543ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-05T11:24:44.345116Z","caller":"traceutil/trace.go:171","msg":"trace[825822823] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:629; }","duration":"452.505749ms","start":"2026-06-05T11:24:43.892595Z","end":"2026-06-05T11:24:44.345101Z","steps":["trace[825822823] 'agreement among raft nodes before linearized reading' (duration: 452.282024ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:24:44.345127Z","caller":"traceutil/trace.go:171","msg":"trace[1912503278] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8zvsb; range_end:; response_count:1; response_revision:629; }","duration":"775.974366ms","start":"2026-06-05T11:24:43.569142Z","end":"2026-06-05T11:24:44.345116Z","steps":["trace[1912503278] 'agreement among raft nodes before linearized reading' (duration: 775.876963ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:24:44.345156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:24:43.569093Z","time spent":"776.057077ms","remote":"127.0.0.1:53224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-8zvsb\" "} {"level":"info","ts":"2026-06-05T11:24:44.345198Z","caller":"traceutil/trace.go:171","msg":"trace[1566711890] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"735.036251ms","start":"2026-06-05T11:24:43.610148Z","end":"2026-06-05T11:24:44.345185Z","steps":["trace[1566711890] 'process raft request' (duration: 87.140156ms)","trace[1566711890] 'compare' (duration: 646.361238ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T11:24:44.345182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.287501ms","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-06-05T11:24:44.345153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:24:43.892559Z","time spent":"452.586451ms","remote":"127.0.0.1:53298","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-06-05T11:24:44.345253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:24:43.610124Z","time spent":"735.094703ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T11:24:44.345283Z","caller":"traceutil/trace.go:171","msg":"trace[143472151] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:629; }","duration":"983.423324ms","start":"2026-06-05T11:24:43.361829Z","end":"2026-06-05T11:24:44.345252Z","steps":["trace[143472151] 'agreement among raft nodes before linearized reading' (duration: 983.132857ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:24:44.345333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:24:43.361811Z","time spent":"983.513167ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-05T11:24:44.474205Z","caller":"traceutil/trace.go:171","msg":"trace[775381653] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"121.794508ms","start":"2026-06-05T11:24:44.35238Z","end":"2026-06-05T11:24:44.474174Z","steps":["trace[775381653] 'process raft request' (duration: 94.128696ms)","trace[775381653] 'compare' (duration: 27.388885ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T11:24:44.474994Z","caller":"traceutil/trace.go:171","msg":"trace[168282526] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"122.420524ms","start":"2026-06-05T11:24:44.352556Z","end":"2026-06-05T11:24:44.474976Z","steps":["trace[168282526] 'process raft request' (duration: 121.502231ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:24:44.475647Z","caller":"traceutil/trace.go:171","msg":"trace[1951969966] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:650; }","duration":"117.34261ms","start":"2026-06-05T11:24:44.358287Z","end":"2026-06-05T11:24:44.47563Z","steps":["trace[1951969966] 'read index received' (duration: 37.519211ms)","trace[1951969966] 'applied index is now lower than readState.Index' (duration: 79.822089ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T11:24:44.475799Z","caller":"traceutil/trace.go:171","msg":"trace[2123913201] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"115.933256ms","start":"2026-06-05T11:24:44.359828Z","end":"2026-06-05T11:24:44.475762Z","steps":["trace[2123913201] 'process raft request' (duration: 114.965703ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:24:44.476153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.800192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T11:24:44.476253Z","caller":"traceutil/trace.go:171","msg":"trace[1592989771] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:633; }","duration":"117.932094ms","start":"2026-06-05T11:24:44.35826Z","end":"2026-06-05T11:24:44.476192Z","steps":["trace[1592989771] 'agreement among raft nodes before linearized reading' (duration: 117.488844ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:24:46.016294Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899719177680758,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T11:24:46.517153Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899719177680758,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T11:24:47.011597Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.672643863s","expected-duration":"1s"} {"level":"info","ts":"2026-06-05T11:24:47.019944Z","caller":"traceutil/trace.go:171","msg":"trace[2144717767] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"2.150748385s","start":"2026-06-05T11:24:44.869157Z","end":"2026-06-05T11:24:47.019905Z","steps":["trace[2144717767] 'process raft request' (duration: 2.143269923s)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:24:47.01994Z","caller":"traceutil/trace.go:171","msg":"trace[2105144990] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"1.504455659s","start":"2026-06-05T11:24:45.515401Z","end":"2026-06-05T11:24:47.019857Z","steps":["trace[2105144990] 'read index received' (duration: 1.496369242s)","trace[2105144990] 'applied index is now lower than readState.Index' (duration: 8.084997ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T11:24:47.020111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.504655443s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T11:24:47.020758Z","caller":"traceutil/trace.go:171","msg":"trace[1707339260] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"1.50536122s","start":"2026-06-05T11:24:45.515354Z","end":"2026-06-05T11:24:47.020715Z","steps":["trace[1707339260] 'agreement among raft nodes before linearized reading' (duration: 1.504647573s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:24:47.020123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:24:44.869129Z","time spent":"2.150904309s","remote":"127.0.0.1:53128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T11:24:47.020831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:24:45.515329Z","time spent":"1.505479473s","remote":"127.0.0.1:32866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-05T11:24:47.020273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.083506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-05T11:24:47.020885Z","caller":"traceutil/trace.go:171","msg":"trace[817588015] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:635; }","duration":"540.728432ms","start":"2026-06-05T11:24:46.480142Z","end":"2026-06-05T11:24:47.02087Z","steps":["trace[817588015] 'agreement among raft nodes before linearized reading' (duration: 540.022565ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:24:47.020923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:24:46.480119Z","time spent":"540.796104ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-05T11:24:47.020347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.980672ms","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-06-05T11:24:47.02102Z","caller":"traceutil/trace.go:171","msg":"trace[475312978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:635; }","duration":"670.707499ms","start":"2026-06-05T11:24:46.350295Z","end":"2026-06-05T11:24:47.021003Z","steps":["trace[475312978] 'agreement among raft nodes before linearized reading' (duration: 669.863499ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:24:47.021102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:24:46.350271Z","time spent":"670.816301ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-05T11:24:47.020353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.989363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-05T11:24:47.021149Z","caller":"traceutil/trace.go:171","msg":"trace[359044726] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:635; }","duration":"542.811872ms","start":"2026-06-05T11:24:46.478325Z","end":"2026-06-05T11:24:47.021136Z","steps":["trace[359044726] 'agreement among raft nodes before linearized reading' (duration: 541.852129ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:24:47.021193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:24:46.478317Z","time spent":"542.867823ms","remote":"127.0.0.1:53298","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-06-05T11:24:47.020445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.064305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-05T11:24:47.021263Z","caller":"traceutil/trace.go:171","msg":"trace[45903465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:635; }","duration":"619.948407ms","start":"2026-06-05T11:24:46.401306Z","end":"2026-06-05T11:24:47.021254Z","steps":["trace[45903465] 'agreement among raft nodes before linearized reading' (duration: 618.900511ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:24:47.021293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:24:46.401288Z","time spent":"619.996178ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-05T11:24:51.903982Z","caller":"traceutil/trace.go:171","msg":"trace[733704372] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"122.050083ms","start":"2026-06-05T11:24:51.781912Z","end":"2026-06-05T11:24:51.903962Z","steps":["trace[733704372] 'process raft request' (duration: 121.532631ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:25:03.373229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.946727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-05T11:25:03.373294Z","caller":"traceutil/trace.go:171","msg":"trace[1520087649] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:796; }","duration":"260.06356ms","start":"2026-06-05T11:25:03.113216Z","end":"2026-06-05T11:25:03.37328Z","steps":["trace[1520087649] 'range keys from in-memory index tree' (duration: 259.70629ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:25:03.373292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.0025ms","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-06-05T11:25:03.373336Z","caller":"traceutil/trace.go:171","msg":"trace[2061270625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:796; }","duration":"249.068572ms","start":"2026-06-05T11:25:03.124255Z","end":"2026-06-05T11:25:03.373323Z","steps":["trace[2061270625] 'range keys from in-memory index tree' (duration: 248.881057ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:25:03.373453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.84468ms","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-06-05T11:25:03.373488Z","caller":"traceutil/trace.go:171","msg":"trace[1374061549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:796; }","duration":"251.89847ms","start":"2026-06-05T11:25:03.121582Z","end":"2026-06-05T11:25:03.37348Z","steps":["trace[1374061549] 'range keys from in-memory index tree' (duration: 251.764078ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:25:03.373643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.933129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-g58dt\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-06-05T11:25:03.373676Z","caller":"traceutil/trace.go:171","msg":"trace[436212829] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-g58dt; range_end:; response_count:1; response_revision:796; }","duration":"703.003221ms","start":"2026-06-05T11:25:02.670664Z","end":"2026-06-05T11:25:03.373667Z","steps":["trace[436212829] 'range keys from in-memory index tree' (duration: 702.812286ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:25:03.373706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:25:02.670635Z","time spent":"703.063813ms","remote":"127.0.0.1:53224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-g58dt\" "} {"level":"warn","ts":"2026-06-05T11:25:03.374216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.192181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T11:25:03.374271Z","caller":"traceutil/trace.go:171","msg":"trace[345677657] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:796; }","duration":"740.167434ms","start":"2026-06-05T11:25:02.63409Z","end":"2026-06-05T11:25:03.374257Z","steps":["trace[345677657] 'count revisions from in-memory index tree' (duration: 739.041427ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:25:03.374306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:25:02.634066Z","time spent":"740.227906ms","remote":"127.0.0.1:53318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-05T11:25:03.374845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.973746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-05T11:25:03.374888Z","caller":"traceutil/trace.go:171","msg":"trace[1441985189] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:796; }","duration":"260.071079ms","start":"2026-06-05T11:25:03.114805Z","end":"2026-06-05T11:25:03.374876Z","steps":["trace[1441985189] 'range keys from in-memory index tree' (duration: 258.121723ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:25:03.619171Z","caller":"traceutil/trace.go:171","msg":"trace[295842387] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"241.032526ms","start":"2026-06-05T11:25:03.378086Z","end":"2026-06-05T11:25:03.619119Z","steps":["trace[295842387] 'process raft request' (duration: 240.710908ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:25:03.627893Z","caller":"traceutil/trace.go:171","msg":"trace[210897940] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"249.229635ms","start":"2026-06-05T11:25:03.378639Z","end":"2026-06-05T11:25:03.627869Z","steps":["trace[210897940] 'process raft request' (duration: 249.05285ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:25:03.628654Z","caller":"traceutil/trace.go:171","msg":"trace[20771875] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"249.757938ms","start":"2026-06-05T11:25:03.37887Z","end":"2026-06-05T11:25:03.628628Z","steps":["trace[20771875] 'process raft request' (duration: 248.932138ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:25:03.629364Z","caller":"traceutil/trace.go:171","msg":"trace[247967647] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"250.347132ms","start":"2026-06-05T11:25:03.379001Z","end":"2026-06-05T11:25:03.629348Z","steps":["trace[247967647] 'process raft request' (duration: 249.522802ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:25:03.629496Z","caller":"traceutil/trace.go:171","msg":"trace[23382364] linearizableReadLoop","detail":"{readStateIndex:829; appliedIndex:824; }","duration":"113.890078ms","start":"2026-06-05T11:25:03.515575Z","end":"2026-06-05T11:25:03.629465Z","steps":["trace[23382364] 'read index received' (duration: 103.451444ms)","trace[23382364] 'applied index is now lower than readState.Index' (duration: 10.436854ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T11:25:03.629616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.033401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T11:25:03.629687Z","caller":"traceutil/trace.go:171","msg":"trace[2056908608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:801; }","duration":"114.144664ms","start":"2026-06-05T11:25:03.515529Z","end":"2026-06-05T11:25:03.629674Z","steps":["trace[2056908608] 'agreement among raft nodes before linearized reading' (duration: 114.041101ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:25:03.629692Z","caller":"traceutil/trace.go:171","msg":"trace[766940254] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"245.811303ms","start":"2026-06-05T11:25:03.383871Z","end":"2026-06-05T11:25:03.629682Z","steps":["trace[766940254] 'process raft request' (duration: 245.334811ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:25:18.032352Z","caller":"traceutil/trace.go:171","msg":"trace[187037622] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"270.723577ms","start":"2026-06-05T11:25:17.761607Z","end":"2026-06-05T11:25:18.03233Z","steps":["trace[187037622] 'process raft request' (duration: 270.626845ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:25:18.032584Z","caller":"traceutil/trace.go:171","msg":"trace[1667778154] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"149.228777ms","start":"2026-06-05T11:25:17.883345Z","end":"2026-06-05T11:25:18.032574Z","steps":["trace[1667778154] 'process raft request' (duration: 148.935169ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:25:18.032617Z","caller":"traceutil/trace.go:171","msg":"trace[161664372] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"271.2297ms","start":"2026-06-05T11:25:17.761328Z","end":"2026-06-05T11:25:18.032558Z","steps":["trace[161664372] 'process raft request' (duration: 259.417902ms)","trace[161664372] 'compare' (duration: 11.352536ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T11:25:23.973253Z","caller":"traceutil/trace.go:171","msg":"trace[2114972663] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"100.733468ms","start":"2026-06-05T11:25:23.87248Z","end":"2026-06-05T11:25:23.973214Z","steps":["trace[2114972663] 'process raft request' (duration: 85.029407ms)","trace[2114972663] 'compare' (duration: 15.502496ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T11:25:28.211377Z","caller":"traceutil/trace.go:171","msg":"trace[1689329269] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"105.756519ms","start":"2026-06-05T11:25:28.105604Z","end":"2026-06-05T11:25:28.211361Z","steps":["trace[1689329269] 'process raft request' (duration: 74.261215ms)","trace[1689329269] 'compare' (duration: 31.081235ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T11:25:28.211666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.51747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-05T11:25:28.211705Z","caller":"traceutil/trace.go:171","msg":"trace[458480299] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1006; }","duration":"102.181642ms","start":"2026-06-05T11:25:28.109136Z","end":"2026-06-05T11:25:28.211317Z","steps":["trace[458480299] 'read index received' (duration: 32.753365ms)","trace[458480299] 'applied index is now lower than readState.Index' (duration: 69.427247ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T11:25:28.211746Z","caller":"traceutil/trace.go:171","msg":"trace[1983607341] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:974; }","duration":"102.635033ms","start":"2026-06-05T11:25:28.109094Z","end":"2026-06-05T11:25:28.211729Z","steps":["trace[1983607341] 'agreement among raft nodes before linearized reading' (duration: 102.351936ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:25:32.69541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.394583ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T11:25:32.695499Z","caller":"traceutil/trace.go:171","msg":"trace[1703446014] linearizableReadLoop","detail":"{readStateIndex:1022; appliedIndex:1021; }","duration":"462.764234ms","start":"2026-06-05T11:25:32.232722Z","end":"2026-06-05T11:25:32.695486Z","steps":["trace[1703446014] 'read index received' (duration: 231.175436ms)","trace[1703446014] 'applied index is now lower than readState.Index' (duration: 231.587958ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T11:25:32.69554Z","caller":"traceutil/trace.go:171","msg":"trace[1440501079] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"474.700944ms","start":"2026-06-05T11:25:32.220809Z","end":"2026-06-05T11:25:32.69551Z","steps":["trace[1440501079] 'process raft request' (duration: 243.129057ms)","trace[1440501079] 'compare' (duration: 231.25827ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T11:25:32.695636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.913297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-05T11:25:32.695655Z","caller":"traceutil/trace.go:171","msg":"trace[1070010201] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:987; }","duration":"462.963979ms","start":"2026-06-05T11:25:32.232685Z","end":"2026-06-05T11:25:32.695649Z","steps":["trace[1070010201] 'agreement among raft nodes before linearized reading' (duration: 462.874876ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:25:32.695682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:25:32.232668Z","time spent":"463.00837ms","remote":"127.0.0.1:53298","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":"warn","ts":"2026-06-05T11:25:32.695686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:25:32.220797Z","time spent":"474.808758ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T11:25:32.695736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.367896ms","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":"warn","ts":"2026-06-05T11:25:32.695718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.918831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T11:25:32.695803Z","caller":"traceutil/trace.go:171","msg":"trace[1969409344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:987; }","duration":"182.034493ms","start":"2026-06-05T11:25:32.513745Z","end":"2026-06-05T11:25:32.695779Z","steps":["trace[1969409344] 'agreement among raft nodes before linearized reading' (duration: 181.90908ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:25:32.696044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.379254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-05T11:25:32.696132Z","caller":"traceutil/trace.go:171","msg":"trace[1314247753] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:987; }","duration":"462.488838ms","start":"2026-06-05T11:25:32.233625Z","end":"2026-06-05T11:25:32.696114Z","steps":["trace[1314247753] 'agreement among raft nodes before linearized reading' (duration: 461.996196ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:25:32.696366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:25:32.233616Z","time spent":"462.738213ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-05T11:25:32.696388Z","caller":"traceutil/trace.go:171","msg":"trace[1308357902] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:987; }","duration":"133.047163ms","start":"2026-06-05T11:25:32.563325Z","end":"2026-06-05T11:25:32.696372Z","steps":["trace[1308357902] 'agreement among raft nodes before linearized reading' (duration: 132.301765ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:25:34.994581Z","caller":"traceutil/trace.go:171","msg":"trace[1673239325] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"190.117658ms","start":"2026-06-05T11:25:34.804442Z","end":"2026-06-05T11:25:34.994559Z","steps":["trace[1673239325] 'process raft request' (duration: 190.066547ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:25:34.994705Z","caller":"traceutil/trace.go:171","msg":"trace[997775566] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"190.276822ms","start":"2026-06-05T11:25:34.804399Z","end":"2026-06-05T11:25:34.994676Z","steps":["trace[997775566] 'process raft request' (duration: 133.112984ms)","trace[997775566] 'compare' (duration: 56.845681ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T11:25:38.695734Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899719177681525,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T11:25:38.916409Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.033146632s","expected-duration":"1s"} {"level":"info","ts":"2026-06-05T11:25:38.916751Z","caller":"traceutil/trace.go:171","msg":"trace[1486996257] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"1.033575412s","start":"2026-06-05T11:25:37.883154Z","end":"2026-06-05T11:25:38.91673Z","steps":["trace[1486996257] 'process raft request' (duration: 1.033439139s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:25:38.916907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:25:37.88313Z","time spent":"1.033690346s","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T11:25:38.925774Z","caller":"traceutil/trace.go:171","msg":"trace[2064293558] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1044; }","duration":"730.51215ms","start":"2026-06-05T11:25:38.195235Z","end":"2026-06-05T11:25:38.925747Z","steps":["trace[2064293558] 'read index received' (duration: 721.370647ms)","trace[2064293558] 'applied index is now lower than readState.Index' (duration: 9.139863ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T11:25:38.926558Z","caller":"traceutil/trace.go:171","msg":"trace[944079556] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"329.892005ms","start":"2026-06-05T11:25:38.596578Z","end":"2026-06-05T11:25:38.92647Z","steps":["trace[944079556] 'process raft request' (duration: 328.955563ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:25:38.926701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:25:38.596553Z","time spent":"330.08378ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T11:25:38.927119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.80431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T11:25:38.927146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.897093ms","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-06-05T11:25:38.927224Z","caller":"traceutil/trace.go:171","msg":"trace[975985897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1011; }","duration":"732.006886ms","start":"2026-06-05T11:25:38.195201Z","end":"2026-06-05T11:25:38.927208Z","steps":["trace[975985897] 'agreement among raft nodes before linearized reading' (duration: 731.082064ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:25:38.927269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:25:38.195187Z","time spent":"732.071587ms","remote":"127.0.0.1:53298","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":"info","ts":"2026-06-05T11:25:38.927247Z","caller":"traceutil/trace.go:171","msg":"trace[119700152] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1011; }","duration":"412.952814ms","start":"2026-06-05T11:25:38.514241Z","end":"2026-06-05T11:25:38.927194Z","steps":["trace[119700152] 'agreement among raft nodes before linearized reading' (duration: 412.217756ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:25:38.927365Z","caller":"traceutil/trace.go:171","msg":"trace[798748743] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"114.83006ms","start":"2026-06-05T11:25:38.811684Z","end":"2026-06-05T11:25:38.926514Z","steps":["trace[798748743] 'process raft request' (duration: 114.016061ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:25:38.927418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:25:38.514223Z","time spent":"413.152168ms","remote":"127.0.0.1:32866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-05T11:25:39.50786Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899719177681531,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T11:25:39.776837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.788307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T11:25:39.776931Z","caller":"traceutil/trace.go:171","msg":"trace[749567253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1011; }","duration":"844.90919ms","start":"2026-06-05T11:25:38.932001Z","end":"2026-06-05T11:25:39.77691Z","steps":["trace[749567253] 'range keys from in-memory index tree' (duration: 844.674654ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:25:39.77697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:25:38.931987Z","time spent":"844.975041ms","remote":"127.0.0.1:32876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-05T11:25:39.777621Z","caller":"traceutil/trace.go:171","msg":"trace[1423772119] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"843.408812ms","start":"2026-06-05T11:25:38.93418Z","end":"2026-06-05T11:25:39.777589Z","steps":["trace[1423772119] 'process raft request' (duration: 797.620189ms)","trace[1423772119] 'compare' (duration: 44.924522ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T11:25:39.778289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:25:38.934138Z","time spent":"844.060708ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T11:25:40.675516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:25:40.161426Z","time spent":"514.08068ms","remote":"127.0.0.1:53822","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-05T11:25:40.67607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.938371ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T11:25:40.676256Z","caller":"traceutil/trace.go:171","msg":"trace[292707857] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"1.741927154s","start":"2026-06-05T11:25:38.934306Z","end":"2026-06-05T11:25:40.676234Z","steps":["trace[292707857] 'process raft request' (duration: 1.389756497s)","trace[292707857] 'compare' (duration: 351.841168ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T11:25:40.676339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:25:38.934265Z","time spent":"1.742017767s","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T11:25:40.979887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.729889ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-05T11:25:40.979996Z","caller":"traceutil/trace.go:171","msg":"trace[1185667668] linearizableReadLoop","detail":"{readStateIndex:1049; appliedIndex:1047; }","duration":"1.973112121s","start":"2026-06-05T11:25:39.006864Z","end":"2026-06-05T11:25:40.979976Z","steps":["trace[1185667668] 'read index received' (duration: 724.948413ms)","trace[1185667668] 'applied index is now lower than readState.Index' (duration: 1.248162128s)"],"step_count":2} {"level":"warn","ts":"2026-06-05T11:25:40.980194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.973305365s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-05T11:25:40.980266Z","caller":"traceutil/trace.go:171","msg":"trace[1109086737] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1013; }","duration":"1.973411818s","start":"2026-06-05T11:25:39.00683Z","end":"2026-06-05T11:25:40.980242Z","steps":["trace[1109086737] 'agreement among raft nodes before linearized reading' (duration: 1.973221474s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:25:40.980299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:25:39.006813Z","time spent":"1.973480061s","remote":"127.0.0.1:53298","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-06-05T11:25:40.9848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.977102509s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-05T11:25:40.984875Z","caller":"traceutil/trace.go:171","msg":"trace[37516250] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1014; }","duration":"1.977187401s","start":"2026-06-05T11:25:39.007672Z","end":"2026-06-05T11:25:40.984859Z","steps":["trace[37516250] 'agreement among raft nodes before linearized reading' (duration: 1.976999586s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:25:40.984915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:25:39.007631Z","time spent":"1.977274993s","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-05T11:25:40.98486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.676288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T11:25:40.984974Z","caller":"traceutil/trace.go:171","msg":"trace[26100437] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1014; }","duration":"873.832322ms","start":"2026-06-05T11:25:40.111122Z","end":"2026-06-05T11:25:40.984954Z","steps":["trace[26100437] 'agreement among raft nodes before linearized reading' (duration: 873.644028ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:25:40.984996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.19929884s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T11:25:40.985011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:25:40.111102Z","time spent":"873.901463ms","remote":"127.0.0.1:53360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-06-05T11:25:40.985036Z","caller":"traceutil/trace.go:171","msg":"trace[1274953458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1014; }","duration":"1.199363611s","start":"2026-06-05T11:25:39.785661Z","end":"2026-06-05T11:25:40.985025Z","steps":["trace[1274953458] 'agreement among raft nodes before linearized reading' (duration: 1.1993076s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:25:40.985063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:25:39.785647Z","time spent":"1.199410153s","remote":"127.0.0.1:32866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-05T11:25:43.106012Z","caller":"traceutil/trace.go:171","msg":"trace[193401399] linearizableReadLoop","detail":"{readStateIndex:1057; appliedIndex:1056; }","duration":"112.076343ms","start":"2026-06-05T11:25:42.993912Z","end":"2026-06-05T11:25:43.105988Z","steps":["trace[193401399] 'read index received' (duration: 111.95874ms)","trace[193401399] 'applied index is now lower than readState.Index' (duration: 116.603µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T11:25:43.106041Z","caller":"traceutil/trace.go:171","msg":"trace[1770151884] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"116.528341ms","start":"2026-06-05T11:25:42.989479Z","end":"2026-06-05T11:25:43.106007Z","steps":["trace[1770151884] 'process raft request' (duration: 116.361437ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:25:43.106266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.34624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-05T11:25:43.106341Z","caller":"traceutil/trace.go:171","msg":"trace[1529889303] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1020; }","duration":"112.465652ms","start":"2026-06-05T11:25:42.993859Z","end":"2026-06-05T11:25:43.106325Z","steps":["trace[1529889303] 'agreement among raft nodes before linearized reading' (duration: 112.238166ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:25:43.106352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.541546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-05T11:25:43.106405Z","caller":"traceutil/trace.go:171","msg":"trace[271162973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1020; }","duration":"110.637247ms","start":"2026-06-05T11:25:42.995754Z","end":"2026-06-05T11:25:43.106391Z","steps":["trace[271162973] 'agreement among raft nodes before linearized reading' (duration: 110.433683ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:25:43.106393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.108635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-05T11:25:43.106587Z","caller":"traceutil/trace.go:171","msg":"trace[414333833] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1020; }","duration":"110.32968ms","start":"2026-06-05T11:25:42.996242Z","end":"2026-06-05T11:25:43.106571Z","steps":["trace[414333833] 'agreement among raft nodes before linearized reading' (duration: 109.957251ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:25:45.239374Z","caller":"traceutil/trace.go:171","msg":"trace[1210332337] linearizableReadLoop","detail":"{readStateIndex:1065; appliedIndex:1064; }","duration":"112.78609ms","start":"2026-06-05T11:25:45.126561Z","end":"2026-06-05T11:25:45.239347Z","steps":["trace[1210332337] 'read index received' (duration: 112.564125ms)","trace[1210332337] 'applied index is now lower than readState.Index' (duration: 221.025µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T11:25:45.239648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.074278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-05T11:25:45.23969Z","caller":"traceutil/trace.go:171","msg":"trace[987766457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1027; }","duration":"113.17607ms","start":"2026-06-05T11:25:45.126504Z","end":"2026-06-05T11:25:45.23968Z","steps":["trace[987766457] 'agreement among raft nodes before linearized reading' (duration: 112.985616ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:25:45.239831Z","caller":"traceutil/trace.go:171","msg":"trace[1699880257] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"128.547993ms","start":"2026-06-05T11:25:45.111247Z","end":"2026-06-05T11:25:45.239795Z","steps":["trace[1699880257] 'process raft request' (duration: 127.918197ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:25:45.370295Z","caller":"traceutil/trace.go:171","msg":"trace[534190180] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"125.811198ms","start":"2026-06-05T11:25:45.244447Z","end":"2026-06-05T11:25:45.370258Z","steps":["trace[534190180] 'process raft request' (duration: 86.874241ms)","trace[534190180] 'compare' (duration: 38.767922ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T11:25:45.400018Z","caller":"traceutil/trace.go:171","msg":"trace[393893651] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"155.463928ms","start":"2026-06-05T11:25:45.244526Z","end":"2026-06-05T11:25:45.39999Z","steps":["trace[393893651] 'process raft request' (duration: 155.204282ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:25:45.400022Z","caller":"traceutil/trace.go:171","msg":"trace[192298238] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"155.447597ms","start":"2026-06-05T11:25:45.244552Z","end":"2026-06-05T11:25:45.4Z","steps":["trace[192298238] 'process raft request' (duration: 155.358815ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:25:45.400168Z","caller":"traceutil/trace.go:171","msg":"trace[1808287603] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1065; }","duration":"103.205138ms","start":"2026-06-05T11:25:45.296951Z","end":"2026-06-05T11:25:45.400156Z","steps":["trace[1808287603] 'read index received' (duration: 34.392246ms)","trace[1808287603] 'applied index is now lower than readState.Index' (duration: 68.811482ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T11:25:45.400219Z","caller":"traceutil/trace.go:171","msg":"trace[564200122] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"146.180901ms","start":"2026-06-05T11:25:45.25399Z","end":"2026-06-05T11:25:45.400171Z","steps":["trace[564200122] 'process raft request' (duration: 145.968486ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:25:45.400293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.31647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T11:25:45.400324Z","caller":"traceutil/trace.go:171","msg":"trace[1058931520] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1031; }","duration":"103.400652ms","start":"2026-06-05T11:25:45.296915Z","end":"2026-06-05T11:25:45.400316Z","steps":["trace[1058931520] 'agreement among raft nodes before linearized reading' (duration: 103.31845ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:25:45.951014Z","caller":"traceutil/trace.go:171","msg":"trace[173574001] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"124.379811ms","start":"2026-06-05T11:25:45.826609Z","end":"2026-06-05T11:25:45.950988Z","steps":["trace[173574001] 'process raft request' (duration: 124.160986ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:25:59.401897Z","caller":"traceutil/trace.go:171","msg":"trace[562735726] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"106.16038ms","start":"2026-06-05T11:25:59.295714Z","end":"2026-06-05T11:25:59.401874Z","steps":["trace[562735726] 'process raft request' (duration: 106.010466ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:04.913061Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899719177681736,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-05T11:26:05.022479Z","caller":"traceutil/trace.go:171","msg":"trace[1841714181] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1130; }","duration":"609.733741ms","start":"2026-06-05T11:26:04.412722Z","end":"2026-06-05T11:26:05.022455Z","steps":["trace[1841714181] 'read index received' (duration: 609.577618ms)","trace[1841714181] 'applied index is now lower than readState.Index' (duration: 155.263µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T11:26:05.022556Z","caller":"traceutil/trace.go:171","msg":"trace[1245066588] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"934.37617ms","start":"2026-06-05T11:26:04.088142Z","end":"2026-06-05T11:26:05.022518Z","steps":["trace[1245066588] 'process raft request' (duration: 934.178535ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:05.022731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.991757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-05T11:26:05.022807Z","caller":"traceutil/trace.go:171","msg":"trace[1194403697] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:1090; }","duration":"610.08407ms","start":"2026-06-05T11:26:04.412706Z","end":"2026-06-05T11:26:05.02279Z","steps":["trace[1194403697] 'agreement among raft nodes before linearized reading' (duration: 609.851124ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:05.022796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:04.088118Z","time spent":"934.522423ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T11:26:05.022854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:04.412653Z","time spent":"610.192183ms","remote":"127.0.0.1:53068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.53\" "} {"level":"warn","ts":"2026-06-05T11:26:05.022891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.184802ms","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-06-05T11:26:05.022941Z","caller":"traceutil/trace.go:171","msg":"trace[1183605126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1090; }","duration":"179.279855ms","start":"2026-06-05T11:26:04.843646Z","end":"2026-06-05T11:26:05.022926Z","steps":["trace[1183605126] 'agreement among raft nodes before linearized reading' (duration: 179.11374ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:05.02275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.668852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T11:26:05.023088Z","caller":"traceutil/trace.go:171","msg":"trace[1804703550] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1090; }","duration":"508.027921ms","start":"2026-06-05T11:26:04.515017Z","end":"2026-06-05T11:26:05.023045Z","steps":["trace[1804703550] 'agreement among raft nodes before linearized reading' (duration: 507.679152ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:05.023177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:04.514999Z","time spent":"508.150444ms","remote":"127.0.0.1:32866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-05T11:26:05.125823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.346073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T11:26:05.125559Z","caller":"traceutil/trace.go:171","msg":"trace[856889988] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1131; }","duration":"101.075856ms","start":"2026-06-05T11:26:05.024467Z","end":"2026-06-05T11:26:05.125543Z","steps":["trace[856889988] 'read index received' (duration: 91.884032ms)","trace[856889988] 'applied index is now lower than readState.Index' (duration: 9.190603ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T11:26:05.125957Z","caller":"traceutil/trace.go:171","msg":"trace[1986340103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1090; }","duration":"101.491926ms","start":"2026-06-05T11:26:05.024422Z","end":"2026-06-05T11:26:05.125914Z","steps":["trace[1986340103] 'agreement among raft nodes before linearized reading' (duration: 101.355933ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:26:05.138828Z","caller":"traceutil/trace.go:171","msg":"trace[862389383] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"109.961722ms","start":"2026-06-05T11:26:05.028844Z","end":"2026-06-05T11:26:05.138806Z","steps":["trace[862389383] 'process raft request' (duration: 109.817698ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:26:07.159775Z","caller":"traceutil/trace.go:171","msg":"trace[305414356] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"127.121938ms","start":"2026-06-05T11:26:07.032594Z","end":"2026-06-05T11:26:07.159716Z","steps":["trace[305414356] 'process raft request' (duration: 126.77179ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:26:07.480301Z","caller":"traceutil/trace.go:171","msg":"trace[1909394698] linearizableReadLoop","detail":"{readStateIndex:1141; appliedIndex:1140; }","duration":"315.325577ms","start":"2026-06-05T11:26:07.164954Z","end":"2026-06-05T11:26:07.480279Z","steps":["trace[1909394698] 'read index received' (duration: 292.390238ms)","trace[1909394698] 'applied index is now lower than readState.Index' (duration: 22.933989ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T11:26:07.480392Z","caller":"traceutil/trace.go:171","msg":"trace[392527587] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"316.540346ms","start":"2026-06-05T11:26:07.163806Z","end":"2026-06-05T11:26:07.480346Z","steps":["trace[392527587] 'process raft request' (duration: 293.530445ms)","trace[392527587] 'compare' (duration: 22.658012ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T11:26:07.480684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.704726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T11:26:07.480771Z","caller":"traceutil/trace.go:171","msg":"trace[936312837] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1099; }","duration":"315.807658ms","start":"2026-06-05T11:26:07.164938Z","end":"2026-06-05T11:26:07.480745Z","steps":["trace[936312837] 'agreement among raft nodes before linearized reading' (duration: 315.529372ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:07.480784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:07.163783Z","time spent":"316.917915ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T11:26:07.48086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:07.1649Z","time spent":"315.946032ms","remote":"127.0.0.1:59608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-06-05T11:26:07.495647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.011876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T11:26:07.495724Z","caller":"traceutil/trace.go:171","msg":"trace[1107755001] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1100; }","duration":"102.123458ms","start":"2026-06-05T11:26:07.393584Z","end":"2026-06-05T11:26:07.495707Z","steps":["trace[1107755001] 'agreement among raft nodes before linearized reading' (duration: 101.994345ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:26.086761Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899719177682018,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T11:26:26.20313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.688322015s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T11:26:26.203206Z","caller":"traceutil/trace.go:171","msg":"trace[689647865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1208; }","duration":"1.688455309s","start":"2026-06-05T11:26:24.514733Z","end":"2026-06-05T11:26:26.203188Z","steps":["trace[689647865] 'range keys from in-memory index tree' (duration: 1.688176852s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:26.203246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:24.514693Z","time spent":"1.688547222s","remote":"127.0.0.1:32866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-05T11:26:26.203426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:25.053117Z","time spent":"1.15030458s","remote":"127.0.0.1:48508","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-05T11:26:26.203632Z","caller":"traceutil/trace.go:171","msg":"trace[892071943] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"635.798858ms","start":"2026-06-05T11:26:25.567803Z","end":"2026-06-05T11:26:26.203601Z","steps":["trace[892071943] 'process raft request' (duration: 635.213734ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:26.203805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.123037ms","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-06-05T11:26:26.203807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:25.567786Z","time spent":"635.936762ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T11:26:26.203861Z","caller":"traceutil/trace.go:171","msg":"trace[746613550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1209; }","duration":"618.201249ms","start":"2026-06-05T11:26:25.585631Z","end":"2026-06-05T11:26:26.203833Z","steps":["trace[746613550] 'agreement among raft nodes before linearized reading' (duration: 618.063126ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:26.203887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:25.585599Z","time spent":"618.279581ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-05T11:26:26.203672Z","caller":"traceutil/trace.go:171","msg":"trace[221661928] linearizableReadLoop","detail":"{readStateIndex:1257; appliedIndex:1257; }","duration":"617.964114ms","start":"2026-06-05T11:26:25.585691Z","end":"2026-06-05T11:26:26.203655Z","steps":["trace[221661928] 'read index received' (duration: 617.957443ms)","trace[221661928] 'applied index is now lower than readState.Index' (duration: 5.241µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T11:26:26.203659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.80412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-05T11:26:26.204471Z","caller":"traceutil/trace.go:171","msg":"trace[1877407038] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1208; }","duration":"963.678902ms","start":"2026-06-05T11:26:25.240776Z","end":"2026-06-05T11:26:26.204455Z","steps":["trace[1877407038] 'range keys from in-memory index tree' (duration: 962.595384ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:26.204503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:25.240756Z","time spent":"963.734583ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-05T11:26:26.209135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.670478ms","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-06-05T11:26:26.209128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.073699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T11:26:26.209256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.442504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-05T11:26:26.209264Z","caller":"traceutil/trace.go:171","msg":"trace[1801560461] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1210; }","duration":"382.838572ms","start":"2026-06-05T11:26:25.826406Z","end":"2026-06-05T11:26:26.209245Z","steps":["trace[1801560461] 'agreement among raft nodes before linearized reading' (duration: 382.563265ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:26:26.209307Z","caller":"traceutil/trace.go:171","msg":"trace[417828734] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1210; }","duration":"371.496965ms","start":"2026-06-05T11:26:25.837797Z","end":"2026-06-05T11:26:26.209294Z","steps":["trace[417828734] 'agreement among raft nodes before linearized reading' (duration: 371.356572ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:26.209355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:25.837717Z","time spent":"371.631598ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-05T11:26:26.209378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:25.826385Z","time spent":"382.955684ms","remote":"127.0.0.1:53298","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":"info","ts":"2026-06-05T11:26:26.20929Z","caller":"traceutil/trace.go:171","msg":"trace[1786552176] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1210; }","duration":"461.295704ms","start":"2026-06-05T11:26:25.74797Z","end":"2026-06-05T11:26:26.209265Z","steps":["trace[1786552176] 'agreement among raft nodes before linearized reading' (duration: 461.019438ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:26.20946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:25.747944Z","time spent":"461.50545ms","remote":"127.0.0.1:59548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-06-05T11:26:26.209502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.694924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-05T11:26:26.209583Z","caller":"traceutil/trace.go:171","msg":"trace[177193111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1210; }","duration":"567.041643ms","start":"2026-06-05T11:26:25.642521Z","end":"2026-06-05T11:26:26.209563Z","steps":["trace[177193111] 'agreement among raft nodes before linearized reading' (duration: 566.538931ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:26.209655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:25.642508Z","time spent":"567.134736ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-05T11:26:30.4934Z","caller":"traceutil/trace.go:171","msg":"trace[648935102] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"223.848426ms","start":"2026-06-05T11:26:30.269527Z","end":"2026-06-05T11:26:30.493376Z","steps":["trace[648935102] 'process raft request' (duration: 223.699583ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:26:40.723662Z","caller":"traceutil/trace.go:171","msg":"trace[544192878] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1324; }","duration":"298.497277ms","start":"2026-06-05T11:26:40.425143Z","end":"2026-06-05T11:26:40.72364Z","steps":["trace[544192878] 'read index received' (duration: 298.2755ms)","trace[544192878] 'applied index is now lower than readState.Index' (duration: 221.047µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T11:26:40.72375Z","caller":"traceutil/trace.go:171","msg":"trace[1811093220] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"307.64619ms","start":"2026-06-05T11:26:40.416084Z","end":"2026-06-05T11:26:40.723731Z","steps":["trace[1811093220] 'process raft request' (duration: 307.326581ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:40.723887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:40.416074Z","time spent":"307.719211ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T11:26:40.723878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.721892ms","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-06-05T11:26:40.723925Z","caller":"traceutil/trace.go:171","msg":"trace[192925772] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1274; }","duration":"298.805844ms","start":"2026-06-05T11:26:40.425112Z","end":"2026-06-05T11:26:40.723918Z","steps":["trace[192925772] 'agreement among raft nodes before linearized reading' (duration: 298.64071ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:40.923365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.649935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T11:26:40.92349Z","caller":"traceutil/trace.go:171","msg":"trace[908093121] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"195.364823ms","start":"2026-06-05T11:26:40.728114Z","end":"2026-06-05T11:26:40.923479Z","steps":["trace[908093121] 'process raft request' (duration: 195.331852ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:26:40.923693Z","caller":"traceutil/trace.go:171","msg":"trace[169084337] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"360.820496ms","start":"2026-06-05T11:26:40.562867Z","end":"2026-06-05T11:26:40.923687Z","steps":["trace[169084337] 'process raft request' (duration: 222.789041ms)","trace[169084337] 'compare' (duration: 137.454281ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T11:26:40.923728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:40.562848Z","time spent":"360.862887ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T11:26:40.923777Z","caller":"traceutil/trace.go:171","msg":"trace[915115057] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1325; }","duration":"200.030465ms","start":"2026-06-05T11:26:40.723728Z","end":"2026-06-05T11:26:40.923758Z","steps":["trace[915115057] 'read index received' (duration: 61.94691ms)","trace[915115057] 'applied index is now lower than readState.Index' (duration: 138.081905ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T11:26:40.923824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.427805ms","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-06-05T11:26:40.923864Z","caller":"traceutil/trace.go:171","msg":"trace[2116428651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1276; }","duration":"394.510837ms","start":"2026-06-05T11:26:40.529349Z","end":"2026-06-05T11:26:40.92386Z","steps":["trace[2116428651] 'agreement among raft nodes before linearized reading' (duration: 394.418705ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:40.923883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:40.529327Z","time spent":"394.552058ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-05T11:26:40.923923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.60052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T11:26:40.923934Z","caller":"traceutil/trace.go:171","msg":"trace[445149369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1276; }","duration":"408.640312ms","start":"2026-06-05T11:26:40.51529Z","end":"2026-06-05T11:26:40.923931Z","steps":["trace[445149369] 'agreement among raft nodes before linearized reading' (duration: 408.621591ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:40.923945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:40.515272Z","time spent":"408.671442ms","remote":"127.0.0.1:32866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-05T11:26:40.923957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.566074ms","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-06-05T11:26:40.923969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.542465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5330"} {"level":"info","ts":"2026-06-05T11:26:40.92402Z","caller":"traceutil/trace.go:171","msg":"trace[1542482300] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1276; }","duration":"498.649115ms","start":"2026-06-05T11:26:40.425355Z","end":"2026-06-05T11:26:40.924005Z","steps":["trace[1542482300] 'agreement among raft nodes before linearized reading' (duration: 498.465891ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:26:40.924039Z","caller":"traceutil/trace.go:171","msg":"trace[1037588129] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1276; }","duration":"302.668158ms","start":"2026-06-05T11:26:40.621352Z","end":"2026-06-05T11:26:40.92402Z","steps":["trace[1037588129] 'agreement among raft nodes before linearized reading' (duration: 302.419042ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:40.924074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:40.425348Z","time spent":"498.713647ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-05T11:26:40.92408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:40.621328Z","time spent":"302.74343ms","remote":"127.0.0.1:53214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5354,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-06-05T11:26:40.924125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.151353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-05T11:26:40.924143Z","caller":"traceutil/trace.go:171","msg":"trace[25669202] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1276; }","duration":"498.192545ms","start":"2026-06-05T11:26:40.425946Z","end":"2026-06-05T11:26:40.924139Z","steps":["trace[25669202] 'agreement among raft nodes before linearized reading' (duration: 498.138013ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:40.924164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:40.425936Z","time spent":"498.224175ms","remote":"127.0.0.1:53298","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-06-05T11:26:41.629188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.246154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T11:26:41.629526Z","caller":"traceutil/trace.go:171","msg":"trace[297474467] linearizableReadLoop","detail":"{readStateIndex:1331; appliedIndex:1328; }","duration":"127.520209ms","start":"2026-06-05T11:26:41.501983Z","end":"2026-06-05T11:26:41.629504Z","steps":["trace[297474467] 'read index received' (duration: 52.362µs)","trace[297474467] 'applied index is now lower than readState.Index' (duration: 127.466477ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T11:26:41.629651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.669593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T11:26:41.629688Z","caller":"traceutil/trace.go:171","msg":"trace[2133104207] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1280; }","duration":"127.749585ms","start":"2026-06-05T11:26:41.501928Z","end":"2026-06-05T11:26:41.629678Z","steps":["trace[2133104207] 'agreement among raft nodes before linearized reading' (duration: 127.644152ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:26:41.629816Z","caller":"traceutil/trace.go:171","msg":"trace[2026097848] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"696.154091ms","start":"2026-06-05T11:26:40.933623Z","end":"2026-06-05T11:26:41.629777Z","steps":["trace[2026097848] 'process raft request' (duration: 695.70731ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:26:41.62985Z","caller":"traceutil/trace.go:171","msg":"trace[1919960147] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"694.878509ms","start":"2026-06-05T11:26:40.934945Z","end":"2026-06-05T11:26:41.629823Z","steps":["trace[1919960147] 'process raft request' (duration: 694.50415ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:41.630098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:40.933592Z","time spent":"696.405446ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T11:26:41.629814Z","caller":"traceutil/trace.go:171","msg":"trace[1470563045] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"696.256243ms","start":"2026-06-05T11:26:40.933524Z","end":"2026-06-05T11:26:41.62978Z","steps":["trace[1470563045] 'process raft request' (duration: 524.23998ms)","trace[1470563045] 'compare' (duration: 171.002169ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T11:26:41.630539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:40.933506Z","time spent":"696.99207ms","remote":"127.0.0.1:53214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5456,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T11:26:41.630731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:40.934932Z","time spent":"695.020742ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T11:26:42.327523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.006506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T11:26:42.327628Z","caller":"traceutil/trace.go:171","msg":"trace[91374088] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1331; }","duration":"422.884279ms","start":"2026-06-05T11:26:41.904722Z","end":"2026-06-05T11:26:42.327606Z","steps":["trace[91374088] 'read index received' (duration: 190.735129ms)","trace[91374088] 'applied index is now lower than readState.Index' (duration: 232.148ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T11:26:42.327827Z","caller":"traceutil/trace.go:171","msg":"trace[1340250968] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"690.562214ms","start":"2026-06-05T11:26:41.637252Z","end":"2026-06-05T11:26:42.327814Z","steps":["trace[1340250968] 'process raft request' (duration: 458.180819ms)","trace[1340250968] 'compare' (duration: 231.888533ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T11:26:42.32813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.393101ms","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":"info","ts":"2026-06-05T11:26:42.328203Z","caller":"traceutil/trace.go:171","msg":"trace[1223532838] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1281; }","duration":"423.480513ms","start":"2026-06-05T11:26:41.904705Z","end":"2026-06-05T11:26:42.328186Z","steps":["trace[1223532838] 'agreement among raft nodes before linearized reading' (duration: 423.279658ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:42.328221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:41.637226Z","time spent":"690.957763ms","remote":"127.0.0.1:53398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T11:26:42.328248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:41.904633Z","time spent":"423.601946ms","remote":"127.0.0.1:53490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":134,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-06-05T11:26:42.367365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.992063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T11:26:42.367437Z","caller":"traceutil/trace.go:171","msg":"trace[1457162718] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1282; }","duration":"122.110836ms","start":"2026-06-05T11:26:42.245308Z","end":"2026-06-05T11:26:42.367418Z","steps":["trace[1457162718] 'agreement among raft nodes before linearized reading' (duration: 121.976583ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:42.803563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.283697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T11:26:42.803648Z","caller":"traceutil/trace.go:171","msg":"trace[524514926] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1282; }","duration":"290.4187ms","start":"2026-06-05T11:26:42.51321Z","end":"2026-06-05T11:26:42.803629Z","steps":["trace[524514926] 'range keys from in-memory index tree' (duration: 290.199075ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:26:43.244248Z","caller":"traceutil/trace.go:171","msg":"trace[299728485] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"254.258837ms","start":"2026-06-05T11:26:42.989932Z","end":"2026-06-05T11:26:43.244191Z","steps":["trace[299728485] 'process raft request' (duration: 160.918182ms)","trace[299728485] 'compare' (duration: 93.188551ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T11:26:44.687665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.582581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T11:26:44.687733Z","caller":"traceutil/trace.go:171","msg":"trace[1469705106] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1287; }","duration":"687.713214ms","start":"2026-06-05T11:26:44.000002Z","end":"2026-06-05T11:26:44.687716Z","steps":["trace[1469705106] 'count revisions from in-memory index tree' (duration: 687.394237ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:44.687768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:43.999978Z","time spent":"687.778366ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-06-05T11:26:44.688174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.404414ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T11:26:44.688338Z","caller":"traceutil/trace.go:171","msg":"trace[231051946] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1287; }","duration":"362.590279ms","start":"2026-06-05T11:26:44.325723Z","end":"2026-06-05T11:26:44.688313Z","steps":["trace[231051946] 'range keys from in-memory index tree' (duration: 362.378784ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:44.915242Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899719177682186,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T11:26:45.416167Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899719177682186,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T11:26:45.541283Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.168624617s","expected-duration":"1s"} {"level":"info","ts":"2026-06-05T11:26:45.541688Z","caller":"traceutil/trace.go:171","msg":"trace[900716270] linearizableReadLoop","detail":"{readStateIndex:1339; appliedIndex:1338; }","duration":"1.127023543s","start":"2026-06-05T11:26:44.414634Z","end":"2026-06-05T11:26:45.541658Z","steps":["trace[900716270] 'read index received' (duration: 1.126807108s)","trace[900716270] 'applied index is now lower than readState.Index' (duration: 215.475µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T11:26:45.541749Z","caller":"traceutil/trace.go:171","msg":"trace[1631406820] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"1.169188241s","start":"2026-06-05T11:26:44.372528Z","end":"2026-06-05T11:26:45.541717Z","steps":["trace[1631406820] 'process raft request' (duration: 1.168998826s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:45.54197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:44.3725Z","time spent":"1.169368285s","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T11:26:45.542078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127402183s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-05T11:26:45.542158Z","caller":"traceutil/trace.go:171","msg":"trace[890070214] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:1288; }","duration":"1.127528497s","start":"2026-06-05T11:26:44.4146Z","end":"2026-06-05T11:26:45.542128Z","steps":["trace[890070214] 'agreement among raft nodes before linearized reading' (duration: 1.127172377s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:45.542214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:44.414585Z","time spent":"1.127619508s","remote":"127.0.0.1:53068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.53\" "} {"level":"warn","ts":"2026-06-05T11:26:45.542561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.233403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-05T11:26:45.542646Z","caller":"traceutil/trace.go:171","msg":"trace[560147334] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1288; }","duration":"495.373836ms","start":"2026-06-05T11:26:45.047259Z","end":"2026-06-05T11:26:45.542633Z","steps":["trace[560147334] 'agreement among raft nodes before linearized reading' (duration: 495.15652ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:45.54268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.011188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-05T11:26:45.542691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:45.04723Z","time spent":"495.447928ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-05T11:26:45.542713Z","caller":"traceutil/trace.go:171","msg":"trace[69969539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1288; }","duration":"678.092419ms","start":"2026-06-05T11:26:44.864614Z","end":"2026-06-05T11:26:45.542706Z","steps":["trace[69969539] 'agreement among raft nodes before linearized reading' (duration: 678.012517ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:45.542737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:44.864589Z","time spent":"678.143651ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-05T11:26:45.542734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.026717209s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T11:26:45.542782Z","caller":"traceutil/trace.go:171","msg":"trace[503235897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1288; }","duration":"1.02678786s","start":"2026-06-05T11:26:44.51598Z","end":"2026-06-05T11:26:45.542768Z","steps":["trace[503235897] 'agreement among raft nodes before linearized reading' (duration: 1.026704628s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:45.542575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028556803s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-05T11:26:45.542826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:44.515968Z","time spent":"1.026851682s","remote":"127.0.0.1:32866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-05T11:26:45.542877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.589307ms","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-06-05T11:26:45.542894Z","caller":"traceutil/trace.go:171","msg":"trace[1669866084] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1288; }","duration":"293.607228ms","start":"2026-06-05T11:26:45.249283Z","end":"2026-06-05T11:26:45.542891Z","steps":["trace[1669866084] 'agreement among raft nodes before linearized reading' (duration: 293.564077ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:26:45.542836Z","caller":"traceutil/trace.go:171","msg":"trace[246444751] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1288; }","duration":"1.02887573s","start":"2026-06-05T11:26:44.513956Z","end":"2026-06-05T11:26:45.542831Z","steps":["trace[246444751] 'agreement among raft nodes before linearized reading' (duration: 1.027834384s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:45.542949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:44.513939Z","time spent":"1.029006034s","remote":"127.0.0.1:53398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-06-05T11:26:45.542615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.590522ms","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":"info","ts":"2026-06-05T11:26:45.543013Z","caller":"traceutil/trace.go:171","msg":"trace[1450816768] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1288; }","duration":"624.035152ms","start":"2026-06-05T11:26:44.918964Z","end":"2026-06-05T11:26:45.543Z","steps":["trace[1450816768] 'agreement among raft nodes before linearized reading' (duration: 623.629613ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:45.543066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:44.918898Z","time spent":"624.149336ms","remote":"127.0.0.1:59598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"info","ts":"2026-06-05T11:26:45.918598Z","caller":"traceutil/trace.go:171","msg":"trace[957512752] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"371.281421ms","start":"2026-06-05T11:26:45.547265Z","end":"2026-06-05T11:26:45.918547Z","steps":["trace[957512752] 'process raft request' (duration: 288.328308ms)","trace[957512752] 'compare' (duration: 82.684487ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T11:26:45.918867Z","caller":"traceutil/trace.go:171","msg":"trace[352330828] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"371.449675ms","start":"2026-06-05T11:26:45.547382Z","end":"2026-06-05T11:26:45.918832Z","steps":["trace[352330828] 'process raft request' (duration: 371.112667ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:26:45.918891Z","caller":"traceutil/trace.go:171","msg":"trace[900468554] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"369.908827ms","start":"2026-06-05T11:26:45.548957Z","end":"2026-06-05T11:26:45.918866Z","steps":["trace[900468554] 'process raft request' (duration: 369.786764ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:45.918888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:45.54723Z","time spent":"371.508187ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T11:26:45.919039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:45.548946Z","time spent":"370.00639ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T11:26:45.919042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:45.547374Z","time spent":"371.565187ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T11:26:45.952546Z","caller":"traceutil/trace.go:171","msg":"trace[954338992] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1340; }","duration":"367.192801ms","start":"2026-06-05T11:26:45.585336Z","end":"2026-06-05T11:26:45.952528Z","steps":["trace[954338992] 'read index received' (duration: 250.24989ms)","trace[954338992] 'applied index is now lower than readState.Index' (duration: 116.941891ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T11:26:45.952666Z","caller":"traceutil/trace.go:171","msg":"trace[227431429] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"374.473538ms","start":"2026-06-05T11:26:45.578181Z","end":"2026-06-05T11:26:45.952655Z","steps":["trace[227431429] 'process raft request' (duration: 374.215962ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:45.952747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:45.578151Z","time spent":"374.53913ms","remote":"127.0.0.1:53068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T11:26:45.952974Z","caller":"traceutil/trace.go:171","msg":"trace[1634227400] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"310.617782ms","start":"2026-06-05T11:26:45.642344Z","end":"2026-06-05T11:26:45.952962Z","steps":["trace[1634227400] 'process raft request' (duration: 310.13548ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:45.953046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:45.642329Z","time spent":"310.686864ms","remote":"127.0.0.1:53110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T11:26:45.953263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.929589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T11:26:45.953307Z","caller":"traceutil/trace.go:171","msg":"trace[988081937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1293; }","duration":"368.002911ms","start":"2026-06-05T11:26:45.585294Z","end":"2026-06-05T11:26:45.953297Z","steps":["trace[988081937] 'agreement among raft nodes before linearized reading' (duration: 367.912709ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:45.953336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:45.585262Z","time spent":"368.067171ms","remote":"127.0.0.1:32866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-05T11:26:45.953571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.832301ms","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-06-05T11:26:45.953609Z","caller":"traceutil/trace.go:171","msg":"trace[1117217211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1293; }","duration":"263.929133ms","start":"2026-06-05T11:26:45.689671Z","end":"2026-06-05T11:26:45.9536Z","steps":["trace[1117217211] 'agreement among raft nodes before linearized reading' (duration: 263.80745ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:45.953934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.391312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-05T11:26:45.953975Z","caller":"traceutil/trace.go:171","msg":"trace[1416569268] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1293; }","duration":"229.432783ms","start":"2026-06-05T11:26:45.724533Z","end":"2026-06-05T11:26:45.953966Z","steps":["trace[1416569268] 'agreement among raft nodes before linearized reading' (duration: 229.33477ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:26:46.10388Z","caller":"traceutil/trace.go:171","msg":"trace[1539402302] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1345; }","duration":"144.993125ms","start":"2026-06-05T11:26:45.958835Z","end":"2026-06-05T11:26:46.103828Z","steps":["trace[1539402302] 'read index received' (duration: 135.37333ms)","trace[1539402302] 'applied index is now lower than readState.Index' (duration: 9.618645ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T11:26:46.103997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.145408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-06-05T11:26:46.10403Z","caller":"traceutil/trace.go:171","msg":"trace[1002843237] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1294; }","duration":"145.22804ms","start":"2026-06-05T11:26:45.958792Z","end":"2026-06-05T11:26:46.10402Z","steps":["trace[1002843237] 'agreement among raft nodes before linearized reading' (duration: 145.148828ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:26:46.104113Z","caller":"traceutil/trace.go:171","msg":"trace[1039178816] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"145.884536ms","start":"2026-06-05T11:26:45.958167Z","end":"2026-06-05T11:26:46.104052Z","steps":["trace[1039178816] 'process raft request' (duration: 136.028696ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:26:46.174836Z","caller":"traceutil/trace.go:171","msg":"trace[1623106574] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"215.857662ms","start":"2026-06-05T11:26:45.958949Z","end":"2026-06-05T11:26:46.174807Z","steps":["trace[1623106574] 'process raft request' (duration: 215.607476ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:47.264845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.873416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T11:26:47.264947Z","caller":"traceutil/trace.go:171","msg":"trace[1061180997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1302; }","duration":"340.04353ms","start":"2026-06-05T11:26:46.924881Z","end":"2026-06-05T11:26:47.264925Z","steps":["trace[1061180997] 'range keys from in-memory index tree' (duration: 339.762483ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:47.264992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:46.924855Z","time spent":"340.127681ms","remote":"127.0.0.1:32866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-05T11:26:47.864312Z","caller":"traceutil/trace.go:171","msg":"trace[1486765538] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"316.229949ms","start":"2026-06-05T11:26:47.548043Z","end":"2026-06-05T11:26:47.864273Z","steps":["trace[1486765538] 'process raft request' (duration: 316.035593ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:47.864547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:47.548009Z","time spent":"316.415183ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T11:26:48.093225Z","caller":"traceutil/trace.go:171","msg":"trace[977999444] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"153.760968ms","start":"2026-06-05T11:26:47.93943Z","end":"2026-06-05T11:26:48.093191Z","steps":["trace[977999444] 'process raft request' (duration: 153.482911ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:26:48.253692Z","caller":"traceutil/trace.go:171","msg":"trace[1846370608] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"312.632582ms","start":"2026-06-05T11:26:47.941047Z","end":"2026-06-05T11:26:48.25368Z","steps":["trace[1846370608] 'process raft request' (duration: 312.513318ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:48.25386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:47.941034Z","time spent":"312.735104ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T11:26:48.253677Z","caller":"traceutil/trace.go:171","msg":"trace[2002929230] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"313.093712ms","start":"2026-06-05T11:26:47.940563Z","end":"2026-06-05T11:26:48.253657Z","steps":["trace[2002929230] 'process raft request' (duration: 216.348024ms)","trace[2002929230] 'compare' (duration: 96.531662ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T11:26:48.253972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:47.940537Z","time spent":"313.38813ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T11:26:48.253637Z","caller":"traceutil/trace.go:171","msg":"trace[906949085] linearizableReadLoop","detail":"{readStateIndex:1358; appliedIndex:1356; }","duration":"145.402703ms","start":"2026-06-05T11:26:48.108204Z","end":"2026-06-05T11:26:48.253607Z","steps":["trace[906949085] 'read index received' (duration: 48.525692ms)","trace[906949085] 'applied index is now lower than readState.Index' (duration: 96.875991ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T11:26:48.25432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.079131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-05T11:26:48.25461Z","caller":"traceutil/trace.go:171","msg":"trace[1084564282] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1306; }","duration":"146.252845ms","start":"2026-06-05T11:26:48.108151Z","end":"2026-06-05T11:26:48.254404Z","steps":["trace[1084564282] 'agreement among raft nodes before linearized reading' (duration: 145.881056ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:26:50.407868Z","caller":"traceutil/trace.go:171","msg":"trace[1969851943] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"144.705477ms","start":"2026-06-05T11:26:50.263107Z","end":"2026-06-05T11:26:50.407813Z","steps":["trace[1969851943] 'process raft request' (duration: 144.395319ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:26:50.408623Z","caller":"traceutil/trace.go:171","msg":"trace[1497712597] linearizableReadLoop","detail":"{readStateIndex:1372; appliedIndex:1370; }","duration":"123.500701ms","start":"2026-06-05T11:26:50.285094Z","end":"2026-06-05T11:26:50.408595Z","steps":["trace[1497712597] 'read index received' (duration: 122.449244ms)","trace[1497712597] 'applied index is now lower than readState.Index' (duration: 1.050597ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T11:26:50.408673Z","caller":"traceutil/trace.go:171","msg":"trace[881761509] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"145.233141ms","start":"2026-06-05T11:26:50.263407Z","end":"2026-06-05T11:26:50.408641Z","steps":["trace[881761509] 'process raft request' (duration: 145.053626ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:50.409077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.933651ms","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-06-05T11:26:50.409187Z","caller":"traceutil/trace.go:171","msg":"trace[1165198640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1319; }","duration":"124.091924ms","start":"2026-06-05T11:26:50.285069Z","end":"2026-06-05T11:26:50.409161Z","steps":["trace[1165198640] 'agreement among raft nodes before linearized reading' (duration: 123.651264ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:26:54.229465Z","caller":"traceutil/trace.go:171","msg":"trace[825405915] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1408; }","duration":"109.89861ms","start":"2026-06-05T11:26:54.119548Z","end":"2026-06-05T11:26:54.229446Z","steps":["trace[825405915] 'read index received' (duration: 109.845869ms)","trace[825405915] 'applied index is now lower than readState.Index' (duration: 51.811µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T11:26:54.229615Z","caller":"traceutil/trace.go:171","msg":"trace[1820729071] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"346.22476ms","start":"2026-06-05T11:26:53.88335Z","end":"2026-06-05T11:26:54.229574Z","steps":["trace[1820729071] 'process raft request' (duration: 345.954984ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:54.229713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.165156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-05T11:26:54.229744Z","caller":"traceutil/trace.go:171","msg":"trace[191079268] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1356; }","duration":"110.259949ms","start":"2026-06-05T11:26:54.119476Z","end":"2026-06-05T11:26:54.229736Z","steps":["trace[191079268] 'agreement among raft nodes before linearized reading' (duration: 110.098915ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:54.229822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:53.883321Z","time spent":"346.399175ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T11:26:54.911748Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899719177682311,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T11:26:55.362397Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.127915235s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-05T11:26:55.824407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.498878778s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T11:26:55.824489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.751546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T11:26:55.824579Z","caller":"traceutil/trace.go:171","msg":"trace[1031316228] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1356; }","duration":"1.499069472s","start":"2026-06-05T11:26:54.325488Z","end":"2026-06-05T11:26:55.824558Z","steps":["trace[1031316228] 'range keys from in-memory index tree' (duration: 1.498855947s)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:26:55.824599Z","caller":"traceutil/trace.go:171","msg":"trace[966593754] linearizableReadLoop","detail":"{readStateIndex:1410; appliedIndex:1409; }","duration":"1.412944472s","start":"2026-06-05T11:26:54.411641Z","end":"2026-06-05T11:26:55.824586Z","steps":["trace[966593754] 'read index received' (duration: 950.989761ms)","trace[966593754] 'applied index is now lower than readState.Index' (duration: 461.953411ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T11:26:55.824662Z","caller":"traceutil/trace.go:171","msg":"trace[215578192] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"1.590266166s","start":"2026-06-05T11:26:54.234349Z","end":"2026-06-05T11:26:55.824615Z","steps":["trace[215578192] 'process raft request' (duration: 1.128307974s)","trace[215578192] 'compare' (duration: 461.585473ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T11:26:55.82476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.413097706s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-05T11:26:55.824842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:54.23432Z","time spent":"1.59042403s","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T11:26:55.824848Z","caller":"traceutil/trace.go:171","msg":"trace[1590855903] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1357; }","duration":"1.41324107s","start":"2026-06-05T11:26:54.411585Z","end":"2026-06-05T11:26:55.824827Z","steps":["trace[1590855903] 'agreement among raft nodes before linearized reading' (duration: 1.413062215s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:55.824873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.395013266s","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-06-05T11:26:55.824823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.30824557s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T11:26:55.824897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:54.411561Z","time spent":"1.413325691s","remote":"127.0.0.1:53358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":70,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-06-05T11:26:55.824908Z","caller":"traceutil/trace.go:171","msg":"trace[1252053147] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1357; }","duration":"1.395080687s","start":"2026-06-05T11:26:54.429816Z","end":"2026-06-05T11:26:55.824897Z","steps":["trace[1252053147] 'agreement among raft nodes before linearized reading' (duration: 1.395002456s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:55.824948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:54.429794Z","time spent":"1.395145578s","remote":"127.0.0.1:53298","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":"info","ts":"2026-06-05T11:26:55.824954Z","caller":"traceutil/trace.go:171","msg":"trace[977834993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1357; }","duration":"1.308415055s","start":"2026-06-05T11:26:54.516504Z","end":"2026-06-05T11:26:55.824919Z","steps":["trace[977834993] 'agreement among raft nodes before linearized reading' (duration: 1.30820665s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:55.825021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:54.516493Z","time spent":"1.308513827s","remote":"127.0.0.1:32866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-05T11:26:55.824797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.40911791s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-06-05T11:26:55.825351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.400176941s","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-06-05T11:26:55.825379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.156045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T11:26:55.825397Z","caller":"traceutil/trace.go:171","msg":"trace[624598269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1357; }","duration":"1.400247762s","start":"2026-06-05T11:26:54.425138Z","end":"2026-06-05T11:26:55.825386Z","steps":["trace[624598269] 'agreement among raft nodes before linearized reading' (duration: 1.400129779s)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:26:55.825376Z","caller":"traceutil/trace.go:171","msg":"trace[1844802842] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:1357; }","duration":"1.409705694s","start":"2026-06-05T11:26:54.415637Z","end":"2026-06-05T11:26:55.825342Z","steps":["trace[1844802842] 'agreement among raft nodes before linearized reading' (duration: 1.409044818s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:55.825432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:54.425125Z","time spent":"1.400299254s","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-05T11:26:55.825436Z","caller":"traceutil/trace.go:171","msg":"trace[183563189] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1357; }","duration":"702.249488ms","start":"2026-06-05T11:26:55.123168Z","end":"2026-06-05T11:26:55.825417Z","steps":["trace[183563189] 'agreement among raft nodes before linearized reading' (duration: 702.127275ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:55.825463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.306473537s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-06-05T11:26:55.825487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:55.123148Z","time spent":"702.32386ms","remote":"127.0.0.1:53116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-06-05T11:26:55.825496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:54.415625Z","time spent":"1.409833807s","remote":"127.0.0.1:53068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.53\" "} {"level":"info","ts":"2026-06-05T11:26:55.825522Z","caller":"traceutil/trace.go:171","msg":"trace[644330863] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1357; }","duration":"1.306551689s","start":"2026-06-05T11:26:54.518955Z","end":"2026-06-05T11:26:55.825507Z","steps":["trace[644330863] 'agreement among raft nodes before linearized reading' (duration: 1.306361224s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:55.825567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:54.518943Z","time spent":"1.30661392s","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-06-05T11:26:55.825574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.17533622s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-06-05T11:26:55.82561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.402938168s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-05T11:26:55.825624Z","caller":"traceutil/trace.go:171","msg":"trace[1925853324] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1357; }","duration":"1.175449603s","start":"2026-06-05T11:26:54.65016Z","end":"2026-06-05T11:26:55.82561Z","steps":["trace[1925853324] 'agreement among raft nodes before linearized reading' (duration: 1.175337761s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:55.825659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:54.650129Z","time spent":"1.175523454s","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-06-05T11:26:55.825662Z","caller":"traceutil/trace.go:171","msg":"trace[421914349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1357; }","duration":"1.403003119s","start":"2026-06-05T11:26:54.422645Z","end":"2026-06-05T11:26:55.825648Z","steps":["trace[421914349] 'agreement among raft nodes before linearized reading' (duration: 1.402903917s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:55.825511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.403666046s","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":"warn","ts":"2026-06-05T11:26:55.825705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:54.422634Z","time spent":"1.403063052s","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-05T11:26:55.825713Z","caller":"traceutil/trace.go:171","msg":"trace[284211125] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1357; }","duration":"1.403916513s","start":"2026-06-05T11:26:54.421785Z","end":"2026-06-05T11:26:55.825702Z","steps":["trace[284211125] 'agreement among raft nodes before linearized reading' (duration: 1.403505063s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:55.825751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:26:54.421775Z","time spent":"1.403965104s","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-05T11:26:55.944577Z","caller":"traceutil/trace.go:171","msg":"trace[935718876] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"113.217641ms","start":"2026-06-05T11:26:55.831333Z","end":"2026-06-05T11:26:55.94455Z","steps":["trace[935718876] 'process raft request' (duration: 112.390531ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:26:55.944868Z","caller":"traceutil/trace.go:171","msg":"trace[301584641] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"113.396624ms","start":"2026-06-05T11:26:55.831453Z","end":"2026-06-05T11:26:55.94485Z","steps":["trace[301584641] 'process raft request' (duration: 113.032475ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:26:55.944968Z","caller":"traceutil/trace.go:171","msg":"trace[955021899] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"113.430315ms","start":"2026-06-05T11:26:55.831504Z","end":"2026-06-05T11:26:55.944934Z","steps":["trace[955021899] 'process raft request' (duration: 113.2151ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:26:55.945431Z","caller":"traceutil/trace.go:171","msg":"trace[202809296] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"113.815724ms","start":"2026-06-05T11:26:55.831559Z","end":"2026-06-05T11:26:55.945375Z","steps":["trace[202809296] 'process raft request' (duration: 113.293762ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:26:55.945534Z","caller":"traceutil/trace.go:171","msg":"trace[639649757] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"113.910506ms","start":"2026-06-05T11:26:55.831609Z","end":"2026-06-05T11:26:55.94552Z","steps":["trace[639649757] 'process raft request' (duration: 113.718981ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:26:55.945552Z","caller":"traceutil/trace.go:171","msg":"trace[980690342] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"113.755992ms","start":"2026-06-05T11:26:55.83171Z","end":"2026-06-05T11:26:55.945466Z","steps":["trace[980690342] 'process raft request' (duration: 113.712911ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:26:55.9898Z","caller":"traceutil/trace.go:171","msg":"trace[682419547] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1411; }","duration":"148.778646ms","start":"2026-06-05T11:26:55.840996Z","end":"2026-06-05T11:26:55.989774Z","steps":["trace[682419547] 'read index received' (duration: 102.747365ms)","trace[682419547] 'applied index is now lower than readState.Index' (duration: 46.029721ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T11:26:55.989838Z","caller":"traceutil/trace.go:171","msg":"trace[340108516] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"152.870127ms","start":"2026-06-05T11:26:55.836936Z","end":"2026-06-05T11:26:55.989807Z","steps":["trace[340108516] 'process raft request' (duration: 152.692653ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:26:55.989984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.960121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T11:26:55.990027Z","caller":"traceutil/trace.go:171","msg":"trace[1088774921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1364; }","duration":"149.069723ms","start":"2026-06-05T11:26:55.840947Z","end":"2026-06-05T11:26:55.990016Z","steps":["trace[1088774921] 'agreement among raft nodes before linearized reading' (duration: 148.92252ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:27:26.343439Z","caller":"traceutil/trace.go:171","msg":"trace[262607543] transaction","detail":"{read_only:false; response_revision:1501; number_of_response:1; }","duration":"170.914603ms","start":"2026-06-05T11:27:26.172489Z","end":"2026-06-05T11:27:26.343404Z","steps":["trace[262607543] 'process raft request' (duration: 88.04667ms)","trace[262607543] 'compare' (duration: 82.537435ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T11:27:26.34346Z","caller":"traceutil/trace.go:171","msg":"trace[589132661] transaction","detail":"{read_only:false; response_revision:1502; number_of_response:1; }","duration":"161.536524ms","start":"2026-06-05T11:27:26.181882Z","end":"2026-06-05T11:27:26.343419Z","steps":["trace[589132661] 'process raft request' (duration: 161.446842ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:27:26.343536Z","caller":"traceutil/trace.go:171","msg":"trace[1535942931] linearizableReadLoop","detail":"{readStateIndex:1561; appliedIndex:1560; }","duration":"164.505397ms","start":"2026-06-05T11:27:26.179004Z","end":"2026-06-05T11:27:26.34351Z","steps":["trace[1535942931] 'read index received' (duration: 81.517621ms)","trace[1535942931] 'applied index is now lower than readState.Index' (duration: 82.984976ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T11:27:26.343952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.359564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-06-05T11:27:26.343965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.251142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-05T11:27:26.344001Z","caller":"traceutil/trace.go:171","msg":"trace[224576789] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1502; }","duration":"171.446047ms","start":"2026-06-05T11:27:26.172543Z","end":"2026-06-05T11:27:26.343989Z","steps":["trace[224576789] 'agreement among raft nodes before linearized reading' (duration: 171.155139ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:27:26.344012Z","caller":"traceutil/trace.go:171","msg":"trace[897623549] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1502; }","duration":"171.329035ms","start":"2026-06-05T11:27:26.172667Z","end":"2026-06-05T11:27:26.343996Z","steps":["trace[897623549] 'agreement among raft nodes before linearized reading' (duration: 170.997686ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:29:20.836603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.579098ms","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-06-05T11:29:20.836685Z","caller":"traceutil/trace.go:171","msg":"trace[1668376197] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2023; }","duration":"111.709291ms","start":"2026-06-05T11:29:20.72496Z","end":"2026-06-05T11:29:20.836669Z","steps":["trace[1668376197] 'range keys from in-memory index tree' (duration: 111.409593ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:29:20.836603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.819341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-05T11:29:20.836856Z","caller":"traceutil/trace.go:171","msg":"trace[1144152364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2023; }","duration":"109.101758ms","start":"2026-06-05T11:29:20.727716Z","end":"2026-06-05T11:29:20.836818Z","steps":["trace[1144152364] 'range keys from in-memory index tree' (duration: 108.631426ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:29:26.997724Z","caller":"traceutil/trace.go:171","msg":"trace[958450244] linearizableReadLoop","detail":"{readStateIndex:2137; appliedIndex:2136; }","duration":"111.171648ms","start":"2026-06-05T11:29:26.88653Z","end":"2026-06-05T11:29:26.997702Z","steps":["trace[958450244] 'read index received' (duration: 110.995684ms)","trace[958450244] 'applied index is now lower than readState.Index' (duration: 175.344µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T11:29:26.997831Z","caller":"traceutil/trace.go:171","msg":"trace[1589150106] transaction","detail":"{read_only:false; response_revision:2053; number_of_response:1; }","duration":"113.694971ms","start":"2026-06-05T11:29:26.884127Z","end":"2026-06-05T11:29:26.997822Z","steps":["trace[1589150106] 'process raft request' (duration: 113.431785ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:29:26.998046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.511287ms","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-06-05T11:29:26.998072Z","caller":"traceutil/trace.go:171","msg":"trace[1352477781] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2053; }","duration":"111.592299ms","start":"2026-06-05T11:29:26.886473Z","end":"2026-06-05T11:29:26.998066Z","steps":["trace[1352477781] 'agreement among raft nodes before linearized reading' (duration: 111.479656ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:29:26.99815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.606179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-05T11:29:26.998169Z","caller":"traceutil/trace.go:171","msg":"trace[1762918882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2053; }","duration":"111.66645ms","start":"2026-06-05T11:29:26.886498Z","end":"2026-06-05T11:29:26.998165Z","steps":["trace[1762918882] 'agreement among raft nodes before linearized reading' (duration: 111.613079ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:29:27.116131Z","caller":"traceutil/trace.go:171","msg":"trace[1272387225] transaction","detail":"{read_only:false; response_revision:2054; number_of_response:1; }","duration":"113.693969ms","start":"2026-06-05T11:29:27.002399Z","end":"2026-06-05T11:29:27.116093Z","steps":["trace[1272387225] 'process raft request' (duration: 35.49618ms)","trace[1272387225] 'compare' (duration: 77.913662ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T11:29:27.116446Z","caller":"traceutil/trace.go:171","msg":"trace[701710835] transaction","detail":"{read_only:false; response_revision:2055; number_of_response:1; }","duration":"113.977006ms","start":"2026-06-05T11:29:27.002436Z","end":"2026-06-05T11:29:27.116413Z","steps":["trace[701710835] 'process raft request' (duration: 113.586826ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:29:27.116565Z","caller":"traceutil/trace.go:171","msg":"trace[175341824] transaction","detail":"{read_only:false; response_revision:2056; number_of_response:1; }","duration":"113.630618ms","start":"2026-06-05T11:29:27.002912Z","end":"2026-06-05T11:29:27.116542Z","steps":["trace[175341824] 'process raft request' (duration: 113.388732ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:29:27.307714Z","caller":"traceutil/trace.go:171","msg":"trace[530753385] linearizableReadLoop","detail":"{readStateIndex:2142; appliedIndex:2140; }","duration":"179.217618ms","start":"2026-06-05T11:29:27.128461Z","end":"2026-06-05T11:29:27.307679Z","steps":["trace[530753385] 'read index received' (duration: 50.843968ms)","trace[530753385] 'applied index is now lower than readState.Index' (duration: 128.37183ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T11:29:27.307783Z","caller":"traceutil/trace.go:171","msg":"trace[1370813693] transaction","detail":"{read_only:false; response_revision:2058; number_of_response:1; }","duration":"186.043716ms","start":"2026-06-05T11:29:27.121704Z","end":"2026-06-05T11:29:27.307748Z","steps":["trace[1370813693] 'process raft request' (duration: 144.420304ms)","trace[1370813693] 'compare' (duration: 41.274093ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T11:29:27.308042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.547726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T11:29:27.308184Z","caller":"traceutil/trace.go:171","msg":"trace[362498568] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:2058; }","duration":"179.742081ms","start":"2026-06-05T11:29:27.128417Z","end":"2026-06-05T11:29:27.308159Z","steps":["trace[362498568] 'agreement among raft nodes before linearized reading' (duration: 179.526546ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:29:29.535123Z","caller":"traceutil/trace.go:171","msg":"trace[172812697] transaction","detail":"{read_only:false; response_revision:2064; number_of_response:1; }","duration":"313.940054ms","start":"2026-06-05T11:29:29.221139Z","end":"2026-06-05T11:29:29.535079Z","steps":["trace[172812697] 'process raft request' (duration: 268.17766ms)","trace[172812697] 'compare' (duration: 45.549879ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T11:29:29.535167Z","caller":"traceutil/trace.go:171","msg":"trace[739701141] transaction","detail":"{read_only:false; response_revision:2065; number_of_response:1; }","duration":"313.924464ms","start":"2026-06-05T11:29:29.221216Z","end":"2026-06-05T11:29:29.53514Z","steps":["trace[739701141] 'process raft request' (duration: 313.794761ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:29:29.535328Z","caller":"traceutil/trace.go:171","msg":"trace[736896693] linearizableReadLoop","detail":"{readStateIndex:2150; appliedIndex:2147; }","duration":"223.664889ms","start":"2026-06-05T11:29:29.31164Z","end":"2026-06-05T11:29:29.535305Z","steps":["trace[736896693] 'read index received' (duration: 177.65805ms)","trace[736896693] 'applied index is now lower than readState.Index' (duration: 46.005059ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T11:29:29.535424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:29:29.221209Z","time spent":"314.048917ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T11:29:29.535594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:29:29.22112Z","time spent":"314.086808ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T11:29:29.5356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.893685ms","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-06-05T11:29:29.535721Z","caller":"traceutil/trace.go:171","msg":"trace[1422705460] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"314.423886ms","start":"2026-06-05T11:29:29.221264Z","end":"2026-06-05T11:29:29.535688Z","steps":["trace[1422705460] 'process raft request' (duration: 313.826531ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:29:29.535757Z","caller":"traceutil/trace.go:171","msg":"trace[590365292] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2066; }","duration":"224.155231ms","start":"2026-06-05T11:29:29.311588Z","end":"2026-06-05T11:29:29.535743Z","steps":["trace[590365292] 'agreement among raft nodes before linearized reading' (duration: 223.798662ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:29:29.53591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:29:29.221256Z","time spent":"314.539459ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T11:29:31.807177Z","caller":"traceutil/trace.go:171","msg":"trace[235859079] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"260.750819ms","start":"2026-06-05T11:29:31.5464Z","end":"2026-06-05T11:29:31.80715Z","steps":["trace[235859079] 'process raft request' (duration: 260.572495ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:29:31.810476Z","caller":"traceutil/trace.go:171","msg":"trace[1039525718] transaction","detail":"{read_only:false; response_revision:2074; number_of_response:1; }","duration":"263.790903ms","start":"2026-06-05T11:29:31.546658Z","end":"2026-06-05T11:29:31.810449Z","steps":["trace[1039525718] 'process raft request' (duration: 263.575498ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:29:31.810704Z","caller":"traceutil/trace.go:171","msg":"trace[2109067670] transaction","detail":"{read_only:false; response_revision:2075; number_of_response:1; }","duration":"263.979858ms","start":"2026-06-05T11:29:31.546706Z","end":"2026-06-05T11:29:31.810685Z","steps":["trace[2109067670] 'process raft request' (duration: 263.68347ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:29:31.810712Z","caller":"traceutil/trace.go:171","msg":"trace[755224451] linearizableReadLoop","detail":"{readStateIndex:2160; appliedIndex:2157; }","duration":"221.441864ms","start":"2026-06-05T11:29:31.589221Z","end":"2026-06-05T11:29:31.810663Z","steps":["trace[755224451] 'read index received' (duration: 217.762834ms)","trace[755224451] 'applied index is now lower than readState.Index' (duration: 3.67632ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T11:29:31.81093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.68977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-05T11:29:31.810982Z","caller":"traceutil/trace.go:171","msg":"trace[1965624833] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2075; }","duration":"221.789102ms","start":"2026-06-05T11:29:31.58918Z","end":"2026-06-05T11:29:31.810969Z","steps":["trace[1965624833] 'agreement among raft nodes before linearized reading' (duration: 221.616798ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:29:31.811017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.945251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-06-05T11:29:31.811104Z","caller":"traceutil/trace.go:171","msg":"trace[1428187066] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2075; }","duration":"115.105564ms","start":"2026-06-05T11:29:31.695979Z","end":"2026-06-05T11:29:31.811084Z","steps":["trace[1428187066] 'agreement among raft nodes before linearized reading' (duration: 114.898449ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:29:33.680805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.346327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T11:29:33.680904Z","caller":"traceutil/trace.go:171","msg":"trace[464257999] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:2082; }","duration":"224.538281ms","start":"2026-06-05T11:29:33.456345Z","end":"2026-06-05T11:29:33.680883Z","steps":["trace[464257999] 'count revisions from in-memory index tree' (duration: 224.209403ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:30:07.555888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.26055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-06-05T11:30:07.555979Z","caller":"traceutil/trace.go:171","msg":"trace[1490418081] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2237; }","duration":"111.402284ms","start":"2026-06-05T11:30:07.444557Z","end":"2026-06-05T11:30:07.555959Z","steps":["trace[1490418081] 'range keys from in-memory index tree' (duration: 110.855001ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:30:46.9956Z","caller":"traceutil/trace.go:171","msg":"trace[1505823093] linearizableReadLoop","detail":"{readStateIndex:2540; appliedIndex:2539; }","duration":"123.768153ms","start":"2026-06-05T11:30:46.87181Z","end":"2026-06-05T11:30:46.995578Z","steps":["trace[1505823093] 'read index received' (duration: 123.544346ms)","trace[1505823093] 'applied index is now lower than readState.Index' (duration: 222.987µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T11:30:46.995664Z","caller":"traceutil/trace.go:171","msg":"trace[1537907566] transaction","detail":"{read_only:false; response_revision:2439; number_of_response:1; }","duration":"412.390777ms","start":"2026-06-05T11:30:46.58324Z","end":"2026-06-05T11:30:46.995631Z","steps":["trace[1537907566] 'process raft request' (duration: 412.167681ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:30:46.995765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.929086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T11:30:46.995802Z","caller":"traceutil/trace.go:171","msg":"trace[811211198] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2439; }","duration":"124.016629ms","start":"2026-06-05T11:30:46.871775Z","end":"2026-06-05T11:30:46.995792Z","steps":["trace[811211198] 'agreement among raft nodes before linearized reading' (duration: 123.927887ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T11:30:46.995815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T11:30:46.583228Z","time spent":"412.506469ms","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T11:30:52.456748Z","caller":"traceutil/trace.go:171","msg":"trace[496903584] transaction","detail":"{read_only:false; response_revision:2470; number_of_response:1; }","duration":"125.332011ms","start":"2026-06-05T11:30:52.331391Z","end":"2026-06-05T11:30:52.456723Z","steps":["trace[496903584] 'process raft request' (duration: 125.107555ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:30:52.456993Z","caller":"traceutil/trace.go:171","msg":"trace[1686723043] transaction","detail":"{read_only:false; response_revision:2472; number_of_response:1; }","duration":"124.787167ms","start":"2026-06-05T11:30:52.332153Z","end":"2026-06-05T11:30:52.45694Z","steps":["trace[1686723043] 'process raft request' (duration: 124.513821ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T11:30:52.456748Z","caller":"traceutil/trace.go:171","msg":"trace[88228457] transaction","detail":"{read_only:false; response_revision:2471; number_of_response:1; }","duration":"124.614343ms","start":"2026-06-05T11:30:52.33211Z","end":"2026-06-05T11:30:52.456724Z","steps":["trace[88228457] 'process raft request' (duration: 124.51058ms)"],"step_count":1}