{"level":"warn","ts":"2026-05-06T19:09:47.293069Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-06T19:09:47.293165Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.78: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.78:2380","--initial-cluster=instance=https://199.204.45.78:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.78:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.78: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-05-06T19:09:47.293251Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-06T19:09:47.293264Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-05-06T19:09:47.293283Z","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-05-06T19:09:47.294155Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"]} {"level":"info","ts":"2026-05-06T19:09:47.294253Z","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.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.78: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-05-06T19:09:47.298073Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.567494ms"} {"level":"info","ts":"2026-05-06T19:09:47.30869Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6eb8766b1ec86e92","cluster-id":"9bbfe9e5d45d3718"} {"level":"info","ts":"2026-05-06T19:09:47.308783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=()"} {"level":"info","ts":"2026-05-06T19:09:47.308822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 0"} {"level":"info","ts":"2026-05-06T19:09:47.308844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6eb8766b1ec86e92 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-06T19:09:47.308856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 1"} {"level":"info","ts":"2026-05-06T19:09:47.308913Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"warn","ts":"2026-05-06T19:09:47.31619Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-06T19:09:47.317388Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-06T19:09:47.318312Z","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-05-06T19:09:47.319984Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6eb8766b1ec86e92","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-06T19:09:47.322532Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6eb8766b1ec86e92","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-06T19:09:47.322677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"info","ts":"2026-05-06T19:09:47.322803Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","added-peer-id":"6eb8766b1ec86e92","added-peer-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-05-06T19:09:47.321701Z","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-05-06T19:09:47.322954Z","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-05-06T19:09:47.322977Z","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-05-06T19:09:47.324542Z","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-05-06T19:09:47.324669Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-05-06T19:09:47.3247Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-05-06T19:09:47.325461Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6eb8766b1ec86e92","initial-advertise-peer-urls":["https://199.204.45.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-06T19:09:47.325509Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-06T19:09:48.309593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 is starting a new election at term 1"} {"level":"info","ts":"2026-05-06T19:09:48.309654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-06T19:09:48.309698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgPreVoteResp from 6eb8766b1ec86e92 at term 1"} {"level":"info","ts":"2026-05-06T19:09:48.309719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became candidate at term 2"} {"level":"info","ts":"2026-05-06T19:09:48.309731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgVoteResp from 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-05-06T19:09:48.309748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became leader at term 2"} {"level":"info","ts":"2026-05-06T19:09:48.309763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6eb8766b1ec86e92 elected leader 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-05-06T19:09:48.315138Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6eb8766b1ec86e92","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.78:2379]}","request-path":"/0/members/6eb8766b1ec86e92/attributes","cluster-id":"9bbfe9e5d45d3718","publish-timeout":"7s"} {"level":"info","ts":"2026-05-06T19:09:48.315171Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T19:09:48.315168Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T19:09:48.315171Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T19:09:48.315466Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-06T19:09:48.31555Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-06T19:09:48.316006Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T19:09:48.316091Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T19:09:48.316114Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T19:09:48.317375Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-06T19:09:48.317519Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.78:2379"} {"level":"info","ts":"2026-05-06T19:09:53.510029Z","caller":"traceutil/trace.go:171","msg":"trace[1371745371] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"133.776314ms","start":"2026-05-06T19:09:53.376226Z","end":"2026-05-06T19:09:53.510002Z","steps":["trace[1371745371] 'process raft request' (duration: 98.080544ms)","trace[1371745371] 'compare' (duration: 35.573713ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:09:53.512885Z","caller":"traceutil/trace.go:171","msg":"trace[1827155866] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"109.426137ms","start":"2026-05-06T19:09:53.403429Z","end":"2026-05-06T19:09:53.512855Z","steps":["trace[1827155866] 'process raft request' (duration: 109.278221ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:09:53.512923Z","caller":"traceutil/trace.go:171","msg":"trace[1417987798] transaction","detail":"{read_only:false; number_of_response:0; response_revision:237; }","duration":"109.199162ms","start":"2026-05-06T19:09:53.403693Z","end":"2026-05-06T19:09:53.512893Z","steps":["trace[1417987798] 'process raft request' (duration: 109.086725ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:09:53.851024Z","caller":"traceutil/trace.go:171","msg":"trace[1323430981] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"102.195541ms","start":"2026-05-06T19:09:53.748777Z","end":"2026-05-06T19:09:53.850972Z","steps":["trace[1323430981] 'process raft request' (duration: 19.286352ms)","trace[1323430981] 'compare' (duration: 82.674864ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:09:54.434502Z","caller":"traceutil/trace.go:171","msg":"trace[377598674] linearizableReadLoop","detail":"{readStateIndex:275; appliedIndex:274; }","duration":"140.339967ms","start":"2026-05-06T19:09:54.294107Z","end":"2026-05-06T19:09:54.434447Z","steps":["trace[377598674] 'read index received' (duration: 140.19805ms)","trace[377598674] 'applied index is now lower than readState.Index' (duration: 140.497µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:09:54.43457Z","caller":"traceutil/trace.go:171","msg":"trace[793121746] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"178.089939ms","start":"2026-05-06T19:09:54.256456Z","end":"2026-05-06T19:09:54.434546Z","steps":["trace[793121746] 'process raft request' (duration: 177.826325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:09:54.434701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.574671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:09:54.434799Z","caller":"traceutil/trace.go:171","msg":"trace[292487393] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:267; }","duration":"140.724727ms","start":"2026-05-06T19:09:54.29406Z","end":"2026-05-06T19:09:54.434785Z","steps":["trace[292487393] 'agreement among raft nodes before linearized reading' (duration: 140.522602ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:09:54.694173Z","caller":"traceutil/trace.go:171","msg":"trace[892474918] transaction","detail":"{read_only:false; number_of_response:0; response_revision:267; }","duration":"353.665493ms","start":"2026-05-06T19:09:54.340479Z","end":"2026-05-06T19:09:54.694144Z","steps":["trace[892474918] 'process raft request' (duration: 316.375911ms)","trace[892474918] 'compare' (duration: 37.155625ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:09:54.694249Z","caller":"traceutil/trace.go:171","msg":"trace[2061675974] transaction","detail":"{read_only:false; number_of_response:0; response_revision:267; }","duration":"353.293071ms","start":"2026-05-06T19:09:54.340946Z","end":"2026-05-06T19:09:54.69424Z","steps":["trace[2061675974] 'process raft request' (duration: 353.167974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:09:54.69428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.784673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3974"} {"level":"info","ts":"2026-05-06T19:09:54.694326Z","caller":"traceutil/trace.go:171","msg":"trace[487107973] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:267; }","duration":"356.89979ms","start":"2026-05-06T19:09:54.337417Z","end":"2026-05-06T19:09:54.694317Z","steps":["trace[487107973] 'agreement among raft nodes before linearized reading' (duration: 356.801622ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:09:54.694348Z","caller":"traceutil/trace.go:171","msg":"trace[1906725065] transaction","detail":"{read_only:false; number_of_response:0; response_revision:267; }","duration":"353.321131ms","start":"2026-05-06T19:09:54.341018Z","end":"2026-05-06T19:09:54.694339Z","steps":["trace[1906725065] 'process raft request' (duration: 353.112725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:09:54.694365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:09:54.337398Z","time spent":"356.958349ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":3997,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-05-06T19:09:54.694193Z","caller":"traceutil/trace.go:171","msg":"trace[1112879315] transaction","detail":"{read_only:false; number_of_response:0; response_revision:267; }","duration":"353.264941ms","start":"2026-05-06T19:09:54.340907Z","end":"2026-05-06T19:09:54.694172Z","steps":["trace[1112879315] 'process raft request' (duration: 353.189563ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:09:54.694174Z","caller":"traceutil/trace.go:171","msg":"trace[776655164] linearizableReadLoop","detail":"{readStateIndex:280; appliedIndex:275; }","duration":"259.566897ms","start":"2026-05-06T19:09:54.434585Z","end":"2026-05-06T19:09:54.694152Z","steps":["trace[776655164] 'read index received' (duration: 222.147097ms)","trace[776655164] 'applied index is now lower than readState.Index' (duration: 37.41898ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:09:54.694403Z","caller":"traceutil/trace.go:171","msg":"trace[1279552129] transaction","detail":"{read_only:false; number_of_response:0; response_revision:267; }","duration":"353.466408ms","start":"2026-05-06T19:09:54.340877Z","end":"2026-05-06T19:09:54.694344Z","steps":["trace[1279552129] 'process raft request' (duration: 353.193494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:09:54.694572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:09:54.340451Z","time spent":"353.79608ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T19:09:54.694569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.391088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T19:09:54.694584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:09:54.3409Z","time spent":"353.410548ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T19:09:54.694625Z","caller":"traceutil/trace.go:171","msg":"trace[906220846] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:0; response_revision:267; }","duration":"256.476787ms","start":"2026-05-06T19:09:54.438132Z","end":"2026-05-06T19:09:54.694609Z","steps":["trace[906220846] 'agreement among raft nodes before linearized reading' (duration: 256.387389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:09:54.694725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:09:54.341009Z","time spent":"353.36699ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T19:09:54.695146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:09:54.340851Z","time spent":"353.682242ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T19:09:54.695235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:09:54.34088Z","time spent":"353.606194ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T19:09:54.820017Z","caller":"traceutil/trace.go:171","msg":"trace[1431800450] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"121.217306ms","start":"2026-05-06T19:09:54.698771Z","end":"2026-05-06T19:09:54.819989Z","steps":["trace[1431800450] 'process raft request' (duration: 121.06693ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:09:55.003656Z","caller":"traceutil/trace.go:171","msg":"trace[428151358] linearizableReadLoop","detail":"{readStateIndex:282; appliedIndex:281; }","duration":"180.839597ms","start":"2026-05-06T19:09:54.822797Z","end":"2026-05-06T19:09:55.003637Z","steps":["trace[428151358] 'read index received' (duration: 180.70774ms)","trace[428151358] 'applied index is now lower than readState.Index' (duration: 130.087µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T19:09:55.003758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.940925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-05-06T19:09:55.003739Z","caller":"traceutil/trace.go:171","msg":"trace[1121197259] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"288.850099ms","start":"2026-05-06T19:09:54.714852Z","end":"2026-05-06T19:09:55.003702Z","steps":["trace[1121197259] 'process raft request' (duration: 288.674673ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:09:55.003784Z","caller":"traceutil/trace.go:171","msg":"trace[769376422] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:269; }","duration":"181.032713ms","start":"2026-05-06T19:09:54.822744Z","end":"2026-05-06T19:09:55.003776Z","steps":["trace[769376422] 'agreement among raft nodes before linearized reading' (duration: 180.966084ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:09:55.16988Z","caller":"traceutil/trace.go:171","msg":"trace[1693453259] linearizableReadLoop","detail":"{readStateIndex:283; appliedIndex:282; }","duration":"152.936356ms","start":"2026-05-06T19:09:55.016917Z","end":"2026-05-06T19:09:55.169854Z","steps":["trace[1693453259] 'read index received' (duration: 145.680358ms)","trace[1693453259] 'applied index is now lower than readState.Index' (duration: 7.254608ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T19:09:55.170042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.095913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:09:55.170084Z","caller":"traceutil/trace.go:171","msg":"trace[686240606] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cronjob-controller; range_end:; response_count:0; response_revision:270; }","duration":"153.178412ms","start":"2026-05-06T19:09:55.016892Z","end":"2026-05-06T19:09:55.17007Z","steps":["trace[686240606] 'agreement among raft nodes before linearized reading' (duration: 153.067284ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:09:55.170484Z","caller":"traceutil/trace.go:171","msg":"trace[1575648456] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"156.374351ms","start":"2026-05-06T19:09:55.014098Z","end":"2026-05-06T19:09:55.170473Z","steps":["trace[1575648456] 'process raft request' (duration: 148.617373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:09:55.659294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.095845ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T19:09:55.659457Z","caller":"traceutil/trace.go:171","msg":"trace[1023317894] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"280.574466ms","start":"2026-05-06T19:09:55.378867Z","end":"2026-05-06T19:09:55.659441Z","steps":["trace[1023317894] 'process raft request' (duration: 110.132399ms)","trace[1023317894] 'compare' (duration: 169.995357ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:09:55.695259Z","caller":"traceutil/trace.go:171","msg":"trace[496339175] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"308.980663ms","start":"2026-05-06T19:09:55.386268Z","end":"2026-05-06T19:09:55.695248Z","steps":["trace[496339175] 'process raft request' (duration: 308.757288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:09:55.695837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:09:55.386241Z","time spent":"309.082232ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4307,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T19:09:55.854831Z","caller":"traceutil/trace.go:171","msg":"trace[592993895] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"156.152665ms","start":"2026-05-06T19:09:55.698652Z","end":"2026-05-06T19:09:55.854805Z","steps":["trace[592993895] 'process raft request' (duration: 81.610424ms)","trace[592993895] 'compare' (duration: 74.363095ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:09:55.85495Z","caller":"traceutil/trace.go:171","msg":"trace[1890399861] linearizableReadLoop","detail":"{readStateIndex:292; appliedIndex:291; }","duration":"156.053078ms","start":"2026-05-06T19:09:55.698854Z","end":"2026-05-06T19:09:55.854907Z","steps":["trace[1890399861] 'read index received' (duration: 81.416409ms)","trace[1890399861] 'applied index is now lower than readState.Index' (duration: 74.630659ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T19:09:55.855107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.228784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7294"} {"level":"info","ts":"2026-05-06T19:09:55.855181Z","caller":"traceutil/trace.go:171","msg":"trace[136698283] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:279; }","duration":"156.322842ms","start":"2026-05-06T19:09:55.698842Z","end":"2026-05-06T19:09:55.855165Z","steps":["trace[136698283] 'agreement among raft nodes before linearized reading' (duration: 156.192855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:09:55.855878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.485601ms","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-05-06T19:09:55.855955Z","caller":"traceutil/trace.go:171","msg":"trace[522696578] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:280; }","duration":"137.588189ms","start":"2026-05-06T19:09:55.718349Z","end":"2026-05-06T19:09:55.855937Z","steps":["trace[522696578] 'agreement among raft nodes before linearized reading' (duration: 137.454032ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:09:55.856199Z","caller":"traceutil/trace.go:171","msg":"trace[631577245] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"156.716493ms","start":"2026-05-06T19:09:55.699455Z","end":"2026-05-06T19:09:55.856171Z","steps":["trace[631577245] 'process raft request' (duration: 156.234293ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:09:56.051175Z","caller":"traceutil/trace.go:171","msg":"trace[361909843] linearizableReadLoop","detail":"{readStateIndex:295; appliedIndex:294; }","duration":"142.850841ms","start":"2026-05-06T19:09:55.90829Z","end":"2026-05-06T19:09:56.051141Z","steps":["trace[361909843] 'read index received' (duration: 115.359717ms)","trace[361909843] 'applied index is now lower than readState.Index' (duration: 27.490234ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:09:56.051199Z","caller":"traceutil/trace.go:171","msg":"trace[857788188] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"187.795651ms","start":"2026-05-06T19:09:55.863381Z","end":"2026-05-06T19:09:56.051177Z","steps":["trace[857788188] 'process raft request' (duration: 160.345406ms)","trace[857788188] 'compare' (duration: 27.314168ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T19:09:56.051321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.73164ms","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-05-06T19:09:56.051353Z","caller":"traceutil/trace.go:171","msg":"trace[540004309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:282; }","duration":"157.786589ms","start":"2026-05-06T19:09:55.893556Z","end":"2026-05-06T19:09:56.051343Z","steps":["trace[540004309] 'agreement among raft nodes before linearized reading' (duration: 157.706281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:09:56.051477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.071795ms","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-05-06T19:09:56.051501Z","caller":"traceutil/trace.go:171","msg":"trace[332904403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:282; }","duration":"175.108873ms","start":"2026-05-06T19:09:55.876386Z","end":"2026-05-06T19:09:56.051495Z","steps":["trace[332904403] 'agreement among raft nodes before linearized reading' (duration: 175.069084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:09:56.051491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.786888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-06T19:09:56.051537Z","caller":"traceutil/trace.go:171","msg":"trace[483093460] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:282; }","duration":"140.832797ms","start":"2026-05-06T19:09:55.91069Z","end":"2026-05-06T19:09:56.051523Z","steps":["trace[483093460] 'agreement among raft nodes before linearized reading' (duration: 140.67841ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:09:56.199167Z","caller":"traceutil/trace.go:171","msg":"trace[121352373] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"144.192012ms","start":"2026-05-06T19:09:56.054941Z","end":"2026-05-06T19:09:56.199133Z","steps":["trace[121352373] 'process raft request' (duration: 109.624044ms)","trace[121352373] 'compare' (duration: 34.350913ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:09:56.199595Z","caller":"traceutil/trace.go:171","msg":"trace[468360375] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"143.038567ms","start":"2026-05-06T19:09:56.056532Z","end":"2026-05-06T19:09:56.199571Z","steps":["trace[468360375] 'process raft request' (duration: 142.552568ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:09:56.199634Z","caller":"traceutil/trace.go:171","msg":"trace[1031015561] linearizableReadLoop","detail":"{readStateIndex:299; appliedIndex:296; }","duration":"121.636689ms","start":"2026-05-06T19:09:56.07796Z","end":"2026-05-06T19:09:56.199596Z","steps":["trace[1031015561] 'read index received' (duration: 86.538423ms)","trace[1031015561] 'applied index is now lower than readState.Index' (duration: 35.091366ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:09:56.199722Z","caller":"traceutil/trace.go:171","msg":"trace[1572101704] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"137.226195ms","start":"2026-05-06T19:09:56.062409Z","end":"2026-05-06T19:09:56.199635Z","steps":["trace[1572101704] 'process raft request' (duration: 136.958001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:09:56.199816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.837104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:09:56.199883Z","caller":"traceutil/trace.go:171","msg":"trace[801665512] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:0; response_revision:286; }","duration":"121.949702ms","start":"2026-05-06T19:09:56.077916Z","end":"2026-05-06T19:09:56.199866Z","steps":["trace[801665512] 'agreement among raft nodes before linearized reading' (duration: 121.826424ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:09:57.553983Z","caller":"traceutil/trace.go:171","msg":"trace[2055090482] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"204.174621ms","start":"2026-05-06T19:09:57.349775Z","end":"2026-05-06T19:09:57.55395Z","steps":["trace[2055090482] 'process raft request' (duration: 181.950816ms)","trace[2055090482] 'compare' (duration: 21.99672ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:09:57.644705Z","caller":"traceutil/trace.go:171","msg":"trace[2122810419] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"288.350352ms","start":"2026-05-06T19:09:57.35633Z","end":"2026-05-06T19:09:57.644681Z","steps":["trace[2122810419] 'process raft request' (duration: 288.240415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:09:57.90344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.632083ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T19:09:57.9037Z","caller":"traceutil/trace.go:171","msg":"trace[211991963] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"247.885236ms","start":"2026-05-06T19:09:57.655782Z","end":"2026-05-06T19:09:57.903668Z","steps":["trace[211991963] 'process raft request' (duration: 70.95692ms)","trace[211991963] 'compare' (duration: 176.461906ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T19:09:58.408726Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967604408009469905,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T19:09:58.909369Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967604408009469905,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T19:09:59.351318Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.443898736s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T19:09:59.358858Z","caller":"traceutil/trace.go:171","msg":"trace[346503021] linearizableReadLoop","detail":"{readStateIndex:314; appliedIndex:313; }","duration":"1.451001754s","start":"2026-05-06T19:09:57.907834Z","end":"2026-05-06T19:09:59.358836Z","steps":["trace[346503021] 'read index received' (duration: 1.443586073s)","trace[346503021] 'applied index is now lower than readState.Index' (duration: 7.414771ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:09:59.35908Z","caller":"traceutil/trace.go:171","msg":"trace[1527080122] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"1.451772317s","start":"2026-05-06T19:09:57.907268Z","end":"2026-05-06T19:09:59.359041Z","steps":["trace[1527080122] 'process raft request' (duration: 1.44419034s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:09:59.359207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.451348936s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T19:09:59.359267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.155366742s","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-05-06T19:09:59.359307Z","caller":"traceutil/trace.go:171","msg":"trace[1628173468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:301; }","duration":"1.15545337s","start":"2026-05-06T19:09:58.203842Z","end":"2026-05-06T19:09:59.359296Z","steps":["trace[1628173468] 'agreement among raft nodes before linearized reading' (duration: 1.155375691s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:09:59.359341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:09:58.203819Z","time spent":"1.155513179s","remote":"127.0.0.1:55672","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-05-06T19:09:59.359336Z","caller":"traceutil/trace.go:171","msg":"trace[325797964] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:0; response_revision:301; }","duration":"1.451500583s","start":"2026-05-06T19:09:57.907789Z","end":"2026-05-06T19:09:59.359289Z","steps":["trace[325797964] 'agreement among raft nodes before linearized reading' (duration: 1.451357446s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:09:59.359398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:09:57.907767Z","time spent":"1.45161326s","remote":"127.0.0.1:55618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-05-06T19:09:59.359467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.155556948s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-06T19:09:59.35961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.448319602s","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-05-06T19:09:59.359645Z","caller":"traceutil/trace.go:171","msg":"trace[377957688] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:301; }","duration":"1.448371302s","start":"2026-05-06T19:09:57.911262Z","end":"2026-05-06T19:09:59.359633Z","steps":["trace[377957688] 'agreement among raft nodes before linearized reading' (duration: 1.447924581s)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:09:59.359658Z","caller":"traceutil/trace.go:171","msg":"trace[179192442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:301; }","duration":"1.155806602s","start":"2026-05-06T19:09:58.203797Z","end":"2026-05-06T19:09:59.359604Z","steps":["trace[179192442] 'agreement among raft nodes before linearized reading' (duration: 1.15542599s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:09:59.359798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:09:58.203768Z","time spent":"1.156010418s","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T19:09:59.359681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:09:57.911252Z","time spent":"1.448422741s","remote":"127.0.0.1:55672","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-05-06T19:09:59.359241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:09:57.907244Z","time spent":"1.451905024s","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T19:10:00.131265Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967604408009469946,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T19:10:00.371667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.823635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T19:10:00.372006Z","caller":"traceutil/trace.go:171","msg":"trace[1457838105] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"811.394891ms","start":"2026-05-06T19:09:59.5606Z","end":"2026-05-06T19:10:00.371995Z","steps":["trace[1457838105] 'process raft request' (duration: 811.356322ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:00.371986Z","caller":"traceutil/trace.go:171","msg":"trace[381414407] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"815.59827ms","start":"2026-05-06T19:09:59.55632Z","end":"2026-05-06T19:10:00.371918Z","steps":["trace[381414407] 'process raft request' (duration: 40.449732ms)","trace[381414407] 'compare' (duration: 774.316416ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T19:10:00.37209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:09:59.560596Z","time spent":"811.44169ms","remote":"127.0.0.1:55768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T19:10:00.372111Z","caller":"traceutil/trace.go:171","msg":"trace[1757716008] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"811.92576ms","start":"2026-05-06T19:09:59.560168Z","end":"2026-05-06T19:10:00.372094Z","steps":["trace[1757716008] 'process raft request' (duration: 811.734254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:00.372131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:09:59.556306Z","time spent":"815.744068ms","remote":"127.0.0.1:55670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2267,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T19:10:00.372204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:09:59.560154Z","time spent":"812.015377ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T19:10:00.372228Z","caller":"traceutil/trace.go:171","msg":"trace[243665284] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"812.263632ms","start":"2026-05-06T19:09:59.559904Z","end":"2026-05-06T19:10:00.372167Z","steps":["trace[243665284] 'process raft request' (duration: 811.87291ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:00.37223Z","caller":"traceutil/trace.go:171","msg":"trace[1562265886] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"811.941179ms","start":"2026-05-06T19:09:59.560245Z","end":"2026-05-06T19:10:00.372186Z","steps":["trace[1562265886] 'process raft request' (duration: 811.687984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:00.372437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:09:59.55989Z","time spent":"812.443509ms","remote":"127.0.0.1:55768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T19:10:00.372515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:09:59.560229Z","time spent":"812.225503ms","remote":"127.0.0.1:55768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T19:10:00.451282Z","caller":"traceutil/trace.go:171","msg":"trace[1930322873] linearizableReadLoop","detail":"{readStateIndex:339; appliedIndex:333; }","duration":"820.558579ms","start":"2026-05-06T19:09:59.630703Z","end":"2026-05-06T19:10:00.451262Z","steps":["trace[1930322873] 'read index received' (duration: 730.895916ms)","trace[1930322873] 'applied index is now lower than readState.Index' (duration: 89.661753ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:10:00.451354Z","caller":"traceutil/trace.go:171","msg":"trace[235262589] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"629.571977ms","start":"2026-05-06T19:09:59.821758Z","end":"2026-05-06T19:10:00.45133Z","steps":["trace[235262589] 'process raft request' (duration: 629.379481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:00.45204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.937111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-05-06T19:10:00.452234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:09:59.821724Z","time spent":"630.423658ms","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T19:10:00.452188Z","caller":"traceutil/trace.go:171","msg":"trace[1968974422] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:326; }","duration":"821.497299ms","start":"2026-05-06T19:09:59.630663Z","end":"2026-05-06T19:10:00.452161Z","steps":["trace[1968974422] 'agreement among raft nodes before linearized reading' (duration: 820.726205ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:00.452317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:09:59.630646Z","time spent":"821.651745ms","remote":"127.0.0.1:55618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-05-06T19:10:00.788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.648447ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T19:10:00.788227Z","caller":"traceutil/trace.go:171","msg":"trace[1083438948] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"410.805192ms","start":"2026-05-06T19:10:00.377397Z","end":"2026-05-06T19:10:00.788202Z","steps":["trace[1083438948] 'process raft request' (duration: 410.680685ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:00.788234Z","caller":"traceutil/trace.go:171","msg":"trace[417528037] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"409.672276ms","start":"2026-05-06T19:10:00.378534Z","end":"2026-05-06T19:10:00.788206Z","steps":["trace[417528037] 'process raft request' (duration: 409.598778ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:00.78824Z","caller":"traceutil/trace.go:171","msg":"trace[530527202] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"411.752122ms","start":"2026-05-06T19:10:00.376448Z","end":"2026-05-06T19:10:00.7882Z","steps":["trace[530527202] 'process raft request' (duration: 151.832221ms)","trace[530527202] 'compare' (duration: 259.52703ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T19:10:00.788341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:00.378527Z","time spent":"409.760185ms","remote":"127.0.0.1:55512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T19:10:00.788325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:00.377387Z","time spent":"410.897131ms","remote":"127.0.0.1:55512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T19:10:00.788455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:00.376421Z","time spent":"411.906299ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T19:10:00.788499Z","caller":"traceutil/trace.go:171","msg":"trace[613264576] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"408.520321ms","start":"2026-05-06T19:10:00.379967Z","end":"2026-05-06T19:10:00.788487Z","steps":["trace[613264576] 'process raft request' (duration: 408.255517ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:00.788583Z","caller":"traceutil/trace.go:171","msg":"trace[2082482761] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"410.002759ms","start":"2026-05-06T19:10:00.378546Z","end":"2026-05-06T19:10:00.788549Z","steps":["trace[2082482761] 'process raft request' (duration: 409.608857ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:00.788607Z","caller":"traceutil/trace.go:171","msg":"trace[1153156399] linearizableReadLoop","detail":"{readStateIndex:345; appliedIndex:339; }","duration":"337.255807ms","start":"2026-05-06T19:10:00.451342Z","end":"2026-05-06T19:10:00.788598Z","steps":["trace[1153156399] 'read index received' (duration: 76.877295ms)","trace[1153156399] 'applied index is now lower than readState.Index' (duration: 260.377812ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T19:10:00.788622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:00.379959Z","time spent":"408.574229ms","remote":"127.0.0.1:55768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T19:10:00.788669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:00.378534Z","time spent":"410.094187ms","remote":"127.0.0.1:55768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2264,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T19:10:00.788723Z","caller":"traceutil/trace.go:171","msg":"trace[1045951935] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"402.357522ms","start":"2026-05-06T19:10:00.386359Z","end":"2026-05-06T19:10:00.788716Z","steps":["trace[1045951935] 'process raft request' (duration: 402.071619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:00.788775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:00.386352Z","time spent":"402.392211ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T19:10:00.788792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.040279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4196"} {"level":"info","ts":"2026-05-06T19:10:00.78883Z","caller":"traceutil/trace.go:171","msg":"trace[1858118642] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:332; }","duration":"733.112157ms","start":"2026-05-06T19:10:00.055707Z","end":"2026-05-06T19:10:00.788819Z","steps":["trace[1858118642] 'agreement among raft nodes before linearized reading' (duration: 733.033199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:00.788851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.140566776s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-06T19:10:00.788875Z","caller":"traceutil/trace.go:171","msg":"trace[629796598] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:332; }","duration":"1.140616814s","start":"2026-05-06T19:09:59.648252Z","end":"2026-05-06T19:10:00.788869Z","steps":["trace[629796598] 'agreement among raft nodes before linearized reading' (duration: 1.140573075s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:00.788872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:00.055684Z","time spent":"733.181816ms","remote":"127.0.0.1:55600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4219,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-06T19:10:00.788885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.063999269s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-05-06T19:10:00.788899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:09:59.648237Z","time spent":"1.140658044s","remote":"127.0.0.1:55618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"info","ts":"2026-05-06T19:10:00.788918Z","caller":"traceutil/trace.go:171","msg":"trace[1064931999] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:332; }","duration":"1.064064267s","start":"2026-05-06T19:09:59.724842Z","end":"2026-05-06T19:10:00.788907Z","steps":["trace[1064931999] 'agreement among raft nodes before linearized reading' (duration: 1.064014078s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:00.788956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:09:59.724825Z","time spent":"1.064124266s","remote":"127.0.0.1:55618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-05-06T19:10:00.789003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.568876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-05-06T19:10:00.789028Z","caller":"traceutil/trace.go:171","msg":"trace[1551657542] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:332; }","duration":"332.620425ms","start":"2026-05-06T19:10:00.456402Z","end":"2026-05-06T19:10:00.789022Z","steps":["trace[1551657542] 'agreement among raft nodes before linearized reading' (duration: 332.580876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:00.789047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:00.456388Z","time spent":"332.652745ms","remote":"127.0.0.1:55618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-05-06T19:10:00.789081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.085998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-05-06T19:10:00.789124Z","caller":"traceutil/trace.go:171","msg":"trace[2134364739] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:332; }","duration":"733.170456ms","start":"2026-05-06T19:10:00.055944Z","end":"2026-05-06T19:10:00.789114Z","steps":["trace[2134364739] 'agreement among raft nodes before linearized reading' (duration: 733.102307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:00.789161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:00.055921Z","time spent":"733.233415ms","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-05-06T19:10:00.789194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.235304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4660"} {"level":"info","ts":"2026-05-06T19:10:00.789214Z","caller":"traceutil/trace.go:171","msg":"trace[389473117] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:332; }","duration":"733.259284ms","start":"2026-05-06T19:10:00.055947Z","end":"2026-05-06T19:10:00.789206Z","steps":["trace[389473117] 'agreement among raft nodes before linearized reading' (duration: 733.232315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:00.78923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:00.05594Z","time spent":"733.286834ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4683,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-05-06T19:10:00.789138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.222005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6751"} {"level":"info","ts":"2026-05-06T19:10:00.789308Z","caller":"traceutil/trace.go:171","msg":"trace[2001618857] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:332; }","duration":"733.396191ms","start":"2026-05-06T19:10:00.055902Z","end":"2026-05-06T19:10:00.789298Z","steps":["trace[2001618857] 'agreement among raft nodes before linearized reading' (duration: 733.215625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:00.789168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.181656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"warn","ts":"2026-05-06T19:10:00.791354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:00.055895Z","time spent":"735.423348ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6774,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-05-06T19:10:00.791367Z","caller":"traceutil/trace.go:171","msg":"trace[988016047] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:332; }","duration":"735.38263ms","start":"2026-05-06T19:10:00.055966Z","end":"2026-05-06T19:10:00.791349Z","steps":["trace[988016047] 'agreement among raft nodes before linearized reading' (duration: 733.182996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:00.791408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:00.055958Z","time spent":"735.438088ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4345,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-05-06T19:10:00.78929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.343162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5326"} {"level":"warn","ts":"2026-05-06T19:10:00.788798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.0769753s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-06T19:10:00.791812Z","caller":"traceutil/trace.go:171","msg":"trace[1552616145] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:332; }","duration":"735.889658ms","start":"2026-05-06T19:10:00.055897Z","end":"2026-05-06T19:10:00.791786Z","steps":["trace[1552616145] 'agreement among raft nodes before linearized reading' (duration: 733.348502ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:00.791896Z","caller":"traceutil/trace.go:171","msg":"trace[839869141] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:332; }","duration":"1.080093154s","start":"2026-05-06T19:09:59.711784Z","end":"2026-05-06T19:10:00.791877Z","steps":["trace[839869141] 'agreement among raft nodes before linearized reading' (duration: 1.076976241s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:00.791917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:00.055871Z","time spent":"736.019476ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5349,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-05-06T19:10:00.791959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:09:59.711768Z","time spent":"1.080175982s","remote":"127.0.0.1:55618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-05-06T19:10:00.788839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.056807243s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-06T19:10:00.792418Z","caller":"traceutil/trace.go:171","msg":"trace[1623941756] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:332; }","duration":"1.060391107s","start":"2026-05-06T19:09:59.732008Z","end":"2026-05-06T19:10:00.792399Z","steps":["trace[1623941756] 'agreement among raft nodes before linearized reading' (duration: 1.056809483s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:00.792452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:09:59.731998Z","time spent":"1.060443475s","remote":"127.0.0.1:55618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-05-06T19:10:00.792723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.899727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7294"} {"level":"info","ts":"2026-05-06T19:10:00.793777Z","caller":"traceutil/trace.go:171","msg":"trace[181115918] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:332; }","duration":"736.957376ms","start":"2026-05-06T19:10:00.055793Z","end":"2026-05-06T19:10:00.79275Z","steps":["trace[181115918] 'agreement among raft nodes before linearized reading' (duration: 736.682852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:00.793871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:00.055786Z","time spent":"738.067902ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7317,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-05-06T19:10:01.124396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.231755ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T19:10:01.124537Z","caller":"traceutil/trace.go:171","msg":"trace[701582592] linearizableReadLoop","detail":"{readStateIndex:346; appliedIndex:345; }","duration":"328.019835ms","start":"2026-05-06T19:10:00.796496Z","end":"2026-05-06T19:10:01.124515Z","steps":["trace[701582592] 'read index received' (duration: 217.553635ms)","trace[701582592] 'applied index is now lower than readState.Index' (duration: 110.46465ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:10:01.124559Z","caller":"traceutil/trace.go:171","msg":"trace[111629245] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"329.959714ms","start":"2026-05-06T19:10:00.794574Z","end":"2026-05-06T19:10:01.124533Z","steps":["trace[111629245] 'process raft request' (duration: 219.473684ms)","trace[111629245] 'compare' (duration: 109.980101ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T19:10:01.124728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:00.794553Z","time spent":"330.067441ms","remote":"127.0.0.1:55512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T19:10:01.124747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.250131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4196"} {"level":"info","ts":"2026-05-06T19:10:01.12479Z","caller":"traceutil/trace.go:171","msg":"trace[1320259214] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:333; }","duration":"328.333659ms","start":"2026-05-06T19:10:00.796443Z","end":"2026-05-06T19:10:01.124777Z","steps":["trace[1320259214] 'agreement among raft nodes before linearized reading' (duration: 328.161622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:01.124833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:00.796419Z","time spent":"328.404297ms","remote":"127.0.0.1:55600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4219,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-06T19:10:01.149943Z","caller":"traceutil/trace.go:171","msg":"trace[523020809] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"353.41667ms","start":"2026-05-06T19:10:00.796515Z","end":"2026-05-06T19:10:01.149931Z","steps":["trace[523020809] 'process raft request' (duration: 353.270273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:01.150021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:00.796499Z","time spent":"353.479349ms","remote":"127.0.0.1:55906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T19:10:01.150272Z","caller":"traceutil/trace.go:171","msg":"trace[823191736] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"352.93804ms","start":"2026-05-06T19:10:00.797309Z","end":"2026-05-06T19:10:01.150247Z","steps":["trace[823191736] 'process raft request' (duration: 352.586417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:01.150355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:00.797301Z","time spent":"353.019508ms","remote":"127.0.0.1:55512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T19:10:01.150343Z","caller":"traceutil/trace.go:171","msg":"trace[950904656] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"353.001829ms","start":"2026-05-06T19:10:00.797329Z","end":"2026-05-06T19:10:01.150331Z","steps":["trace[950904656] 'process raft request' (duration: 352.855592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:01.150464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:00.797315Z","time spent":"353.126646ms","remote":"127.0.0.1:55596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T19:10:01.150609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.862224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4196"} {"level":"info","ts":"2026-05-06T19:10:01.150645Z","caller":"traceutil/trace.go:171","msg":"trace[1000981576] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:338; }","duration":"350.915923ms","start":"2026-05-06T19:10:00.799717Z","end":"2026-05-06T19:10:01.150633Z","steps":["trace[1000981576] 'agreement among raft nodes before linearized reading' (duration: 350.842524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:01.150673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:00.799707Z","time spent":"350.960652ms","remote":"127.0.0.1:55600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4219,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-06T19:10:01.150676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.05807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-06T19:10:01.1507Z","caller":"traceutil/trace.go:171","msg":"trace[177514587] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:338; }","duration":"351.090369ms","start":"2026-05-06T19:10:00.799602Z","end":"2026-05-06T19:10:01.150693Z","steps":["trace[177514587] 'agreement among raft nodes before linearized reading' (duration: 351.041591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:01.150727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:00.799599Z","time spent":"351.115979ms","remote":"127.0.0.1:55618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"info","ts":"2026-05-06T19:10:01.150719Z","caller":"traceutil/trace.go:171","msg":"trace[1990792932] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"353.271973ms","start":"2026-05-06T19:10:00.797428Z","end":"2026-05-06T19:10:01.1507Z","steps":["trace[1990792932] 'process raft request' (duration: 352.839702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:01.15083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.127419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4196"} {"level":"warn","ts":"2026-05-06T19:10:01.150825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:00.797397Z","time spent":"353.370951ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T19:10:01.150849Z","caller":"traceutil/trace.go:171","msg":"trace[930301676] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:338; }","duration":"351.146438ms","start":"2026-05-06T19:10:00.799696Z","end":"2026-05-06T19:10:01.150843Z","steps":["trace[930301676] 'agreement among raft nodes before linearized reading' (duration: 351.112479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:01.150868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:00.799668Z","time spent":"351.195697ms","remote":"127.0.0.1:55600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4219,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-06T19:10:01.150792Z","caller":"traceutil/trace.go:171","msg":"trace[1858918520] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"348.102543ms","start":"2026-05-06T19:10:00.80268Z","end":"2026-05-06T19:10:01.150782Z","steps":["trace[1858918520] 'process raft request' (duration: 347.642192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:01.150932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:00.802667Z","time spent":"348.23121ms","remote":"127.0.0.1:55768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T19:10:01.150956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.309605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4196"} {"level":"info","ts":"2026-05-06T19:10:01.150976Z","caller":"traceutil/trace.go:171","msg":"trace[1029108567] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:338; }","duration":"351.335175ms","start":"2026-05-06T19:10:00.799634Z","end":"2026-05-06T19:10:01.15097Z","steps":["trace[1029108567] 'agreement among raft nodes before linearized reading' (duration: 351.303235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:01.150993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:00.799629Z","time spent":"351.359354ms","remote":"127.0.0.1:55600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4219,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-06T19:10:01.151107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.525847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:10:01.151138Z","caller":"traceutil/trace.go:171","msg":"trace[326653858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:338; }","duration":"149.581925ms","start":"2026-05-06T19:10:01.001545Z","end":"2026-05-06T19:10:01.151127Z","steps":["trace[326653858] 'agreement among raft nodes before linearized reading' (duration: 149.530186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:01.151266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.364374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4196"} {"level":"info","ts":"2026-05-06T19:10:01.151299Z","caller":"traceutil/trace.go:171","msg":"trace[1043799723] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:338; }","duration":"351.399713ms","start":"2026-05-06T19:10:00.799885Z","end":"2026-05-06T19:10:01.151284Z","steps":["trace[1043799723] 'agreement among raft nodes before linearized reading' (duration: 351.356174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:01.151346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:00.799868Z","time spent":"351.466702ms","remote":"127.0.0.1:55600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4219,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-06T19:10:01.152405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.875602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-06T19:10:01.152473Z","caller":"traceutil/trace.go:171","msg":"trace[693732147] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:338; }","duration":"352.95408ms","start":"2026-05-06T19:10:00.799501Z","end":"2026-05-06T19:10:01.152456Z","steps":["trace[693732147] 'agreement among raft nodes before linearized reading' (duration: 352.831813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:01.152513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:00.799437Z","time spent":"353.067768ms","remote":"127.0.0.1:55618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-05-06T19:10:01.152536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.694613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-06T19:10:01.152565Z","caller":"traceutil/trace.go:171","msg":"trace[2026393279] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:338; }","duration":"354.735992ms","start":"2026-05-06T19:10:00.797821Z","end":"2026-05-06T19:10:01.152557Z","steps":["trace[2026393279] 'agreement among raft nodes before linearized reading' (duration: 354.681433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:01.152588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:00.797795Z","time spent":"354.787782ms","remote":"127.0.0.1:55618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-05-06T19:10:01.152582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.97705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4196"} {"level":"info","ts":"2026-05-06T19:10:01.152633Z","caller":"traceutil/trace.go:171","msg":"trace[1474585864] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:338; }","duration":"353.039849ms","start":"2026-05-06T19:10:00.799578Z","end":"2026-05-06T19:10:01.152618Z","steps":["trace[1474585864] 'agreement among raft nodes before linearized reading' (duration: 352.947981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:01.152664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:00.799574Z","time spent":"353.083537ms","remote":"127.0.0.1:55600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4219,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-06T19:10:01.154466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.961255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-06T19:10:01.154518Z","caller":"traceutil/trace.go:171","msg":"trace[895759451] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:338; }","duration":"357.033644ms","start":"2026-05-06T19:10:00.79747Z","end":"2026-05-06T19:10:01.154504Z","steps":["trace[895759451] 'agreement among raft nodes before linearized reading' (duration: 352.93916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:01.15455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:00.797462Z","time spent":"357.080373ms","remote":"127.0.0.1:55618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"info","ts":"2026-05-06T19:10:01.384196Z","caller":"traceutil/trace.go:171","msg":"trace[1423251175] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"222.316051ms","start":"2026-05-06T19:10:01.161867Z","end":"2026-05-06T19:10:01.384183Z","steps":["trace[1423251175] 'process raft request' (duration: 222.220563ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:01.384226Z","caller":"traceutil/trace.go:171","msg":"trace[614561394] transaction","detail":"{read_only:false; number_of_response:1; response_revision:339; }","duration":"225.695931ms","start":"2026-05-06T19:10:01.158514Z","end":"2026-05-06T19:10:01.38421Z","steps":["trace[614561394] 'process raft request' (duration: 173.356814ms)","trace[614561394] 'compare' (duration: 52.088512ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:10:01.384373Z","caller":"traceutil/trace.go:171","msg":"trace[1907912261] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"221.469419ms","start":"2026-05-06T19:10:01.162895Z","end":"2026-05-06T19:10:01.384364Z","steps":["trace[1907912261] 'process raft request' (duration: 221.219495ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:01.384422Z","caller":"traceutil/trace.go:171","msg":"trace[1161912559] linearizableReadLoop","detail":"{readStateIndex:357; appliedIndex:352; }","duration":"108.082231ms","start":"2026-05-06T19:10:01.27633Z","end":"2026-05-06T19:10:01.384412Z","steps":["trace[1161912559] 'read index received' (duration: 55.454421ms)","trace[1161912559] 'applied index is now lower than readState.Index' (duration: 52.62697ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T19:10:01.38454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.201428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-06T19:10:01.38457Z","caller":"traceutil/trace.go:171","msg":"trace[1659077459] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:343; }","duration":"108.267057ms","start":"2026-05-06T19:10:01.276291Z","end":"2026-05-06T19:10:01.384558Z","steps":["trace[1659077459] 'agreement among raft nodes before linearized reading' (duration: 108.180789ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:01.38454Z","caller":"traceutil/trace.go:171","msg":"trace[1489765941] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"220.777954ms","start":"2026-05-06T19:10:01.163734Z","end":"2026-05-06T19:10:01.384512Z","steps":["trace[1489765941] 'process raft request' (duration: 220.406882ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:01.38479Z","caller":"traceutil/trace.go:171","msg":"trace[299370217] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"223.940357ms","start":"2026-05-06T19:10:01.160842Z","end":"2026-05-06T19:10:01.384782Z","steps":["trace[299370217] 'process raft request' (duration: 223.191153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:01.774994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.029423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T19:10:01.775222Z","caller":"traceutil/trace.go:171","msg":"trace[1070623409] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"386.617195ms","start":"2026-05-06T19:10:01.388564Z","end":"2026-05-06T19:10:01.775181Z","steps":["trace[1070623409] 'process raft request' (duration: 243.308118ms)","trace[1070623409] 'compare' (duration: 142.892265ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:10:01.77532Z","caller":"traceutil/trace.go:171","msg":"trace[1744509163] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"386.556786ms","start":"2026-05-06T19:10:01.388727Z","end":"2026-05-06T19:10:01.775283Z","steps":["trace[1744509163] 'process raft request' (duration: 386.351951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:01.775365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:01.388516Z","time spent":"386.771391ms","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T19:10:01.775415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:01.388713Z","time spent":"386.647324ms","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T19:10:01.775512Z","caller":"traceutil/trace.go:171","msg":"trace[965537635] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"386.698603ms","start":"2026-05-06T19:10:01.388786Z","end":"2026-05-06T19:10:01.775484Z","steps":["trace[965537635] 'process raft request' (duration: 386.324081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:01.775601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:01.388754Z","time spent":"386.800251ms","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T19:10:01.775801Z","caller":"traceutil/trace.go:171","msg":"trace[807698288] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"386.481048ms","start":"2026-05-06T19:10:01.389305Z","end":"2026-05-06T19:10:01.775786Z","steps":["trace[807698288] 'process raft request' (duration: 386.070676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:01.775954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:01.389297Z","time spent":"386.541377ms","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T19:10:01.77647Z","caller":"traceutil/trace.go:171","msg":"trace[350488228] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"304.987947ms","start":"2026-05-06T19:10:01.471473Z","end":"2026-05-06T19:10:01.77646Z","steps":["trace[350488228] 'process raft request' (duration: 304.942978ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:01.776511Z","caller":"traceutil/trace.go:171","msg":"trace[1065127452] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"387.033776ms","start":"2026-05-06T19:10:01.389463Z","end":"2026-05-06T19:10:01.776496Z","steps":["trace[1065127452] 'process raft request' (duration: 386.264552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:01.776569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:01.389456Z","time spent":"387.088685ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3378,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T19:10:01.776661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:01.471454Z","time spent":"305.049706ms","remote":"127.0.0.1:55474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T19:10:01.776781Z","caller":"traceutil/trace.go:171","msg":"trace[189509402] linearizableReadLoop","detail":"{readStateIndex:359; appliedIndex:358; }","duration":"388.111433ms","start":"2026-05-06T19:10:01.388648Z","end":"2026-05-06T19:10:01.776759Z","steps":["trace[189509402] 'read index received' (duration: 81.984311ms)","trace[189509402] 'applied index is now lower than readState.Index' (duration: 306.125652ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T19:10:01.776892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.242511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7294"} {"level":"info","ts":"2026-05-06T19:10:01.776921Z","caller":"traceutil/trace.go:171","msg":"trace[1887956647] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:349; }","duration":"388.301009ms","start":"2026-05-06T19:10:01.388611Z","end":"2026-05-06T19:10:01.776912Z","steps":["trace[1887956647] 'agreement among raft nodes before linearized reading' (duration: 388.222391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:01.776951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:01.388594Z","time spent":"388.350338ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7317,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-05-06T19:10:01.777073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.290461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-05-06T19:10:01.777095Z","caller":"traceutil/trace.go:171","msg":"trace[179495130] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-7c96b6546b; range_end:; response_count:1; response_revision:349; }","duration":"387.334749ms","start":"2026-05-06T19:10:01.389755Z","end":"2026-05-06T19:10:01.777089Z","steps":["trace[179495130] 'agreement among raft nodes before linearized reading' (duration: 387.29383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:01.777114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:01.389747Z","time spent":"387.363149ms","remote":"127.0.0.1:55906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":3797,"request content":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" "} {"level":"warn","ts":"2026-05-06T19:10:02.026445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.567129ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T19:10:02.026724Z","caller":"traceutil/trace.go:171","msg":"trace[849333956] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"243.204795ms","start":"2026-05-06T19:10:01.783501Z","end":"2026-05-06T19:10:02.026706Z","steps":["trace[849333956] 'process raft request' (duration: 243.040579ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:02.02681Z","caller":"traceutil/trace.go:171","msg":"trace[47767280] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"244.812351ms","start":"2026-05-06T19:10:01.781937Z","end":"2026-05-06T19:10:02.026749Z","steps":["trace[47767280] 'process raft request' (duration: 121.87145ms)","trace[47767280] 'compare' (duration: 122.443142ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:10:02.027009Z","caller":"traceutil/trace.go:171","msg":"trace[225155549] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"243.374692ms","start":"2026-05-06T19:10:01.783594Z","end":"2026-05-06T19:10:02.026969Z","steps":["trace[225155549] 'process raft request' (duration: 243.009909ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:02.027086Z","caller":"traceutil/trace.go:171","msg":"trace[1342336468] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"241.598749ms","start":"2026-05-06T19:10:01.785474Z","end":"2026-05-06T19:10:02.027073Z","steps":["trace[1342336468] 'process raft request' (duration: 241.180768ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:02.027135Z","caller":"traceutil/trace.go:171","msg":"trace[418098361] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"198.840001ms","start":"2026-05-06T19:10:01.828277Z","end":"2026-05-06T19:10:02.027117Z","steps":["trace[418098361] 'process raft request' (duration: 198.667004ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:02.192647Z","caller":"traceutil/trace.go:171","msg":"trace[119206550] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"157.752091ms","start":"2026-05-06T19:10:02.034862Z","end":"2026-05-06T19:10:02.192614Z","steps":["trace[119206550] 'process raft request' (duration: 121.028599ms)","trace[119206550] 'compare' (duration: 36.472347ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:10:02.192816Z","caller":"traceutil/trace.go:171","msg":"trace[29736726] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:370; }","duration":"144.138945ms","start":"2026-05-06T19:10:02.048648Z","end":"2026-05-06T19:10:02.192786Z","steps":["trace[29736726] 'read index received' (duration: 107.334175ms)","trace[29736726] 'applied index is now lower than readState.Index' (duration: 36.80263ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T19:10:02.19299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.315961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" ","response":"range_response_count:1 size:3819"} {"level":"info","ts":"2026-05-06T19:10:02.193031Z","caller":"traceutil/trace.go:171","msg":"trace[1828733304] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-7c96b6546b; range_end:; response_count:1; response_revision:357; }","duration":"144.397879ms","start":"2026-05-06T19:10:02.048621Z","end":"2026-05-06T19:10:02.193019Z","steps":["trace[1828733304] 'agreement among raft nodes before linearized reading' (duration: 144.264522ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:02.192961Z","caller":"traceutil/trace.go:171","msg":"trace[1544766194] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"151.865084ms","start":"2026-05-06T19:10:02.040984Z","end":"2026-05-06T19:10:02.192849Z","steps":["trace[1544766194] 'process raft request' (duration: 151.55538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:02.407864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.456499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:10:02.407943Z","caller":"traceutil/trace.go:171","msg":"trace[624989640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:357; }","duration":"111.597466ms","start":"2026-05-06T19:10:02.296327Z","end":"2026-05-06T19:10:02.407924Z","steps":["trace[624989640] 'range keys from in-memory index tree' (duration: 111.361441ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:04.014014Z","caller":"traceutil/trace.go:171","msg":"trace[114194325] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"132.509663ms","start":"2026-05-06T19:10:03.881479Z","end":"2026-05-06T19:10:04.013989Z","steps":["trace[114194325] 'process raft request' (duration: 70.449688ms)","trace[114194325] 'compare' (duration: 61.961247ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:10:06.10836Z","caller":"traceutil/trace.go:171","msg":"trace[764214446] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:386; }","duration":"229.964488ms","start":"2026-05-06T19:10:05.878373Z","end":"2026-05-06T19:10:06.108338Z","steps":["trace[764214446] 'read index received' (duration: 229.877499ms)","trace[764214446] 'applied index is now lower than readState.Index' (duration: 86.109µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:10:06.108466Z","caller":"traceutil/trace.go:171","msg":"trace[141596466] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"230.111915ms","start":"2026-05-06T19:10:05.878341Z","end":"2026-05-06T19:10:06.108453Z","steps":["trace[141596466] 'process raft request' (duration: 229.86153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:06.108583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.191273ms","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-05-06T19:10:06.108632Z","caller":"traceutil/trace.go:171","msg":"trace[1842727922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:371; }","duration":"230.304371ms","start":"2026-05-06T19:10:05.878315Z","end":"2026-05-06T19:10:06.10862Z","steps":["trace[1842727922] 'agreement among raft nodes before linearized reading' (duration: 230.185093ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:06.240179Z","caller":"traceutil/trace.go:171","msg":"trace[1009440724] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"127.611353ms","start":"2026-05-06T19:10:06.112545Z","end":"2026-05-06T19:10:06.240157Z","steps":["trace[1009440724] 'process raft request' (duration: 127.552964ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:06.240195Z","caller":"traceutil/trace.go:171","msg":"trace[731665144] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"358.686969ms","start":"2026-05-06T19:10:05.881472Z","end":"2026-05-06T19:10:06.240159Z","steps":["trace[731665144] 'process raft request' (duration: 294.906462ms)","trace[731665144] 'compare' (duration: 63.59263ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:10:06.240212Z","caller":"traceutil/trace.go:171","msg":"trace[496974245] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:387; }","duration":"131.760688ms","start":"2026-05-06T19:10:06.108423Z","end":"2026-05-06T19:10:06.240184Z","steps":["trace[496974245] 'read index received' (duration: 67.964952ms)","trace[496974245] 'applied index is now lower than readState.Index' (duration: 63.790696ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T19:10:06.240347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:05.881455Z","time spent":"358.812095ms","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T19:10:06.240404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.5714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-06T19:10:06.240472Z","caller":"traceutil/trace.go:171","msg":"trace[1700064355] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:373; }","duration":"360.696978ms","start":"2026-05-06T19:10:05.879758Z","end":"2026-05-06T19:10:06.240455Z","steps":["trace[1700064355] 'agreement among raft nodes before linearized reading' (duration: 360.541701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:06.240563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:05.879736Z","time spent":"360.805695ms","remote":"127.0.0.1:55672","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-05-06T19:10:08.353166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.399204ms","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-05-06T19:10:08.353233Z","caller":"traceutil/trace.go:171","msg":"trace[300796699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:377; }","duration":"109.506711ms","start":"2026-05-06T19:10:08.243717Z","end":"2026-05-06T19:10:08.353224Z","steps":["trace[300796699] 'range keys from in-memory index tree' (duration: 109.280296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:08.353289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.57912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-06T19:10:08.353333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.57987ms","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-05-06T19:10:08.353351Z","caller":"traceutil/trace.go:171","msg":"trace[40569763] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:377; }","duration":"109.628069ms","start":"2026-05-06T19:10:08.243717Z","end":"2026-05-06T19:10:08.353346Z","steps":["trace[40569763] 'range keys from in-memory index tree' (duration: 109.490361ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:08.353367Z","caller":"traceutil/trace.go:171","msg":"trace[1423990063] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:377; }","duration":"102.682058ms","start":"2026-05-06T19:10:08.250664Z","end":"2026-05-06T19:10:08.353346Z","steps":["trace[1423990063] 'range keys from in-memory index tree' (duration: 102.472562ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:08.665456Z","caller":"traceutil/trace.go:171","msg":"trace[1432560546] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"307.474885ms","start":"2026-05-06T19:10:08.357958Z","end":"2026-05-06T19:10:08.665433Z","steps":["trace[1432560546] 'process raft request' (duration: 307.427755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:08.665611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:08.357942Z","time spent":"307.590083ms","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T19:10:08.665905Z","caller":"traceutil/trace.go:171","msg":"trace[1300455674] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"307.974435ms","start":"2026-05-06T19:10:08.357888Z","end":"2026-05-06T19:10:08.665862Z","steps":["trace[1300455674] 'process raft request' (duration: 221.6967ms)","trace[1300455674] 'compare' (duration: 85.682847ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T19:10:08.666127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:08.357876Z","time spent":"308.152371ms","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T19:10:10.580758Z","caller":"traceutil/trace.go:171","msg":"trace[1775825635] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"111.975673ms","start":"2026-05-06T19:10:10.468756Z","end":"2026-05-06T19:10:10.580731Z","steps":["trace[1775825635] 'process raft request' (duration: 74.58495ms)","trace[1775825635] 'compare' (duration: 37.268245ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:10:12.792654Z","caller":"traceutil/trace.go:171","msg":"trace[521392647] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:404; }","duration":"497.320543ms","start":"2026-05-06T19:10:12.295314Z","end":"2026-05-06T19:10:12.792635Z","steps":["trace[521392647] 'read index received' (duration: 497.179026ms)","trace[521392647] 'applied index is now lower than readState.Index' (duration: 140.957µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:10:12.792805Z","caller":"traceutil/trace.go:171","msg":"trace[296302295] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"562.824418ms","start":"2026-05-06T19:10:12.22993Z","end":"2026-05-06T19:10:12.792755Z","steps":["trace[296302295] 'process raft request' (duration: 562.614322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:12.792962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:12.229903Z","time spent":"562.980145ms","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T19:10:12.792875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.542289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T19:10:12.79299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.90296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-06T19:10:12.793099Z","caller":"traceutil/trace.go:171","msg":"trace[1912578934] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:387; }","duration":"497.786014ms","start":"2026-05-06T19:10:12.295278Z","end":"2026-05-06T19:10:12.793064Z","steps":["trace[1912578934] 'agreement among raft nodes before linearized reading' (duration: 497.46894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:12.793162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:12.295258Z","time spent":"497.867783ms","remote":"127.0.0.1:55450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T19:10:12.793167Z","caller":"traceutil/trace.go:171","msg":"trace[2068303485] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:387; }","duration":"272.054427ms","start":"2026-05-06T19:10:12.52102Z","end":"2026-05-06T19:10:12.793074Z","steps":["trace[2068303485] 'agreement among raft nodes before linearized reading' (duration: 271.851391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:13.797229Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967604408009470085,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T19:10:14.298107Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967604408009470085,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T19:10:14.799219Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967604408009470085,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T19:10:15.296235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999922318s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-06T19:10:15.296329Z","caller":"traceutil/trace.go:171","msg":"trace[595242050] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000076065s","start":"2026-05-06T19:10:13.296232Z","end":"2026-05-06T19:10:15.296308Z","steps":["trace[595242050] 'agreement among raft nodes before linearized reading' (duration: 1.999954387s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:15.296408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:13.296211Z","time spent":"2.000185133s","remote":"127.0.0.1:55450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/06 19:10:15 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-06T19:10:15.299351Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967604408009470085,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T19:10:15.800344Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967604408009470085,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T19:10:15.883807Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.052196769s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T19:10:15.88436Z","caller":"traceutil/trace.go:171","msg":"trace[738684166] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"3.087393024s","start":"2026-05-06T19:10:12.796945Z","end":"2026-05-06T19:10:15.884338Z","steps":["trace[738684166] 'process raft request' (duration: 3.087248927s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:15.884515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:12.796915Z","time spent":"3.087492382s","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T19:10:15.893834Z","caller":"traceutil/trace.go:171","msg":"trace[737900572] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"3.096247565s","start":"2026-05-06T19:10:12.797574Z","end":"2026-05-06T19:10:15.893822Z","steps":["trace[737900572] 'process raft request' (duration: 3.096186766s)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:15.893861Z","caller":"traceutil/trace.go:171","msg":"trace[1941699645] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"3.096570799s","start":"2026-05-06T19:10:12.797273Z","end":"2026-05-06T19:10:15.893844Z","steps":["trace[1941699645] 'process raft request' (duration: 3.096428931s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:15.893909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:12.79755Z","time spent":"3.096317573s","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T19:10:15.893978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:12.797253Z","time spent":"3.096646638s","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T19:10:15.894013Z","caller":"traceutil/trace.go:171","msg":"trace[993325286] linearizableReadLoop","detail":"{readStateIndex:409; appliedIndex:405; }","duration":"2.597711419s","start":"2026-05-06T19:10:13.29628Z","end":"2026-05-06T19:10:15.893991Z","steps":["trace[993325286] 'read index received' (duration: 2.587902246s)","trace[993325286] 'applied index is now lower than readState.Index' (duration: 9.807503ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:10:15.89404Z","caller":"traceutil/trace.go:171","msg":"trace[1297917594] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"2.089075073s","start":"2026-05-06T19:10:13.804947Z","end":"2026-05-06T19:10:15.894022Z","steps":["trace[1297917594] 'process raft request' (duration: 2.088835958s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:15.89458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:13.804924Z","time spent":"2.089554185s","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T19:10:15.894765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.097361005s","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-05-06T19:10:15.8948Z","caller":"traceutil/trace.go:171","msg":"trace[1853495490] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:391; }","duration":"1.097444554s","start":"2026-05-06T19:10:14.797346Z","end":"2026-05-06T19:10:15.894791Z","steps":["trace[1853495490] 'agreement among raft nodes before linearized reading' (duration: 1.097370915s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:15.894821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:14.797321Z","time spent":"1.097494772s","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-06T19:10:15.894912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.32825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:10:15.894937Z","caller":"traceutil/trace.go:171","msg":"trace[161768409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:391; }","duration":"598.384999ms","start":"2026-05-06T19:10:15.296544Z","end":"2026-05-06T19:10:15.894929Z","steps":["trace[161768409] 'agreement among raft nodes before linearized reading' (duration: 598.35402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:15.894952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:15.296523Z","time spent":"598.426239ms","remote":"127.0.0.1:55448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T19:10:16.007503Z","caller":"traceutil/trace.go:171","msg":"trace[1079198717] linearizableReadLoop","detail":"{readStateIndex:411; appliedIndex:409; }","duration":"104.313817ms","start":"2026-05-06T19:10:15.903169Z","end":"2026-05-06T19:10:16.007482Z","steps":["trace[1079198717] 'read index received' (duration: 12.057319ms)","trace[1079198717] 'applied index is now lower than readState.Index' (duration: 92.255658ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:10:16.007562Z","caller":"traceutil/trace.go:171","msg":"trace[761376620] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"105.250689ms","start":"2026-05-06T19:10:15.902265Z","end":"2026-05-06T19:10:16.007516Z","steps":["trace[761376620] 'process raft request' (duration: 79.071869ms)","trace[761376620] 'compare' (duration: 26.039843ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T19:10:16.007656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.469404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:10:16.007687Z","caller":"traceutil/trace.go:171","msg":"trace[825192369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:393; }","duration":"104.531273ms","start":"2026-05-06T19:10:15.903146Z","end":"2026-05-06T19:10:16.007677Z","steps":["trace[825192369] 'agreement among raft nodes before linearized reading' (duration: 104.440895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:17.001452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.934923ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-06T19:10:18.397794Z","caller":"traceutil/trace.go:171","msg":"trace[829324154] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"137.516447ms","start":"2026-05-06T19:10:18.260256Z","end":"2026-05-06T19:10:18.397773Z","steps":["trace[829324154] 'process raft request' (duration: 137.37402ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:18.52827Z","caller":"traceutil/trace.go:171","msg":"trace[1876762314] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"123.408242ms","start":"2026-05-06T19:10:18.404842Z","end":"2026-05-06T19:10:18.52825Z","steps":["trace[1876762314] 'process raft request' (duration: 98.360472ms)","trace[1876762314] 'compare' (duration: 24.957892ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T19:10:19.042842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.014817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T19:10:19.043326Z","caller":"traceutil/trace.go:171","msg":"trace[1114095536] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"480.241936ms","start":"2026-05-06T19:10:18.563066Z","end":"2026-05-06T19:10:19.043308Z","steps":["trace[1114095536] 'process raft request' (duration: 480.125238ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:19.043556Z","caller":"traceutil/trace.go:171","msg":"trace[383833213] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"483.413776ms","start":"2026-05-06T19:10:18.56013Z","end":"2026-05-06T19:10:19.043544Z","steps":["trace[383833213] 'process raft request' (duration: 482.961325ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:19.043577Z","caller":"traceutil/trace.go:171","msg":"trace[1839195766] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"505.879914ms","start":"2026-05-06T19:10:18.537654Z","end":"2026-05-06T19:10:19.043534Z","steps":["trace[1839195766] 'process raft request' (duration: 505.282115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:19.043688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:18.560119Z","time spent":"483.489185ms","remote":"127.0.0.1:55512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T19:10:19.043632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:18.563058Z","time spent":"480.52345ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T19:10:19.043801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:18.537644Z","time spent":"506.047661ms","remote":"127.0.0.1:55882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T19:10:19.043432Z","caller":"traceutil/trace.go:171","msg":"trace[227245326] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"508.906208ms","start":"2026-05-06T19:10:18.534454Z","end":"2026-05-06T19:10:19.04336Z","steps":["trace[227245326] 'process raft request' (duration: 326.310442ms)","trace[227245326] 'compare' (duration: 181.787811ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:10:19.043471Z","caller":"traceutil/trace.go:171","msg":"trace[1346792791] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"481.922484ms","start":"2026-05-06T19:10:18.561512Z","end":"2026-05-06T19:10:19.043435Z","steps":["trace[1346792791] 'process raft request' (duration: 481.61207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:19.043988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:18.534447Z","time spent":"509.461836ms","remote":"127.0.0.1:55512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T19:10:19.043327Z","caller":"traceutil/trace.go:171","msg":"trace[1646924459] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"483.402697ms","start":"2026-05-06T19:10:18.559902Z","end":"2026-05-06T19:10:19.043305Z","steps":["trace[1646924459] 'process raft request' (duration: 483.149711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:19.044173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:18.559871Z","time spent":"484.26192ms","remote":"127.0.0.1:55512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T19:10:19.044201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:18.561497Z","time spent":"482.566372ms","remote":"127.0.0.1:55906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3795,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T19:10:19.350304Z","caller":"traceutil/trace.go:171","msg":"trace[751888146] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:426; }","duration":"297.557392ms","start":"2026-05-06T19:10:19.052724Z","end":"2026-05-06T19:10:19.350281Z","steps":["trace[751888146] 'read index received' (duration: 200.726301ms)","trace[751888146] 'applied index is now lower than readState.Index' (duration: 96.830141ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T19:10:19.350458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.721999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3810"} {"level":"info","ts":"2026-05-06T19:10:19.350509Z","caller":"traceutil/trace.go:171","msg":"trace[100085342] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:408; }","duration":"297.808228ms","start":"2026-05-06T19:10:19.052686Z","end":"2026-05-06T19:10:19.350494Z","steps":["trace[100085342] 'agreement among raft nodes before linearized reading' (duration: 297.714959ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:19.350951Z","caller":"traceutil/trace.go:171","msg":"trace[2102345930] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"298.449055ms","start":"2026-05-06T19:10:19.052469Z","end":"2026-05-06T19:10:19.350918Z","steps":["trace[2102345930] 'process raft request' (duration: 200.962556ms)","trace[2102345930] 'compare' (duration: 96.741973ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:10:19.508859Z","caller":"traceutil/trace.go:171","msg":"trace[372382539] transaction","detail":"{read_only:false; number_of_response:1; response_revision:414; }","duration":"100.914255ms","start":"2026-05-06T19:10:19.407918Z","end":"2026-05-06T19:10:19.508833Z","steps":["trace[372382539] 'process raft request' (duration: 86.665082ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:21.056076Z","caller":"traceutil/trace.go:171","msg":"trace[688570749] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"177.90834ms","start":"2026-05-06T19:10:20.878149Z","end":"2026-05-06T19:10:21.056057Z","steps":["trace[688570749] 'process raft request' (duration: 177.792772ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:28.123854Z","caller":"traceutil/trace.go:171","msg":"trace[1368659421] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"103.120514ms","start":"2026-05-06T19:10:28.020711Z","end":"2026-05-06T19:10:28.123832Z","steps":["trace[1368659421] 'process raft request' (duration: 102.937808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:28.515364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.565498ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T19:10:28.51546Z","caller":"traceutil/trace.go:171","msg":"trace[872520227] linearizableReadLoop","detail":"{readStateIndex:471; appliedIndex:470; }","duration":"378.580323ms","start":"2026-05-06T19:10:28.136864Z","end":"2026-05-06T19:10:28.515444Z","steps":["trace[872520227] 'read index received' (duration: 192.870888ms)","trace[872520227] 'applied index is now lower than readState.Index' (duration: 185.708485ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:10:28.515538Z","caller":"traceutil/trace.go:171","msg":"trace[2124409534] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"384.640645ms","start":"2026-05-06T19:10:28.130881Z","end":"2026-05-06T19:10:28.515522Z","steps":["trace[2124409534] 'process raft request' (duration: 198.836991ms)","trace[2124409534] 'compare' (duration: 185.422761ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T19:10:28.515563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.705201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-06T19:10:28.515761Z","caller":"traceutil/trace.go:171","msg":"trace[206866361] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:449; }","duration":"378.936307ms","start":"2026-05-06T19:10:28.136811Z","end":"2026-05-06T19:10:28.515748Z","steps":["trace[206866361] 'agreement among raft nodes before linearized reading' (duration: 378.717141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:28.515863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:28.136792Z","time spent":"379.056205ms","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T19:10:28.515827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.963572ms","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-05-06T19:10:28.516053Z","caller":"traceutil/trace.go:171","msg":"trace[1462445132] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:449; }","duration":"377.220467ms","start":"2026-05-06T19:10:28.138776Z","end":"2026-05-06T19:10:28.515997Z","steps":["trace[1462445132] 'agreement among raft nodes before linearized reading' (duration: 376.838624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:28.516123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:28.138766Z","time spent":"377.335665ms","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T19:10:28.516125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:28.130866Z","time spent":"384.788171ms","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T19:10:30.252991Z","caller":"traceutil/trace.go:171","msg":"trace[2069418700] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"123.581135ms","start":"2026-05-06T19:10:30.12938Z","end":"2026-05-06T19:10:30.252961Z","steps":["trace[2069418700] 'process raft request' (duration: 123.34028ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:30.650678Z","caller":"traceutil/trace.go:171","msg":"trace[232186319] linearizableReadLoop","detail":"{readStateIndex:480; appliedIndex:478; }","duration":"131.445046ms","start":"2026-05-06T19:10:30.519209Z","end":"2026-05-06T19:10:30.650654Z","steps":["trace[232186319] 'read index received' (duration: 52.441029ms)","trace[232186319] 'applied index is now lower than readState.Index' (duration: 79.003157ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:10:30.650718Z","caller":"traceutil/trace.go:171","msg":"trace[1715914652] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"208.576987ms","start":"2026-05-06T19:10:30.442112Z","end":"2026-05-06T19:10:30.650689Z","steps":["trace[1715914652] 'process raft request' (duration: 129.52537ms)","trace[1715914652] 'compare' (duration: 78.808761ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:10:30.650812Z","caller":"traceutil/trace.go:171","msg":"trace[1953587761] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"207.939518ms","start":"2026-05-06T19:10:30.442862Z","end":"2026-05-06T19:10:30.650802Z","steps":["trace[1953587761] 'process raft request' (duration: 207.748501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:30.650948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.472078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-06T19:10:30.650982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.731701ms","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-05-06T19:10:30.651007Z","caller":"traceutil/trace.go:171","msg":"trace[353678457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:458; }","duration":"119.556447ms","start":"2026-05-06T19:10:30.531435Z","end":"2026-05-06T19:10:30.650992Z","steps":["trace[353678457] 'agreement among raft nodes before linearized reading' (duration: 119.40155ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:30.651018Z","caller":"traceutil/trace.go:171","msg":"trace[1610469501] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:458; }","duration":"131.850409ms","start":"2026-05-06T19:10:30.519156Z","end":"2026-05-06T19:10:30.651006Z","steps":["trace[1610469501] 'agreement among raft nodes before linearized reading' (duration: 131.744001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:30.651022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.3236ms","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-05-06T19:10:30.651063Z","caller":"traceutil/trace.go:171","msg":"trace[178799833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:458; }","duration":"105.394859ms","start":"2026-05-06T19:10:30.545655Z","end":"2026-05-06T19:10:30.65105Z","steps":["trace[178799833] 'agreement among raft nodes before linearized reading' (duration: 105.303841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:30.925674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.98701ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T19:10:30.926033Z","caller":"traceutil/trace.go:171","msg":"trace[2055950002] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"269.943667ms","start":"2026-05-06T19:10:30.656059Z","end":"2026-05-06T19:10:30.926003Z","steps":["trace[2055950002] 'process raft request' (duration: 162.552474ms)","trace[2055950002] 'compare' (duration: 106.839773ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:10:30.926147Z","caller":"traceutil/trace.go:171","msg":"trace[1016821587] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"269.998596ms","start":"2026-05-06T19:10:30.656125Z","end":"2026-05-06T19:10:30.926124Z","steps":["trace[1016821587] 'process raft request' (duration: 269.653522ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:30.926381Z","caller":"traceutil/trace.go:171","msg":"trace[800411974] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"269.902577ms","start":"2026-05-06T19:10:30.656467Z","end":"2026-05-06T19:10:30.92637Z","steps":["trace[800411974] 'process raft request' (duration: 269.550213ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:30.926382Z","caller":"traceutil/trace.go:171","msg":"trace[1185331129] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"269.860278ms","start":"2026-05-06T19:10:30.656502Z","end":"2026-05-06T19:10:30.926363Z","steps":["trace[1185331129] 'process raft request' (duration: 269.592883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:33.187842Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967604408009470291,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T19:10:33.68846Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967604408009470291,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T19:10:33.7476Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.093392272s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T19:10:33.748217Z","caller":"traceutil/trace.go:171","msg":"trace[1372494376] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"1.094073671s","start":"2026-05-06T19:10:32.654121Z","end":"2026-05-06T19:10:33.748195Z","steps":["trace[1372494376] 'process raft request' (duration: 1.093844825s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:33.748388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:32.654106Z","time spent":"1.094220887s","remote":"127.0.0.1:55490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T19:10:35.296655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000470149s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-06T19:10:35.296762Z","caller":"traceutil/trace.go:171","msg":"trace[1085996223] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000627297s","start":"2026-05-06T19:10:33.296114Z","end":"2026-05-06T19:10:35.296741Z","steps":["trace[1085996223] 'agreement among raft nodes before linearized reading' (duration: 2.000494609s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:35.296848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:33.296099Z","time spent":"2.000738685s","remote":"127.0.0.1:55450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/06 19:10:35 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-06T19:10:35.335534Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.586626259s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T19:10:35.33597Z","caller":"traceutil/trace.go:171","msg":"trace[368200363] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"2.67100789s","start":"2026-05-06T19:10:32.664947Z","end":"2026-05-06T19:10:35.335955Z","steps":["trace[368200363] 'process raft request' (duration: 2.670800134s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:35.336066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:32.66493Z","time spent":"2.671087759s","remote":"127.0.0.1:55490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T19:10:35.336079Z","caller":"traceutil/trace.go:171","msg":"trace[1740182683] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"2.667878145s","start":"2026-05-06T19:10:32.668173Z","end":"2026-05-06T19:10:35.336051Z","steps":["trace[1740182683] 'process raft request' (duration: 2.667716128s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:35.336184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:32.668167Z","time spent":"2.667973454s","remote":"127.0.0.1:55490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T19:10:35.336233Z","caller":"traceutil/trace.go:171","msg":"trace[357268408] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"2.665491587s","start":"2026-05-06T19:10:32.67073Z","end":"2026-05-06T19:10:35.336221Z","steps":["trace[357268408] 'process raft request' (duration: 2.665210342s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:35.336272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:32.670699Z","time spent":"2.665553267s","remote":"127.0.0.1:55490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T19:10:35.336447Z","caller":"traceutil/trace.go:171","msg":"trace[2058199414] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"2.658341823s","start":"2026-05-06T19:10:32.678097Z","end":"2026-05-06T19:10:35.336439Z","steps":["trace[2058199414] 'process raft request' (duration: 2.658208416s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:35.336488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:32.678077Z","time spent":"2.658393002s","remote":"127.0.0.1:55490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T19:10:35.336543Z","caller":"traceutil/trace.go:171","msg":"trace[1782635469] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"2.658424762s","start":"2026-05-06T19:10:32.678113Z","end":"2026-05-06T19:10:35.336538Z","steps":["trace[1782635469] 'process raft request' (duration: 2.658327273s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:35.336572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:32.678096Z","time spent":"2.658464091s","remote":"127.0.0.1:55490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T19:10:35.336615Z","caller":"traceutil/trace.go:171","msg":"trace[793950553] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"2.65848903s","start":"2026-05-06T19:10:32.678121Z","end":"2026-05-06T19:10:35.33661Z","steps":["trace[793950553] 'process raft request' (duration: 2.658367173s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:35.336643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:32.678098Z","time spent":"2.65853361s","remote":"127.0.0.1:55490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T19:10:35.336701Z","caller":"traceutil/trace.go:171","msg":"trace[249354631] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"2.651956116s","start":"2026-05-06T19:10:32.684739Z","end":"2026-05-06T19:10:35.336695Z","steps":["trace[249354631] 'process raft request' (duration: 2.651790429s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:35.336731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:32.684731Z","time spent":"2.651986836s","remote":"127.0.0.1:55490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T19:10:35.336795Z","caller":"traceutil/trace.go:171","msg":"trace[1990082749] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"2.649295902s","start":"2026-05-06T19:10:32.687492Z","end":"2026-05-06T19:10:35.336788Z","steps":["trace[1990082749] 'process raft request' (duration: 2.649082556s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:35.336824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:32.687467Z","time spent":"2.649345792s","remote":"127.0.0.1:55490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T19:10:35.336833Z","caller":"traceutil/trace.go:171","msg":"trace[85146706] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:515; }","duration":"2.649656677s","start":"2026-05-06T19:10:32.68716Z","end":"2026-05-06T19:10:35.336817Z","steps":["trace[85146706] 'read index received' (duration: 1.060892905s)","trace[85146706] 'applied index is now lower than readState.Index' (duration: 1.588762622s)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:10:35.336928Z","caller":"traceutil/trace.go:171","msg":"trace[1379755158] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"2.628811134s","start":"2026-05-06T19:10:32.70811Z","end":"2026-05-06T19:10:35.336921Z","steps":["trace[1379755158] 'process raft request' (duration: 2.628573148s)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:35.337015Z","caller":"traceutil/trace.go:171","msg":"trace[874502967] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"2.481346152s","start":"2026-05-06T19:10:32.85561Z","end":"2026-05-06T19:10:35.336956Z","steps":["trace[874502967] 'process raft request' (duration: 2.481144096s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:35.337078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:32.708094Z","time spent":"2.628968781s","remote":"127.0.0.1:55490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T19:10:35.33695Z","caller":"traceutil/trace.go:171","msg":"trace[1931149138] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"2.641707507s","start":"2026-05-06T19:10:32.695238Z","end":"2026-05-06T19:10:35.336946Z","steps":["trace[1931149138] 'process raft request' (duration: 2.641390932s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:35.337139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:32.695224Z","time spent":"2.641904503s","remote":"127.0.0.1:55490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T19:10:35.33718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:32.855592Z","time spent":"2.48150587s","remote":"127.0.0.1:55490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T19:10:35.337325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.650110678s","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-05-06T19:10:35.337436Z","caller":"traceutil/trace.go:171","msg":"trace[2052812274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:504; }","duration":"2.650285825s","start":"2026-05-06T19:10:32.687131Z","end":"2026-05-06T19:10:35.337417Z","steps":["trace[2052812274] 'agreement among raft nodes before linearized reading' (duration: 2.649867583s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:35.337524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:32.687123Z","time spent":"2.650389014s","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-06T19:10:35.438079Z","caller":"traceutil/trace.go:171","msg":"trace[1389511636] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"1.684823258s","start":"2026-05-06T19:10:33.753212Z","end":"2026-05-06T19:10:35.438035Z","steps":["trace[1389511636] 'process raft request' (duration: 1.682005057s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:35.438178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.508174619s","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-05-06T19:10:35.438226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:33.753192Z","time spent":"1.684975375s","remote":"127.0.0.1:55958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T19:10:35.438085Z","caller":"traceutil/trace.go:171","msg":"trace[1744392670] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:527; }","duration":"101.021559ms","start":"2026-05-06T19:10:35.33703Z","end":"2026-05-06T19:10:35.438052Z","steps":["trace[1744392670] 'read index received' (duration: 98.145639ms)","trace[1744392670] 'applied index is now lower than readState.Index' (duration: 2.87433ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:10:35.438541Z","caller":"traceutil/trace.go:171","msg":"trace[1792770809] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"1.682525418s","start":"2026-05-06T19:10:33.756005Z","end":"2026-05-06T19:10:35.438531Z","steps":["trace[1792770809] 'process raft request' (duration: 1.681877069s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:35.438579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.508530422s","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-05-06T19:10:35.438713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124368339s","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-05-06T19:10:35.438743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.462294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:10:35.438779Z","caller":"traceutil/trace.go:171","msg":"trace[15047895] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:506; }","duration":"1.124498566s","start":"2026-05-06T19:10:34.314264Z","end":"2026-05-06T19:10:35.438763Z","steps":["trace[15047895] 'agreement among raft nodes before linearized reading' (duration: 1.124196261s)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:35.438778Z","caller":"traceutil/trace.go:171","msg":"trace[1710145340] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:506; }","duration":"141.531813ms","start":"2026-05-06T19:10:35.297237Z","end":"2026-05-06T19:10:35.438769Z","steps":["trace[1710145340] 'agreement among raft nodes before linearized reading' (duration: 141.409215ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:35.438734Z","caller":"traceutil/trace.go:171","msg":"trace[1726069817] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:506; }","duration":"2.508713009s","start":"2026-05-06T19:10:32.930002Z","end":"2026-05-06T19:10:35.438715Z","steps":["trace[1726069817] 'agreement among raft nodes before linearized reading' (duration: 2.508506962s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:35.438822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:34.314247Z","time spent":"1.124567235s","remote":"127.0.0.1:55672","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":"warn","ts":"2026-05-06T19:10:35.438616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:33.755998Z","time spent":"1.682570407s","remote":"127.0.0.1:55490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T19:10:35.439379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:32.929984Z","time spent":"2.509377657s","remote":"127.0.0.1:55672","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-05-06T19:10:35.440127Z","caller":"traceutil/trace.go:171","msg":"trace[1969225483] transaction","detail":"{read_only:false; number_of_response:1; response_revision:506; }","duration":"1.682225444s","start":"2026-05-06T19:10:33.756197Z","end":"2026-05-06T19:10:35.438422Z","steps":["trace[1969225483] 'process raft request' (duration: 1.681752302s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:35.440322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:33.756187Z","time spent":"1.684001262s","remote":"127.0.0.1:55490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6937,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T19:10:35.440703Z","caller":"traceutil/trace.go:171","msg":"trace[73623709] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:506; }","duration":"2.508299236s","start":"2026-05-06T19:10:32.929935Z","end":"2026-05-06T19:10:35.438234Z","steps":["trace[73623709] 'agreement among raft nodes before linearized reading' (duration: 2.508156089s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:35.440903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:32.929911Z","time spent":"2.51097823s","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-06T19:10:42.287072Z","caller":"traceutil/trace.go:171","msg":"trace[1772453683] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"221.849066ms","start":"2026-05-06T19:10:42.065138Z","end":"2026-05-06T19:10:42.286987Z","steps":["trace[1772453683] 'process raft request' (duration: 221.066789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:43.985741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.581857074s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T19:10:43.986007Z","caller":"traceutil/trace.go:171","msg":"trace[1162057439] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"1.52874992s","start":"2026-05-06T19:10:42.457236Z","end":"2026-05-06T19:10:43.985985Z","steps":["trace[1162057439] 'read index received' (duration: 38.479µs)","trace[1162057439] 'applied index is now lower than readState.Index' (duration: 1.528707962s)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:10:43.986213Z","caller":"traceutil/trace.go:171","msg":"trace[1659753228] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"1.646310515s","start":"2026-05-06T19:10:42.339821Z","end":"2026-05-06T19:10:43.986132Z","steps":["trace[1659753228] 'process raft request' (duration: 63.807062ms)","trace[1659753228] 'compare' (duration: 1.581699347s)"],"step_count":2} {"level":"warn","ts":"2026-05-06T19:10:43.98636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.529119434s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xkxwz\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-06T19:10:43.986397Z","caller":"traceutil/trace.go:171","msg":"trace[1126344882] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xkxwz; range_end:; response_count:1; response_revision:608; }","duration":"1.529219123s","start":"2026-05-06T19:10:42.457166Z","end":"2026-05-06T19:10:43.986385Z","steps":["trace[1126344882] 'agreement among raft nodes before linearized reading' (duration: 1.528972907s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:43.986438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:42.457146Z","time spent":"1.529284382s","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-xkxwz\" "} {"level":"warn","ts":"2026-05-06T19:10:43.986469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:42.339793Z","time spent":"1.646557461s","remote":"127.0.0.1:55654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T19:10:43.986497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.064009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:10:43.986588Z","caller":"traceutil/trace.go:171","msg":"trace[1275662395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"689.208957ms","start":"2026-05-06T19:10:43.297353Z","end":"2026-05-06T19:10:43.986562Z","steps":["trace[1275662395] 'agreement among raft nodes before linearized reading' (duration: 689.057719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:43.98659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.869763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-06T19:10:43.986633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:43.297332Z","time spent":"689.293186ms","remote":"127.0.0.1:55450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T19:10:43.986694Z","caller":"traceutil/trace.go:171","msg":"trace[1973426276] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:608; }","duration":"464.99705ms","start":"2026-05-06T19:10:43.521647Z","end":"2026-05-06T19:10:43.986644Z","steps":["trace[1973426276] 'agreement among raft nodes before linearized reading' (duration: 464.761614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:43.986745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:43.521623Z","time spent":"465.113609ms","remote":"127.0.0.1:55672","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-05-06T19:10:43.986752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.364027ms","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-05-06T19:10:43.986806Z","caller":"traceutil/trace.go:171","msg":"trace[1603248522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:608; }","duration":"466.450096ms","start":"2026-05-06T19:10:43.52034Z","end":"2026-05-06T19:10:43.98679Z","steps":["trace[1603248522] 'agreement among raft nodes before linearized reading' (duration: 466.232049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:43.986844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:43.520323Z","time spent":"466.513485ms","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T19:10:43.986835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.249864ms","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-05-06T19:10:43.986954Z","caller":"traceutil/trace.go:171","msg":"trace[261876756] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:608; }","duration":"454.396411ms","start":"2026-05-06T19:10:43.532522Z","end":"2026-05-06T19:10:43.986919Z","steps":["trace[261876756] 'agreement among raft nodes before linearized reading' (duration: 453.993367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:43.986969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.214737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-06T19:10:43.987003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:43.532509Z","time spent":"454.485119ms","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-06T19:10:43.987011Z","caller":"traceutil/trace.go:171","msg":"trace[1329646429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:608; }","duration":"495.323804ms","start":"2026-05-06T19:10:43.491676Z","end":"2026-05-06T19:10:43.987Z","steps":["trace[1329646429] 'agreement among raft nodes before linearized reading' (duration: 495.210446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:43.98705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:43.491631Z","time spent":"495.410913ms","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-06T19:10:46.035395Z","caller":"traceutil/trace.go:171","msg":"trace[529308105] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"311.720697ms","start":"2026-05-06T19:10:45.723652Z","end":"2026-05-06T19:10:46.035373Z","steps":["trace[529308105] 'process raft request' (duration: 311.609199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:46.035549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:45.723623Z","time spent":"311.832885ms","remote":"127.0.0.1:55490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T19:10:46.040828Z","caller":"traceutil/trace.go:171","msg":"trace[848234904] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"110.121468ms","start":"2026-05-06T19:10:45.930692Z","end":"2026-05-06T19:10:46.040814Z","steps":["trace[848234904] 'process raft request' (duration: 110.02231ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:49.667124Z","caller":"traceutil/trace.go:171","msg":"trace[1436963032] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"104.447539ms","start":"2026-05-06T19:10:49.56265Z","end":"2026-05-06T19:10:49.667098Z","steps":["trace[1436963032] 'process raft request' (duration: 102.357154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:50.34524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.31772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T19:10:50.34541Z","caller":"traceutil/trace.go:171","msg":"trace[606448679] linearizableReadLoop","detail":"{readStateIndex:726; appliedIndex:724; }","duration":"280.633468ms","start":"2026-05-06T19:10:50.06476Z","end":"2026-05-06T19:10:50.345393Z","steps":["trace[606448679] 'read index received' (duration: 34.891887ms)","trace[606448679] 'applied index is now lower than readState.Index' (duration: 245.740631ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:10:50.345501Z","caller":"traceutil/trace.go:171","msg":"trace[438473225] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"281.201279ms","start":"2026-05-06T19:10:50.064289Z","end":"2026-05-06T19:10:50.34549Z","steps":["trace[438473225] 'process raft request' (duration: 123.573504ms)","trace[438473225] 'compare' (duration: 157.243681ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:10:50.345524Z","caller":"traceutil/trace.go:171","msg":"trace[439144383] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"279.177432ms","start":"2026-05-06T19:10:50.066337Z","end":"2026-05-06T19:10:50.345514Z","steps":["trace[439144383] 'process raft request' (duration: 278.978066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:50.345704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.942273ms","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-05-06T19:10:50.345742Z","caller":"traceutil/trace.go:171","msg":"trace[268797635] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:697; }","duration":"280.992742ms","start":"2026-05-06T19:10:50.064738Z","end":"2026-05-06T19:10:50.34573Z","steps":["trace[268797635] 'agreement among raft nodes before linearized reading' (duration: 280.883994ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:50.425226Z","caller":"traceutil/trace.go:171","msg":"trace[177412902] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"303.429247ms","start":"2026-05-06T19:10:50.121778Z","end":"2026-05-06T19:10:50.425207Z","steps":["trace[177412902] 'process raft request' (duration: 303.335349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:50.42532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.359425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T19:10:50.425345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:50.121758Z","time spent":"303.526786ms","remote":"127.0.0.1:55600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4414,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T19:10:50.42537Z","caller":"traceutil/trace.go:171","msg":"trace[2014208306] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:698; }","duration":"299.438353ms","start":"2026-05-06T19:10:50.125921Z","end":"2026-05-06T19:10:50.425359Z","steps":["trace[2014208306] 'agreement among raft nodes before linearized reading' (duration: 299.309686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:50.425523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.394327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:10:50.425555Z","caller":"traceutil/trace.go:171","msg":"trace[1241999870] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:698; }","duration":"129.472505ms","start":"2026-05-06T19:10:50.296074Z","end":"2026-05-06T19:10:50.425547Z","steps":["trace[1241999870] 'agreement among raft nodes before linearized reading' (duration: 129.423916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:50.425683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.318356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:10:50.425716Z","caller":"traceutil/trace.go:171","msg":"trace[1493848890] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:698; }","duration":"226.369936ms","start":"2026-05-06T19:10:50.199338Z","end":"2026-05-06T19:10:50.425708Z","steps":["trace[1493848890] 'agreement among raft nodes before linearized reading' (duration: 226.321456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:50.425761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.862993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:10:50.425832Z","caller":"traceutil/trace.go:171","msg":"trace[186646053] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:698; }","duration":"249.901572ms","start":"2026-05-06T19:10:50.175887Z","end":"2026-05-06T19:10:50.425789Z","steps":["trace[186646053] 'agreement among raft nodes before linearized reading' (duration: 249.847663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:50.426011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.984285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:10:50.426045Z","caller":"traceutil/trace.go:171","msg":"trace[530561962] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:698; }","duration":"300.019604ms","start":"2026-05-06T19:10:50.126017Z","end":"2026-05-06T19:10:50.426037Z","steps":["trace[530561962] 'agreement among raft nodes before linearized reading' (duration: 299.972185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:50.426076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:10:50.126001Z","time spent":"300.065764ms","remote":"127.0.0.1:36726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-05-06T19:10:50.426199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.211448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:10:50.426232Z","caller":"traceutil/trace.go:171","msg":"trace[2119047981] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:698; }","duration":"296.261907ms","start":"2026-05-06T19:10:50.129961Z","end":"2026-05-06T19:10:50.426223Z","steps":["trace[2119047981] 'agreement among raft nodes before linearized reading' (duration: 296.211388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:50.426345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.883783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:10:50.426379Z","caller":"traceutil/trace.go:171","msg":"trace[1648694247] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:698; }","duration":"240.951991ms","start":"2026-05-06T19:10:50.185418Z","end":"2026-05-06T19:10:50.42637Z","steps":["trace[1648694247] 'agreement among raft nodes before linearized reading' (duration: 240.903142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:50.426505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.778221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:10:50.426539Z","caller":"traceutil/trace.go:171","msg":"trace[1741960614] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:698; }","duration":"227.814411ms","start":"2026-05-06T19:10:50.198715Z","end":"2026-05-06T19:10:50.42653Z","steps":["trace[1741960614] 'agreement among raft nodes before linearized reading' (duration: 227.763732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:50.426603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.147919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:10:50.426634Z","caller":"traceutil/trace.go:171","msg":"trace[1819000259] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:698; }","duration":"241.206447ms","start":"2026-05-06T19:10:50.185418Z","end":"2026-05-06T19:10:50.426625Z","steps":["trace[1819000259] 'agreement among raft nodes before linearized reading' (duration: 241.154738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:50.426878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.155642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:10:50.427399Z","caller":"traceutil/trace.go:171","msg":"trace[1373484137] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:698; }","duration":"228.126216ms","start":"2026-05-06T19:10:50.198776Z","end":"2026-05-06T19:10:50.426902Z","steps":["trace[1373484137] 'agreement among raft nodes before linearized reading' (duration: 227.176152ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:50.529951Z","caller":"traceutil/trace.go:171","msg":"trace[2032038365] linearizableReadLoop","detail":"{readStateIndex:730; appliedIndex:728; }","duration":"102.548776ms","start":"2026-05-06T19:10:50.427388Z","end":"2026-05-06T19:10:50.529937Z","steps":["trace[2032038365] 'read index received' (duration: 102.412218ms)","trace[2032038365] 'applied index is now lower than readState.Index' (duration: 136.048µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:10:50.530014Z","caller":"traceutil/trace.go:171","msg":"trace[449387948] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"181.298999ms","start":"2026-05-06T19:10:50.348675Z","end":"2026-05-06T19:10:50.529973Z","steps":["trace[449387948] 'process raft request' (duration: 181.108952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:50.530191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.773402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:10:50.530274Z","caller":"traceutil/trace.go:171","msg":"trace[838896571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:700; }","duration":"102.926479ms","start":"2026-05-06T19:10:50.427329Z","end":"2026-05-06T19:10:50.530256Z","steps":["trace[838896571] 'agreement among raft nodes before linearized reading' (duration: 102.762112ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:10:50.530153Z","caller":"traceutil/trace.go:171","msg":"trace[673148173] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"142.845941ms","start":"2026-05-06T19:10:50.387238Z","end":"2026-05-06T19:10:50.530084Z","steps":["trace[673148173] 'process raft request' (duration: 142.669614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:58.491416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.001964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:10:58.491507Z","caller":"traceutil/trace.go:171","msg":"trace[1600144912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:758; }","duration":"196.137442ms","start":"2026-05-06T19:10:58.295348Z","end":"2026-05-06T19:10:58.491486Z","steps":["trace[1600144912] 'range keys from in-memory index tree' (duration: 195.913486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:58.491595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.984981ms","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-05-06T19:10:58.491637Z","caller":"traceutil/trace.go:171","msg":"trace[2102690740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:758; }","duration":"116.05602ms","start":"2026-05-06T19:10:58.375566Z","end":"2026-05-06T19:10:58.491622Z","steps":["trace[2102690740] 'range keys from in-memory index tree' (duration: 115.859763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:10:58.491508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.077009ms","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-05-06T19:10:58.491747Z","caller":"traceutil/trace.go:171","msg":"trace[1288034035] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:758; }","duration":"116.347515ms","start":"2026-05-06T19:10:58.375384Z","end":"2026-05-06T19:10:58.491732Z","steps":["trace[1288034035] 'range keys from in-memory index tree' (duration: 115.863183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.093973Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967604408009470839,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T19:11:01.594169Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967604408009470839,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T19:11:01.715821Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.134055619s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T19:11:01.716287Z","caller":"traceutil/trace.go:171","msg":"trace[1797744207] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:807; }","duration":"1.122725744s","start":"2026-05-06T19:11:00.5935Z","end":"2026-05-06T19:11:01.716226Z","steps":["trace[1797744207] 'read index received' (duration: 1.122469989s)","trace[1797744207] 'applied index is now lower than readState.Index' (duration: 254.005µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T19:11:01.716574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.013007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T19:11:01.716648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042992143s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:11:01.716666Z","caller":"traceutil/trace.go:171","msg":"trace[1742841946] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:774; }","duration":"922.158335ms","start":"2026-05-06T19:11:00.794483Z","end":"2026-05-06T19:11:01.716642Z","steps":["trace[1742841946] 'agreement among raft nodes before linearized reading' (duration: 921.968348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.71669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.123152928s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:11:01.716693Z","caller":"traceutil/trace.go:171","msg":"trace[1417991447] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:774; }","duration":"1.043041062s","start":"2026-05-06T19:11:00.673633Z","end":"2026-05-06T19:11:01.716674Z","steps":["trace[1417991447] 'agreement among raft nodes before linearized reading' (duration: 1.042985733s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.716665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020357374s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T19:11:01.716733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.79448Z","time spent":"922.235173ms","remote":"127.0.0.1:37230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-06T19:11:01.716735Z","caller":"traceutil/trace.go:171","msg":"trace[1364675588] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:774; }","duration":"1.123256846s","start":"2026-05-06T19:11:00.593464Z","end":"2026-05-06T19:11:01.716721Z","steps":["trace[1364675588] 'agreement among raft nodes before linearized reading' (duration: 1.123165798s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.716783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.647159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T19:11:01.716802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.593444Z","time spent":"1.123345494s","remote":"127.0.0.1:37148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-06T19:11:01.716816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.043334107s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:11:01.716829Z","caller":"traceutil/trace.go:171","msg":"trace[725325627] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:774; }","duration":"870.691979ms","start":"2026-05-06T19:11:00.846119Z","end":"2026-05-06T19:11:01.716811Z","steps":["trace[725325627] 'agreement among raft nodes before linearized reading' (duration: 870.64341ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:11:01.716847Z","caller":"traceutil/trace.go:171","msg":"trace[660539896] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:774; }","duration":"1.043389476s","start":"2026-05-06T19:11:00.67345Z","end":"2026-05-06T19:11:01.716839Z","steps":["trace[660539896] 'agreement among raft nodes before linearized reading' (duration: 1.043321258s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.716873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.846114Z","time spent":"870.749628ms","remote":"127.0.0.1:37262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-05-06T19:11:01.716884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.673436Z","time spent":"1.043440685s","remote":"127.0.0.1:37182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-06T19:11:01.716921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.39454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:11:01.717007Z","caller":"traceutil/trace.go:171","msg":"trace[59796167] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:774; }","duration":"922.499698ms","start":"2026-05-06T19:11:00.794459Z","end":"2026-05-06T19:11:01.716958Z","steps":["trace[59796167] 'agreement among raft nodes before linearized reading' (duration: 922.38109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.717099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.489035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T19:11:01.717106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.794452Z","time spent":"922.591277ms","remote":"127.0.0.1:37230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"info","ts":"2026-05-06T19:11:01.717131Z","caller":"traceutil/trace.go:171","msg":"trace[269609317] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:774; }","duration":"871.535105ms","start":"2026-05-06T19:11:00.845587Z","end":"2026-05-06T19:11:01.717122Z","steps":["trace[269609317] 'agreement among raft nodes before linearized reading' (duration: 871.488146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.717172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.845577Z","time spent":"871.586864ms","remote":"127.0.0.1:37262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-05-06T19:11:01.71713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.043655402s","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-05-06T19:11:01.717173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.488183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T19:11:01.717207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.796223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:11:01.717208Z","caller":"traceutil/trace.go:171","msg":"trace[195071123] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:774; }","duration":"1.043733551s","start":"2026-05-06T19:11:00.673465Z","end":"2026-05-06T19:11:01.717199Z","steps":["trace[195071123] 'agreement among raft nodes before linearized reading' (duration: 1.043640082s)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:11:01.717225Z","caller":"traceutil/trace.go:171","msg":"trace[234813319] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:774; }","duration":"887.562831ms","start":"2026-05-06T19:11:00.829648Z","end":"2026-05-06T19:11:01.717211Z","steps":["trace[234813319] 'agreement among raft nodes before linearized reading' (duration: 887.487393ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:11:01.717236Z","caller":"traceutil/trace.go:171","msg":"trace[2008655378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:774; }","duration":"421.844822ms","start":"2026-05-06T19:11:01.295382Z","end":"2026-05-06T19:11:01.717227Z","steps":["trace[2008655378] 'agreement among raft nodes before linearized reading' (duration: 421.809123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.717243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.673426Z","time spent":"1.043809139s","remote":"127.0.0.1:37182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-06T19:11:01.717263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:01.295366Z","time spent":"421.892672ms","remote":"127.0.0.1:55450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T19:11:01.717267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.829638Z","time spent":"887.616251ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-05-06T19:11:01.717304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.81616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:11:01.717332Z","caller":"traceutil/trace.go:171","msg":"trace[255934554] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:774; }","duration":"871.849149ms","start":"2026-05-06T19:11:00.845475Z","end":"2026-05-06T19:11:01.717324Z","steps":["trace[255934554] 'agreement among raft nodes before linearized reading' (duration: 871.80271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.71736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.845423Z","time spent":"871.929808ms","remote":"127.0.0.1:37262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-05-06T19:11:01.717371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.073181668s","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":"info","ts":"2026-05-06T19:11:01.717402Z","caller":"traceutil/trace.go:171","msg":"trace[1587306265] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:774; }","duration":"1.073224196s","start":"2026-05-06T19:11:00.644168Z","end":"2026-05-06T19:11:01.717392Z","steps":["trace[1587306265] 'agreement among raft nodes before linearized reading' (duration: 1.073177817s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.717432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.644164Z","time spent":"1.073259876s","remote":"127.0.0.1:37178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-06T19:11:01.717444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.535991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-06T19:11:01.717169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.888861ms","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-05-06T19:11:01.717472Z","caller":"traceutil/trace.go:171","msg":"trace[1018875457] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:774; }","duration":"433.58918ms","start":"2026-05-06T19:11:01.283875Z","end":"2026-05-06T19:11:01.717464Z","steps":["trace[1018875457] 'agreement among raft nodes before linearized reading' (duration: 433.504142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.717502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:01.283859Z","time spent":"433.637289ms","remote":"127.0.0.1:55474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.78\" "} {"level":"warn","ts":"2026-05-06T19:11:01.717491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.080534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:11:01.717501Z","caller":"traceutil/trace.go:171","msg":"trace[1876301084] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:774; }","duration":"306.250906ms","start":"2026-05-06T19:11:01.41124Z","end":"2026-05-06T19:11:01.717491Z","steps":["trace[1876301084] 'agreement among raft nodes before linearized reading' (duration: 305.807353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.717542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:01.411223Z","time spent":"306.306804ms","remote":"127.0.0.1:55672","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-05-06T19:11:01.71754Z","caller":"traceutil/trace.go:171","msg":"trace[639392871] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:774; }","duration":"905.146073ms","start":"2026-05-06T19:11:00.812381Z","end":"2026-05-06T19:11:01.717527Z","steps":["trace[639392871] 'agreement among raft nodes before linearized reading' (duration: 905.074274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.717552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.073446033s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T19:11:01.717584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.812372Z","time spent":"905.199382ms","remote":"127.0.0.1:37232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-05-06T19:11:01.71677Z","caller":"traceutil/trace.go:171","msg":"trace[1944133674] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:774; }","duration":"1.020504272s","start":"2026-05-06T19:11:00.696249Z","end":"2026-05-06T19:11:01.716754Z","steps":["trace[1944133674] 'agreement among raft nodes before linearized reading' (duration: 1.020269866s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.716664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.880509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T19:11:01.717605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.883141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T19:11:01.717627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.696229Z","time spent":"1.021389797s","remote":"127.0.0.1:37192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 "} {"level":"info","ts":"2026-05-06T19:11:01.717646Z","caller":"traceutil/trace.go:171","msg":"trace[400056343] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:774; }","duration":"886.905532ms","start":"2026-05-06T19:11:00.83073Z","end":"2026-05-06T19:11:01.717635Z","steps":["trace[400056343] 'agreement among raft nodes before linearized reading' (duration: 885.84363ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:11:01.717662Z","caller":"traceutil/trace.go:171","msg":"trace[908189458] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:774; }","duration":"855.94496ms","start":"2026-05-06T19:11:00.861699Z","end":"2026-05-06T19:11:01.717644Z","steps":["trace[908189458] 'agreement among raft nodes before linearized reading' (duration: 855.825152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.717683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.830723Z","time spent":"886.949292ms","remote":"127.0.0.1:37232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"warn","ts":"2026-05-06T19:11:01.7177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.861659Z","time spent":"856.032938ms","remote":"127.0.0.1:37276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-05-06T19:11:01.717733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080645715s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-bfj5z\" ","response":"range_response_count:1 size:3964"} {"level":"warn","ts":"2026-05-06T19:11:01.716745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.673627Z","time spent":"1.043104611s","remote":"127.0.0.1:37182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 "} {"level":"info","ts":"2026-05-06T19:11:01.717769Z","caller":"traceutil/trace.go:171","msg":"trace[1714286629] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-bfj5z; range_end:; response_count:1; response_revision:774; }","duration":"1.080706243s","start":"2026-05-06T19:11:00.637054Z","end":"2026-05-06T19:11:01.71776Z","steps":["trace[1714286629] 'agreement among raft nodes before linearized reading' (duration: 1.080576166s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.716599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.939717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:11:01.717842Z","caller":"traceutil/trace.go:171","msg":"trace[489586937] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:774; }","duration":"856.173867ms","start":"2026-05-06T19:11:00.861625Z","end":"2026-05-06T19:11:01.717799Z","steps":["trace[489586937] 'agreement among raft nodes before linearized reading' (duration: 854.919967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.717894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.861617Z","time spent":"856.264485ms","remote":"127.0.0.1:37276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-05-06T19:11:01.717891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.500287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:11:01.717606Z","caller":"traceutil/trace.go:171","msg":"trace[587517025] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:774; }","duration":"1.073503082s","start":"2026-05-06T19:11:00.64408Z","end":"2026-05-06T19:11:01.717583Z","steps":["trace[587517025] 'agreement among raft nodes before linearized reading' (duration: 1.073446023s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.717925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.940319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:11:01.717944Z","caller":"traceutil/trace.go:171","msg":"trace[182842714] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:774; }","duration":"905.571077ms","start":"2026-05-06T19:11:00.812358Z","end":"2026-05-06T19:11:01.717929Z","steps":["trace[182842714] 'agreement among raft nodes before linearized reading' (duration: 905.490718ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:11:01.717958Z","caller":"traceutil/trace.go:171","msg":"trace[570938816] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:774; }","duration":"835.989297ms","start":"2026-05-06T19:11:00.881959Z","end":"2026-05-06T19:11:01.717948Z","steps":["trace[570938816] 'agreement among raft nodes before linearized reading' (duration: 835.938658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.717968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.644072Z","time spent":"1.073874456s","remote":"127.0.0.1:37178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-06T19:11:01.717992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.881949Z","time spent":"836.034527ms","remote":"127.0.0.1:37280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-05-06T19:11:01.718043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.950132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T19:11:01.718179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.36476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T19:11:01.718237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110504796s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:11:01.718241Z","caller":"traceutil/trace.go:171","msg":"trace[799903009] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:774; }","duration":"855.444859ms","start":"2026-05-06T19:11:00.862781Z","end":"2026-05-06T19:11:01.718226Z","steps":["trace[799903009] 'agreement among raft nodes before linearized reading' (duration: 855.35105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.717988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.812345Z","time spent":"905.629606ms","remote":"127.0.0.1:37232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"info","ts":"2026-05-06T19:11:01.718268Z","caller":"traceutil/trace.go:171","msg":"trace[1216556930] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:774; }","duration":"1.110558734s","start":"2026-05-06T19:11:00.607699Z","end":"2026-05-06T19:11:01.718258Z","steps":["trace[1216556930] 'agreement among raft nodes before linearized reading' (duration: 1.110519305s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.717474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109778377s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T19:11:01.718294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.862767Z","time spent":"855.510078ms","remote":"127.0.0.1:37276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-05-06T19:11:01.718303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.607682Z","time spent":"1.110612054s","remote":"127.0.0.1:37164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"info","ts":"2026-05-06T19:11:01.718319Z","caller":"traceutil/trace.go:171","msg":"trace[1634670236] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:774; }","duration":"1.110673033s","start":"2026-05-06T19:11:00.60763Z","end":"2026-05-06T19:11:01.718303Z","steps":["trace[1634670236] 'agreement among raft nodes before linearized reading' (duration: 1.109739398s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.718343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.009911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T19:11:01.718373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.607617Z","time spent":"1.110736942s","remote":"127.0.0.1:37164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-05-06T19:11:01.718384Z","caller":"traceutil/trace.go:171","msg":"trace[340607254] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:774; }","duration":"965.07868ms","start":"2026-05-06T19:11:00.753295Z","end":"2026-05-06T19:11:01.718374Z","steps":["trace[340607254] 'agreement among raft nodes before linearized reading' (duration: 965.013841ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:11:01.718188Z","caller":"traceutil/trace.go:171","msg":"trace[1347205483] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:774; }","duration":"923.100269ms","start":"2026-05-06T19:11:00.795073Z","end":"2026-05-06T19:11:01.718174Z","steps":["trace[1347205483] 'agreement among raft nodes before linearized reading' (duration: 922.938601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.718384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.300773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T19:11:01.718449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.637022Z","time spent":"1.081418322s","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-bfj5z\" "} {"level":"warn","ts":"2026-05-06T19:11:01.718467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.978531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T19:11:01.718195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109861125s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:11:01.718522Z","caller":"traceutil/trace.go:171","msg":"trace[1561870994] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:774; }","duration":"837.04564ms","start":"2026-05-06T19:11:00.881465Z","end":"2026-05-06T19:11:01.718511Z","steps":["trace[1561870994] 'agreement among raft nodes before linearized reading' (duration: 836.965971ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:11:01.718477Z","caller":"traceutil/trace.go:171","msg":"trace[64978869] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:774; }","duration":"939.405272ms","start":"2026-05-06T19:11:00.779055Z","end":"2026-05-06T19:11:01.718461Z","steps":["trace[64978869] 'agreement among raft nodes before linearized reading' (duration: 939.241405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.718567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.881458Z","time spent":"837.098029ms","remote":"127.0.0.1:37280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-05-06T19:11:01.717587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.123252046s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T19:11:01.718607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.653258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T19:11:01.718586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.77905Z","time spent":"939.51599ms","remote":"127.0.0.1:37218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 "} {"level":"info","ts":"2026-05-06T19:11:01.718638Z","caller":"traceutil/trace.go:171","msg":"trace[614539067] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:774; }","duration":"964.715826ms","start":"2026-05-06T19:11:00.753914Z","end":"2026-05-06T19:11:01.718629Z","steps":["trace[614539067] 'agreement among raft nodes before linearized reading' (duration: 964.675217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.718673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.753893Z","time spent":"964.772306ms","remote":"127.0.0.1:37208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-06T19:11:01.718454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"940.058101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T19:11:01.718416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.753278Z","time spent":"965.12994ms","remote":"127.0.0.1:37208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-06T19:11:01.71872Z","caller":"traceutil/trace.go:171","msg":"trace[468630491] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:774; }","duration":"940.328717ms","start":"2026-05-06T19:11:00.778379Z","end":"2026-05-06T19:11:01.718708Z","steps":["trace[468630491] 'agreement among raft nodes before linearized reading' (duration: 940.054571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.718465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.795068Z","time spent":"923.378364ms","remote":"127.0.0.1:37230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-06T19:11:01.718761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.778376Z","time spent":"940.370106ms","remote":"127.0.0.1:37218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-06T19:11:01.718762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.328536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:11:01.718806Z","caller":"traceutil/trace.go:171","msg":"trace[1557610839] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:774; }","duration":"965.407445ms","start":"2026-05-06T19:11:00.753386Z","end":"2026-05-06T19:11:01.718794Z","steps":["trace[1557610839] 'agreement among raft nodes before linearized reading' (duration: 965.336557ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:11:01.718625Z","caller":"traceutil/trace.go:171","msg":"trace[1959991018] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:774; }","duration":"1.12429242s","start":"2026-05-06T19:11:00.594312Z","end":"2026-05-06T19:11:01.718604Z","steps":["trace[1959991018] 'agreement among raft nodes before linearized reading' (duration: 1.123246707s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.718841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.75336Z","time spent":"965.472274ms","remote":"127.0.0.1:37208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-06T19:11:01.718853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.594306Z","time spent":"1.124532976s","remote":"127.0.0.1:37148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-06T19:11:01.718862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.468654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T19:11:01.716584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.12297s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:11:01.718533Z","caller":"traceutil/trace.go:171","msg":"trace[1207822656] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:774; }","duration":"1.11020658s","start":"2026-05-06T19:11:00.608309Z","end":"2026-05-06T19:11:01.718515Z","steps":["trace[1207822656] 'agreement among raft nodes before linearized reading' (duration: 1.109849976s)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:11:01.718932Z","caller":"traceutil/trace.go:171","msg":"trace[572534500] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:774; }","duration":"1.125372872s","start":"2026-05-06T19:11:00.59354Z","end":"2026-05-06T19:11:01.718912Z","steps":["trace[572534500] 'agreement among raft nodes before linearized reading' (duration: 1.122900312s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.718951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.608299Z","time spent":"1.110641733s","remote":"127.0.0.1:37164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"warn","ts":"2026-05-06T19:11:01.718986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.593535Z","time spent":"1.125433851s","remote":"127.0.0.1:37148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-06T19:11:01.719082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022093095s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:11:01.719119Z","caller":"traceutil/trace.go:171","msg":"trace[1340277629] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:774; }","duration":"1.022154964s","start":"2026-05-06T19:11:00.696954Z","end":"2026-05-06T19:11:01.719109Z","steps":["trace[1340277629] 'agreement among raft nodes before linearized reading' (duration: 1.022093965s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.718569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"940.192559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T19:11:01.719149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.696938Z","time spent":"1.022203364s","remote":"127.0.0.1:37192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 "} {"level":"warn","ts":"2026-05-06T19:11:01.716744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.21329ms","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-05-06T19:11:01.71916Z","caller":"traceutil/trace.go:171","msg":"trace[1072533350] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:774; }","duration":"940.837549ms","start":"2026-05-06T19:11:00.778312Z","end":"2026-05-06T19:11:01.71915Z","steps":["trace[1072533350] 'agreement among raft nodes before linearized reading' (duration: 940.1598ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:11:01.719182Z","caller":"traceutil/trace.go:171","msg":"trace[1959076627] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:774; }","duration":"862.6792ms","start":"2026-05-06T19:11:00.856496Z","end":"2026-05-06T19:11:01.719175Z","steps":["trace[1959076627] 'agreement among raft nodes before linearized reading' (duration: 860.22007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.719198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.778298Z","time spent":"940.889627ms","remote":"127.0.0.1:37218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-06T19:11:01.719209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.856484Z","time spent":"862.717199ms","remote":"127.0.0.1:55686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-05-06T19:11:01.717106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.389724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T19:11:01.71928Z","caller":"traceutil/trace.go:171","msg":"trace[229390113] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:774; }","duration":"889.590379ms","start":"2026-05-06T19:11:00.829677Z","end":"2026-05-06T19:11:01.719267Z","steps":["trace[229390113] 'agreement among raft nodes before linearized reading' (duration: 887.373735ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:11:01.718911Z","caller":"traceutil/trace.go:171","msg":"trace[1778927764] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:774; }","duration":"837.542782ms","start":"2026-05-06T19:11:00.881355Z","end":"2026-05-06T19:11:01.718897Z","steps":["trace[1778927764] 'agreement among raft nodes before linearized reading' (duration: 837.449553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.71932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.82966Z","time spent":"889.646867ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-06T19:11:01.719317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022984341s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T19:11:01.71933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.881338Z","time spent":"837.976185ms","remote":"127.0.0.1:37280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"info","ts":"2026-05-06T19:11:01.719357Z","caller":"traceutil/trace.go:171","msg":"trace[886730457] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:774; }","duration":"1.023041969s","start":"2026-05-06T19:11:00.696305Z","end":"2026-05-06T19:11:01.719347Z","steps":["trace[886730457] 'agreement among raft nodes before linearized reading' (duration: 1.02298215s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.719388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.6963Z","time spent":"1.023079749s","remote":"127.0.0.1:37192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-05-06T19:11:01.719651Z","caller":"traceutil/trace.go:171","msg":"trace[1978156809] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"1.134728347s","start":"2026-05-06T19:11:00.581679Z","end":"2026-05-06T19:11:01.716407Z","steps":["trace[1978156809] 'process raft request' (duration: 1.134385903s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:11:01.719833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:11:00.581664Z","time spent":"1.138080483s","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T19:11:01.858432Z","caller":"traceutil/trace.go:171","msg":"trace[1838333116] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"129.193424ms","start":"2026-05-06T19:11:01.729221Z","end":"2026-05-06T19:11:01.858414Z","steps":["trace[1838333116] 'process raft request' (duration: 129.147365ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:11:01.859096Z","caller":"traceutil/trace.go:171","msg":"trace[48432898] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"130.652451ms","start":"2026-05-06T19:11:01.72842Z","end":"2026-05-06T19:11:01.859072Z","steps":["trace[48432898] 'process raft request' (duration: 129.769885ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:11:20.012385Z","caller":"traceutil/trace.go:171","msg":"trace[135626496] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"103.67872ms","start":"2026-05-06T19:11:19.908672Z","end":"2026-05-06T19:11:20.012351Z","steps":["trace[135626496] 'process raft request' (duration: 103.448163ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:11:20.156001Z","caller":"traceutil/trace.go:171","msg":"trace[1003130094] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"137.741896ms","start":"2026-05-06T19:11:20.018231Z","end":"2026-05-06T19:11:20.155972Z","steps":["trace[1003130094] 'process raft request' (duration: 78.72722ms)","trace[1003130094] 'compare' (duration: 58.870328ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:12:41.255213Z","caller":"traceutil/trace.go:171","msg":"trace[571219324] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"114.918189ms","start":"2026-05-06T19:12:41.140268Z","end":"2026-05-06T19:12:41.255186Z","steps":["trace[571219324] 'process raft request' (duration: 114.728802ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:12:51.426736Z","caller":"traceutil/trace.go:171","msg":"trace[119850163] linearizableReadLoop","detail":"{readStateIndex:1358; appliedIndex:1356; }","duration":"115.57487ms","start":"2026-05-06T19:12:51.311147Z","end":"2026-05-06T19:12:51.426722Z","steps":["trace[119850163] 'read index received' (duration: 113.820117ms)","trace[119850163] 'applied index is now lower than readState.Index' (duration: 1.754103ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:12:51.426823Z","caller":"traceutil/trace.go:171","msg":"trace[904623983] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"119.497489ms","start":"2026-05-06T19:12:51.307319Z","end":"2026-05-06T19:12:51.426816Z","steps":["trace[904623983] 'process raft request' (duration: 119.281302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:12:51.426985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.817536ms","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-05-06T19:12:51.427094Z","caller":"traceutil/trace.go:171","msg":"trace[1758469886] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1298; }","duration":"115.930074ms","start":"2026-05-06T19:12:51.311112Z","end":"2026-05-06T19:12:51.427042Z","steps":["trace[1758469886] 'agreement among raft nodes before linearized reading' (duration: 115.718397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:12:51.49854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.016073ms","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-05-06T19:12:51.498623Z","caller":"traceutil/trace.go:171","msg":"trace[387481106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1300; }","duration":"134.106182ms","start":"2026-05-06T19:12:51.364497Z","end":"2026-05-06T19:12:51.498603Z","steps":["trace[387481106] 'agreement among raft nodes before linearized reading' (duration: 133.850146ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T19:13:04.01913Z","caller":"traceutil/trace.go:171","msg":"trace[1395303683] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"352.671739ms","start":"2026-05-06T19:13:03.666434Z","end":"2026-05-06T19:13:04.019106Z","steps":["trace[1395303683] 'process raft request' (duration: 345.263635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:13:04.019296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T19:13:03.666424Z","time spent":"352.783398ms","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T19:13:04.99739Z","caller":"traceutil/trace.go:171","msg":"trace[1508528397] linearizableReadLoop","detail":"{readStateIndex:1410; appliedIndex:1409; }","duration":"253.052422ms","start":"2026-05-06T19:13:04.744305Z","end":"2026-05-06T19:13:04.997357Z","steps":["trace[1508528397] 'read index received' (duration: 252.845765ms)","trace[1508528397] 'applied index is now lower than readState.Index' (duration: 205.627µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T19:13:04.997434Z","caller":"traceutil/trace.go:171","msg":"trace[1662025571] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"297.236523ms","start":"2026-05-06T19:13:04.700164Z","end":"2026-05-06T19:13:04.9974Z","steps":["trace[1662025571] 'process raft request' (duration: 297.043776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:13:04.997584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.976874ms","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-05-06T19:13:04.997632Z","caller":"traceutil/trace.go:171","msg":"trace[122390524] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1348; }","duration":"179.045313ms","start":"2026-05-06T19:13:04.818574Z","end":"2026-05-06T19:13:04.997619Z","steps":["trace[122390524] 'agreement among raft nodes before linearized reading' (duration: 178.949544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:13:04.997735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.415066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-06T19:13:04.997842Z","caller":"traceutil/trace.go:171","msg":"trace[575940969] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1348; }","duration":"253.554154ms","start":"2026-05-06T19:13:04.744244Z","end":"2026-05-06T19:13:04.997798Z","steps":["trace[575940969] 'agreement among raft nodes before linearized reading' (duration: 253.269798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T19:13:04.997909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.233759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T19:13:04.997964Z","caller":"traceutil/trace.go:171","msg":"trace[334954608] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1348; }","duration":"118.337398ms","start":"2026-05-06T19:13:04.879614Z","end":"2026-05-06T19:13:04.997951Z","steps":["trace[334954608] 'agreement among raft nodes before linearized reading' (duration: 118.093972ms)"],"step_count":1}