{"level":"warn","ts":"2026-07-13T16:53:10.146076Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-13T16:53:10.146195Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169: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.169:2380","--initial-cluster=controller=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169:2380","--name=controller","--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-07-13T16:53:10.14627Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-13T16:53:10.146324Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-07-13T16:53:10.146349Z","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-07-13T16:53:10.147136Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-07-13T16:53:10.147276Z","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":"controller","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.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"controller=https://199.204.45.169: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-07-13T16:53:10.153834Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.52359ms"} {"level":"info","ts":"2026-07-13T16:53:10.162929Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-07-13T16:53:10.163036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-07-13T16:53:10.163096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-07-13T16:53:10.163113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-13T16:53:10.163152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-07-13T16:53:10.163204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-07-13T16:53:10.170588Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-13T16:53:10.172224Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-13T16:53:10.174458Z","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-07-13T16:53:10.177003Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-13T16:53:10.177133Z","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-07-13T16:53:10.177206Z","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-07-13T16:53:10.177215Z","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-07-13T16:53:10.177507Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-13T16:53:10.178446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-07-13T16:53:10.180795Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-07-13T16:53:10.181907Z","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-07-13T16:53:10.182088Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-07-13T16:53:10.182124Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-07-13T16:53:10.182186Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-13T16:53:10.182218Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-13T16:53:10.9639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-07-13T16:53:10.963971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-07-13T16:53:10.964018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-07-13T16:53:10.964045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-07-13T16:53:10.964058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-07-13T16:53:10.964109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-07-13T16:53:10.964125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-07-13T16:53:10.96544Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-13T16:53:10.966403Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:controller ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-07-13T16:53:10.966399Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-13T16:53:10.967024Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-07-13T16:53:10.967369Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-13T16:53:10.967425Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-13T16:53:10.967154Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-13T16:53:10.967212Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-13T16:53:10.96789Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-13T16:53:10.968043Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-07-13T16:53:10.970339Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-13T16:53:14.497366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.539444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-13T16:53:14.497546Z","caller":"traceutil/trace.go:171","msg":"trace[137652185] transaction","detail":"{read_only:false; response_revision:139; number_of_response:1; }","duration":"168.812312ms","start":"2026-07-13T16:53:14.328713Z","end":"2026-07-13T16:53:14.497525Z","steps":["trace[137652185] 'process raft request' (duration: 20.52498ms)","trace[137652185] 'compare' (duration: 147.408402ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T16:53:14.637694Z","caller":"traceutil/trace.go:171","msg":"trace[267519646] transaction","detail":"{read_only:false; response_revision:141; number_of_response:1; }","duration":"111.074233ms","start":"2026-07-13T16:53:14.52659Z","end":"2026-07-13T16:53:14.637665Z","steps":["trace[267519646] 'process raft request' (duration: 94.518924ms)","trace[267519646] 'compare' (duration: 16.184184ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T16:53:22.057031Z","caller":"traceutil/trace.go:171","msg":"trace[1480186205] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"101.792946ms","start":"2026-07-13T16:53:21.955195Z","end":"2026-07-13T16:53:22.056988Z","steps":["trace[1480186205] 'process raft request' (duration: 54.893032ms)","trace[1480186205] 'compare' (duration: 46.671882ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T16:53:22.078273Z","caller":"traceutil/trace.go:171","msg":"trace[54946784] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"116.739424ms","start":"2026-07-13T16:53:21.961511Z","end":"2026-07-13T16:53:22.07825Z","steps":["trace[54946784] 'process raft request' (duration: 116.529072ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:53:22.194433Z","caller":"traceutil/trace.go:171","msg":"trace[795849962] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"112.855133ms","start":"2026-07-13T16:53:22.081547Z","end":"2026-07-13T16:53:22.194402Z","steps":["trace[795849962] 'process raft request' (duration: 102.570048ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:53:22.194433Z","caller":"traceutil/trace.go:171","msg":"trace[2013985671] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"109.28287ms","start":"2026-07-13T16:53:22.085125Z","end":"2026-07-13T16:53:22.194408Z","steps":["trace[2013985671] 'process raft request' (duration: 109.214989ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:53:24.108119Z","caller":"traceutil/trace.go:171","msg":"trace[424706550] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"452.959308ms","start":"2026-07-13T16:53:23.655134Z","end":"2026-07-13T16:53:24.108093Z","steps":["trace[424706550] 'process raft request' (duration: 452.719956ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:24.108987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:23.65511Z","time spent":"453.397672ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7230,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T16:53:29.369318Z","caller":"traceutil/trace.go:171","msg":"trace[402071977] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"163.963837ms","start":"2026-07-13T16:53:29.205297Z","end":"2026-07-13T16:53:29.369261Z","steps":["trace[402071977] 'process raft request' (duration: 163.748735ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:53:29.605415Z","caller":"traceutil/trace.go:171","msg":"trace[1863710101] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"229.963844ms","start":"2026-07-13T16:53:29.37543Z","end":"2026-07-13T16:53:29.605394Z","steps":["trace[1863710101] 'process raft request' (duration: 229.841513ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:53:29.605892Z","caller":"traceutil/trace.go:171","msg":"trace[606093004] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:434; }","duration":"144.953635ms","start":"2026-07-13T16:53:29.460929Z","end":"2026-07-13T16:53:29.605883Z","steps":["trace[606093004] 'read index received' (duration: 144.949535ms)","trace[606093004] 'applied index is now lower than readState.Index' (duration: 3.3µs)"],"step_count":2} {"level":"warn","ts":"2026-07-13T16:53:29.606156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.216507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-controller\" ","response":"range_response_count:1 size:6906"} {"level":"info","ts":"2026-07-13T16:53:29.606188Z","caller":"traceutil/trace.go:171","msg":"trace[1845895542] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-controller; range_end:; response_count:1; response_revision:420; }","duration":"145.292297ms","start":"2026-07-13T16:53:29.460886Z","end":"2026-07-13T16:53:29.606179Z","steps":["trace[1845895542] 'agreement among raft nodes before linearized reading' (duration: 145.218507ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:30.110125Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605829972636047019,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-13T16:53:31.608411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999226691s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-13T16:53:31.608493Z","caller":"traceutil/trace.go:171","msg":"trace[1224202804] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999332362s","start":"2026-07-13T16:53:29.609141Z","end":"2026-07-13T16:53:31.608473Z","steps":["trace[1224202804] 'agreement among raft nodes before linearized reading' (duration: 1.999225351s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:31.608541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:29.609097Z","time spent":"1.999435264s","remote":"127.0.0.1:39350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/13 16:53:31 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-13T16:53:31.722483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203922577s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-13T16:53:31.7226Z","caller":"traceutil/trace.go:171","msg":"trace[1294645174] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"2.113689224s","start":"2026-07-13T16:53:29.608892Z","end":"2026-07-13T16:53:31.722582Z","steps":["trace[1294645174] 'process raft request' (duration: 909.601286ms)","trace[1294645174] 'compare' (duration: 1.203810886s)"],"step_count":2} {"level":"warn","ts":"2026-07-13T16:53:31.722676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:29.608824Z","time spent":"2.113827916s","remote":"127.0.0.1:39426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-13T16:53:31.723117Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.204499093s","expected-duration":"1s"} {"level":"info","ts":"2026-07-13T16:53:31.723452Z","caller":"traceutil/trace.go:171","msg":"trace[2137925752] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:434; }","duration":"2.11428274s","start":"2026-07-13T16:53:29.609147Z","end":"2026-07-13T16:53:31.72343Z","steps":["trace[2137925752] 'read index received' (duration: 909.292574ms)","trace[2137925752] 'applied index is now lower than readState.Index' (duration: 1.204989116s)"],"step_count":2} {"level":"info","ts":"2026-07-13T16:53:31.723566Z","caller":"traceutil/trace.go:171","msg":"trace[968766205] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"2.112359864s","start":"2026-07-13T16:53:29.611179Z","end":"2026-07-13T16:53:31.723539Z","steps":["trace[968766205] 'process raft request' (duration: 2.112159303s)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:53:31.723657Z","caller":"traceutil/trace.go:171","msg":"trace[115902199] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"2.109830822s","start":"2026-07-13T16:53:29.613814Z","end":"2026-07-13T16:53:31.723644Z","steps":["trace[115902199] 'process raft request' (duration: 2.10958179s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:31.723703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:29.61117Z","time spent":"2.112459945s","remote":"127.0.0.1:39742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-13T16:53:31.723759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:29.613806Z","time spent":"2.109882852s","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6681,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T16:53:31.723841Z","caller":"traceutil/trace.go:171","msg":"trace[1265005527] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"2.113888667s","start":"2026-07-13T16:53:29.609932Z","end":"2026-07-13T16:53:31.72382Z","steps":["trace[1265005527] 'process raft request' (duration: 2.113311563s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:31.724184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:29.609893Z","time spent":"2.11422016s","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3541,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-13T16:53:32.184697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.442995ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-13T16:53:32.185668Z","caller":"traceutil/trace.go:171","msg":"trace[99825012] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:438; }","duration":"462.038067ms","start":"2026-07-13T16:53:31.723611Z","end":"2026-07-13T16:53:32.185649Z","steps":["trace[99825012] 'read index received' (duration: 102.653454ms)","trace[99825012] 'applied index is now lower than readState.Index' (duration: 359.382453ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T16:53:32.185653Z","caller":"traceutil/trace.go:171","msg":"trace[1031673595] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"572.180515ms","start":"2026-07-13T16:53:31.613446Z","end":"2026-07-13T16:53:32.185627Z","steps":["trace[1031673595] 'process raft request' (duration: 571.930953ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:53:32.185807Z","caller":"traceutil/trace.go:171","msg":"trace[1671049953] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"453.848308ms","start":"2026-07-13T16:53:31.73193Z","end":"2026-07-13T16:53:32.185778Z","steps":["trace[1671049953] 'process raft request' (duration: 453.717017ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:32.185877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:31.613426Z","time spent":"572.371477ms","remote":"127.0.0.1:39426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":769,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-13T16:53:32.185989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:31.731914Z","time spent":"453.984299ms","remote":"127.0.0.1:39766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T16:53:32.186256Z","caller":"traceutil/trace.go:171","msg":"trace[515839242] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"455.911735ms","start":"2026-07-13T16:53:31.729802Z","end":"2026-07-13T16:53:32.185714Z","steps":["trace[515839242] 'process raft request' (duration: 455.793984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:32.185995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136985431s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-13T16:53:32.18636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.400732899s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-13T16:53:32.186388Z","caller":"traceutil/trace.go:171","msg":"trace[718493160] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:427; }","duration":"1.137420385s","start":"2026-07-13T16:53:31.048953Z","end":"2026-07-13T16:53:32.186374Z","steps":["trace[718493160] 'agreement among raft nodes before linearized reading' (duration: 1.136736639s)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:53:32.186418Z","caller":"traceutil/trace.go:171","msg":"trace[1573183477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:428; }","duration":"1.400803791s","start":"2026-07-13T16:53:30.785595Z","end":"2026-07-13T16:53:32.186399Z","steps":["trace[1573183477] 'agreement among raft nodes before linearized reading' (duration: 1.400165096s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:32.18644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:31.048935Z","time spent":"1.137491475s","remote":"127.0.0.1:39600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-13T16:53:32.186366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:31.729774Z","time spent":"456.528271ms","remote":"127.0.0.1:39426","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-07-13T16:53:32.186468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:30.785582Z","time spent":"1.400876701s","remote":"127.0.0.1:39600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-13T16:53:32.18659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.406130265s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-13T16:53:32.186623Z","caller":"traceutil/trace.go:171","msg":"trace[395284391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:428; }","duration":"1.406187817s","start":"2026-07-13T16:53:30.780427Z","end":"2026-07-13T16:53:32.186614Z","steps":["trace[395284391] 'agreement among raft nodes before linearized reading' (duration: 1.4053623s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:32.186656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:30.780408Z","time spent":"1.406241177s","remote":"127.0.0.1:39600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-13T16:53:32.186737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.537493823s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-13T16:53:32.186761Z","caller":"traceutil/trace.go:171","msg":"trace[390718050] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:428; }","duration":"1.537557654s","start":"2026-07-13T16:53:30.649196Z","end":"2026-07-13T16:53:32.186754Z","steps":["trace[390718050] 'agreement among raft nodes before linearized reading' (duration: 1.536621586s)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:32.186792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:30.649173Z","time spent":"1.537608584s","remote":"127.0.0.1:39600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-13T16:53:32.186892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.594901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T16:53:32.186954Z","caller":"traceutil/trace.go:171","msg":"trace[517470861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:428; }","duration":"570.665521ms","start":"2026-07-13T16:53:31.616257Z","end":"2026-07-13T16:53:32.186922Z","steps":["trace[517470861] 'agreement among raft nodes before linearized reading' (duration: 569.582792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:32.186987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:31.616248Z","time spent":"570.731861ms","remote":"127.0.0.1:39344","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-07-13T16:53:32.187Z","caller":"traceutil/trace.go:171","msg":"trace[1590979572] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"451.400098ms","start":"2026-07-13T16:53:31.735588Z","end":"2026-07-13T16:53:32.186988Z","steps":["trace[1590979572] 'process raft request' (duration: 450.122977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:32.18712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:31.735579Z","time spent":"451.452328ms","remote":"127.0.0.1:39766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3773,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T16:53:32.187214Z","caller":"traceutil/trace.go:171","msg":"trace[1382751710] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"451.507508ms","start":"2026-07-13T16:53:31.735693Z","end":"2026-07-13T16:53:32.1872Z","steps":["trace[1382751710] 'process raft request' (duration: 450.41675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:32.187305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:31.735674Z","time spent":"451.582229ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4015,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T16:53:32.187382Z","caller":"traceutil/trace.go:171","msg":"trace[842691130] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"451.252966ms","start":"2026-07-13T16:53:31.736116Z","end":"2026-07-13T16:53:32.187368Z","steps":["trace[842691130] 'process raft request' (duration: 451.014834ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:32.187458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:31.736106Z","time spent":"451.305946ms","remote":"127.0.0.1:39426","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-07-13T16:53:32.187543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.095013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-controller\" ","response":"range_response_count:1 size:4268"} {"level":"info","ts":"2026-07-13T16:53:32.187588Z","caller":"traceutil/trace.go:171","msg":"trace[1327400375] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-controller; range_end:; response_count:1; response_revision:430; }","duration":"458.171684ms","start":"2026-07-13T16:53:31.729404Z","end":"2026-07-13T16:53:32.187576Z","steps":["trace[1327400375] 'agreement among raft nodes before linearized reading' (duration: 457.919992ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:32.187618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:31.729388Z","time spent":"458.223375ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":4291,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-controller\" "} {"level":"warn","ts":"2026-07-13T16:53:43.098311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.495918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"warn","ts":"2026-07-13T16:53:43.09836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.769655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329936"} {"level":"info","ts":"2026-07-13T16:53:43.098427Z","caller":"traceutil/trace.go:171","msg":"trace[425979386] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:510; }","duration":"165.634969ms","start":"2026-07-13T16:53:42.93275Z","end":"2026-07-13T16:53:43.098385Z","steps":["trace[425979386] 'range keys from in-memory index tree' (duration: 165.377387ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:53:43.098453Z","caller":"traceutil/trace.go:171","msg":"trace[1512085770] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:510; }","duration":"119.903426ms","start":"2026-07-13T16:53:42.978528Z","end":"2026-07-13T16:53:43.098432Z","steps":["trace[1512085770] 'range keys from in-memory index tree' (duration: 119.588024ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:43.099214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.932892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-07-13T16:53:43.099385Z","caller":"traceutil/trace.go:171","msg":"trace[1382864300] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:510; }","duration":"165.471037ms","start":"2026-07-13T16:53:42.933901Z","end":"2026-07-13T16:53:43.099373Z","steps":["trace[1382864300] 'range keys from in-memory index tree' (duration: 164.882472ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:53:46.23614Z","caller":"traceutil/trace.go:171","msg":"trace[50352944] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"172.171117ms","start":"2026-07-13T16:53:46.063885Z","end":"2026-07-13T16:53:46.236057Z","steps":["trace[50352944] 'read index received' (duration: 139.335743ms)","trace[50352944] 'applied index is now lower than readState.Index' (duration: 32.834204ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T16:53:46.236209Z","caller":"traceutil/trace.go:171","msg":"trace[211346591] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"178.038301ms","start":"2026-07-13T16:53:46.058107Z","end":"2026-07-13T16:53:46.236145Z","steps":["trace[211346591] 'process raft request' (duration: 145.085055ms)","trace[211346591] 'compare' (duration: 32.702654ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T16:53:46.236387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.4817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-07-13T16:53:46.236434Z","caller":"traceutil/trace.go:171","msg":"trace[114547084] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:589; }","duration":"172.599492ms","start":"2026-07-13T16:53:46.063822Z","end":"2026-07-13T16:53:46.236421Z","steps":["trace[114547084] 'agreement among raft nodes before linearized reading' (duration: 172.39501ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:53:46.238354Z","caller":"traceutil/trace.go:171","msg":"trace[602144285] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"129.189777ms","start":"2026-07-13T16:53:46.109139Z","end":"2026-07-13T16:53:46.238329Z","steps":["trace[602144285] 'process raft request' (duration: 128.648083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:46.475467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.292018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-07-13T16:53:46.475541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.290942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tcproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31145"} {"level":"info","ts":"2026-07-13T16:53:46.47562Z","caller":"traceutil/trace.go:171","msg":"trace[1939070103] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:592; }","duration":"105.475569ms","start":"2026-07-13T16:53:46.370121Z","end":"2026-07-13T16:53:46.475596Z","steps":["trace[1939070103] 'range keys from in-memory index tree' (duration: 104.985776ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:53:46.475641Z","caller":"traceutil/trace.go:171","msg":"trace[49408760] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/tcproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:592; }","duration":"135.439793ms","start":"2026-07-13T16:53:46.340178Z","end":"2026-07-13T16:53:46.475618Z","steps":["trace[49408760] 'range keys from in-memory index tree' (duration: 134.795648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:46.475433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.352123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-13T16:53:46.475786Z","caller":"traceutil/trace.go:171","msg":"trace[965551260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:592; }","duration":"119.736606ms","start":"2026-07-13T16:53:46.356016Z","end":"2026-07-13T16:53:46.475752Z","steps":["trace[965551260] 'range keys from in-memory index tree' (duration: 118.94372ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:53:46.478331Z","caller":"traceutil/trace.go:171","msg":"trace[311536032] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"102.740089ms","start":"2026-07-13T16:53:46.375572Z","end":"2026-07-13T16:53:46.478312Z","steps":["trace[311536032] 'process raft request' (duration: 100.340801ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:53:48.036641Z","caller":"traceutil/trace.go:171","msg":"trace[1571516271] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"109.299201ms","start":"2026-07-13T16:53:47.927322Z","end":"2026-07-13T16:53:48.036621Z","steps":["trace[1571516271] 'process raft request' (duration: 109.18218ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:53:48.593747Z","caller":"traceutil/trace.go:171","msg":"trace[1140315384] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"102.481227ms","start":"2026-07-13T16:53:48.491242Z","end":"2026-07-13T16:53:48.593723Z","steps":["trace[1140315384] 'read index received' (duration: 101.100627ms)","trace[1140315384] 'applied index is now lower than readState.Index' (duration: 1.37953ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T16:53:48.593834Z","caller":"traceutil/trace.go:171","msg":"trace[826824444] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"123.899694ms","start":"2026-07-13T16:53:48.469896Z","end":"2026-07-13T16:53:48.593796Z","steps":["trace[826824444] 'process raft request' (duration: 122.726746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:48.593993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.737489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-13T16:53:48.594039Z","caller":"traceutil/trace.go:171","msg":"trace[246737166] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:602; }","duration":"102.82976ms","start":"2026-07-13T16:53:48.491199Z","end":"2026-07-13T16:53:48.594028Z","steps":["trace[246737166] 'agreement among raft nodes before linearized reading' (duration: 102.644369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:48.594055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.356045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-13T16:53:48.59415Z","caller":"traceutil/trace.go:171","msg":"trace[245579192] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:602; }","duration":"102.506617ms","start":"2026-07-13T16:53:48.491627Z","end":"2026-07-13T16:53:48.594133Z","steps":["trace[245579192] 'agreement among raft nodes before linearized reading' (duration: 102.285145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:50.225333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.161081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T16:53:50.225421Z","caller":"traceutil/trace.go:171","msg":"trace[44684442] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:609; }","duration":"549.277492ms","start":"2026-07-13T16:53:49.676123Z","end":"2026-07-13T16:53:50.225401Z","steps":["trace[44684442] 'range keys from in-memory index tree' (duration: 549.103641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:50.225461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:49.67611Z","time spent":"549.342852ms","remote":"127.0.0.1:39662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-07-13T16:53:51.204784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.847696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-07-13T16:53:51.20492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.301635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-13T16:53:51.204959Z","caller":"traceutil/trace.go:171","msg":"trace[146126496] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:610; }","duration":"586.067958ms","start":"2026-07-13T16:53:50.618872Z","end":"2026-07-13T16:53:51.20494Z","steps":["trace[146126496] 'range keys from in-memory index tree' (duration: 585.720066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:51.204972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.081068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-13T16:53:51.205011Z","caller":"traceutil/trace.go:171","msg":"trace[123050280] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:610; }","duration":"595.438586ms","start":"2026-07-13T16:53:50.609547Z","end":"2026-07-13T16:53:51.204986Z","steps":["trace[123050280] 'range keys from in-memory index tree' (duration: 595.090303ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:53:51.205112Z","caller":"traceutil/trace.go:171","msg":"trace[1316743747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:610; }","duration":"586.224709ms","start":"2026-07-13T16:53:50.618823Z","end":"2026-07-13T16:53:51.205048Z","steps":["trace[1316743747] 'range keys from in-memory index tree' (duration: 585.904747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:51.205147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:50.609528Z","time spent":"595.606497ms","remote":"127.0.0.1:39600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-13T16:53:51.205048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:50.618852Z","time spent":"586.183568ms","remote":"127.0.0.1:39600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-13T16:53:51.205191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:50.618806Z","time spent":"586.36956ms","remote":"127.0.0.1:39600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-13T16:53:51.288313Z","caller":"traceutil/trace.go:171","msg":"trace[1252457563] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"627.159174ms","start":"2026-07-13T16:53:50.66112Z","end":"2026-07-13T16:53:51.288279Z","steps":["trace[1252457563] 'process raft request' (duration: 621.400573ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:51.288437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:50.66109Z","time spent":"627.282035ms","remote":"127.0.0.1:39446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036201,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T16:53:51.451038Z","caller":"traceutil/trace.go:171","msg":"trace[88592954] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"240.502235ms","start":"2026-07-13T16:53:51.210514Z","end":"2026-07-13T16:53:51.451016Z","steps":["trace[88592954] 'process raft request' (duration: 240.346004ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:53:51.451377Z","caller":"traceutil/trace.go:171","msg":"trace[1813694313] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:632; }","duration":"407.139243ms","start":"2026-07-13T16:53:51.044203Z","end":"2026-07-13T16:53:51.451342Z","steps":["trace[1813694313] 'read index received' (duration: 236.748737ms)","trace[1813694313] 'applied index is now lower than readState.Index' (duration: 170.389626ms)"],"step_count":2} {"level":"info","ts":"2026-07-13T16:53:51.451414Z","caller":"traceutil/trace.go:171","msg":"trace[2015624704] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"240.780627ms","start":"2026-07-13T16:53:51.210609Z","end":"2026-07-13T16:53:51.45139Z","steps":["trace[2015624704] 'process raft request' (duration: 240.365684ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:53:51.451472Z","caller":"traceutil/trace.go:171","msg":"trace[2017771806] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"240.202483ms","start":"2026-07-13T16:53:51.211257Z","end":"2026-07-13T16:53:51.451459Z","steps":["trace[2017771806] 'process raft request' (duration: 239.984422ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:51.451597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.379745ms","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-07-13T16:53:51.451663Z","caller":"traceutil/trace.go:171","msg":"trace[1915187595] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:614; }","duration":"407.478865ms","start":"2026-07-13T16:53:51.044166Z","end":"2026-07-13T16:53:51.451645Z","steps":["trace[1915187595] 'agreement among raft nodes before linearized reading' (duration: 407.379665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:51.451703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:51.044148Z","time spent":"407.545456ms","remote":"127.0.0.1:33340","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-07-13T16:53:51.453428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.192368ms","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-07-13T16:53:51.453491Z","caller":"traceutil/trace.go:171","msg":"trace[1341033694] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:615; }","duration":"409.309069ms","start":"2026-07-13T16:53:51.044169Z","end":"2026-07-13T16:53:51.453478Z","steps":["trace[1341033694] 'agreement among raft nodes before linearized reading' (duration: 409.195958ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:51.453545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:51.044141Z","time spent":"409.38638ms","remote":"127.0.0.1:33340","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-07-13T16:53:51.453797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.384733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-13T16:53:51.453852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.224637ms","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-07-13T16:53:51.453883Z","caller":"traceutil/trace.go:171","msg":"trace[1004559832] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:615; }","duration":"344.277848ms","start":"2026-07-13T16:53:51.109589Z","end":"2026-07-13T16:53:51.453867Z","steps":["trace[1004559832] 'agreement among raft nodes before linearized reading' (duration: 344.247657ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:53:51.453894Z","caller":"traceutil/trace.go:171","msg":"trace[1183321863] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:615; }","duration":"204.497504ms","start":"2026-07-13T16:53:51.249384Z","end":"2026-07-13T16:53:51.453881Z","steps":["trace[1183321863] 'agreement among raft nodes before linearized reading' (duration: 204.366903ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:51.453912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:51.109574Z","time spent":"344.331918ms","remote":"127.0.0.1:33348","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-07-13T16:53:51.453988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.205032ms","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-07-13T16:53:51.454036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.407779ms","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-07-13T16:53:51.454078Z","caller":"traceutil/trace.go:171","msg":"trace[1748404919] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:615; }","duration":"344.464799ms","start":"2026-07-13T16:53:51.109589Z","end":"2026-07-13T16:53:51.454054Z","steps":["trace[1748404919] 'agreement among raft nodes before linearized reading' (duration: 344.427709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:51.4541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:51.109568Z","time spent":"344.526939ms","remote":"127.0.0.1:33348","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":"info","ts":"2026-07-13T16:53:51.454089Z","caller":"traceutil/trace.go:171","msg":"trace[1375825396] 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:615; }","duration":"313.365624ms","start":"2026-07-13T16:53:51.140685Z","end":"2026-07-13T16:53:51.454051Z","steps":["trace[1375825396] 'agreement among raft nodes before linearized reading' (duration: 313.088882ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:51.454148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:51.140667Z","time spent":"313.468614ms","remote":"127.0.0.1:33350","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":"warn","ts":"2026-07-13T16:53:51.4542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.600575ms","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-07-13T16:53:51.454221Z","caller":"traceutil/trace.go:171","msg":"trace[594427153] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:615; }","duration":"375.634525ms","start":"2026-07-13T16:53:51.078581Z","end":"2026-07-13T16:53:51.454215Z","steps":["trace[594427153] 'agreement among raft nodes before linearized reading' (duration: 375.600015ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:51.454241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:51.078575Z","time spent":"375.660915ms","remote":"127.0.0.1:33344","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-07-13T16:53:51.45438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.814656ms","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-07-13T16:53:51.454385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.059023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T16:53:51.4544Z","caller":"traceutil/trace.go:171","msg":"trace[1252387254] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:615; }","duration":"375.877346ms","start":"2026-07-13T16:53:51.078517Z","end":"2026-07-13T16:53:51.454394Z","steps":["trace[1252387254] 'agreement among raft nodes before linearized reading' (duration: 375.844086ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:51.4544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.928176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T16:53:51.454424Z","caller":"traceutil/trace.go:171","msg":"trace[701331971] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:615; }","duration":"112.119133ms","start":"2026-07-13T16:53:51.342294Z","end":"2026-07-13T16:53:51.454413Z","steps":["trace[701331971] 'agreement among raft nodes before linearized reading' (duration: 112.062783ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:53:51.454438Z","caller":"traceutil/trace.go:171","msg":"trace[1607094323] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:615; }","duration":"204.979116ms","start":"2026-07-13T16:53:51.24945Z","end":"2026-07-13T16:53:51.454429Z","steps":["trace[1607094323] 'agreement among raft nodes before linearized reading' (duration: 204.909296ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:51.454435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.741041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-13T16:53:51.454458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:51.078499Z","time spent":"375.918497ms","remote":"127.0.0.1:33344","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":"info","ts":"2026-07-13T16:53:51.454481Z","caller":"traceutil/trace.go:171","msg":"trace[888762588] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:615; }","duration":"227.817133ms","start":"2026-07-13T16:53:51.226653Z","end":"2026-07-13T16:53:51.45447Z","steps":["trace[888762588] 'agreement among raft nodes before linearized reading' (duration: 227.749172ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:51.454546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.729422ms","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-07-13T16:53:51.454558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.420885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T16:53:51.454566Z","caller":"traceutil/trace.go:171","msg":"trace[1074187947] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:615; }","duration":"409.767342ms","start":"2026-07-13T16:53:51.044793Z","end":"2026-07-13T16:53:51.45456Z","steps":["trace[1074187947] 'agreement among raft nodes before linearized reading' (duration: 409.736242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:51.453821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.25438ms","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-07-13T16:53:51.454579Z","caller":"traceutil/trace.go:171","msg":"trace[377584498] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:615; }","duration":"112.599796ms","start":"2026-07-13T16:53:51.341973Z","end":"2026-07-13T16:53:51.454572Z","steps":["trace[377584498] 'agreement among raft nodes before linearized reading' (duration: 112.565846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:51.45459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:51.044782Z","time spent":"409.801593ms","remote":"127.0.0.1:33340","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":"info","ts":"2026-07-13T16:53:51.454604Z","caller":"traceutil/trace.go:171","msg":"trace[542795970] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:615; }","duration":"344.074507ms","start":"2026-07-13T16:53:51.110518Z","end":"2026-07-13T16:53:51.454593Z","steps":["trace[542795970] 'agreement among raft nodes before linearized reading' (duration: 343.281691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:51.454644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:51.110498Z","time spent":"344.133057ms","remote":"127.0.0.1:33348","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":"warn","ts":"2026-07-13T16:53:51.454648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.467597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-13T16:53:51.45468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.556706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T16:53:51.45468Z","caller":"traceutil/trace.go:171","msg":"trace[931790552] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:615; }","duration":"228.509287ms","start":"2026-07-13T16:53:51.226161Z","end":"2026-07-13T16:53:51.454671Z","steps":["trace[931790552] 'agreement among raft nodes before linearized reading' (duration: 228.461777ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:53:51.454704Z","caller":"traceutil/trace.go:171","msg":"trace[1690145973] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:615; }","duration":"112.587496ms","start":"2026-07-13T16:53:51.342108Z","end":"2026-07-13T16:53:51.454696Z","steps":["trace[1690145973] 'agreement among raft nodes before linearized reading' (duration: 112.553746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:51.454786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.516994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T16:53:51.454806Z","caller":"traceutil/trace.go:171","msg":"trace[643428506] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:615; }","duration":"204.536794ms","start":"2026-07-13T16:53:51.250263Z","end":"2026-07-13T16:53:51.4548Z","steps":["trace[643428506] 'agreement among raft nodes before linearized reading' (duration: 204.507934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:51.454828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.670898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T16:53:51.454862Z","caller":"traceutil/trace.go:171","msg":"trace[1555381028] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:615; }","duration":"228.713868ms","start":"2026-07-13T16:53:51.226137Z","end":"2026-07-13T16:53:51.454851Z","steps":["trace[1555381028] 'agreement among raft nodes before linearized reading' (duration: 228.668038ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:51.455051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.152829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T16:53:51.455196Z","caller":"traceutil/trace.go:171","msg":"trace[232415237] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:615; }","duration":"263.310031ms","start":"2026-07-13T16:53:51.191874Z","end":"2026-07-13T16:53:51.455184Z","steps":["trace[232415237] 'agreement among raft nodes before linearized reading' (duration: 263.14672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:51.455391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.520173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T16:53:51.455421Z","caller":"traceutil/trace.go:171","msg":"trace[1201070981] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:615; }","duration":"263.581683ms","start":"2026-07-13T16:53:51.191831Z","end":"2026-07-13T16:53:51.455413Z","steps":["trace[1201070981] 'agreement among raft nodes before linearized reading' (duration: 263.529303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:51.455558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.045059ms","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-07-13T16:53:51.455587Z","caller":"traceutil/trace.go:171","msg":"trace[538049810] 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:615; }","duration":"314.104439ms","start":"2026-07-13T16:53:51.141474Z","end":"2026-07-13T16:53:51.455578Z","steps":["trace[538049810] 'agreement among raft nodes before linearized reading' (duration: 314.061099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:51.455623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:51.141453Z","time spent":"314.16202ms","remote":"127.0.0.1:33350","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-07-13T16:53:51.455593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.436791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T16:53:51.455835Z","caller":"traceutil/trace.go:171","msg":"trace[821833410] 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:615; }","duration":"314.693004ms","start":"2026-07-13T16:53:51.141132Z","end":"2026-07-13T16:53:51.455825Z","steps":["trace[821833410] 'agreement among raft nodes before linearized reading' (duration: 314.439262ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:51.455874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:51.141122Z","time spent":"314.738504ms","remote":"127.0.0.1:33350","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-07-13T16:53:56.69434Z","caller":"traceutil/trace.go:171","msg":"trace[790131520] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"254.327556ms","start":"2026-07-13T16:53:56.439959Z","end":"2026-07-13T16:53:56.694287Z","steps":["trace[790131520] 'process raft request' (duration: 254.178225ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:53:58.40687Z","caller":"traceutil/trace.go:171","msg":"trace[1996215498] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:669; }","duration":"199.331253ms","start":"2026-07-13T16:53:58.207516Z","end":"2026-07-13T16:53:58.406847Z","steps":["trace[1996215498] 'read index received' (duration: 199.025971ms)","trace[1996215498] 'applied index is now lower than readState.Index' (duration: 304.352µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T16:53:58.406973Z","caller":"traceutil/trace.go:171","msg":"trace[222374992] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"289.482344ms","start":"2026-07-13T16:53:58.117429Z","end":"2026-07-13T16:53:58.406912Z","steps":["trace[222374992] 'process raft request' (duration: 289.177142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:58.407164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.634905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7ks44\" ","response":"range_response_count:1 size:16660"} {"level":"info","ts":"2026-07-13T16:53:58.407237Z","caller":"traceutil/trace.go:171","msg":"trace[1481142038] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7ks44; range_end:; response_count:1; response_revision:646; }","duration":"199.751246ms","start":"2026-07-13T16:53:58.207465Z","end":"2026-07-13T16:53:58.407216Z","steps":["trace[1481142038] 'agreement among raft nodes before linearized reading' (duration: 199.501524ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:53:58.752338Z","caller":"traceutil/trace.go:171","msg":"trace[1959616597] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"330.487681ms","start":"2026-07-13T16:53:58.421826Z","end":"2026-07-13T16:53:58.752313Z","steps":["trace[1959616597] 'process raft request' (duration: 330.31601ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:53:58.752484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:58.4218Z","time spent":"330.620793ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16807,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T16:53:59.026528Z","caller":"traceutil/trace.go:171","msg":"trace[1884810410] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"267.217808ms","start":"2026-07-13T16:53:58.759293Z","end":"2026-07-13T16:53:59.02651Z","steps":["trace[1884810410] 'process raft request' (duration: 212.499766ms)","trace[1884810410] 'compare' (duration: 54.596971ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T16:54:00.263359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.377993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-13T16:54:00.263439Z","caller":"traceutil/trace.go:171","msg":"trace[1657284475] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:648; }","duration":"767.491333ms","start":"2026-07-13T16:53:59.495927Z","end":"2026-07-13T16:54:00.263418Z","steps":["trace[1657284475] 'range keys from in-memory index tree' (duration: 767.224112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:54:00.263482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:59.495914Z","time spent":"767.559894ms","remote":"127.0.0.1:39600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-13T16:54:00.263616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.70736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-13T16:54:00.263633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.778374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-13T16:54:00.263688Z","caller":"traceutil/trace.go:171","msg":"trace[206573067] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"726.814461ms","start":"2026-07-13T16:53:59.536855Z","end":"2026-07-13T16:54:00.26367Z","steps":["trace[206573067] 'range keys from in-memory index tree' (duration: 726.647219ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:54:00.263709Z","caller":"traceutil/trace.go:171","msg":"trace[1887746011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:648; }","duration":"774.901105ms","start":"2026-07-13T16:53:59.488793Z","end":"2026-07-13T16:54:00.263694Z","steps":["trace[1887746011] 'range keys from in-memory index tree' (duration: 774.618034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:54:00.263734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:59.536841Z","time spent":"726.884291ms","remote":"127.0.0.1:39344","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-07-13T16:54:00.263758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:59.488775Z","time spent":"774.976205ms","remote":"127.0.0.1:39600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-13T16:54:00.26379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.669799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-13T16:54:00.263829Z","caller":"traceutil/trace.go:171","msg":"trace[1961547366] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:648; }","duration":"769.759209ms","start":"2026-07-13T16:53:59.494058Z","end":"2026-07-13T16:54:00.263817Z","steps":["trace[1961547366] 'range keys from in-memory index tree' (duration: 769.571668ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:54:00.263867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:53:59.494044Z","time spent":"769.81696ms","remote":"127.0.0.1:39600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-13T16:54:00.966324Z","caller":"traceutil/trace.go:171","msg":"trace[729689689] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"698.422359ms","start":"2026-07-13T16:54:00.267878Z","end":"2026-07-13T16:54:00.9663Z","steps":["trace[729689689] 'process raft request' (duration: 698.179448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:54:00.966496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:54:00.267861Z","time spent":"698.53923ms","remote":"127.0.0.1:39600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":425,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T16:54:00.985376Z","caller":"traceutil/trace.go:171","msg":"trace[944791946] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"717.020596ms","start":"2026-07-13T16:54:00.268302Z","end":"2026-07-13T16:54:00.985323Z","steps":["trace[944791946] 'process raft request' (duration: 716.858925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:54:00.985544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:54:00.268286Z","time spent":"717.165098ms","remote":"127.0.0.1:39600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":466,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T16:54:00.985716Z","caller":"traceutil/trace.go:171","msg":"trace[1476292984] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"717.036097ms","start":"2026-07-13T16:54:00.268658Z","end":"2026-07-13T16:54:00.985694Z","steps":["trace[1476292984] 'process raft request' (duration: 716.626473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:54:00.985844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:54:00.268639Z","time spent":"717.148528ms","remote":"127.0.0.1:39600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T16:54:06.102933Z","caller":"traceutil/trace.go:171","msg":"trace[741211960] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"632.111694ms","start":"2026-07-13T16:54:05.470773Z","end":"2026-07-13T16:54:06.102885Z","steps":["trace[741211960] 'process raft request' (duration: 589.106564ms)","trace[741211960] 'compare' (duration: 42.479517ms)"],"step_count":2} {"level":"warn","ts":"2026-07-13T16:54:06.103131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:54:05.470757Z","time spent":"632.252845ms","remote":"127.0.0.1:39600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":479,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-13T16:54:16.704223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.886297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-13T16:54:16.704331Z","caller":"traceutil/trace.go:171","msg":"trace[2083437227] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:799; }","duration":"171.032503ms","start":"2026-07-13T16:54:16.533267Z","end":"2026-07-13T16:54:16.7043Z","steps":["trace[2083437227] 'range keys from in-memory index tree' (duration: 170.756632ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:54:20.035589Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605829972636047809,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-13T16:54:20.071966Z","caller":"traceutil/trace.go:171","msg":"trace[1465089785] linearizableReadLoop","detail":"{readStateIndex:849; appliedIndex:848; }","duration":"537.308944ms","start":"2026-07-13T16:54:19.534631Z","end":"2026-07-13T16:54:20.07194Z","steps":["trace[1465089785] 'read index received' (duration: 537.045715ms)","trace[1465089785] 'applied index is now lower than readState.Index' (duration: 262.299µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T16:54:20.072043Z","caller":"traceutil/trace.go:171","msg":"trace[517499261] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"732.822772ms","start":"2026-07-13T16:54:19.339167Z","end":"2026-07-13T16:54:20.071989Z","steps":["trace[517499261] 'process raft request' (duration: 732.622095ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:54:20.072117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.46194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-13T16:54:20.072273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:54:19.339145Z","time spent":"733.03179ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4036,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-13T16:54:20.072292Z","caller":"traceutil/trace.go:171","msg":"trace[277092653] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:819; }","duration":"537.676328ms","start":"2026-07-13T16:54:19.534596Z","end":"2026-07-13T16:54:20.072272Z","steps":["trace[277092653] 'agreement among raft nodes before linearized reading' (duration: 537.424679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:54:20.072343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-13T16:54:19.53458Z","time spent":"537.75296ms","remote":"127.0.0.1:39344","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-07-13T16:54:57.485634Z","caller":"traceutil/trace.go:171","msg":"trace[1489187804] linearizableReadLoop","detail":"{readStateIndex:1087; appliedIndex:1086; }","duration":"165.995526ms","start":"2026-07-13T16:54:57.319603Z","end":"2026-07-13T16:54:57.485598Z","steps":["trace[1489187804] 'read index received' (duration: 165.798651ms)","trace[1489187804] 'applied index is now lower than readState.Index' (duration: 195.975µs)"],"step_count":2} {"level":"info","ts":"2026-07-13T16:54:57.485674Z","caller":"traceutil/trace.go:171","msg":"trace[324113186] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"180.239515ms","start":"2026-07-13T16:54:57.305415Z","end":"2026-07-13T16:54:57.485654Z","steps":["trace[324113186] 'process raft request' (duration: 180.050121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:54:57.485843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.228721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-13T16:54:57.485884Z","caller":"traceutil/trace.go:171","msg":"trace[1008493379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1048; }","duration":"166.306554ms","start":"2026-07-13T16:54:57.319568Z","end":"2026-07-13T16:54:57.485874Z","steps":["trace[1008493379] 'agreement among raft nodes before linearized reading' (duration: 166.179211ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:54:57.485936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.621398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking\" ","response":"range_response_count:1 size:370"} {"level":"warn","ts":"2026-07-13T16:54:57.485885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.497656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/secretgen-controller/\" range_end:\"/registry/jobs/secretgen-controller0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-13T16:54:57.486029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.54595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:1 size:3017"} {"level":"info","ts":"2026-07-13T16:54:57.486025Z","caller":"traceutil/trace.go:171","msg":"trace[363933170] range","detail":"{range_begin:/registry/jobs/secretgen-controller/; range_end:/registry/jobs/secretgen-controller0; response_count:0; response_revision:1048; }","duration":"128.647249ms","start":"2026-07-13T16:54:57.357355Z","end":"2026-07-13T16:54:57.486002Z","steps":["trace[363933170] 'agreement among raft nodes before linearized reading' (duration: 128.491655ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:54:57.486082Z","caller":"traceutil/trace.go:171","msg":"trace[1685851057] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:1; response_revision:1048; }","duration":"131.606671ms","start":"2026-07-13T16:54:57.354447Z","end":"2026-07-13T16:54:57.486054Z","steps":["trace[1685851057] 'agreement among raft nodes before linearized reading' (duration: 131.5503ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:54:57.486173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.168732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:4 size:329936"} {"level":"info","ts":"2026-07-13T16:54:57.486212Z","caller":"traceutil/trace.go:171","msg":"trace[2017243562] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:1048; }","duration":"127.216794ms","start":"2026-07-13T16:54:57.358986Z","end":"2026-07-13T16:54:57.486202Z","steps":["trace[2017243562] 'agreement among raft nodes before linearized reading' (duration: 127.011369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:54:57.486254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.478336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-13T16:54:57.486281Z","caller":"traceutil/trace.go:171","msg":"trace[1017786596] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1048; }","duration":"137.528987ms","start":"2026-07-13T16:54:57.348742Z","end":"2026-07-13T16:54:57.486271Z","steps":["trace[1017786596] 'agreement among raft nodes before linearized reading' (duration: 137.465246ms)"],"step_count":1} {"level":"info","ts":"2026-07-13T16:54:57.485977Z","caller":"traceutil/trace.go:171","msg":"trace[1698916122] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking; range_end:; response_count:1; response_revision:1048; }","duration":"106.68383ms","start":"2026-07-13T16:54:57.379282Z","end":"2026-07-13T16:54:57.485966Z","steps":["trace[1698916122] 'agreement among raft nodes before linearized reading' (duration: 106.622738ms)"],"step_count":1} {"level":"warn","ts":"2026-07-13T16:54:57.485959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.424836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-13T16:54:57.486446Z","caller":"traceutil/trace.go:171","msg":"trace[215473941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1048; }","duration":"155.933528ms","start":"2026-07-13T16:54:57.330487Z","end":"2026-07-13T16:54:57.486421Z","steps":["trace[215473941] 'agreement among raft nodes before linearized reading' (duration: 155.336784ms)"],"step_count":1}