{"level":"warn","ts":"2026-08-12T11:01:45.306405Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-12T11:01:45.306565Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.233: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.19.213.233:2380","--initial-cluster=instance=https://199.19.213.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.233: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-08-12T11:01:45.306706Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-12T11:01:45.306743Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.233:2380"]} {"level":"info","ts":"2026-08-12T11:01:45.306847Z","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-08-12T11:01:45.307946Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.233:2379"]} {"level":"info","ts":"2026-08-12T11:01:45.308049Z","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.19.213.233:2380"],"listen-peer-urls":["https://199.19.213.233:2380"],"advertise-client-urls":["https://199.19.213.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.233: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-08-12T11:01:45.312595Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.13416ms"} {"level":"info","ts":"2026-08-12T11:01:45.321275Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"de5c2ed1f829ccc5","cluster-id":"1c2360c7fdb72bdb"} {"level":"info","ts":"2026-08-12T11:01:45.321348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 switched to configuration voters=()"} {"level":"info","ts":"2026-08-12T11:01:45.321368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became follower at term 0"} {"level":"info","ts":"2026-08-12T11:01:45.321379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft de5c2ed1f829ccc5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-12T11:01:45.321386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became follower at term 1"} {"level":"info","ts":"2026-08-12T11:01:45.321416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 switched to configuration voters=(16022733053623913669)"} {"level":"warn","ts":"2026-08-12T11:01:45.331722Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-12T11:01:45.333224Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-12T11:01:45.334763Z","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-08-12T11:01:45.337749Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"de5c2ed1f829ccc5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-12T11:01:45.338381Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"de5c2ed1f829ccc5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-12T11:01:45.339258Z","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-08-12T11:01:45.339412Z","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-08-12T11:01:45.339438Z","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-08-12T11:01:45.340298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 switched to configuration voters=(16022733053623913669)"} {"level":"info","ts":"2026-08-12T11:01:45.340481Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1c2360c7fdb72bdb","local-member-id":"de5c2ed1f829ccc5","added-peer-id":"de5c2ed1f829ccc5","added-peer-peer-urls":["https://199.19.213.233:2380"]} {"level":"info","ts":"2026-08-12T11:01:45.342178Z","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-08-12T11:01:45.342449Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"de5c2ed1f829ccc5","initial-advertise-peer-urls":["https://199.19.213.233:2380"],"listen-peer-urls":["https://199.19.213.233:2380"],"advertise-client-urls":["https://199.19.213.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-12T11:01:45.342483Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-12T11:01:45.342558Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.233:2380"} {"level":"info","ts":"2026-08-12T11:01:45.342577Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.233:2380"} {"level":"info","ts":"2026-08-12T11:01:46.122305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 is starting a new election at term 1"} {"level":"info","ts":"2026-08-12T11:01:46.122368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-12T11:01:46.122403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 received MsgPreVoteResp from de5c2ed1f829ccc5 at term 1"} {"level":"info","ts":"2026-08-12T11:01:46.122418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became candidate at term 2"} {"level":"info","ts":"2026-08-12T11:01:46.122437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 received MsgVoteResp from de5c2ed1f829ccc5 at term 2"} {"level":"info","ts":"2026-08-12T11:01:46.122454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became leader at term 2"} {"level":"info","ts":"2026-08-12T11:01:46.122464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: de5c2ed1f829ccc5 elected leader de5c2ed1f829ccc5 at term 2"} {"level":"info","ts":"2026-08-12T11:01:46.124683Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"de5c2ed1f829ccc5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.233:2379]}","request-path":"/0/members/de5c2ed1f829ccc5/attributes","cluster-id":"1c2360c7fdb72bdb","publish-timeout":"7s"} {"level":"info","ts":"2026-08-12T11:01:46.124826Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T11:01:46.124876Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T11:01:46.125003Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T11:01:46.127334Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-12T11:01:46.129645Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.233:2379"} {"level":"info","ts":"2026-08-12T11:01:46.131042Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1c2360c7fdb72bdb","local-member-id":"de5c2ed1f829ccc5","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T11:01:46.131163Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T11:01:46.131202Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T11:01:46.134069Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-12T11:01:46.134143Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-12T11:01:47.652979Z","caller":"traceutil/trace.go:171","msg":"trace[753353049] linearizableReadLoop","detail":"{readStateIndex:10; appliedIndex:5; }","duration":"230.728205ms","start":"2026-08-12T11:01:47.422216Z","end":"2026-08-12T11:01:47.652944Z","steps":["trace[753353049] 'read index received' (duration: 228.463899ms)","trace[753353049] 'applied index is now lower than readState.Index' (duration: 2.263075ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:01:47.653343Z","caller":"traceutil/trace.go:171","msg":"trace[1021029305] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"253.294393ms","start":"2026-08-12T11:01:47.400001Z","end":"2026-08-12T11:01:47.653295Z","steps":["trace[1021029305] 'process raft request' (duration: 252.834339ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:01:47.653409Z","caller":"traceutil/trace.go:171","msg":"trace[777352382] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"252.319954ms","start":"2026-08-12T11:01:47.40107Z","end":"2026-08-12T11:01:47.65339Z","steps":["trace[777352382] 'process raft request' (duration: 251.83364ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:01:47.653544Z","caller":"traceutil/trace.go:171","msg":"trace[113951192] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"252.563281ms","start":"2026-08-12T11:01:47.400969Z","end":"2026-08-12T11:01:47.653532Z","steps":["trace[113951192] 'process raft request' (duration: 251.902932ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:01:47.653542Z","caller":"traceutil/trace.go:171","msg":"trace[1749397043] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"254.374363ms","start":"2026-08-12T11:01:47.399157Z","end":"2026-08-12T11:01:47.653531Z","steps":["trace[1749397043] 'process raft request' (duration: 251.512181ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:47.653626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.518542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-12T11:01:47.653679Z","caller":"traceutil/trace.go:171","msg":"trace[683235286] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"253.634422ms","start":"2026-08-12T11:01:47.399954Z","end":"2026-08-12T11:01:47.653589Z","steps":["trace[683235286] 'process raft request' (duration: 252.835509ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:01:47.653788Z","caller":"traceutil/trace.go:171","msg":"trace[1273828494] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:7; }","duration":"251.789469ms","start":"2026-08-12T11:01:47.401982Z","end":"2026-08-12T11:01:47.653771Z","steps":["trace[1273828494] 'agreement among raft nodes before linearized reading' (duration: 251.427489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:47.653586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.474899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-12T11:01:47.654042Z","caller":"traceutil/trace.go:171","msg":"trace[1336091039] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:7; }","duration":"252.922883ms","start":"2026-08-12T11:01:47.401067Z","end":"2026-08-12T11:01:47.653989Z","steps":["trace[1336091039] 'agreement among raft nodes before linearized reading' (duration: 252.13616ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:01:47.898233Z","caller":"traceutil/trace.go:171","msg":"trace[381832065] linearizableReadLoop","detail":"{readStateIndex:12; appliedIndex:10; }","duration":"244.903752ms","start":"2026-08-12T11:01:47.653272Z","end":"2026-08-12T11:01:47.898176Z","steps":["trace[381832065] 'read index received' (duration: 203.863833ms)","trace[381832065] 'applied index is now lower than readState.Index' (duration: 41.038279ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:01:47.898486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.984007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-12T11:01:47.898536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.415029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-12T11:01:47.89862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.915399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-12T11:01:47.898643Z","caller":"traceutil/trace.go:171","msg":"trace[1319332567] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"445.584313ms","start":"2026-08-12T11:01:47.453031Z","end":"2026-08-12T11:01:47.898615Z","steps":["trace[1319332567] 'agreement among raft nodes before linearized reading' (duration: 445.361247ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:01:47.898664Z","caller":"traceutil/trace.go:171","msg":"trace[1425445249] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:9; }","duration":"425.95286ms","start":"2026-08-12T11:01:47.472696Z","end":"2026-08-12T11:01:47.898649Z","steps":["trace[1425445249] 'agreement among raft nodes before linearized reading' (duration: 425.900769ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:47.898707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:47.472663Z","time spent":"426.035473ms","remote":"127.0.0.1:57276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-08-12T11:01:47.89878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:47.45302Z","time spent":"445.682587ms","remote":"127.0.0.1:57296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-08-12T11:01:47.898825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.501247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-12T11:01:47.898939Z","caller":"traceutil/trace.go:171","msg":"trace[571976255] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"425.665402ms","start":"2026-08-12T11:01:47.473251Z","end":"2026-08-12T11:01:47.898916Z","steps":["trace[571976255] 'agreement among raft nodes before linearized reading' (duration: 425.096106ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:01:47.898946Z","caller":"traceutil/trace.go:171","msg":"trace[1708155079] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"407.654985ms","start":"2026-08-12T11:01:47.491254Z","end":"2026-08-12T11:01:47.898909Z","steps":["trace[1708155079] 'process raft request' (duration: 406.827511ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:01:47.898969Z","caller":"traceutil/trace.go:171","msg":"trace[1201952927] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"423.019066ms","start":"2026-08-12T11:01:47.475925Z","end":"2026-08-12T11:01:47.898944Z","steps":["trace[1201952927] 'process raft request' (duration: 381.088852ms)","trace[1201952927] 'compare' (duration: 40.814602ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:01:47.898992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:47.473239Z","time spent":"425.745404ms","remote":"127.0.0.1:57296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-08-12T11:01:47.898567Z","caller":"traceutil/trace.go:171","msg":"trace[2019727324] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"445.10108ms","start":"2026-08-12T11:01:47.453445Z","end":"2026-08-12T11:01:47.898546Z","steps":["trace[2019727324] 'agreement among raft nodes before linearized reading' (duration: 444.899375ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:47.899146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.576144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-12T11:01:47.899184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:47.453428Z","time spent":"445.742138ms","remote":"127.0.0.1:57296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-08-12T11:01:47.899193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.08818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-12T11:01:47.899229Z","caller":"traceutil/trace.go:171","msg":"trace[1807804531] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"445.698008ms","start":"2026-08-12T11:01:47.45351Z","end":"2026-08-12T11:01:47.899208Z","steps":["trace[1807804531] 'agreement among raft nodes before linearized reading' (duration: 445.329327ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:01:47.899232Z","caller":"traceutil/trace.go:171","msg":"trace[850400417] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"375.145392ms","start":"2026-08-12T11:01:47.524075Z","end":"2026-08-12T11:01:47.899221Z","steps":["trace[850400417] 'agreement among raft nodes before linearized reading' (duration: 375.08544ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:47.899279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:47.524063Z","time spent":"375.209463ms","remote":"127.0.0.1:57296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-08-12T11:01:47.899294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.917077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.233\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-12T11:01:47.899322Z","caller":"traceutil/trace.go:171","msg":"trace[1151566885] range","detail":"{range_begin:/registry/masterleases/199.19.213.233; range_end:; response_count:0; response_revision:9; }","duration":"411.960379ms","start":"2026-08-12T11:01:47.487354Z","end":"2026-08-12T11:01:47.899314Z","steps":["trace[1151566885] 'agreement among raft nodes before linearized reading' (duration: 411.908237ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:47.899284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:47.453487Z","time spent":"445.782919ms","remote":"127.0.0.1:57296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-08-12T11:01:47.899345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:47.487346Z","time spent":"411.994179ms","remote":"127.0.0.1:57230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.19.213.233\" "} {"level":"warn","ts":"2026-08-12T11:01:47.899086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.919038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-kwsrh\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-08-12T11:01:47.899491Z","caller":"traceutil/trace.go:171","msg":"trace[308754189] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-kwsrh; range_end:; response_count:1; response_revision:9; }","duration":"474.481464ms","start":"2026-08-12T11:01:47.424952Z","end":"2026-08-12T11:01:47.899433Z","steps":["trace[308754189] 'agreement among raft nodes before linearized reading' (duration: 473.550967ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:47.899588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:47.424932Z","time spent":"474.640898ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":884,"request content":"key:\"/registry/certificatesigningrequests/csr-kwsrh\" "} {"level":"warn","ts":"2026-08-12T11:01:47.899713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:47.491237Z","time spent":"407.810469ms","remote":"127.0.0.1:57362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T11:01:47.899904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:47.475906Z","time spent":"423.14772ms","remote":"127.0.0.1:57612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T11:01:47.900249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.678258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-08-12T11:01:47.900376Z","caller":"traceutil/trace.go:171","msg":"trace[485553767] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:9; }","duration":"475.872744ms","start":"2026-08-12T11:01:47.424484Z","end":"2026-08-12T11:01:47.900357Z","steps":["trace[485553767] 'agreement among raft nodes before linearized reading' (duration: 473.866716ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:47.900522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:01:47.424476Z","time spent":"476.028738ms","remote":"127.0.0.1:57362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3194,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-08-12T11:01:47.900628Z","caller":"traceutil/trace.go:171","msg":"trace[268037305] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"242.293107ms","start":"2026-08-12T11:01:47.658321Z","end":"2026-08-12T11:01:47.900614Z","steps":["trace[268037305] 'process raft request' (duration: 242.261656ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:01:47.900806Z","caller":"traceutil/trace.go:171","msg":"trace[731240531] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"244.867331ms","start":"2026-08-12T11:01:47.655929Z","end":"2026-08-12T11:01:47.900796Z","steps":["trace[731240531] 'process raft request' (duration: 243.586834ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:01:47.900945Z","caller":"traceutil/trace.go:171","msg":"trace[1578431069] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"245.000045ms","start":"2026-08-12T11:01:47.655932Z","end":"2026-08-12T11:01:47.900932Z","steps":["trace[1578431069] 'process raft request' (duration: 244.429319ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:01:47.901238Z","caller":"traceutil/trace.go:171","msg":"trace[1768355777] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"243.266415ms","start":"2026-08-12T11:01:47.657949Z","end":"2026-08-12T11:01:47.901216Z","steps":["trace[1768355777] 'process raft request' (duration: 242.600166ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:01:47.901495Z","caller":"traceutil/trace.go:171","msg":"trace[540993852] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"243.564364ms","start":"2026-08-12T11:01:47.657909Z","end":"2026-08-12T11:01:47.901473Z","steps":["trace[540993852] 'process raft request' (duration: 242.607626ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:01:47.901625Z","caller":"traceutil/trace.go:171","msg":"trace[1413395294] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"243.896213ms","start":"2026-08-12T11:01:47.657717Z","end":"2026-08-12T11:01:47.901614Z","steps":["trace[1413395294] 'process raft request' (duration: 242.712199ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:01:47.901763Z","caller":"traceutil/trace.go:171","msg":"trace[1871227176] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"243.888152ms","start":"2026-08-12T11:01:47.657852Z","end":"2026-08-12T11:01:47.90174Z","steps":["trace[1871227176] 'process raft request' (duration: 242.618966ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:55.683235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.318044ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T11:01:55.683398Z","caller":"traceutil/trace.go:171","msg":"trace[712342928] linearizableReadLoop","detail":"{readStateIndex:315; appliedIndex:313; }","duration":"200.72271ms","start":"2026-08-12T11:01:55.482662Z","end":"2026-08-12T11:01:55.683385Z","steps":["trace[712342928] 'read index received' (duration: 84.929183ms)","trace[712342928] 'applied index is now lower than readState.Index' (duration: 115.792777ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:01:55.683469Z","caller":"traceutil/trace.go:171","msg":"trace[541656330] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"242.023473ms","start":"2026-08-12T11:01:55.441436Z","end":"2026-08-12T11:01:55.68346Z","steps":["trace[541656330] 'process raft request' (duration: 241.894379ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:01:55.683571Z","caller":"traceutil/trace.go:171","msg":"trace[1472046345] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"243.055991ms","start":"2026-08-12T11:01:55.440486Z","end":"2026-08-12T11:01:55.683542Z","steps":["trace[1472046345] 'process raft request' (duration: 127.149271ms)","trace[1472046345] 'compare' (duration: 115.229492ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:01:55.683776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.459456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-08-12T11:01:55.683817Z","caller":"traceutil/trace.go:171","msg":"trace[2128978855] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:304; }","duration":"151.512128ms","start":"2026-08-12T11:01:55.532293Z","end":"2026-08-12T11:01:55.683805Z","steps":["trace[2128978855] 'agreement among raft nodes before linearized reading' (duration: 151.429906ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:55.683957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.502744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-08-12T11:01:55.683989Z","caller":"traceutil/trace.go:171","msg":"trace[523821284] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:304; }","duration":"117.535695ms","start":"2026-08-12T11:01:55.566446Z","end":"2026-08-12T11:01:55.683982Z","steps":["trace[523821284] 'agreement among raft nodes before linearized reading' (duration: 117.476984ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:55.683993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.777441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-08-12T11:01:55.684032Z","caller":"traceutil/trace.go:171","msg":"trace[896576230] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:304; }","duration":"117.840653ms","start":"2026-08-12T11:01:55.566182Z","end":"2026-08-12T11:01:55.684023Z","steps":["trace[896576230] 'agreement among raft nodes before linearized reading' (duration: 117.777652ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:55.684151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.825608ms","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-08-12T11:01:55.684178Z","caller":"traceutil/trace.go:171","msg":"trace[2079274089] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:304; }","duration":"115.866099ms","start":"2026-08-12T11:01:55.568305Z","end":"2026-08-12T11:01:55.684171Z","steps":["trace[2079274089] 'agreement among raft nodes before linearized reading' (duration: 115.788627ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:55.684493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.901822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-08-12T11:01:55.684537Z","caller":"traceutil/trace.go:171","msg":"trace[715098937] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:304; }","duration":"201.988415ms","start":"2026-08-12T11:01:55.482537Z","end":"2026-08-12T11:01:55.684525Z","steps":["trace[715098937] 'agreement among raft nodes before linearized reading' (duration: 201.927153ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:01:55.685728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.532921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-08-12T11:01:55.685788Z","caller":"traceutil/trace.go:171","msg":"trace[1438674299] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:304; }","duration":"103.604302ms","start":"2026-08-12T11:01:55.582167Z","end":"2026-08-12T11:01:55.685771Z","steps":["trace[1438674299] 'agreement among raft nodes before linearized reading' (duration: 101.617508ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:01:55.800321Z","caller":"traceutil/trace.go:171","msg":"trace[229026871] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"111.589242ms","start":"2026-08-12T11:01:55.688696Z","end":"2026-08-12T11:01:55.800285Z","steps":["trace[229026871] 'process raft request' (duration: 111.359946ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:01:55.811235Z","caller":"traceutil/trace.go:171","msg":"trace[1451229528] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"121.452393ms","start":"2026-08-12T11:01:55.689763Z","end":"2026-08-12T11:01:55.811215Z","steps":["trace[1451229528] 'process raft request' (duration: 121.283499ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:01:55.811312Z","caller":"traceutil/trace.go:171","msg":"trace[2095327778] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"119.417718ms","start":"2026-08-12T11:01:55.691876Z","end":"2026-08-12T11:01:55.811294Z","steps":["trace[2095327778] 'process raft request' (duration: 119.335745ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:01:55.811319Z","caller":"traceutil/trace.go:171","msg":"trace[1947902656] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"120.891538ms","start":"2026-08-12T11:01:55.690403Z","end":"2026-08-12T11:01:55.811294Z","steps":["trace[1947902656] 'process raft request' (duration: 120.751534ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:01:55.811549Z","caller":"traceutil/trace.go:171","msg":"trace[542965988] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"116.912138ms","start":"2026-08-12T11:01:55.694626Z","end":"2026-08-12T11:01:55.811538Z","steps":["trace[542965988] 'process raft request' (duration: 116.800935ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:01:55.811533Z","caller":"traceutil/trace.go:171","msg":"trace[1598628471] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"118.688388ms","start":"2026-08-12T11:01:55.692832Z","end":"2026-08-12T11:01:55.81152Z","steps":["trace[1598628471] 'process raft request' (duration: 118.4153ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:01:57.070753Z","caller":"traceutil/trace.go:171","msg":"trace[1957856232] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"209.741687ms","start":"2026-08-12T11:01:56.860961Z","end":"2026-08-12T11:01:57.070703Z","steps":["trace[1957856232] 'process raft request' (duration: 209.589293ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:01:57.255755Z","caller":"traceutil/trace.go:171","msg":"trace[1447546106] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"112.441194ms","start":"2026-08-12T11:01:57.14327Z","end":"2026-08-12T11:01:57.255711Z","steps":["trace[1447546106] 'process raft request' (duration: 32.084381ms)","trace[1447546106] 'compare' (duration: 80.103376ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:02:06.437248Z","caller":"traceutil/trace.go:171","msg":"trace[1671308792] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"114.457611ms","start":"2026-08-12T11:02:06.322765Z","end":"2026-08-12T11:02:06.437222Z","steps":["trace[1671308792] 'process raft request' (duration: 114.229455ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:02:11.968348Z","caller":"traceutil/trace.go:171","msg":"trace[835956086] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"169.21912ms","start":"2026-08-12T11:02:11.799106Z","end":"2026-08-12T11:02:11.968325Z","steps":["trace[835956086] 'process raft request' (duration: 168.783029ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:02:26.587814Z","caller":"traceutil/trace.go:171","msg":"trace[1170937465] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"309.460281ms","start":"2026-08-12T11:02:26.278334Z","end":"2026-08-12T11:02:26.587794Z","steps":["trace[1170937465] 'process raft request' (duration: 309.281897ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:02:26.58795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:02:26.278305Z","time spent":"309.576453ms","remote":"127.0.0.1:57248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:02:26.594985Z","caller":"traceutil/trace.go:171","msg":"trace[686156703] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:587; }","duration":"247.966957ms","start":"2026-08-12T11:02:26.347Z","end":"2026-08-12T11:02:26.594967Z","steps":["trace[686156703] 'read index received' (duration: 240.482659ms)","trace[686156703] 'applied index is now lower than readState.Index' (duration: 7.483588ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:02:26.595039Z","caller":"traceutil/trace.go:171","msg":"trace[1090347555] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"261.404996ms","start":"2026-08-12T11:02:26.333613Z","end":"2026-08-12T11:02:26.595018Z","steps":["trace[1090347555] 'process raft request' (duration: 261.254682ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:02:26.595148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.13403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-08-12T11:02:26.595179Z","caller":"traceutil/trace.go:171","msg":"trace[1995424684] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:565; }","duration":"248.218912ms","start":"2026-08-12T11:02:26.346951Z","end":"2026-08-12T11:02:26.59517Z","steps":["trace[1995424684] 'agreement among raft nodes before linearized reading' (duration: 248.10911ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:02:26.917563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.447738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:65436"} {"level":"info","ts":"2026-08-12T11:02:26.917649Z","caller":"traceutil/trace.go:171","msg":"trace[1458447518] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:568; }","duration":"222.54087ms","start":"2026-08-12T11:02:26.695089Z","end":"2026-08-12T11:02:26.91763Z","steps":["trace[1458447518] 'range keys from in-memory index tree' (duration: 222.303125ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:02:26.922083Z","caller":"traceutil/trace.go:171","msg":"trace[812618555] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"151.013116ms","start":"2026-08-12T11:02:26.771038Z","end":"2026-08-12T11:02:26.922051Z","steps":["trace[812618555] 'process raft request' (duration: 150.478373ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:02:27.182809Z","caller":"traceutil/trace.go:171","msg":"trace[238150389] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"117.091798ms","start":"2026-08-12T11:02:27.065659Z","end":"2026-08-12T11:02:27.182751Z","steps":["trace[238150389] 'process raft request' (duration: 116.690268ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:02:32.779366Z","caller":"traceutil/trace.go:171","msg":"trace[1720488917] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"143.529694ms","start":"2026-08-12T11:02:32.635812Z","end":"2026-08-12T11:02:32.779342Z","steps":["trace[1720488917] 'process raft request' (duration: 143.479513ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:02:32.779478Z","caller":"traceutil/trace.go:171","msg":"trace[1227392087] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"143.626347ms","start":"2026-08-12T11:02:32.635792Z","end":"2026-08-12T11:02:32.779418Z","steps":["trace[1227392087] 'process raft request' (duration: 143.35776ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:02:34.666708Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755375631493951036,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T11:02:35.074495Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.118094115s","expected-duration":"1s"} {"level":"info","ts":"2026-08-12T11:02:35.075114Z","caller":"traceutil/trace.go:171","msg":"trace[1363692334] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"1.118860942s","start":"2026-08-12T11:02:33.956229Z","end":"2026-08-12T11:02:35.07509Z","steps":["trace[1363692334] 'process raft request' (duration: 1.118635797s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:02:35.075255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:02:33.956201Z","time spent":"1.118984215s","remote":"127.0.0.1:57364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:02:35.088537Z","caller":"traceutil/trace.go:171","msg":"trace[1966394732] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:638; }","duration":"922.404282ms","start":"2026-08-12T11:02:34.166097Z","end":"2026-08-12T11:02:35.088501Z","steps":["trace[1966394732] 'read index received' (duration: 908.751157ms)","trace[1966394732] 'applied index is now lower than readState.Index' (duration: 13.647015ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:02:35.088687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.583816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-12T11:02:35.088743Z","caller":"traceutil/trace.go:171","msg":"trace[1092966023] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:614; }","duration":"922.649277ms","start":"2026-08-12T11:02:34.166079Z","end":"2026-08-12T11:02:35.088728Z","steps":["trace[1092966023] 'agreement among raft nodes before linearized reading' (duration: 922.490354ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:02:35.088778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:02:34.165934Z","time spent":"922.838902ms","remote":"127.0.0.1:57426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-12T11:02:35.088864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.280303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-12T11:02:35.088914Z","caller":"traceutil/trace.go:171","msg":"trace[697767101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:614; }","duration":"304.394595ms","start":"2026-08-12T11:02:34.784505Z","end":"2026-08-12T11:02:35.088899Z","steps":["trace[697767101] 'agreement among raft nodes before linearized reading' (duration: 304.207941ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:02:35.088948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.419205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-12T11:02:35.088953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:02:34.784482Z","time spent":"304.462326ms","remote":"127.0.0.1:57426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-12T11:02:35.088979Z","caller":"traceutil/trace.go:171","msg":"trace[2134986591] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:614; }","duration":"109.489077ms","start":"2026-08-12T11:02:34.97948Z","end":"2026-08-12T11:02:35.088969Z","steps":["trace[2134986591] 'agreement among raft nodes before linearized reading' (duration: 109.391915ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:02:35.089006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.064508ms","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-08-12T11:02:35.089034Z","caller":"traceutil/trace.go:171","msg":"trace[1459036876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:614; }","duration":"304.129559ms","start":"2026-08-12T11:02:34.784896Z","end":"2026-08-12T11:02:35.089026Z","steps":["trace[1459036876] 'agreement among raft nodes before linearized reading' (duration: 304.045667ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:02:35.089056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:02:34.784877Z","time spent":"304.17343ms","remote":"127.0.0.1:57426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-12T11:02:38.336034Z","caller":"traceutil/trace.go:171","msg":"trace[434439468] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"207.527586ms","start":"2026-08-12T11:02:38.128483Z","end":"2026-08-12T11:02:38.336011Z","steps":["trace[434439468] 'process raft request' (duration: 207.332271ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:02:48.3151Z","caller":"traceutil/trace.go:171","msg":"trace[632215652] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"260.737289ms","start":"2026-08-12T11:02:48.054335Z","end":"2026-08-12T11:02:48.315072Z","steps":["trace[632215652] 'process raft request' (duration: 260.585756ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:02:51.532139Z","caller":"traceutil/trace.go:171","msg":"trace[542549447] linearizableReadLoop","detail":"{readStateIndex:800; appliedIndex:799; }","duration":"369.916246ms","start":"2026-08-12T11:02:51.1622Z","end":"2026-08-12T11:02:51.532116Z","steps":["trace[542549447] 'read index received' (duration: 369.731962ms)","trace[542549447] 'applied index is now lower than readState.Index' (duration: 183.244µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:02:51.532222Z","caller":"traceutil/trace.go:171","msg":"trace[749098585] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"489.97881ms","start":"2026-08-12T11:02:51.042208Z","end":"2026-08-12T11:02:51.532186Z","steps":["trace[749098585] 'process raft request' (duration: 489.781576ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:02:51.532369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.151791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-12T11:02:51.532423Z","caller":"traceutil/trace.go:171","msg":"trace[106492702] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:770; }","duration":"370.255763ms","start":"2026-08-12T11:02:51.162154Z","end":"2026-08-12T11:02:51.53241Z","steps":["trace[106492702] 'agreement among raft nodes before linearized reading' (duration: 370.09324ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:02:51.532423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:02:51.042186Z","time spent":"490.120493ms","remote":"127.0.0.1:57364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:02:51.532457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:02:51.162129Z","time spent":"370.319524ms","remote":"127.0.0.1:57426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-12T11:02:51.532681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.37641ms","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-08-12T11:02:51.532744Z","caller":"traceutil/trace.go:171","msg":"trace[1397159022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:770; }","duration":"362.482702ms","start":"2026-08-12T11:02:51.170246Z","end":"2026-08-12T11:02:51.532729Z","steps":["trace[1397159022] 'agreement among raft nodes before linearized reading' (duration: 362.321859ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:02:51.532737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.164732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-08-12T11:02:51.532787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:02:51.170227Z","time spent":"362.548984ms","remote":"127.0.0.1:57426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-12T11:02:51.532822Z","caller":"traceutil/trace.go:171","msg":"trace[1316774348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:770; }","duration":"360.278644ms","start":"2026-08-12T11:02:51.172523Z","end":"2026-08-12T11:02:51.532801Z","steps":["trace[1316774348] 'agreement among raft nodes before linearized reading' (duration: 360.013178ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:02:51.532762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.535365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-12T11:02:51.532868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:02:51.172514Z","time spent":"360.346406ms","remote":"127.0.0.1:57426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-12T11:02:51.532885Z","caller":"traceutil/trace.go:171","msg":"trace[823176180] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:770; }","duration":"363.74541ms","start":"2026-08-12T11:02:51.16912Z","end":"2026-08-12T11:02:51.532865Z","steps":["trace[823176180] 'agreement among raft nodes before linearized reading' (duration: 363.381482ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:02:51.532936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:02:51.169101Z","time spent":"363.821041ms","remote":"127.0.0.1:57426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-12T11:03:44.14577Z","caller":"traceutil/trace.go:171","msg":"trace[61763969] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"100.406831ms","start":"2026-08-12T11:03:44.04534Z","end":"2026-08-12T11:03:44.145746Z","steps":["trace[61763969] 'process raft request' (duration: 100.272769ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:38.767393Z","caller":"traceutil/trace.go:171","msg":"trace[1939037744] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"530.235691ms","start":"2026-08-12T11:04:38.237123Z","end":"2026-08-12T11:04:38.767359Z","steps":["trace[1939037744] 'process raft request' (duration: 530.092509ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:38.767522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:38.237106Z","time spent":"530.344134ms","remote":"127.0.0.1:57426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:04:38.767382Z","caller":"traceutil/trace.go:171","msg":"trace[1422832411] linearizableReadLoop","detail":"{readStateIndex:1337; appliedIndex:1336; }","duration":"456.001808ms","start":"2026-08-12T11:04:38.311359Z","end":"2026-08-12T11:04:38.76736Z","steps":["trace[1422832411] 'read index received' (duration: 455.981178ms)","trace[1422832411] 'applied index is now lower than readState.Index' (duration: 19.45µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:04:38.76771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.346825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-12T11:04:38.767766Z","caller":"traceutil/trace.go:171","msg":"trace[59759797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1283; }","duration":"456.435187ms","start":"2026-08-12T11:04:38.31132Z","end":"2026-08-12T11:04:38.767755Z","steps":["trace[59759797] 'agreement among raft nodes before linearized reading' (duration: 456.108251ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:38.767908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:38.311304Z","time spent":"456.591369ms","remote":"127.0.0.1:57426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-12T11:04:39.067847Z","caller":"traceutil/trace.go:171","msg":"trace[367466315] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"295.272821ms","start":"2026-08-12T11:04:38.772542Z","end":"2026-08-12T11:04:39.067815Z","steps":["trace[367466315] 'process raft request' (duration: 295.21119ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:39.067974Z","caller":"traceutil/trace.go:171","msg":"trace[1456007925] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"469.666754ms","start":"2026-08-12T11:04:38.598269Z","end":"2026-08-12T11:04:39.067936Z","steps":["trace[1456007925] 'process raft request' (duration: 468.075654ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:39.068128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:38.598247Z","time spent":"469.806177ms","remote":"127.0.0.1:57426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:04:39.068182Z","caller":"traceutil/trace.go:171","msg":"trace[454807799] linearizableReadLoop","detail":"{readStateIndex:1338; appliedIndex:1337; }","duration":"300.348206ms","start":"2026-08-12T11:04:38.767749Z","end":"2026-08-12T11:04:39.068098Z","steps":["trace[454807799] 'read index received' (duration: 298.595753ms)","trace[454807799] 'applied index is now lower than readState.Index' (duration: 1.731813ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:04:39.068405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.437728ms","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-08-12T11:04:39.068495Z","caller":"traceutil/trace.go:171","msg":"trace[1983748630] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1285; }","duration":"722.580651ms","start":"2026-08-12T11:04:38.345892Z","end":"2026-08-12T11:04:39.068473Z","steps":["trace[1983748630] 'agreement among raft nodes before linearized reading' (duration: 722.273245ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:39.068356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.368386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-12T11:04:39.068587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:38.345872Z","time spent":"722.666512ms","remote":"127.0.0.1:57426","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-08-12T11:04:39.068577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.109265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:04:39.068654Z","caller":"traceutil/trace.go:171","msg":"trace[1444780426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1285; }","duration":"536.731373ms","start":"2026-08-12T11:04:38.531883Z","end":"2026-08-12T11:04:39.068615Z","steps":["trace[1444780426] 'agreement among raft nodes before linearized reading' (duration: 536.196643ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:39.068736Z","caller":"traceutil/trace.go:171","msg":"trace[290933953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1285; }","duration":"224.372941ms","start":"2026-08-12T11:04:38.844339Z","end":"2026-08-12T11:04:39.068712Z","steps":["trace[290933953] 'agreement among raft nodes before linearized reading' (duration: 224.079445ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:39.068767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:38.531862Z","time spent":"536.870406ms","remote":"127.0.0.1:57426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-12T11:04:46.308278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.075953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:04:46.308412Z","caller":"traceutil/trace.go:171","msg":"trace[1007483653] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1315; }","duration":"448.244956ms","start":"2026-08-12T11:04:45.860144Z","end":"2026-08-12T11:04:46.308389Z","steps":["trace[1007483653] 'count revisions from in-memory index tree' (duration: 448.009231ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:46.308301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.97849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T11:04:46.308469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:45.860133Z","time spent":"448.324488ms","remote":"127.0.0.1:34128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-08-12T11:04:46.308538Z","caller":"traceutil/trace.go:171","msg":"trace[1287090865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1315; }","duration":"464.226965ms","start":"2026-08-12T11:04:45.844261Z","end":"2026-08-12T11:04:46.308488Z","steps":["trace[1287090865] 'range keys from in-memory index tree' (duration: 463.896039ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:46.308713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:45.844243Z","time spent":"464.390498ms","remote":"127.0.0.1:57200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-12T11:04:46.915143Z","caller":"traceutil/trace.go:171","msg":"trace[1994872220] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"110.761949ms","start":"2026-08-12T11:04:46.804353Z","end":"2026-08-12T11:04:46.915115Z","steps":["trace[1994872220] 'process raft request' (duration: 110.535935ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:47.398485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.028653ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T11:04:47.398562Z","caller":"traceutil/trace.go:171","msg":"trace[268814750] linearizableReadLoop","detail":"{readStateIndex:1373; appliedIndex:1372; }","duration":"285.191565ms","start":"2026-08-12T11:04:47.113358Z","end":"2026-08-12T11:04:47.39855Z","steps":["trace[268814750] 'read index received' (duration: 120.992039ms)","trace[268814750] 'applied index is now lower than readState.Index' (duration: 164.198666ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:04:47.398809Z","caller":"traceutil/trace.go:171","msg":"trace[1063755038] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"290.720699ms","start":"2026-08-12T11:04:47.108079Z","end":"2026-08-12T11:04:47.398799Z","steps":["trace[1063755038] 'process raft request' (duration: 126.34718ms)","trace[1063755038] 'compare' (duration: 163.88586ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:04:47.399023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.085616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-12T11:04:47.399089Z","caller":"traceutil/trace.go:171","msg":"trace[1706037993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1317; }","duration":"276.184817ms","start":"2026-08-12T11:04:47.122887Z","end":"2026-08-12T11:04:47.399071Z","steps":["trace[1706037993] 'agreement among raft nodes before linearized reading' (duration: 275.974133ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:47.399164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.144582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-12T11:04:47.399249Z","caller":"traceutil/trace.go:171","msg":"trace[798087167] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1317; }","duration":"166.260665ms","start":"2026-08-12T11:04:47.232967Z","end":"2026-08-12T11:04:47.399227Z","steps":["trace[798087167] 'agreement among raft nodes before linearized reading' (duration: 165.99758ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:47.399314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.621586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-12T11:04:47.399344Z","caller":"traceutil/trace.go:171","msg":"trace[1799697198] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1317; }","duration":"238.674257ms","start":"2026-08-12T11:04:47.160662Z","end":"2026-08-12T11:04:47.399337Z","steps":["trace[1799697198] 'agreement among raft nodes before linearized reading' (duration: 238.570295ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:47.399446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.095882ms","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-08-12T11:04:47.39947Z","caller":"traceutil/trace.go:171","msg":"trace[1895280476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1317; }","duration":"286.174943ms","start":"2026-08-12T11:04:47.113287Z","end":"2026-08-12T11:04:47.399462Z","steps":["trace[1895280476] 'agreement among raft nodes before linearized reading' (duration: 285.709675ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:47.729475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.792327ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T11:04:47.729588Z","caller":"traceutil/trace.go:171","msg":"trace[1316863149] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"326.621669ms","start":"2026-08-12T11:04:47.402943Z","end":"2026-08-12T11:04:47.729565Z","steps":["trace[1316863149] 'process raft request' (duration: 101.672849ms)","trace[1316863149] 'compare' (duration: 224.514142ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:04:47.729645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:47.402916Z","time spent":"326.70256ms","remote":"127.0.0.1:57426","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-08-12T11:04:48.193548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.517651ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T11:04:48.19399Z","caller":"traceutil/trace.go:171","msg":"trace[1276090680] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"790.052861ms","start":"2026-08-12T11:04:47.403906Z","end":"2026-08-12T11:04:48.193959Z","steps":["trace[1276090680] 'process raft request' (duration: 468.066111ms)","trace[1276090680] 'compare' (duration: 321.44706ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:04:48.194151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:47.40389Z","time spent":"790.183363ms","remote":"127.0.0.1:57426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:04:48.194462Z","caller":"traceutil/trace.go:171","msg":"trace[1063364452] linearizableReadLoop","detail":"{readStateIndex:1377; appliedIndex:1374; }","duration":"351.503732ms","start":"2026-08-12T11:04:47.84294Z","end":"2026-08-12T11:04:48.194443Z","steps":["trace[1063364452] 'read index received' (duration: 28.932291ms)","trace[1063364452] 'applied index is now lower than readState.Index' (duration: 322.570221ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:04:48.194562Z","caller":"traceutil/trace.go:171","msg":"trace[2145511730] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"790.501459ms","start":"2026-08-12T11:04:47.404049Z","end":"2026-08-12T11:04:48.194551Z","steps":["trace[2145511730] 'process raft request' (duration: 790.330106ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:48.194624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:47.404035Z","time spent":"790.55315ms","remote":"127.0.0.1:57426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:04:48.194677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.153264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-08-12T11:04:48.194761Z","caller":"traceutil/trace.go:171","msg":"trace[1115390831] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1321; }","duration":"291.266347ms","start":"2026-08-12T11:04:47.90348Z","end":"2026-08-12T11:04:48.194747Z","steps":["trace[1115390831] 'agreement among raft nodes before linearized reading' (duration: 291.144625ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:48.194769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.721165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:46729"} {"level":"warn","ts":"2026-08-12T11:04:48.194708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.728076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:04:48.194843Z","caller":"traceutil/trace.go:171","msg":"trace[401790428] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1321; }","duration":"291.827047ms","start":"2026-08-12T11:04:47.902987Z","end":"2026-08-12T11:04:48.194814Z","steps":["trace[401790428] 'agreement among raft nodes before linearized reading' (duration: 291.604033ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:48.194842Z","caller":"traceutil/trace.go:171","msg":"trace[1478294937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1321; }","duration":"351.925969ms","start":"2026-08-12T11:04:47.842898Z","end":"2026-08-12T11:04:48.194824Z","steps":["trace[1478294937] 'agreement among raft nodes before linearized reading' (duration: 351.698955ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:48.194915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:47.842879Z","time spent":"352.024142ms","remote":"127.0.0.1:57200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-12T11:04:48.195033Z","caller":"traceutil/trace.go:171","msg":"trace[989213460] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"790.460198ms","start":"2026-08-12T11:04:47.403981Z","end":"2026-08-12T11:04:48.194442Z","steps":["trace[989213460] 'process raft request' (duration: 789.653543ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:48.195126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:04:47.403975Z","time spent":"791.11117ms","remote":"127.0.0.1:57426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}