{"level":"warn","ts":"2026-05-19T19:11:04.119762Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T19:11:04.119855Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.57: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.57:2380","--initial-cluster=instance=https://199.204.45.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.57:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-19T19:11:04.120056Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T19:11:04.120079Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-05-19T19:11:04.120144Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-19T19:11:04.120963Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"]} {"level":"info","ts":"2026-05-19T19:11:04.121134Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.57:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-19T19:11:04.131273Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.827776ms"} {"level":"info","ts":"2026-05-19T19:11:04.153261Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6478f0ac5aeb8e1","cluster-id":"6c58cff80d4a5b62"} {"level":"info","ts":"2026-05-19T19:11:04.153376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T19:11:04.153492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 0"} {"level":"info","ts":"2026-05-19T19:11:04.153511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6478f0ac5aeb8e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T19:11:04.15364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 1"} {"level":"info","ts":"2026-05-19T19:11:04.153775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"warn","ts":"2026-05-19T19:11:04.170051Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T19:11:04.17438Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T19:11:04.177462Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-19T19:11:04.180941Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6478f0ac5aeb8e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T19:11:04.18134Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6478f0ac5aeb8e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T19:11:04.181314Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-19T19:11:04.181488Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-19T19:11:04.181572Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-19T19:11:04.186166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"info","ts":"2026-05-19T19:11:04.186304Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","added-peer-id":"b6478f0ac5aeb8e1","added-peer-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-05-19T19:11:04.187601Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-19T19:11:04.187758Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-05-19T19:11:04.1878Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-05-19T19:11:04.188131Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6478f0ac5aeb8e1","initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T19:11:04.188165Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T19:11:04.454407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T19:11:04.454503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T19:11:04.454546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgPreVoteResp from b6478f0ac5aeb8e1 at term 1"} {"level":"info","ts":"2026-05-19T19:11:04.454594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became candidate at term 2"} {"level":"info","ts":"2026-05-19T19:11:04.454615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgVoteResp from b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-05-19T19:11:04.454637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became leader at term 2"} {"level":"info","ts":"2026-05-19T19:11:04.454656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6478f0ac5aeb8e1 elected leader b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-05-19T19:11:04.463236Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T19:11:04.463463Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T19:11:04.463448Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T19:11:04.46387Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T19:11:04.463946Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T19:11:04.465644Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T19:11:04.467431Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.57:2379"} {"level":"info","ts":"2026-05-19T19:11:04.463443Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6478f0ac5aeb8e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.57:2379]}","request-path":"/0/members/b6478f0ac5aeb8e1/attributes","cluster-id":"6c58cff80d4a5b62","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T19:11:04.508106Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T19:11:04.508232Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T19:11:04.508262Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T19:11:06.975473Z","caller":"traceutil/trace.go:171","msg":"trace[1527359362] linearizableReadLoop","detail":"{readStateIndex:118; appliedIndex:117; }","duration":"129.007855ms","start":"2026-05-19T19:11:06.846432Z","end":"2026-05-19T19:11:06.97544Z","steps":["trace[1527359362] 'read index received' (duration: 124.985329ms)","trace[1527359362] 'applied index is now lower than readState.Index' (duration: 4.021686ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:11:06.97561Z","caller":"traceutil/trace.go:171","msg":"trace[2061805962] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"129.45327ms","start":"2026-05-19T19:11:06.846131Z","end":"2026-05-19T19:11:06.975584Z","steps":["trace[2061805962] 'process raft request' (duration: 125.376792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:06.975743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.277484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T19:11:06.975874Z","caller":"traceutil/trace.go:171","msg":"trace[1620245938] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:113; }","duration":"129.430459ms","start":"2026-05-19T19:11:06.846423Z","end":"2026-05-19T19:11:06.975853Z","steps":["trace[1620245938] 'agreement among raft nodes before linearized reading' (duration: 129.181351ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:13.605864Z","caller":"traceutil/trace.go:171","msg":"trace[207772317] linearizableReadLoop","detail":"{readStateIndex:353; appliedIndex:352; }","duration":"117.927157ms","start":"2026-05-19T19:11:13.487911Z","end":"2026-05-19T19:11:13.605838Z","steps":["trace[207772317] 'read index received' (duration: 117.6909ms)","trace[207772317] 'applied index is now lower than readState.Index' (duration: 234.867µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T19:11:13.606033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.103835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-19T19:11:13.606076Z","caller":"traceutil/trace.go:171","msg":"trace[1916500019] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:337; }","duration":"118.197624ms","start":"2026-05-19T19:11:13.487867Z","end":"2026-05-19T19:11:13.606064Z","steps":["trace[1916500019] 'agreement among raft nodes before linearized reading' (duration: 118.070065ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:13.606046Z","caller":"traceutil/trace.go:171","msg":"trace[1736444254] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"189.155377ms","start":"2026-05-19T19:11:13.416856Z","end":"2026-05-19T19:11:13.606012Z","steps":["trace[1736444254] 'process raft request' (duration: 188.775043ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:14.487449Z","caller":"traceutil/trace.go:171","msg":"trace[1987932621] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"210.913964ms","start":"2026-05-19T19:11:14.276515Z","end":"2026-05-19T19:11:14.487429Z","steps":["trace[1987932621] 'process raft request' (duration: 210.697306ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:14.487629Z","caller":"traceutil/trace.go:171","msg":"trace[1448819753] linearizableReadLoop","detail":"{readStateIndex:360; appliedIndex:358; }","duration":"170.944231ms","start":"2026-05-19T19:11:14.316653Z","end":"2026-05-19T19:11:14.487597Z","steps":["trace[1448819753] 'read index received' (duration: 170.529827ms)","trace[1448819753] 'applied index is now lower than readState.Index' (duration: 412.434µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:11:14.487666Z","caller":"traceutil/trace.go:171","msg":"trace[480016207] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"210.301781ms","start":"2026-05-19T19:11:14.277342Z","end":"2026-05-19T19:11:14.487644Z","steps":["trace[480016207] 'process raft request' (duration: 210.047694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:14.487884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.201558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-05-19T19:11:14.487944Z","caller":"traceutil/trace.go:171","msg":"trace[1220239259] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:344; }","duration":"171.313977ms","start":"2026-05-19T19:11:14.316616Z","end":"2026-05-19T19:11:14.48793Z","steps":["trace[1220239259] 'agreement among raft nodes before linearized reading' (duration: 171.168069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:14.487957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.022985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-05-19T19:11:14.488013Z","caller":"traceutil/trace.go:171","msg":"trace[678848080] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:344; }","duration":"133.113024ms","start":"2026-05-19T19:11:14.354883Z","end":"2026-05-19T19:11:14.487996Z","steps":["trace[678848080] 'agreement among raft nodes before linearized reading' (duration: 132.917167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:14.488267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.330567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-19T19:11:14.488306Z","caller":"traceutil/trace.go:171","msg":"trace[902818149] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:344; }","duration":"121.388566ms","start":"2026-05-19T19:11:14.366909Z","end":"2026-05-19T19:11:14.488297Z","steps":["trace[902818149] 'agreement among raft nodes before linearized reading' (duration: 121.324327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:14.803692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.58972ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T19:11:14.804Z","caller":"traceutil/trace.go:171","msg":"trace[2116023351] linearizableReadLoop","detail":"{readStateIndex:374; appliedIndex:373; }","duration":"171.712452ms","start":"2026-05-19T19:11:14.632268Z","end":"2026-05-19T19:11:14.80398Z","steps":["trace[2116023351] 'read index received' (duration: 42.311002ms)","trace[2116023351] 'applied index is now lower than readState.Index' (duration: 129.39967ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T19:11:14.804171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.761546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-19T19:11:14.804214Z","caller":"traceutil/trace.go:171","msg":"trace[210432708] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:358; }","duration":"187.847795ms","start":"2026-05-19T19:11:14.616357Z","end":"2026-05-19T19:11:14.804204Z","steps":["trace[210432708] 'agreement among raft nodes before linearized reading' (duration: 187.731577ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:14.804015Z","caller":"traceutil/trace.go:171","msg":"trace[1286870633] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"208.841648ms","start":"2026-05-19T19:11:14.595145Z","end":"2026-05-19T19:11:14.803986Z","steps":["trace[1286870633] 'process raft request' (duration: 79.422908ms)","trace[1286870633] 'compare' (duration: 128.289054ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T19:11:14.856232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.1195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-05-19T19:11:14.85639Z","caller":"traceutil/trace.go:171","msg":"trace[251211821] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"216.239038ms","start":"2026-05-19T19:11:14.640124Z","end":"2026-05-19T19:11:14.856363Z","steps":["trace[251211821] 'process raft request' (duration: 215.800473ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:14.856322Z","caller":"traceutil/trace.go:171","msg":"trace[56689190] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:359; }","duration":"189.222198ms","start":"2026-05-19T19:11:14.667083Z","end":"2026-05-19T19:11:14.856305Z","steps":["trace[56689190] 'agreement among raft nodes before linearized reading' (duration: 189.06559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:14.856779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.9963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-19T19:11:14.856867Z","caller":"traceutil/trace.go:171","msg":"trace[1495787893] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:359; }","duration":"140.128757ms","start":"2026-05-19T19:11:14.716717Z","end":"2026-05-19T19:11:14.856846Z","steps":["trace[1495787893] 'agreement among raft nodes before linearized reading' (duration: 139.959239ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:15.017639Z","caller":"traceutil/trace.go:171","msg":"trace[352549415] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"209.202113ms","start":"2026-05-19T19:11:14.8084Z","end":"2026-05-19T19:11:15.017602Z","steps":["trace[352549415] 'process raft request' (duration: 125.692135ms)","trace[352549415] 'compare' (duration: 83.215792ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:11:15.017988Z","caller":"traceutil/trace.go:171","msg":"trace[406655387] linearizableReadLoop","detail":"{readStateIndex:377; appliedIndex:375; }","duration":"158.746711ms","start":"2026-05-19T19:11:14.859229Z","end":"2026-05-19T19:11:15.017975Z","steps":["trace[406655387] 'read index received' (duration: 74.877805ms)","trace[406655387] 'applied index is now lower than readState.Index' (duration: 83.868046ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:11:15.01818Z","caller":"traceutil/trace.go:171","msg":"trace[45618280] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"204.810367ms","start":"2026-05-19T19:11:14.813278Z","end":"2026-05-19T19:11:15.018089Z","steps":["trace[45618280] 'process raft request' (duration: 204.198144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:15.01871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.462112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-05-19T19:11:15.01876Z","caller":"traceutil/trace.go:171","msg":"trace[350931661] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:361; }","duration":"159.558161ms","start":"2026-05-19T19:11:14.859191Z","end":"2026-05-19T19:11:15.018749Z","steps":["trace[350931661] 'agreement among raft nodes before linearized reading' (duration: 158.83461ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:15.053887Z","caller":"traceutil/trace.go:171","msg":"trace[620883066] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"189.022155ms","start":"2026-05-19T19:11:14.864844Z","end":"2026-05-19T19:11:15.053866Z","steps":["trace[620883066] 'process raft request' (duration: 188.978326ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:15.053996Z","caller":"traceutil/trace.go:171","msg":"trace[926691197] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"190.136391ms","start":"2026-05-19T19:11:14.863824Z","end":"2026-05-19T19:11:15.053961Z","steps":["trace[926691197] 'process raft request' (duration: 189.895564ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:15.206093Z","caller":"traceutil/trace.go:171","msg":"trace[918645101] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"148.483197ms","start":"2026-05-19T19:11:15.057576Z","end":"2026-05-19T19:11:15.206059Z","steps":["trace[918645101] 'process raft request' (duration: 121.110973ms)","trace[918645101] 'compare' (duration: 26.843101ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:11:15.206464Z","caller":"traceutil/trace.go:171","msg":"trace[1090669102] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"147.901933ms","start":"2026-05-19T19:11:15.058553Z","end":"2026-05-19T19:11:15.206455Z","steps":["trace[1090669102] 'process raft request' (duration: 147.568908ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:15.206281Z","caller":"traceutil/trace.go:171","msg":"trace[976778595] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:379; }","duration":"148.32464ms","start":"2026-05-19T19:11:15.057932Z","end":"2026-05-19T19:11:15.206256Z","steps":["trace[976778595] 'read index received' (duration: 45.057701ms)","trace[976778595] 'applied index is now lower than readState.Index' (duration: 103.265789ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:11:15.20655Z","caller":"traceutil/trace.go:171","msg":"trace[1875460614] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"146.092655ms","start":"2026-05-19T19:11:15.060434Z","end":"2026-05-19T19:11:15.206527Z","steps":["trace[1875460614] 'process raft request' (duration: 145.757589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:15.206556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.608127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-19T19:11:15.20665Z","caller":"traceutil/trace.go:171","msg":"trace[493543154] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:368; }","duration":"148.720545ms","start":"2026-05-19T19:11:15.057915Z","end":"2026-05-19T19:11:15.206636Z","steps":["trace[493543154] 'agreement among raft nodes before linearized reading' (duration: 148.573547ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:15.212543Z","caller":"traceutil/trace.go:171","msg":"trace[428129764] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"105.085108ms","start":"2026-05-19T19:11:15.107446Z","end":"2026-05-19T19:11:15.212531Z","steps":["trace[428129764] 'process raft request' (duration: 104.92104ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:15.212789Z","caller":"traceutil/trace.go:171","msg":"trace[925648787] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"104.982979ms","start":"2026-05-19T19:11:15.107721Z","end":"2026-05-19T19:11:15.212704Z","steps":["trace[925648787] 'process raft request' (duration: 104.724662ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:15.213696Z","caller":"traceutil/trace.go:171","msg":"trace[1401534223] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"104.277737ms","start":"2026-05-19T19:11:15.109397Z","end":"2026-05-19T19:11:15.213675Z","steps":["trace[1401534223] 'process raft request' (duration: 103.098462ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:15.386533Z","caller":"traceutil/trace.go:171","msg":"trace[38420499] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"109.606905ms","start":"2026-05-19T19:11:15.276883Z","end":"2026-05-19T19:11:15.38649Z","steps":["trace[38420499] 'process raft request' (duration: 81.294942ms)","trace[38420499] 'compare' (duration: 27.873419ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:11:15.386594Z","caller":"traceutil/trace.go:171","msg":"trace[368037350] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"108.578188ms","start":"2026-05-19T19:11:15.277994Z","end":"2026-05-19T19:11:15.386572Z","steps":["trace[368037350] 'process raft request' (duration: 108.308762ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:15.386636Z","caller":"traceutil/trace.go:171","msg":"trace[2056480410] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"109.637615ms","start":"2026-05-19T19:11:15.276986Z","end":"2026-05-19T19:11:15.386623Z","steps":["trace[2056480410] 'process raft request' (duration: 109.273489ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:15.386718Z","caller":"traceutil/trace.go:171","msg":"trace[1089914803] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"104.477475ms","start":"2026-05-19T19:11:15.282129Z","end":"2026-05-19T19:11:15.386607Z","steps":["trace[1089914803] 'process raft request' (duration: 104.228198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:18.970581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.747819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T19:11:18.970664Z","caller":"traceutil/trace.go:171","msg":"trace[700289083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:405; }","duration":"167.869428ms","start":"2026-05-19T19:11:18.802776Z","end":"2026-05-19T19:11:18.970645Z","steps":["trace[700289083] 'range keys from in-memory index tree' (duration: 167.6181ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:26.496433Z","caller":"traceutil/trace.go:171","msg":"trace[1037570889] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"632.896038ms","start":"2026-05-19T19:11:25.863522Z","end":"2026-05-19T19:11:26.496418Z","steps":["trace[1037570889] 'process raft request' (duration: 632.770948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:26.496637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:25.863493Z","time spent":"632.991837ms","remote":"127.0.0.1:47620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T19:11:27.050998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.448818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T19:11:27.05109Z","caller":"traceutil/trace.go:171","msg":"trace[1600125637] linearizableReadLoop","detail":"{readStateIndex:475; appliedIndex:474; }","duration":"372.069226ms","start":"2026-05-19T19:11:26.679007Z","end":"2026-05-19T19:11:27.051077Z","steps":["trace[1600125637] 'read index received' (duration: 205.423809ms)","trace[1600125637] 'applied index is now lower than readState.Index' (duration: 166.644487ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:11:27.051109Z","caller":"traceutil/trace.go:171","msg":"trace[1307902256] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"617.815255ms","start":"2026-05-19T19:11:26.433273Z","end":"2026-05-19T19:11:27.051088Z","steps":["trace[1307902256] 'process raft request' (duration: 451.211588ms)","trace[1307902256] 'compare' (duration: 166.331528ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T19:11:27.051191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.178846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-19T19:11:27.051209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:26.433253Z","time spent":"617.901065ms","remote":"127.0.0.1:47620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T19:11:27.051227Z","caller":"traceutil/trace.go:171","msg":"trace[1459765497] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:455; }","duration":"372.242234ms","start":"2026-05-19T19:11:26.678975Z","end":"2026-05-19T19:11:27.051217Z","steps":["trace[1459765497] 'agreement among raft nodes before linearized reading' (duration: 372.167455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:27.051253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:26.678961Z","time spent":"372.287504ms","remote":"127.0.0.1:47620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T19:11:27.051417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.86029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T19:11:27.051446Z","caller":"traceutil/trace.go:171","msg":"trace[598552470] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:455; }","duration":"242.919119ms","start":"2026-05-19T19:11:26.808521Z","end":"2026-05-19T19:11:27.05144Z","steps":["trace[598552470] 'agreement among raft nodes before linearized reading' (duration: 242.87792ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:27.285272Z","caller":"traceutil/trace.go:171","msg":"trace[452559675] linearizableReadLoop","detail":"{readStateIndex:476; appliedIndex:475; }","duration":"171.018937ms","start":"2026-05-19T19:11:27.114236Z","end":"2026-05-19T19:11:27.285255Z","steps":["trace[452559675] 'read index received' (duration: 92.227621ms)","trace[452559675] 'applied index is now lower than readState.Index' (duration: 78.790706ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:11:27.285354Z","caller":"traceutil/trace.go:171","msg":"trace[1891211285] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"230.163094ms","start":"2026-05-19T19:11:27.05517Z","end":"2026-05-19T19:11:27.285333Z","steps":["trace[1891211285] 'process raft request' (duration: 151.344528ms)","trace[1891211285] 'compare' (duration: 78.645106ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T19:11:27.285383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.131377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-19T19:11:27.285403Z","caller":"traceutil/trace.go:171","msg":"trace[636239919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:456; }","duration":"171.264686ms","start":"2026-05-19T19:11:27.114132Z","end":"2026-05-19T19:11:27.285396Z","steps":["trace[636239919] 'agreement among raft nodes before linearized reading' (duration: 171.197376ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:29.669167Z","caller":"traceutil/trace.go:171","msg":"trace[714606337] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"201.152455ms","start":"2026-05-19T19:11:29.467997Z","end":"2026-05-19T19:11:29.66915Z","steps":["trace[714606337] 'process raft request' (duration: 201.050196ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:32.831016Z","caller":"traceutil/trace.go:171","msg":"trace[459857875] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"282.028221ms","start":"2026-05-19T19:11:32.548972Z","end":"2026-05-19T19:11:32.831Z","steps":["trace[459857875] 'process raft request' (duration: 281.918391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:38.845575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.215197ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T19:11:38.845689Z","caller":"traceutil/trace.go:171","msg":"trace[1660599398] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:567; }","duration":"900.681245ms","start":"2026-05-19T19:11:37.944987Z","end":"2026-05-19T19:11:38.845668Z","steps":["trace[1660599398] 'read index received' (duration: 337.318438ms)","trace[1660599398] 'applied index is now lower than readState.Index' (duration: 563.361257ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:11:38.84579Z","caller":"traceutil/trace.go:171","msg":"trace[1030599271] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"901.591036ms","start":"2026-05-19T19:11:37.944183Z","end":"2026-05-19T19:11:38.845774Z","steps":["trace[1030599271] 'process raft request' (duration: 338.094259ms)","trace[1030599271] 'compare' (duration: 563.033107ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T19:11:38.845871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:37.94416Z","time spent":"901.678296ms","remote":"127.0.0.1:47392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T19:11:38.845979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.989175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-19T19:11:38.846003Z","caller":"traceutil/trace.go:171","msg":"trace[1693086314] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:543; }","duration":"901.040686ms","start":"2026-05-19T19:11:37.944955Z","end":"2026-05-19T19:11:38.845996Z","steps":["trace[1693086314] 'agreement among raft nodes before linearized reading' (duration: 900.993635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:38.846021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:37.944939Z","time spent":"901.078046ms","remote":"127.0.0.1:47628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-05-19T19:11:38.848253Z","caller":"traceutil/trace.go:171","msg":"trace[2112718646] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"900.128516ms","start":"2026-05-19T19:11:37.948113Z","end":"2026-05-19T19:11:38.848242Z","steps":["trace[2112718646] 'process raft request' (duration: 899.883816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:38.848332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:37.9481Z","time spent":"900.197366ms","remote":"127.0.0.1:47392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T19:11:38.84856Z","caller":"traceutil/trace.go:171","msg":"trace[244792928] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"894.320459ms","start":"2026-05-19T19:11:37.954224Z","end":"2026-05-19T19:11:38.848544Z","steps":["trace[244792928] 'process raft request' (duration: 893.966699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:38.84864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:37.954213Z","time spent":"894.38365ms","remote":"127.0.0.1:47392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T19:11:38.84872Z","caller":"traceutil/trace.go:171","msg":"trace[2007832835] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"894.01292ms","start":"2026-05-19T19:11:37.9547Z","end":"2026-05-19T19:11:38.848713Z","steps":["trace[2007832835] 'process raft request' (duration: 893.721849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:38.848764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:37.954691Z","time spent":"894.04649ms","remote":"127.0.0.1:47392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T19:11:38.848846Z","caller":"traceutil/trace.go:171","msg":"trace[1672362605] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"785.203004ms","start":"2026-05-19T19:11:38.063629Z","end":"2026-05-19T19:11:38.848832Z","steps":["trace[1672362605] 'process raft request' (duration: 784.938902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:38.848896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:38.063607Z","time spent":"785.266024ms","remote":"127.0.0.1:47392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T19:11:45.372656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.549762ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T19:11:45.372812Z","caller":"traceutil/trace.go:171","msg":"trace[1823011458] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"429.298347ms","start":"2026-05-19T19:11:44.943485Z","end":"2026-05-19T19:11:45.372784Z","steps":["trace[1823011458] 'process raft request' (duration: 74.532994ms)","trace[1823011458] 'compare' (duration: 354.414522ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:11:45.372784Z","caller":"traceutil/trace.go:171","msg":"trace[2001005814] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"413.685871ms","start":"2026-05-19T19:11:44.959076Z","end":"2026-05-19T19:11:45.372762Z","steps":["trace[2001005814] 'read index received' (duration: 58.893838ms)","trace[2001005814] 'applied index is now lower than readState.Index' (duration: 354.790073ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T19:11:45.37312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.5054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T19:11:45.37313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.809546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T19:11:45.373156Z","caller":"traceutil/trace.go:171","msg":"trace[985494119] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:621; }","duration":"313.57549ms","start":"2026-05-19T19:11:45.059572Z","end":"2026-05-19T19:11:45.373147Z","steps":["trace[985494119] 'agreement among raft nodes before linearized reading' (duration: 313.50594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:45.373766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:45.059558Z","time spent":"314.194634ms","remote":"127.0.0.1:42988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"info","ts":"2026-05-19T19:11:45.373792Z","caller":"traceutil/trace.go:171","msg":"trace[719313376] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:621; }","duration":"412.909898ms","start":"2026-05-19T19:11:44.960272Z","end":"2026-05-19T19:11:45.373182Z","steps":["trace[719313376] 'agreement among raft nodes before linearized reading' (duration: 412.790247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:45.373821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.610359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T19:11:45.373159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:44.94347Z","time spent":"429.486459ms","remote":"127.0.0.1:47878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T19:11:45.373173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.857396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T19:11:45.373907Z","caller":"traceutil/trace.go:171","msg":"trace[830832524] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:621; }","duration":"286.361343ms","start":"2026-05-19T19:11:45.087525Z","end":"2026-05-19T19:11:45.373886Z","steps":["trace[830832524] 'agreement among raft nodes before linearized reading' (duration: 285.571999ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:45.373988Z","caller":"traceutil/trace.go:171","msg":"trace[91697631] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:621; }","duration":"313.666552ms","start":"2026-05-19T19:11:45.060267Z","end":"2026-05-19T19:11:45.373933Z","steps":["trace[91697631] 'agreement among raft nodes before linearized reading' (duration: 312.853567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:45.374131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:45.060255Z","time spent":"313.821952ms","remote":"127.0.0.1:42988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-05-19T19:11:45.374123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:44.960265Z","time spent":"413.796522ms","remote":"127.0.0.1:42950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-05-19T19:11:45.374375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.781165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T19:11:45.374468Z","caller":"traceutil/trace.go:171","msg":"trace[337945380] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:621; }","duration":"333.869975ms","start":"2026-05-19T19:11:45.040556Z","end":"2026-05-19T19:11:45.374426Z","steps":["trace[337945380] 'agreement among raft nodes before linearized reading' (duration: 333.529774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:45.374515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:45.040551Z","time spent":"333.951316ms","remote":"127.0.0.1:42972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-19T19:11:45.374748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.810491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T19:11:45.374802Z","caller":"traceutil/trace.go:171","msg":"trace[262888018] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:621; }","duration":"287.287138ms","start":"2026-05-19T19:11:45.087501Z","end":"2026-05-19T19:11:45.374788Z","steps":["trace[262888018] 'agreement among raft nodes before linearized reading' (duration: 285.79687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:45.37316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.052602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T19:11:45.374916Z","caller":"traceutil/trace.go:171","msg":"trace[1441112105] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:621; }","duration":"415.835431ms","start":"2026-05-19T19:11:44.959054Z","end":"2026-05-19T19:11:45.374889Z","steps":["trace[1441112105] 'agreement among raft nodes before linearized reading' (duration: 413.907672ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:45.374938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.609427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T19:11:45.374963Z","caller":"traceutil/trace.go:171","msg":"trace[1778927570] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:621; }","duration":"355.643998ms","start":"2026-05-19T19:11:45.01931Z","end":"2026-05-19T19:11:45.374954Z","steps":["trace[1778927570] 'agreement among raft nodes before linearized reading' (duration: 355.592557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:45.374991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:45.019307Z","time spent":"355.677098ms","remote":"127.0.0.1:42964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 "} {"level":"warn","ts":"2026-05-19T19:11:45.37499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:44.959044Z","time spent":"415.912012ms","remote":"127.0.0.1:42950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 "} {"level":"warn","ts":"2026-05-19T19:11:45.375121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.148891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T19:11:45.375171Z","caller":"traceutil/trace.go:171","msg":"trace[43481914] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:621; }","duration":"356.227561ms","start":"2026-05-19T19:11:45.018929Z","end":"2026-05-19T19:11:45.375157Z","steps":["trace[43481914] 'agreement among raft nodes before linearized reading' (duration: 356.138051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:45.375181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.823566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T19:11:45.375222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:45.018922Z","time spent":"356.288792ms","remote":"127.0.0.1:42964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-05-19T19:11:45.375237Z","caller":"traceutil/trace.go:171","msg":"trace[1582193530] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:621; }","duration":"414.882197ms","start":"2026-05-19T19:11:44.960338Z","end":"2026-05-19T19:11:45.37522Z","steps":["trace[1582193530] 'agreement among raft nodes before linearized reading' (duration: 414.804426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:45.374211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.560726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T19:11:45.375349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.076291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T19:11:45.375392Z","caller":"traceutil/trace.go:171","msg":"trace[283174406] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:621; }","duration":"335.121601ms","start":"2026-05-19T19:11:45.040251Z","end":"2026-05-19T19:11:45.375372Z","steps":["trace[283174406] 'agreement among raft nodes before linearized reading' (duration: 335.049781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:45.37542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:45.040225Z","time spent":"335.187431ms","remote":"127.0.0.1:42972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-19T19:11:45.375397Z","caller":"traceutil/trace.go:171","msg":"trace[466106164] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:621; }","duration":"315.735161ms","start":"2026-05-19T19:11:45.059614Z","end":"2026-05-19T19:11:45.37535Z","steps":["trace[466106164] 'agreement among raft nodes before linearized reading' (duration: 314.531675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:45.375506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.551673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T19:11:45.375553Z","caller":"traceutil/trace.go:171","msg":"trace[543400945] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:621; }","duration":"356.623733ms","start":"2026-05-19T19:11:45.018916Z","end":"2026-05-19T19:11:45.375539Z","steps":["trace[543400945] 'agreement among raft nodes before linearized reading' (duration: 356.405212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:45.375296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:44.960333Z","time spent":"414.935977ms","remote":"127.0.0.1:42950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 "} {"level":"warn","ts":"2026-05-19T19:11:45.375527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:45.05961Z","time spent":"315.897132ms","remote":"127.0.0.1:42988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-05-19T19:11:45.375709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:45.018903Z","time spent":"356.791133ms","remote":"127.0.0.1:42964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-19T19:11:45.375564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.395012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T19:11:45.375818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.892291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T19:11:45.375861Z","caller":"traceutil/trace.go:171","msg":"trace[1872623775] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:621; }","duration":"268.965951ms","start":"2026-05-19T19:11:45.106875Z","end":"2026-05-19T19:11:45.375841Z","steps":["trace[1872623775] 'agreement among raft nodes before linearized reading' (duration: 268.914931ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:45.37585Z","caller":"traceutil/trace.go:171","msg":"trace[1204999774] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:621; }","duration":"335.694433ms","start":"2026-05-19T19:11:45.040122Z","end":"2026-05-19T19:11:45.375816Z","steps":["trace[1204999774] 'agreement among raft nodes before linearized reading' (duration: 335.382732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:45.37591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:45.040115Z","time spent":"335.775433ms","remote":"127.0.0.1:42972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-19T19:11:45.375948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.068321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T19:11:45.375952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.595808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T19:11:45.37609Z","caller":"traceutil/trace.go:171","msg":"trace[1333833361] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:621; }","duration":"268.735739ms","start":"2026-05-19T19:11:45.107329Z","end":"2026-05-19T19:11:45.376065Z","steps":["trace[1333833361] 'agreement among raft nodes before linearized reading' (duration: 268.465778ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:45.375993Z","caller":"traceutil/trace.go:171","msg":"trace[972118216] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:621; }","duration":"269.126082ms","start":"2026-05-19T19:11:45.106854Z","end":"2026-05-19T19:11:45.37598Z","steps":["trace[972118216] 'agreement among raft nodes before linearized reading' (duration: 269.051531ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:46.159649Z","caller":"traceutil/trace.go:171","msg":"trace[1030531625] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"389.952983ms","start":"2026-05-19T19:11:45.769659Z","end":"2026-05-19T19:11:46.159612Z","steps":["trace[1030531625] 'read index received' (duration: 389.923573ms)","trace[1030531625] 'applied index is now lower than readState.Index' (duration: 28.46µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:11:46.159798Z","caller":"traceutil/trace.go:171","msg":"trace[882882850] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"480.182186ms","start":"2026-05-19T19:11:45.679521Z","end":"2026-05-19T19:11:46.159703Z","steps":["trace[882882850] 'process raft request' (duration: 479.965225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:46.159935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.274024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T19:11:46.160004Z","caller":"traceutil/trace.go:171","msg":"trace[1931180809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:627; }","duration":"390.347634ms","start":"2026-05-19T19:11:45.769634Z","end":"2026-05-19T19:11:46.159982Z","steps":["trace[1931180809] 'agreement among raft nodes before linearized reading' (duration: 390.120773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:46.160091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:45.679502Z","time spent":"480.404657ms","remote":"127.0.0.1:47522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T19:11:46.160137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:45.769564Z","time spent":"390.556335ms","remote":"127.0.0.1:47620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T19:11:46.660605Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322103177535151692,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T19:11:47.014189Z","caller":"traceutil/trace.go:171","msg":"trace[246797253] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"1.230820648s","start":"2026-05-19T19:11:45.783339Z","end":"2026-05-19T19:11:47.01416Z","steps":["trace[246797253] 'process raft request' (duration: 1.229787443s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:47.01446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:45.783317Z","time spent":"1.23101027s","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T19:11:47.806073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999870833s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-19T19:11:47.806171Z","caller":"traceutil/trace.go:171","msg":"trace[1515772219] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000026824s","start":"2026-05-19T19:11:45.806119Z","end":"2026-05-19T19:11:47.806146Z","steps":["trace[1515772219] 'agreement among raft nodes before linearized reading' (duration: 1.999907563s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:47.806234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:45.806072Z","time spent":"2.000149354s","remote":"127.0.0.1:47334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T19:11:48.034596Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.021699102s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T19:11:48.120937Z","caller":"traceutil/trace.go:171","msg":"trace[694359727] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:653; }","duration":"1.961179527s","start":"2026-05-19T19:11:46.159729Z","end":"2026-05-19T19:11:48.120908Z","steps":["trace[694359727] 'read index received' (duration: 853.248003ms)","trace[694359727] 'applied index is now lower than readState.Index' (duration: 1.107930024s)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:11:48.121067Z","caller":"traceutil/trace.go:171","msg":"trace[212059629] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"1.954602912s","start":"2026-05-19T19:11:46.166447Z","end":"2026-05-19T19:11:48.121049Z","steps":["trace[212059629] 'process raft request' (duration: 1.868445511s)","trace[212059629] 'compare' (duration: 85.624919ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T19:11:48.121193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:46.166428Z","time spent":"1.954681403s","remote":"127.0.0.1:47620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T19:11:48.142222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.190833208s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-19T19:11:48.142303Z","caller":"traceutil/trace.go:171","msg":"trace[1619828876] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"1.075272932s","start":"2026-05-19T19:11:47.067018Z","end":"2026-05-19T19:11:48.142291Z","steps":["trace[1619828876] 'process raft request' (duration: 1.07482618s)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:48.142306Z","caller":"traceutil/trace.go:171","msg":"trace[440586928] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"1.190962808s","start":"2026-05-19T19:11:46.951325Z","end":"2026-05-19T19:11:48.142288Z","steps":["trace[440586928] 'agreement among raft nodes before linearized reading' (duration: 1.190712117s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:48.142368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:46.951304Z","time spent":"1.191053989s","remote":"127.0.0.1:47620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T19:11:48.142255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.592666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-19T19:11:48.142725Z","caller":"traceutil/trace.go:171","msg":"trace[1043997919] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:631; }","duration":"256.081228ms","start":"2026-05-19T19:11:47.886618Z","end":"2026-05-19T19:11:48.142699Z","steps":["trace[1043997919] 'agreement among raft nodes before linearized reading' (duration: 255.500135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:48.142772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.421659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T19:11:48.142854Z","caller":"traceutil/trace.go:171","msg":"trace[1394763830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"335.541049ms","start":"2026-05-19T19:11:47.807297Z","end":"2026-05-19T19:11:48.142838Z","steps":["trace[1394763830] 'agreement among raft nodes before linearized reading' (duration: 335.427238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:48.142628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.936833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-19T19:11:48.14292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:47.807273Z","time spent":"335.63544ms","remote":"127.0.0.1:47340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T19:11:48.142619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:47.066993Z","time spent":"1.075339333s","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T19:11:48.142941Z","caller":"traceutil/trace.go:171","msg":"trace[384035040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"648.282026ms","start":"2026-05-19T19:11:47.494635Z","end":"2026-05-19T19:11:48.142917Z","steps":["trace[384035040] 'agreement among raft nodes before linearized reading' (duration: 647.802112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:48.143107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:47.494624Z","time spent":"648.406106ms","remote":"127.0.0.1:47620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T19:11:48.142613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.584682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-19T19:11:48.143181Z","caller":"traceutil/trace.go:171","msg":"trace[1284690263] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"659.143396ms","start":"2026-05-19T19:11:47.484018Z","end":"2026-05-19T19:11:48.143162Z","steps":["trace[1284690263] 'agreement among raft nodes before linearized reading' (duration: 658.516172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:48.143237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:47.483928Z","time spent":"659.295136ms","remote":"127.0.0.1:47620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-19T19:11:48.143366Z","caller":"traceutil/trace.go:171","msg":"trace[1282272163] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"912.059481ms","start":"2026-05-19T19:11:47.231285Z","end":"2026-05-19T19:11:48.143344Z","steps":["trace[1282272163] 'process raft request' (duration: 910.664563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:48.143496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:47.231262Z","time spent":"912.174942ms","remote":"127.0.0.1:47620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T19:11:50.151605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000529056s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-19T19:11:50.151672Z","caller":"traceutil/trace.go:171","msg":"trace[489873726] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000639547s","start":"2026-05-19T19:11:48.151017Z","end":"2026-05-19T19:11:50.151657Z","steps":["trace[489873726] 'agreement among raft nodes before linearized reading' (duration: 2.000554016s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:50.151724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:48.151002Z","time spent":"2.000714817s","remote":"127.0.0.1:47334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/19 19:11:50 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-19T19:11:51.062557Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.522653766s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-19T19:11:51.327948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.582459ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T19:11:51.32811Z","caller":"traceutil/trace.go:171","msg":"trace[2047148915] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:631; }","duration":"848.756121ms","start":"2026-05-19T19:11:50.479336Z","end":"2026-05-19T19:11:51.328092Z","steps":["trace[2047148915] 'range keys from in-memory index tree' (duration: 848.565939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:51.328345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.992403458s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T19:11:51.328773Z","caller":"traceutil/trace.go:171","msg":"trace[1525374994] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"3.135825868s","start":"2026-05-19T19:11:48.192923Z","end":"2026-05-19T19:11:51.328749Z","steps":["trace[1525374994] 'process raft request' (duration: 3.135759707s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:51.328909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:48.192899Z","time spent":"3.135936428s","remote":"127.0.0.1:47366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T19:11:51.329166Z","caller":"traceutil/trace.go:171","msg":"trace[1666933293] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"3.178779922s","start":"2026-05-19T19:11:48.150366Z","end":"2026-05-19T19:11:51.329146Z","steps":["trace[1666933293] 'process raft request' (duration: 3.178266359s)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:51.329199Z","caller":"traceutil/trace.go:171","msg":"trace[970841532] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"3.180104289s","start":"2026-05-19T19:11:48.149048Z","end":"2026-05-19T19:11:51.329152Z","steps":["trace[970841532] 'process raft request' (duration: 3.179534036s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:51.329254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:48.150347Z","time spent":"3.178863763s","remote":"127.0.0.1:47620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T19:11:51.329349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:48.149027Z","time spent":"3.18023998s","remote":"127.0.0.1:47620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T19:11:51.329369Z","caller":"traceutil/trace.go:171","msg":"trace[1387482663] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:658; }","duration":"3.180980835s","start":"2026-05-19T19:11:48.148373Z","end":"2026-05-19T19:11:51.329354Z","steps":["trace[1387482663] 'read index received' (duration: 43.628139ms)","trace[1387482663] 'applied index is now lower than readState.Index' (duration: 3.137351386s)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:11:51.329424Z","caller":"traceutil/trace.go:171","msg":"trace[703925310] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"3.18164143s","start":"2026-05-19T19:11:48.147767Z","end":"2026-05-19T19:11:51.329409Z","steps":["trace[703925310] 'process raft request' (duration: 3.180696513s)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:51.329472Z","caller":"traceutil/trace.go:171","msg":"trace[978301316] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"3.182081452s","start":"2026-05-19T19:11:48.147367Z","end":"2026-05-19T19:11:51.329448Z","steps":["trace[978301316] 'process raft request' (duration: 188.488806ms)","trace[978301316] 'compare' (duration: 2.990885757s)"],"step_count":2} {"level":"warn","ts":"2026-05-19T19:11:51.32954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:48.147751Z","time spent":"3.18172446s","remote":"127.0.0.1:47620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T19:11:51.329576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:48.147343Z","time spent":"3.182185873s","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T19:11:51.329543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.181160127s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-19T19:11:51.329739Z","caller":"traceutil/trace.go:171","msg":"trace[2099937591] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:636; }","duration":"3.181420199s","start":"2026-05-19T19:11:48.148308Z","end":"2026-05-19T19:11:51.329728Z","steps":["trace[2099937591] 'agreement among raft nodes before linearized reading' (duration: 3.181173847s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:51.329782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:48.14829Z","time spent":"3.181481649s","remote":"127.0.0.1:47546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"info","ts":"2026-05-19T19:11:51.5519Z","caller":"traceutil/trace.go:171","msg":"trace[548467279] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"222.455232ms","start":"2026-05-19T19:11:51.329399Z","end":"2026-05-19T19:11:51.551854Z","steps":["trace[548467279] 'read index received' (duration: 124.884878ms)","trace[548467279] 'applied index is now lower than readState.Index' (duration: 97.569474ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:11:51.551923Z","caller":"traceutil/trace.go:171","msg":"trace[548006279] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.286763672s","start":"2026-05-19T19:11:50.265121Z","end":"2026-05-19T19:11:51.551885Z","steps":["trace[548006279] 'process raft request' (duration: 1.189166989s)","trace[548006279] 'compare' (duration: 97.450272ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T19:11:51.552118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.554908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"warn","ts":"2026-05-19T19:11:51.552135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:50.265094Z","time spent":"1.286920574s","remote":"127.0.0.1:47620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T19:11:51.552175Z","caller":"traceutil/trace.go:171","msg":"trace[1962539080] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:637; }","duration":"220.624409ms","start":"2026-05-19T19:11:51.331518Z","end":"2026-05-19T19:11:51.552142Z","steps":["trace[1962539080] 'agreement among raft nodes before linearized reading' (duration: 220.493647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:51.552162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.400148305s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T19:11:51.552183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.426229401s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T19:11:51.552236Z","caller":"traceutil/trace.go:171","msg":"trace[1973367118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:637; }","duration":"1.426318781s","start":"2026-05-19T19:11:50.125904Z","end":"2026-05-19T19:11:51.552223Z","steps":["trace[1973367118] 'agreement among raft nodes before linearized reading' (duration: 1.426092559s)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:51.552233Z","caller":"traceutil/trace.go:171","msg":"trace[1714148100] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"1.400261795s","start":"2026-05-19T19:11:50.151953Z","end":"2026-05-19T19:11:51.552214Z","steps":["trace[1714148100] 'agreement among raft nodes before linearized reading' (duration: 1.400019333s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:51.55227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:50.12588Z","time spent":"1.426381141s","remote":"127.0.0.1:47620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T19:11:51.552279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:50.151937Z","time spent":"1.400331435s","remote":"127.0.0.1:47340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T19:11:51.552298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.767596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-19T19:11:51.55237Z","caller":"traceutil/trace.go:171","msg":"trace[723725222] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:637; }","duration":"218.858967ms","start":"2026-05-19T19:11:51.333493Z","end":"2026-05-19T19:11:51.552352Z","steps":["trace[723725222] 'agreement among raft nodes before linearized reading' (duration: 218.717246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:12:07.92341Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322103177535152019,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T19:12:08.423573Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322103177535152019,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T19:12:08.890204Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.46947966s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T19:12:08.890793Z","caller":"traceutil/trace.go:171","msg":"trace[911738422] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"1.470151868s","start":"2026-05-19T19:12:07.420612Z","end":"2026-05-19T19:12:08.890764Z","steps":["trace[911738422] 'process raft request' (duration: 1.469995556s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:12:08.891021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:12:07.420587Z","time spent":"1.47028232s","remote":"127.0.0.1:47620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T19:12:08.918493Z","caller":"traceutil/trace.go:171","msg":"trace[1850986477] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"1.125676602s","start":"2026-05-19T19:12:07.792787Z","end":"2026-05-19T19:12:08.918464Z","steps":["trace[1850986477] 'process raft request' (duration: 1.125491059s)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:12:08.918592Z","caller":"traceutil/trace.go:171","msg":"trace[1172443647] linearizableReadLoop","detail":"{readStateIndex:829; appliedIndex:827; }","duration":"1.496196243s","start":"2026-05-19T19:12:07.422374Z","end":"2026-05-19T19:12:08.91857Z","steps":["trace[1172443647] 'read index received' (duration: 1.468208803s)","trace[1172443647] 'applied index is now lower than readState.Index' (duration: 27.98562ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T19:12:08.918663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:12:07.792751Z","time spent":"1.125809233s","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T19:12:08.91869Z","caller":"traceutil/trace.go:171","msg":"trace[710321091] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"277.05903ms","start":"2026-05-19T19:12:08.6416Z","end":"2026-05-19T19:12:08.918659Z","steps":["trace[710321091] 'process raft request' (duration: 276.813817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:12:08.918808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.496423856s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-19T19:12:08.918837Z","caller":"traceutil/trace.go:171","msg":"trace[617333103] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:797; }","duration":"1.496520587s","start":"2026-05-19T19:12:07.422309Z","end":"2026-05-19T19:12:08.91883Z","steps":["trace[617333103] 'agreement among raft nodes before linearized reading' (duration: 1.496363185s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:12:08.918871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:12:07.422198Z","time spent":"1.496667529s","remote":"127.0.0.1:47620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T19:12:08.918982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.492906731s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T19:12:08.919002Z","caller":"traceutil/trace.go:171","msg":"trace[815514567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:797; }","duration":"1.492938412s","start":"2026-05-19T19:12:07.426059Z","end":"2026-05-19T19:12:08.918997Z","steps":["trace[815514567] 'agreement among raft nodes before linearized reading' (duration: 1.492888782s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:12:08.919018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:12:07.426051Z","time spent":"1.492963132s","remote":"127.0.0.1:47620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T19:12:08.919068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.030573828s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-19T19:12:08.919113Z","caller":"traceutil/trace.go:171","msg":"trace[2107990189] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:797; }","duration":"1.030660119s","start":"2026-05-19T19:12:07.888442Z","end":"2026-05-19T19:12:08.919102Z","steps":["trace[2107990189] 'agreement among raft nodes before linearized reading' (duration: 1.030530668s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:12:08.919148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.315698798s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-19T19:12:08.919149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:12:07.888418Z","time spent":"1.030723841s","remote":"127.0.0.1:47366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.57\" "} {"level":"info","ts":"2026-05-19T19:12:08.919172Z","caller":"traceutil/trace.go:171","msg":"trace[300233157] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:797; }","duration":"1.315762088s","start":"2026-05-19T19:12:07.603404Z","end":"2026-05-19T19:12:08.919167Z","steps":["trace[300233157] 'agreement among raft nodes before linearized reading' (duration: 1.315706077s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:12:08.919193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:12:07.603382Z","time spent":"1.315806509s","remote":"127.0.0.1:47620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T19:12:08.919192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.237513345s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T19:12:08.919239Z","caller":"traceutil/trace.go:171","msg":"trace[974491477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:797; }","duration":"1.237602827s","start":"2026-05-19T19:12:07.681625Z","end":"2026-05-19T19:12:08.919228Z","steps":["trace[974491477] 'agreement among raft nodes before linearized reading' (duration: 1.237462124s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:12:08.919275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:12:07.681606Z","time spent":"1.237661877s","remote":"127.0.0.1:47620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T19:12:08.919317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.124585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T19:12:08.919343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.129084185s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T19:12:08.919353Z","caller":"traceutil/trace.go:171","msg":"trace[1708315678] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:797; }","duration":"110.196866ms","start":"2026-05-19T19:12:08.809147Z","end":"2026-05-19T19:12:08.919344Z","steps":["trace[1708315678] 'agreement among raft nodes before linearized reading' (duration: 110.147076ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:12:08.919364Z","caller":"traceutil/trace.go:171","msg":"trace[833365409] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:797; }","duration":"1.129137776s","start":"2026-05-19T19:12:07.790221Z","end":"2026-05-19T19:12:08.919358Z","steps":["trace[833365409] 'agreement among raft nodes before linearized reading' (duration: 1.129102645s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:12:08.919391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:12:07.790206Z","time spent":"1.129178436s","remote":"127.0.0.1:47474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-05-19T19:12:47.353469Z","caller":"traceutil/trace.go:171","msg":"trace[1944729881] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"128.539674ms","start":"2026-05-19T19:12:47.224907Z","end":"2026-05-19T19:12:47.353447Z","steps":["trace[1944729881] 'process raft request' (duration: 127.83199ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:12:47.353766Z","caller":"traceutil/trace.go:171","msg":"trace[1283262804] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"128.395571ms","start":"2026-05-19T19:12:47.225359Z","end":"2026-05-19T19:12:47.353754Z","steps":["trace[1283262804] 'process raft request' (duration: 128.017873ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:13:29.808443Z","caller":"traceutil/trace.go:171","msg":"trace[1098211348] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"143.617641ms","start":"2026-05-19T19:13:29.664802Z","end":"2026-05-19T19:13:29.808419Z","steps":["trace[1098211348] 'process raft request' (duration: 143.473838ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:13:29.808693Z","caller":"traceutil/trace.go:171","msg":"trace[1344817322] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"143.824296ms","start":"2026-05-19T19:13:29.664828Z","end":"2026-05-19T19:13:29.808652Z","steps":["trace[1344817322] 'process raft request' (duration: 143.56641ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:13:52.842206Z","caller":"traceutil/trace.go:171","msg":"trace[1615573975] linearizableReadLoop","detail":"{readStateIndex:1353; appliedIndex:1352; }","duration":"118.998272ms","start":"2026-05-19T19:13:52.723184Z","end":"2026-05-19T19:13:52.842182Z","steps":["trace[1615573975] 'read index received' (duration: 118.900969ms)","trace[1615573975] 'applied index is now lower than readState.Index' (duration: 96.423µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:13:52.842344Z","caller":"traceutil/trace.go:171","msg":"trace[1640138584] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"139.679669ms","start":"2026-05-19T19:13:52.702631Z","end":"2026-05-19T19:13:52.84231Z","steps":["trace[1640138584] 'process raft request' (duration: 139.401352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:13:52.842381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.184207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T19:13:52.842569Z","caller":"traceutil/trace.go:171","msg":"trace[2027145201] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1296; }","duration":"119.370962ms","start":"2026-05-19T19:13:52.723169Z","end":"2026-05-19T19:13:52.84254Z","steps":["trace[2027145201] 'agreement among raft nodes before linearized reading' (duration: 119.151826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:13:54.158563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.521195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-19T19:13:54.158603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.125497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-19T19:13:54.158642Z","caller":"traceutil/trace.go:171","msg":"trace[671836094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1303; }","duration":"142.660719ms","start":"2026-05-19T19:13:54.015964Z","end":"2026-05-19T19:13:54.158624Z","steps":["trace[671836094] 'range keys from in-memory index tree' (duration: 142.296958ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:13:54.158676Z","caller":"traceutil/trace.go:171","msg":"trace[1617135059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1303; }","duration":"114.229891ms","start":"2026-05-19T19:13:54.04443Z","end":"2026-05-19T19:13:54.15866Z","steps":["trace[1617135059] 'range keys from in-memory index tree' (duration: 113.986334ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:13:56.240415Z","caller":"traceutil/trace.go:171","msg":"trace[180487944] linearizableReadLoop","detail":"{readStateIndex:1368; appliedIndex:1367; }","duration":"372.842343ms","start":"2026-05-19T19:13:55.867535Z","end":"2026-05-19T19:13:56.240378Z","steps":["trace[180487944] 'read index received' (duration: 346.254164ms)","trace[180487944] 'applied index is now lower than readState.Index' (duration: 26.586429ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:13:56.240569Z","caller":"traceutil/trace.go:171","msg":"trace[1028908781] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"378.967635ms","start":"2026-05-19T19:13:55.861558Z","end":"2026-05-19T19:13:56.240525Z","steps":["trace[1028908781] 'process raft request' (duration: 352.331825ms)","trace[1028908781] 'compare' (duration: 26.218828ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T19:13:56.240679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.408316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T19:13:56.24073Z","caller":"traceutil/trace.go:171","msg":"trace[51057002] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1310; }","duration":"322.503509ms","start":"2026-05-19T19:13:55.918215Z","end":"2026-05-19T19:13:56.240718Z","steps":["trace[51057002] 'agreement among raft nodes before linearized reading' (duration: 322.406436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:13:56.240761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:13:55.918193Z","time spent":"322.56252ms","remote":"127.0.0.1:47842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-05-19T19:13:56.240764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:13:55.861536Z","time spent":"379.118999ms","remote":"127.0.0.1:47620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T19:13:56.240833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.257124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-19T19:13:56.24088Z","caller":"traceutil/trace.go:171","msg":"trace[707931881] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1310; }","duration":"373.356617ms","start":"2026-05-19T19:13:55.86751Z","end":"2026-05-19T19:13:56.240867Z","steps":["trace[707931881] 'agreement among raft nodes before linearized reading' (duration: 373.064799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:13:56.240922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:13:55.867499Z","time spent":"373.413048ms","remote":"127.0.0.1:47620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T19:14:03.787643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.339648ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-19T19:14:03.787764Z","caller":"traceutil/trace.go:171","msg":"trace[12551596] linearizableReadLoop","detail":"{readStateIndex:1400; appliedIndex:1399; }","duration":"368.457627ms","start":"2026-05-19T19:14:03.419282Z","end":"2026-05-19T19:14:03.78774Z","steps":["trace[12551596] 'read index received' (duration: 134.938575ms)","trace[12551596] 'applied index is now lower than readState.Index' (duration: 233.517062ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T19:14:03.787896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.608212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T19:14:03.787953Z","caller":"traceutil/trace.go:171","msg":"trace[1348274421] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1340; }","duration":"368.715196ms","start":"2026-05-19T19:14:03.419219Z","end":"2026-05-19T19:14:03.787935Z","steps":["trace[1348274421] 'agreement among raft nodes before linearized reading' (duration: 368.585841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:14:03.788003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:14:03.419193Z","time spent":"368.795218ms","remote":"127.0.0.1:47606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-05-19T19:14:04.009398Z","caller":"traceutil/trace.go:171","msg":"trace[338188874] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"165.161765ms","start":"2026-05-19T19:14:03.843914Z","end":"2026-05-19T19:14:04.009076Z","steps":["trace[338188874] 'process raft request' (duration: 165.002421ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:14:14.721573Z","caller":"traceutil/trace.go:171","msg":"trace[780880335] linearizableReadLoop","detail":"{readStateIndex:1443; appliedIndex:1442; }","duration":"384.367998ms","start":"2026-05-19T19:14:14.337182Z","end":"2026-05-19T19:14:14.72155Z","steps":["trace[780880335] 'read index received' (duration: 384.239414ms)","trace[780880335] 'applied index is now lower than readState.Index' (duration: 127.114µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:14:14.721608Z","caller":"traceutil/trace.go:171","msg":"trace[2095687515] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"384.586014ms","start":"2026-05-19T19:14:14.337Z","end":"2026-05-19T19:14:14.721586Z","steps":["trace[2095687515] 'process raft request' (duration: 384.401909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:14:14.721776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:14:14.336988Z","time spent":"384.702378ms","remote":"127.0.0.1:47620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T19:14:14.721768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.566854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:cronjob-controller\" ","response":"range_response_count:1 size:747"} {"level":"info","ts":"2026-05-19T19:14:14.722076Z","caller":"traceutil/trace.go:171","msg":"trace[537426103] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:cronjob-controller; range_end:; response_count:1; response_revision:1380; }","duration":"384.930234ms","start":"2026-05-19T19:14:14.337126Z","end":"2026-05-19T19:14:14.722057Z","steps":["trace[537426103] 'agreement among raft nodes before linearized reading' (duration: 384.529212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:14:14.722166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:14:14.337106Z","time spent":"385.007377ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":770,"request content":"key:\"/registry/clusterrolebindings/system:controller:cronjob-controller\" "} {"level":"info","ts":"2026-05-19T19:14:14.724421Z","caller":"traceutil/trace.go:171","msg":"trace[359167928] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"385.299234ms","start":"2026-05-19T19:14:14.339099Z","end":"2026-05-19T19:14:14.724399Z","steps":["trace[359167928] 'process raft request' (duration: 385.14442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:14:14.724568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:14:14.339076Z","time spent":"385.411849ms","remote":"127.0.0.1:47620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T19:14:14.724779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.895654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T19:14:14.724847Z","caller":"traceutil/trace.go:171","msg":"trace[294695457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1383; }","duration":"383.992446ms","start":"2026-05-19T19:14:14.340839Z","end":"2026-05-19T19:14:14.724831Z","steps":["trace[294695457] 'agreement among raft nodes before linearized reading' (duration: 383.821492ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:14:14.724875Z","caller":"traceutil/trace.go:171","msg":"trace[1503083645] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"385.482011ms","start":"2026-05-19T19:14:14.339361Z","end":"2026-05-19T19:14:14.724843Z","steps":["trace[1503083645] 'process raft request' (duration: 385.000616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:14:14.724911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:14:14.340825Z","time spent":"384.059069ms","remote":"127.0.0.1:47620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T19:14:14.725039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:14:14.339351Z","time spent":"385.608334ms","remote":"127.0.0.1:47620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T19:14:14.725123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.863113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:route-controller\" ","response":"range_response_count:1 size:677"} {"level":"info","ts":"2026-05-19T19:14:14.725243Z","caller":"traceutil/trace.go:171","msg":"trace[859770734] range","detail":"{range_begin:/registry/clusterroles/system:controller:route-controller; range_end:; response_count:1; response_revision:1383; }","duration":"326.059378ms","start":"2026-05-19T19:14:14.399141Z","end":"2026-05-19T19:14:14.725201Z","steps":["trace[859770734] 'agreement among raft nodes before linearized reading' (duration: 325.619405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:14:14.725336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:14:14.39912Z","time spent":"326.182752ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":700,"request content":"key:\"/registry/clusterroles/system:controller:route-controller\" "}