{"level":"warn","ts":"2026-05-14T07:24:45.151818Z","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-14T07:24:45.15191Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162: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.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162: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-14T07:24:45.151995Z","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-14T07:24:45.152003Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-05-14T07:24:45.15202Z","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-14T07:24:45.153147Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-05-14T07:24:45.153219Z","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.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162: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-14T07:24:45.158269Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.863146ms"} {"level":"info","ts":"2026-05-14T07:24:45.166003Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-05-14T07:24:45.166076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-05-14T07:24:45.166094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-05-14T07:24:45.166103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-14T07:24:45.16611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-05-14T07:24:45.16614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-05-14T07:24:45.18395Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-14T07:24:45.185459Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-14T07:24:45.188022Z","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-14T07:24:45.190595Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-14T07:24:45.1907Z","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-14T07:24:45.192112Z","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-14T07:24:45.192125Z","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-14T07:24:45.190863Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-14T07:24:45.194796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-05-14T07:24:45.194914Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-05-14T07:24:45.196018Z","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-14T07:24:45.196142Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-05-14T07:24:45.196167Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-05-14T07:24:45.1963Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-14T07:24:45.196354Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-14T07:24:45.766287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-05-14T07:24:45.766355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-05-14T07:24:45.766371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-05-14T07:24:45.766386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-05-14T07:24:45.766393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-05-14T07:24:45.766403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-05-14T07:24:45.766412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-05-14T07:24:45.771977Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-05-14T07:24:45.772009Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T07:24:45.772139Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T07:24:45.772199Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T07:24:45.774379Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T07:24:45.774428Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-05-14T07:24:45.774467Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-14T07:24:45.774562Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-14T07:24:45.774664Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T07:24:45.774722Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T07:24:45.775589Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-14T07:24:47.184791Z","caller":"traceutil/trace.go:171","msg":"trace[259215949] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"115.609521ms","start":"2026-05-14T07:24:47.069143Z","end":"2026-05-14T07:24:47.184753Z","steps":["trace[259215949] 'process raft request' (duration: 87.559293ms)","trace[259215949] 'compare' (duration: 27.818464ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:24:47.18494Z","caller":"traceutil/trace.go:171","msg":"trace[275314711] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"115.017585ms","start":"2026-05-14T07:24:47.069893Z","end":"2026-05-14T07:24:47.184911Z","steps":["trace[275314711] 'process raft request' (duration: 114.784352ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:47.185004Z","caller":"traceutil/trace.go:171","msg":"trace[1673801361] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"114.825074ms","start":"2026-05-14T07:24:47.070153Z","end":"2026-05-14T07:24:47.184978Z","steps":["trace[1673801361] 'process raft request' (duration: 114.572629ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:47.185069Z","caller":"traceutil/trace.go:171","msg":"trace[1955924609] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"104.802612ms","start":"2026-05-14T07:24:47.080253Z","end":"2026-05-14T07:24:47.185056Z","steps":["trace[1955924609] 'process raft request' (duration: 104.642842ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:47.185344Z","caller":"traceutil/trace.go:171","msg":"trace[2126816923] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"114.965553ms","start":"2026-05-14T07:24:47.070344Z","end":"2026-05-14T07:24:47.18531Z","steps":["trace[2126816923] 'process raft request' (duration: 114.452653ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:47.185468Z","caller":"traceutil/trace.go:171","msg":"trace[1505459512] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"115.146213ms","start":"2026-05-14T07:24:47.070305Z","end":"2026-05-14T07:24:47.185452Z","steps":["trace[1505459512] 'process raft request' (duration: 114.455072ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:47.185078Z","caller":"traceutil/trace.go:171","msg":"trace[1944220878] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"104.876867ms","start":"2026-05-14T07:24:47.080139Z","end":"2026-05-14T07:24:47.185015Z","steps":["trace[1944220878] 'process raft request' (duration: 104.703876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:47.185586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.93747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-14T07:24:47.18567Z","caller":"traceutil/trace.go:171","msg":"trace[451730417] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"116.351605ms","start":"2026-05-14T07:24:47.069308Z","end":"2026-05-14T07:24:47.185659Z","steps":["trace[451730417] 'agreement among raft nodes before linearized reading' (duration: 115.886857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:47.185774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.738908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-14T07:24:47.185916Z","caller":"traceutil/trace.go:171","msg":"trace[1559341061] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"115.913519ms","start":"2026-05-14T07:24:47.069971Z","end":"2026-05-14T07:24:47.185885Z","steps":["trace[1559341061] 'agreement among raft nodes before linearized reading' (duration: 115.164705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:48.755798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.571088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-14T07:24:48.755908Z","caller":"traceutil/trace.go:171","msg":"trace[406152374] transaction","detail":"{read_only:false; response_revision:99; number_of_response:1; }","duration":"271.439817ms","start":"2026-05-14T07:24:48.48445Z","end":"2026-05-14T07:24:48.75589Z","steps":["trace[406152374] 'process raft request' (duration: 139.338428ms)","trace[406152374] 'compare' (duration: 131.45248ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:24:49.044863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.854323ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-14T07:24:49.045017Z","caller":"traceutil/trace.go:171","msg":"trace[60531881] transaction","detail":"{read_only:false; response_revision:101; number_of_response:1; }","duration":"280.266744ms","start":"2026-05-14T07:24:48.764714Z","end":"2026-05-14T07:24:49.044981Z","steps":["trace[60531881] 'process raft request' (duration: 177.166927ms)","trace[60531881] 'compare' (duration: 102.721635ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:24:49.41827Z","caller":"traceutil/trace.go:171","msg":"trace[335998546] transaction","detail":"{read_only:false; response_revision:115; number_of_response:1; }","duration":"118.905081ms","start":"2026-05-14T07:24:49.299343Z","end":"2026-05-14T07:24:49.418248Z","steps":["trace[335998546] 'process raft request' (duration: 118.830877ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:49.418278Z","caller":"traceutil/trace.go:171","msg":"trace[1865727753] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"119.466124ms","start":"2026-05-14T07:24:49.298783Z","end":"2026-05-14T07:24:49.418249Z","steps":["trace[1865727753] 'process raft request' (duration: 119.275613ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:49.418604Z","caller":"traceutil/trace.go:171","msg":"trace[207747741] transaction","detail":"{read_only:false; response_revision:116; number_of_response:1; }","duration":"118.668426ms","start":"2026-05-14T07:24:49.299921Z","end":"2026-05-14T07:24:49.418589Z","steps":["trace[207747741] 'process raft request' (duration: 118.292494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:51.089775Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744667910139945,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T07:24:51.78622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.25114033s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-14T07:24:51.786374Z","caller":"traceutil/trace.go:171","msg":"trace[2021054498] linearizableReadLoop","detail":"{readStateIndex:248; appliedIndex:246; }","duration":"1.197025656s","start":"2026-05-14T07:24:50.589332Z","end":"2026-05-14T07:24:51.786358Z","steps":["trace[2021054498] 'read index received' (duration: 758.071976ms)","trace[2021054498] 'applied index is now lower than readState.Index' (duration: 438.95293ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:24:51.78655Z","caller":"traceutil/trace.go:171","msg":"trace[1700496214] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"1.628528769s","start":"2026-05-14T07:24:50.158009Z","end":"2026-05-14T07:24:51.786538Z","steps":["trace[1700496214] 'process raft request' (duration: 377.005706ms)","trace[1700496214] 'compare' (duration: 1.250292519s)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:24:51.786603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:50.157987Z","time spent":"1.628594582s","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T07:24:51.786816Z","caller":"traceutil/trace.go:171","msg":"trace[1100339805] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"1.553389176s","start":"2026-05-14T07:24:50.233417Z","end":"2026-05-14T07:24:51.786806Z","steps":["trace[1100339805] 'process raft request' (duration: 1.552889896s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:51.786861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:50.233397Z","time spent":"1.553446039s","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1069,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T07:24:51.786973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.197635132s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:24:51.786999Z","caller":"traceutil/trace.go:171","msg":"trace[1396540285] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:243; }","duration":"1.197694616s","start":"2026-05-14T07:24:50.589297Z","end":"2026-05-14T07:24:51.786992Z","steps":["trace[1396540285] 'agreement among raft nodes before linearized reading' (duration: 1.197636742s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:51.787024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:50.589274Z","time spent":"1.197740618s","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T07:24:51.787201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.231383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-14T07:24:51.787237Z","caller":"traceutil/trace.go:171","msg":"trace[1473902552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:243; }","duration":"365.293837ms","start":"2026-05-14T07:24:51.421931Z","end":"2026-05-14T07:24:51.787225Z","steps":["trace[1473902552] 'agreement among raft nodes before linearized reading' (duration: 365.237123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:51.787257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:51.421911Z","time spent":"365.341719ms","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-14T07:24:51.787425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.447112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:24:51.787463Z","caller":"traceutil/trace.go:171","msg":"trace[963587075] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:243; }","duration":"170.500076ms","start":"2026-05-14T07:24:51.616946Z","end":"2026-05-14T07:24:51.787446Z","steps":["trace[963587075] 'agreement among raft nodes before linearized reading' (duration: 170.462654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:51.787553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.547712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-14T07:24:51.787581Z","caller":"traceutil/trace.go:171","msg":"trace[902879849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:243; }","duration":"365.588994ms","start":"2026-05-14T07:24:51.421988Z","end":"2026-05-14T07:24:51.787577Z","steps":["trace[902879849] 'agreement among raft nodes before linearized reading' (duration: 365.546801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:51.787599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:51.421981Z","time spent":"365.613466ms","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-14T07:24:52.126575Z","caller":"traceutil/trace.go:171","msg":"trace[803445464] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"334.833546ms","start":"2026-05-14T07:24:51.791699Z","end":"2026-05-14T07:24:52.126533Z","steps":["trace[803445464] 'process raft request' (duration: 332.944613ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:52.126641Z","caller":"traceutil/trace.go:171","msg":"trace[580544188] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"334.797704ms","start":"2026-05-14T07:24:51.791802Z","end":"2026-05-14T07:24:52.1266Z","steps":["trace[580544188] 'process raft request' (duration: 334.659036ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:52.126634Z","caller":"traceutil/trace.go:171","msg":"trace[1878398864] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"333.780423ms","start":"2026-05-14T07:24:51.792828Z","end":"2026-05-14T07:24:52.126609Z","steps":["trace[1878398864] 'process raft request' (duration: 333.701319ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:52.126834Z","caller":"traceutil/trace.go:171","msg":"trace[304484701] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"333.923362ms","start":"2026-05-14T07:24:51.79289Z","end":"2026-05-14T07:24:52.126813Z","steps":["trace[304484701] 'process raft request' (duration: 333.675287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:52.127015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:51.792883Z","time spent":"334.093422ms","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T07:24:52.1268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:51.79168Z","time spent":"334.992875ms","remote":"127.0.0.1:48702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":342,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T07:24:52.12706Z","caller":"traceutil/trace.go:171","msg":"trace[897330913] linearizableReadLoop","detail":"{readStateIndex:252; appliedIndex:248; }","duration":"333.89711ms","start":"2026-05-14T07:24:51.793112Z","end":"2026-05-14T07:24:52.127009Z","steps":["trace[897330913] 'read index received' (duration: 331.517548ms)","trace[897330913] 'applied index is now lower than readState.Index' (duration: 2.377492ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:24:52.127224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.102072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:24:52.12728Z","caller":"traceutil/trace.go:171","msg":"trace[388091342] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:0; response_revision:248; }","duration":"334.180648ms","start":"2026-05-14T07:24:51.793087Z","end":"2026-05-14T07:24:52.127268Z","steps":["trace[388091342] 'agreement among raft nodes before linearized reading' (duration: 334.002547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:52.127305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:51.793071Z","time spent":"334.2266ms","remote":"127.0.0.1:48362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.162\" "} {"level":"info","ts":"2026-05-14T07:24:52.127384Z","caller":"traceutil/trace.go:171","msg":"trace[2113539926] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"333.443824ms","start":"2026-05-14T07:24:51.793891Z","end":"2026-05-14T07:24:52.127334Z","steps":["trace[2113539926] 'process raft request' (duration: 332.858949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:52.127536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:51.793866Z","time spent":"333.604243ms","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T07:24:52.128565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:51.792817Z","time spent":"334.05415ms","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:24:52.128669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:51.791793Z","time spent":"334.920732ms","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:24:52.900297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.352229ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-14T07:24:52.900413Z","caller":"traceutil/trace.go:171","msg":"trace[1019315535] linearizableReadLoop","detail":"{readStateIndex:281; appliedIndex:280; }","duration":"118.327402ms","start":"2026-05-14T07:24:52.782069Z","end":"2026-05-14T07:24:52.900397Z","steps":["trace[1019315535] 'read index received' (duration: 51.923µs)","trace[1019315535] 'applied index is now lower than readState.Index' (duration: 118.274149ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:24:52.900447Z","caller":"traceutil/trace.go:171","msg":"trace[688107499] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"166.117941ms","start":"2026-05-14T07:24:52.734299Z","end":"2026-05-14T07:24:52.900417Z","steps":["trace[688107499] 'process raft request' (duration: 35.57928ms)","trace[688107499] 'compare' (duration: 130.220992ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:24:52.900635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.555336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pvc-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:24:52.900683Z","caller":"traceutil/trace.go:171","msg":"trace[960013990] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pvc-protection-controller; range_end:; response_count:0; response_revision:275; }","duration":"118.643792ms","start":"2026-05-14T07:24:52.782026Z","end":"2026-05-14T07:24:52.90067Z","steps":["trace[960013990] 'agreement among raft nodes before linearized reading' (duration: 118.461291ms)"],"step_count":1} 2026/05/14 07:24:53 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-05-14T07:24:54.043451Z","caller":"traceutil/trace.go:171","msg":"trace[497189736] linearizableReadLoop","detail":"{readStateIndex:300; appliedIndex:299; }","duration":"261.356245ms","start":"2026-05-14T07:24:53.782074Z","end":"2026-05-14T07:24:54.04343Z","steps":["trace[497189736] 'read index received' (duration: 177.063159ms)","trace[497189736] 'applied index is now lower than readState.Index' (duration: 84.292396ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:24:54.043558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.476443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-14T07:24:54.043584Z","caller":"traceutil/trace.go:171","msg":"trace[177195869] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:294; }","duration":"261.558528ms","start":"2026-05-14T07:24:53.782018Z","end":"2026-05-14T07:24:54.043577Z","steps":["trace[177195869] 'agreement among raft nodes before linearized reading' (duration: 261.491664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:54.062934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.154784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:24:54.062978Z","caller":"traceutil/trace.go:171","msg":"trace[94901810] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:295; }","duration":"133.251181ms","start":"2026-05-14T07:24:53.929717Z","end":"2026-05-14T07:24:54.062968Z","steps":["trace[94901810] 'agreement among raft nodes before linearized reading' (duration: 133.162125ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:54.272572Z","caller":"traceutil/trace.go:171","msg":"trace[1993231048] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"154.422253ms","start":"2026-05-14T07:24:54.118123Z","end":"2026-05-14T07:24:54.272545Z","steps":["trace[1993231048] 'process raft request' (duration: 97.955637ms)","trace[1993231048] 'compare' (duration: 56.304046ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:24:54.274292Z","caller":"traceutil/trace.go:171","msg":"trace[217156711] transaction","detail":"{read_only:false; number_of_response:0; response_revision:301; }","duration":"155.356148ms","start":"2026-05-14T07:24:54.118905Z","end":"2026-05-14T07:24:54.274261Z","steps":["trace[217156711] 'process raft request' (duration: 155.184128ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:54.274549Z","caller":"traceutil/trace.go:171","msg":"trace[1794011278] linearizableReadLoop","detail":"{readStateIndex:309; appliedIndex:306; }","duration":"142.649018ms","start":"2026-05-14T07:24:54.131882Z","end":"2026-05-14T07:24:54.274531Z","steps":["trace[1794011278] 'read index received' (duration: 84.210034ms)","trace[1794011278] 'applied index is now lower than readState.Index' (duration: 58.438304ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:24:54.274657Z","caller":"traceutil/trace.go:171","msg":"trace[1881617310] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"152.256783ms","start":"2026-05-14T07:24:54.122385Z","end":"2026-05-14T07:24:54.274642Z","steps":["trace[1881617310] 'process raft request' (duration: 151.768634ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:54.274648Z","caller":"traceutil/trace.go:171","msg":"trace[638428615] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"136.278527ms","start":"2026-05-14T07:24:54.138351Z","end":"2026-05-14T07:24:54.274629Z","steps":["trace[638428615] 'process raft request' (duration: 136.052344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:54.2749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.735156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-14T07:24:54.27467Z","caller":"traceutil/trace.go:171","msg":"trace[1361512606] transaction","detail":"{read_only:false; number_of_response:0; response_revision:304; }","duration":"136.054604ms","start":"2026-05-14T07:24:54.138601Z","end":"2026-05-14T07:24:54.274656Z","steps":["trace[1361512606] 'process raft request' (duration: 135.847252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:54.274893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.212712ms","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-14T07:24:54.274934Z","caller":"traceutil/trace.go:171","msg":"trace[995521112] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:305; }","duration":"133.786969ms","start":"2026-05-14T07:24:54.141137Z","end":"2026-05-14T07:24:54.274924Z","steps":["trace[995521112] 'agreement among raft nodes before linearized reading' (duration: 133.6375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:54.274858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.61806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-14T07:24:54.274968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.50328ms","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-14T07:24:54.274698Z","caller":"traceutil/trace.go:171","msg":"trace[907123346] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"136.330471ms","start":"2026-05-14T07:24:54.138354Z","end":"2026-05-14T07:24:54.274685Z","steps":["trace[907123346] 'process raft request' (duration: 135.909415ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:54.274972Z","caller":"traceutil/trace.go:171","msg":"trace[1212490562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:305; }","duration":"142.312479ms","start":"2026-05-14T07:24:54.132639Z","end":"2026-05-14T07:24:54.274951Z","steps":["trace[1212490562] 'agreement among raft nodes before linearized reading' (duration: 142.176471ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:54.275004Z","caller":"traceutil/trace.go:171","msg":"trace[776951648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:305; }","duration":"142.566894ms","start":"2026-05-14T07:24:54.132426Z","end":"2026-05-14T07:24:54.274993Z","steps":["trace[776951648] 'agreement among raft nodes before linearized reading' (duration: 142.343981ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:54.274742Z","caller":"traceutil/trace.go:171","msg":"trace[843735174] transaction","detail":"{read_only:false; number_of_response:0; response_revision:302; }","duration":"136.449068ms","start":"2026-05-14T07:24:54.13827Z","end":"2026-05-14T07:24:54.274719Z","steps":["trace[843735174] 'process raft request' (duration: 135.954208ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:54.274999Z","caller":"traceutil/trace.go:171","msg":"trace[213638450] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:305; }","duration":"128.776579ms","start":"2026-05-14T07:24:54.146204Z","end":"2026-05-14T07:24:54.27498Z","steps":["trace[213638450] 'agreement among raft nodes before linearized reading' (duration: 128.578698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:54.274822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.921195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-14T07:24:54.274716Z","caller":"traceutil/trace.go:171","msg":"trace[521818869] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"134.587237ms","start":"2026-05-14T07:24:54.140114Z","end":"2026-05-14T07:24:54.274701Z","steps":["trace[521818869] 'process raft request' (duration: 134.368484ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:54.275139Z","caller":"traceutil/trace.go:171","msg":"trace[151877290] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:305; }","duration":"143.251405ms","start":"2026-05-14T07:24:54.131845Z","end":"2026-05-14T07:24:54.275096Z","steps":["trace[151877290] 'agreement among raft nodes before linearized reading' (duration: 142.899574ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:54.755845Z","caller":"traceutil/trace.go:171","msg":"trace[1892293165] linearizableReadLoop","detail":"{readStateIndex:328; appliedIndex:327; }","duration":"223.623388ms","start":"2026-05-14T07:24:54.532196Z","end":"2026-05-14T07:24:54.75582Z","steps":["trace[1892293165] 'read index received' (duration: 179.205363ms)","trace[1892293165] 'applied index is now lower than readState.Index' (duration: 44.416605ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:24:54.756027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.81535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-14T07:24:54.756157Z","caller":"traceutil/trace.go:171","msg":"trace[78502490] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:319; }","duration":"224.018302ms","start":"2026-05-14T07:24:54.532121Z","end":"2026-05-14T07:24:54.756139Z","steps":["trace[78502490] 'agreement among raft nodes before linearized reading' (duration: 223.80592ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:54.756241Z","caller":"traceutil/trace.go:171","msg":"trace[912096140] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"259.263899ms","start":"2026-05-14T07:24:54.49684Z","end":"2026-05-14T07:24:54.756104Z","steps":["trace[912096140] 'process raft request' (duration: 214.666173ms)","trace[912096140] 'compare' (duration: 44.140039ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:24:54.957769Z","caller":"traceutil/trace.go:171","msg":"trace[589589967] linearizableReadLoop","detail":"{readStateIndex:331; appliedIndex:330; }","duration":"137.464579ms","start":"2026-05-14T07:24:54.820276Z","end":"2026-05-14T07:24:54.957741Z","steps":["trace[589589967] 'read index received' (duration: 78.59935ms)","trace[589589967] 'applied index is now lower than readState.Index' (duration: 58.864269ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:24:54.957837Z","caller":"traceutil/trace.go:171","msg":"trace[1515927888] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"170.953251ms","start":"2026-05-14T07:24:54.786859Z","end":"2026-05-14T07:24:54.957812Z","steps":["trace[1515927888] 'process raft request' (duration: 111.967135ms)","trace[1515927888] 'compare' (duration: 58.759573ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:24:54.958229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.778498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-05-14T07:24:54.958318Z","caller":"traceutil/trace.go:171","msg":"trace[208783399] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:322; }","duration":"138.076665ms","start":"2026-05-14T07:24:54.820222Z","end":"2026-05-14T07:24:54.958298Z","steps":["trace[208783399] 'agreement among raft nodes before linearized reading' (duration: 137.678781ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:55.150427Z","caller":"traceutil/trace.go:171","msg":"trace[632968459] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"109.940343ms","start":"2026-05-14T07:24:55.040458Z","end":"2026-05-14T07:24:55.150399Z","steps":["trace[632968459] 'process raft request' (duration: 59.29599ms)","trace[632968459] 'compare' (duration: 50.515495ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:24:55.168149Z","caller":"traceutil/trace.go:171","msg":"trace[1703343544] linearizableReadLoop","detail":"{readStateIndex:335; appliedIndex:333; }","duration":"120.757781ms","start":"2026-05-14T07:24:55.047383Z","end":"2026-05-14T07:24:55.168141Z","steps":["trace[1703343544] 'read index received' (duration: 52.455401ms)","trace[1703343544] 'applied index is now lower than readState.Index' (duration: 68.3017ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:24:55.168209Z","caller":"traceutil/trace.go:171","msg":"trace[1428682496] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"127.520086ms","start":"2026-05-14T07:24:55.040685Z","end":"2026-05-14T07:24:55.168205Z","steps":["trace[1428682496] 'process raft request' (duration: 127.371947ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:55.168352Z","caller":"traceutil/trace.go:171","msg":"trace[1245904138] transaction","detail":"{read_only:false; number_of_response:0; response_revision:326; }","duration":"119.941452ms","start":"2026-05-14T07:24:55.048381Z","end":"2026-05-14T07:24:55.168323Z","steps":["trace[1245904138] 'process raft request' (duration: 119.724959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:55.168533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.985754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4663"} {"level":"info","ts":"2026-05-14T07:24:55.168596Z","caller":"traceutil/trace.go:171","msg":"trace[1835735587] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:326; }","duration":"121.229509ms","start":"2026-05-14T07:24:55.047351Z","end":"2026-05-14T07:24:55.168581Z","steps":["trace[1835735587] 'agreement among raft nodes before linearized reading' (duration: 120.861507ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:56.37024Z","caller":"traceutil/trace.go:171","msg":"trace[1617054255] linearizableReadLoop","detail":"{readStateIndex:352; appliedIndex:351; }","duration":"187.826343ms","start":"2026-05-14T07:24:56.182385Z","end":"2026-05-14T07:24:56.370211Z","steps":["trace[1617054255] 'read index received' (duration: 180.596129ms)","trace[1617054255] 'applied index is now lower than readState.Index' (duration: 7.229184ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:24:56.370371Z","caller":"traceutil/trace.go:171","msg":"trace[626260436] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"233.288289ms","start":"2026-05-14T07:24:56.137056Z","end":"2026-05-14T07:24:56.370344Z","steps":["trace[626260436] 'process raft request' (duration: 226.060855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:56.370424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.013344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:24:56.370463Z","caller":"traceutil/trace.go:171","msg":"trace[392670502] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:0; response_revision:342; }","duration":"188.108759ms","start":"2026-05-14T07:24:56.182341Z","end":"2026-05-14T07:24:56.37045Z","steps":["trace[392670502] 'agreement among raft nodes before linearized reading' (duration: 188.024434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:56.708675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.624579ms","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-05-14T07:24:56.708748Z","caller":"traceutil/trace.go:171","msg":"trace[196466928] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:347; }","duration":"205.732966ms","start":"2026-05-14T07:24:56.502998Z","end":"2026-05-14T07:24:56.708731Z","steps":["trace[196466928] 'range keys from in-memory index tree' (duration: 205.545295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:56.708932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.865453ms","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-14T07:24:56.708969Z","caller":"traceutil/trace.go:171","msg":"trace[1377254437] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:347; }","duration":"205.924978ms","start":"2026-05-14T07:24:56.503035Z","end":"2026-05-14T07:24:56.70896Z","steps":["trace[1377254437] 'range keys from in-memory index tree' (duration: 205.770738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:56.708953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.24253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-05-14T07:24:56.709015Z","caller":"traceutil/trace.go:171","msg":"trace[167698169] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:347; }","duration":"127.347996ms","start":"2026-05-14T07:24:56.58165Z","end":"2026-05-14T07:24:56.708998Z","steps":["trace[167698169] 'range keys from in-memory index tree' (duration: 127.145504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:56.709094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.002142ms","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-14T07:24:56.709128Z","caller":"traceutil/trace.go:171","msg":"trace[1141124291] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:347; }","duration":"206.058475ms","start":"2026-05-14T07:24:56.503061Z","end":"2026-05-14T07:24:56.70912Z","steps":["trace[1141124291] 'range keys from in-memory index tree' (duration: 205.923308ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:56.709224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.548746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-14T07:24:56.709267Z","caller":"traceutil/trace.go:171","msg":"trace[2022082572] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:347; }","duration":"177.592468ms","start":"2026-05-14T07:24:56.531664Z","end":"2026-05-14T07:24:56.709256Z","steps":["trace[2022082572] 'range keys from in-memory index tree' (duration: 177.494903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:56.709312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.96153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-14T07:24:56.70935Z","caller":"traceutil/trace.go:171","msg":"trace[832097333] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:347; }","duration":"202.019003ms","start":"2026-05-14T07:24:56.507322Z","end":"2026-05-14T07:24:56.709341Z","steps":["trace[832097333] 'range keys from in-memory index tree' (duration: 201.905267ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:57.121609Z","caller":"traceutil/trace.go:171","msg":"trace[647573068] linearizableReadLoop","detail":"{readStateIndex:358; appliedIndex:357; }","duration":"189.933398ms","start":"2026-05-14T07:24:56.931658Z","end":"2026-05-14T07:24:57.121592Z","steps":["trace[647573068] 'read index received' (duration: 189.766918ms)","trace[647573068] 'applied index is now lower than readState.Index' (duration: 165.7µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:24:57.121744Z","caller":"traceutil/trace.go:171","msg":"trace[1199354765] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"229.276098ms","start":"2026-05-14T07:24:56.892438Z","end":"2026-05-14T07:24:57.121714Z","steps":["trace[1199354765] 'process raft request' (duration: 229.037543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:57.121865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.673563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:24:57.121928Z","caller":"traceutil/trace.go:171","msg":"trace[1750818645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:348; }","duration":"181.756418ms","start":"2026-05-14T07:24:56.940157Z","end":"2026-05-14T07:24:57.121913Z","steps":["trace[1750818645] 'agreement among raft nodes before linearized reading' (duration: 181.613779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:57.121925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.942501ms","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-05-14T07:24:57.122006Z","caller":"traceutil/trace.go:171","msg":"trace[830151472] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:348; }","duration":"140.027246ms","start":"2026-05-14T07:24:56.981944Z","end":"2026-05-14T07:24:57.121971Z","steps":["trace[830151472] 'agreement among raft nodes before linearized reading' (duration: 139.901869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:57.122275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.609508ms","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-14T07:24:57.122307Z","caller":"traceutil/trace.go:171","msg":"trace[1460315294] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:348; }","duration":"190.652031ms","start":"2026-05-14T07:24:56.931646Z","end":"2026-05-14T07:24:57.122298Z","steps":["trace[1460315294] 'agreement among raft nodes before linearized reading' (duration: 190.041854ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:57.501434Z","caller":"traceutil/trace.go:171","msg":"trace[40526355] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"370.324046ms","start":"2026-05-14T07:24:57.131064Z","end":"2026-05-14T07:24:57.501388Z","steps":["trace[40526355] 'process raft request' (duration: 286.581568ms)","trace[40526355] 'compare' (duration: 83.330003ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:24:57.501612Z","caller":"traceutil/trace.go:171","msg":"trace[1740059873] linearizableReadLoop","detail":"{readStateIndex:365; appliedIndex:358; }","duration":"368.461524ms","start":"2026-05-14T07:24:57.13312Z","end":"2026-05-14T07:24:57.501582Z","steps":["trace[1740059873] 'read index received' (duration: 62.945389ms)","trace[1740059873] 'applied index is now lower than readState.Index' (duration: 305.514995ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:24:57.501744Z","caller":"traceutil/trace.go:171","msg":"trace[1289806913] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"370.489906ms","start":"2026-05-14T07:24:57.131182Z","end":"2026-05-14T07:24:57.501672Z","steps":["trace[1289806913] 'process raft request' (duration: 370.232431ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:57.50189Z","caller":"traceutil/trace.go:171","msg":"trace[527750649] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"370.700069ms","start":"2026-05-14T07:24:57.131147Z","end":"2026-05-14T07:24:57.501848Z","steps":["trace[527750649] 'process raft request' (duration: 370.120184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:57.501948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.131176Z","time spent":"370.673637ms","remote":"127.0.0.1:48612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2263,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:24:57.502043Z","caller":"traceutil/trace.go:171","msg":"trace[1234017352] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"369.293584ms","start":"2026-05-14T07:24:57.132741Z","end":"2026-05-14T07:24:57.502035Z","steps":["trace[1234017352] 'process raft request' (duration: 368.794404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:57.502149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.131132Z","time spent":"370.898261ms","remote":"127.0.0.1:48530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4355,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:24:57.502196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.132735Z","time spent":"369.358948ms","remote":"127.0.0.1:48714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:24:57.502291Z","caller":"traceutil/trace.go:171","msg":"trace[239258290] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"371.109273ms","start":"2026-05-14T07:24:57.131165Z","end":"2026-05-14T07:24:57.502274Z","steps":["trace[239258290] 'process raft request' (duration: 370.188538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:57.502555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.13115Z","time spent":"371.216619ms","remote":"127.0.0.1:48564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T07:24:57.502007Z","caller":"traceutil/trace.go:171","msg":"trace[1617929143] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"369.309115ms","start":"2026-05-14T07:24:57.132684Z","end":"2026-05-14T07:24:57.501993Z","steps":["trace[1617929143] 'process raft request' (duration: 368.808825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:57.502779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.132671Z","time spent":"370.069851ms","remote":"127.0.0.1:48714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:24:57.502573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.447314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-14T07:24:57.503007Z","caller":"traceutil/trace.go:171","msg":"trace[1525142966] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:355; }","duration":"369.896531ms","start":"2026-05-14T07:24:57.133096Z","end":"2026-05-14T07:24:57.502992Z","steps":["trace[1525142966] 'agreement among raft nodes before linearized reading' (duration: 369.426492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:57.503734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.133091Z","time spent":"369.997486ms","remote":"127.0.0.1:48564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-05-14T07:24:57.50543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.131056Z","time spent":"370.510897ms","remote":"127.0.0.1:48714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:24:57.514788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.677618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-xhkdsr\" ","response":"range_response_count:1 size:540"} {"level":"warn","ts":"2026-05-14T07:24:57.51503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.505953ms","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-14T07:24:57.515071Z","caller":"traceutil/trace.go:171","msg":"trace[1655029130] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:356; }","duration":"283.572176ms","start":"2026-05-14T07:24:57.231493Z","end":"2026-05-14T07:24:57.515065Z","steps":["trace[1655029130] 'agreement among raft nodes before linearized reading' (duration: 283.501982ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:57.515363Z","caller":"traceutil/trace.go:171","msg":"trace[1368289313] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"316.484633ms","start":"2026-05-14T07:24:57.19887Z","end":"2026-05-14T07:24:57.515355Z","steps":["trace[1368289313] 'process raft request' (duration: 315.728677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:57.515347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.641685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7111"} {"level":"info","ts":"2026-05-14T07:24:57.515412Z","caller":"traceutil/trace.go:171","msg":"trace[976649686] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:356; }","duration":"378.71562ms","start":"2026-05-14T07:24:57.136681Z","end":"2026-05-14T07:24:57.515397Z","steps":["trace[976649686] 'agreement among raft nodes before linearized reading' (duration: 378.590463ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:57.515453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.19885Z","time spent":"316.533207ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1739,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T07:24:57.515485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.136679Z","time spent":"378.767363ms","remote":"127.0.0.1:48536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7133,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-05-14T07:24:57.515596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.078151ms","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-14T07:24:57.515618Z","caller":"traceutil/trace.go:171","msg":"trace[1646712327] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:356; }","duration":"133.132134ms","start":"2026-05-14T07:24:57.38248Z","end":"2026-05-14T07:24:57.515613Z","steps":["trace[1646712327] 'agreement among raft nodes before linearized reading' (duration: 133.091782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:57.51571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.838476ms","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-14T07:24:57.515748Z","caller":"traceutil/trace.go:171","msg":"trace[1141778567] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:356; }","duration":"333.901379ms","start":"2026-05-14T07:24:57.181838Z","end":"2026-05-14T07:24:57.51574Z","steps":["trace[1141778567] 'agreement among raft nodes before linearized reading' (duration: 333.806663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:57.515747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.716931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-05-14T07:24:57.515791Z","caller":"traceutil/trace.go:171","msg":"trace[518173851] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:356; }","duration":"176.778975ms","start":"2026-05-14T07:24:57.339006Z","end":"2026-05-14T07:24:57.515785Z","steps":["trace[518173851] 'agreement among raft nodes before linearized reading' (duration: 176.715051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:57.51593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.332128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-14T07:24:57.515949Z","caller":"traceutil/trace.go:171","msg":"trace[1336975929] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:356; }","duration":"183.413073ms","start":"2026-05-14T07:24:57.332531Z","end":"2026-05-14T07:24:57.515944Z","steps":["trace[1336975929] 'agreement among raft nodes before linearized reading' (duration: 183.3617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:57.516093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.399555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-05-14T07:24:57.516111Z","caller":"traceutil/trace.go:171","msg":"trace[979820272] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:356; }","duration":"233.468389ms","start":"2026-05-14T07:24:57.282639Z","end":"2026-05-14T07:24:57.516107Z","steps":["trace[979820272] 'agreement among raft nodes before linearized reading' (duration: 233.433477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:57.515772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.181827Z","time spent":"333.939622ms","remote":"127.0.0.1:48564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-05-14T07:24:57.516385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.574031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"info","ts":"2026-05-14T07:24:57.516416Z","caller":"traceutil/trace.go:171","msg":"trace[1833804862] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:356; }","duration":"379.611234ms","start":"2026-05-14T07:24:57.136797Z","end":"2026-05-14T07:24:57.516408Z","steps":["trace[1833804862] 'agreement among raft nodes before linearized reading' (duration: 379.551561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:57.51644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.136794Z","time spent":"379.640476ms","remote":"127.0.0.1:48536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5358,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-05-14T07:24:57.517019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.8788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-05-14T07:24:57.517055Z","caller":"traceutil/trace.go:171","msg":"trace[805323425] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:356; }","duration":"380.920733ms","start":"2026-05-14T07:24:57.136126Z","end":"2026-05-14T07:24:57.517047Z","steps":["trace[805323425] 'agreement among raft nodes before linearized reading' (duration: 380.864659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:57.517107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.424523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4663"} {"level":"info","ts":"2026-05-14T07:24:57.51486Z","caller":"traceutil/trace.go:171","msg":"trace[960159223] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-xhkdsr; range_end:; response_count:1; response_revision:356; }","duration":"378.789704ms","start":"2026-05-14T07:24:57.136056Z","end":"2026-05-14T07:24:57.514846Z","steps":["trace[960159223] 'agreement among raft nodes before linearized reading' (duration: 378.632915ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:57.517201Z","caller":"traceutil/trace.go:171","msg":"trace[676103859] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:356; }","duration":"380.534389ms","start":"2026-05-14T07:24:57.136651Z","end":"2026-05-14T07:24:57.517186Z","steps":["trace[676103859] 'agreement among raft nodes before linearized reading' (duration: 380.337787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:57.517235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.136648Z","time spent":"380.580032ms","remote":"127.0.0.1:48536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4685,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-05-14T07:24:57.517273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.497177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-05-14T07:24:57.517346Z","caller":"traceutil/trace.go:171","msg":"trace[1444636171] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:356; }","duration":"380.662407ms","start":"2026-05-14T07:24:57.13667Z","end":"2026-05-14T07:24:57.517333Z","steps":["trace[1444636171] 'agreement among raft nodes before linearized reading' (duration: 380.430413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:57.517396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.136668Z","time spent":"380.7203ms","remote":"127.0.0.1:48536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6987,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-05-14T07:24:57.517219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.136051Z","time spent":"381.156826ms","remote":"127.0.0.1:48420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":562,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-xhkdsr\" "} {"level":"warn","ts":"2026-05-14T07:24:57.522228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.136122Z","time spent":"380.949024ms","remote":"127.0.0.1:48536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4348,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-05-14T07:24:58.06947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.145453ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-14T07:24:58.069856Z","caller":"traceutil/trace.go:171","msg":"trace[311926527] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"555.406675ms","start":"2026-05-14T07:24:57.514403Z","end":"2026-05-14T07:24:58.06981Z","steps":["trace[311926527] 'process raft request' (duration: 555.290958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:58.070001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.514382Z","time spent":"555.568925ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T07:24:58.07035Z","caller":"traceutil/trace.go:171","msg":"trace[537878922] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"558.852472ms","start":"2026-05-14T07:24:57.511457Z","end":"2026-05-14T07:24:58.070309Z","steps":["trace[537878922] 'process raft request' (duration: 152.799505ms)","trace[537878922] 'compare' (duration: 404.986653ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:24:58.070323Z","caller":"traceutil/trace.go:171","msg":"trace[492424631] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"555.923956ms","start":"2026-05-14T07:24:57.514368Z","end":"2026-05-14T07:24:58.070292Z","steps":["trace[492424631] 'process raft request' (duration: 555.202442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:58.070453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.511429Z","time spent":"558.959349ms","remote":"127.0.0.1:48564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":172,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T07:24:58.070521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.514352Z","time spent":"556.053854ms","remote":"127.0.0.1:48536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7228,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:24:58.487796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.852557ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T07:24:58.488061Z","caller":"traceutil/trace.go:171","msg":"trace[1260812033] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"973.512933ms","start":"2026-05-14T07:24:57.514521Z","end":"2026-05-14T07:24:58.488034Z","steps":["trace[1260812033] 'process raft request' (duration: 763.330375ms)","trace[1260812033] 'compare' (duration: 209.72102ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:24:58.488184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.514516Z","time spent":"973.6181ms","remote":"127.0.0.1:48714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:24:58.488535Z","caller":"traceutil/trace.go:171","msg":"trace[1628403066] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"973.966831ms","start":"2026-05-14T07:24:57.514549Z","end":"2026-05-14T07:24:58.488516Z","steps":["trace[1628403066] 'process raft request' (duration: 973.559956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:58.488627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.514523Z","time spent":"974.057666ms","remote":"127.0.0.1:48714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2264,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:24:58.488853Z","caller":"traceutil/trace.go:171","msg":"trace[1415443335] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"973.741057ms","start":"2026-05-14T07:24:57.51509Z","end":"2026-05-14T07:24:58.488831Z","steps":["trace[1415443335] 'process raft request' (duration: 973.345373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:58.488955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.515081Z","time spent":"973.823562ms","remote":"127.0.0.1:48536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4781,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:24:58.488937Z","caller":"traceutil/trace.go:171","msg":"trace[898083077] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"971.948319ms","start":"2026-05-14T07:24:57.516959Z","end":"2026-05-14T07:24:58.488907Z","steps":["trace[898083077] 'process raft request' (duration: 971.557056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:58.489102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.516949Z","time spent":"972.072066ms","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:24:58.489244Z","caller":"traceutil/trace.go:171","msg":"trace[204842015] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"966.453059ms","start":"2026-05-14T07:24:57.522781Z","end":"2026-05-14T07:24:58.489234Z","steps":["trace[204842015] 'process raft request' (duration: 965.866104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:58.489337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.522768Z","time spent":"966.506512ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T07:24:58.490439Z","caller":"traceutil/trace.go:171","msg":"trace[497460108] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"965.899016ms","start":"2026-05-14T07:24:57.524519Z","end":"2026-05-14T07:24:58.490418Z","steps":["trace[497460108] 'process raft request' (duration: 964.373584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:58.490539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.524497Z","time spent":"966.000072ms","remote":"127.0.0.1:48850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T07:24:58.490862Z","caller":"traceutil/trace.go:171","msg":"trace[2013735363] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"964.7958ms","start":"2026-05-14T07:24:57.526051Z","end":"2026-05-14T07:24:58.490847Z","steps":["trace[2013735363] 'process raft request' (duration: 964.592528ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:58.490896Z","caller":"traceutil/trace.go:171","msg":"trace[142945963] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"966.040085ms","start":"2026-05-14T07:24:57.524838Z","end":"2026-05-14T07:24:58.490878Z","steps":["trace[142945963] 'process raft request' (duration: 965.6254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:58.490958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.526045Z","time spent":"964.866574ms","remote":"127.0.0.1:48536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:24:58.491064Z","caller":"traceutil/trace.go:171","msg":"trace[1934086021] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"971.008813ms","start":"2026-05-14T07:24:57.520037Z","end":"2026-05-14T07:24:58.491046Z","steps":["trace[1934086021] 'process raft request' (duration: 968.528564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:58.491175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.520011Z","time spent":"971.129779ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T07:24:58.491382Z","caller":"traceutil/trace.go:171","msg":"trace[404649660] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"416.536313ms","start":"2026-05-14T07:24:58.074831Z","end":"2026-05-14T07:24:58.491367Z","steps":["trace[404649660] 'process raft request' (duration: 415.921106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:58.491468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:58.074818Z","time spent":"416.613848ms","remote":"127.0.0.1:48564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T07:24:58.491522Z","caller":"traceutil/trace.go:171","msg":"trace[1214791769] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"966.857354ms","start":"2026-05-14T07:24:57.524653Z","end":"2026-05-14T07:24:58.491511Z","steps":["trace[1214791769] 'process raft request' (duration: 965.759118ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:58.491752Z","caller":"traceutil/trace.go:171","msg":"trace[2147392055] linearizableReadLoop","detail":"{readStateIndex:373; appliedIndex:366; }","duration":"971.822061ms","start":"2026-05-14T07:24:57.51991Z","end":"2026-05-14T07:24:58.491732Z","steps":["trace[2147392055] 'read index received' (duration: 144.501826ms)","trace[2147392055] 'applied index is now lower than readState.Index' (duration: 827.317375ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:24:58.491925Z","caller":"traceutil/trace.go:171","msg":"trace[84771469] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"417.279989ms","start":"2026-05-14T07:24:58.074629Z","end":"2026-05-14T07:24:58.491909Z","steps":["trace[84771469] 'process raft request' (duration: 416.077446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:58.49207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:58.074607Z","time spent":"417.411636ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T07:24:58.492134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.216385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-05-14T07:24:58.492198Z","caller":"traceutil/trace.go:171","msg":"trace[1978211389] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:372; }","duration":"972.30332ms","start":"2026-05-14T07:24:57.51988Z","end":"2026-05-14T07:24:58.492183Z","steps":["trace[1978211389] 'agreement among raft nodes before linearized reading' (duration: 972.197164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:58.492246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.519869Z","time spent":"972.366124ms","remote":"127.0.0.1:48530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4391,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-14T07:24:58.492267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.524642Z","time spent":"966.920197ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T07:24:58.491039Z","caller":"traceutil/trace.go:171","msg":"trace[142696917] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"966.420477ms","start":"2026-05-14T07:24:57.524605Z","end":"2026-05-14T07:24:58.491026Z","steps":["trace[142696917] 'process raft request' (duration: 965.740087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:58.49259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.524233Z","time spent":"968.323272ms","remote":"127.0.0.1:48516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T07:24:58.492746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.655792ms","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-14T07:24:58.492797Z","caller":"traceutil/trace.go:171","msg":"trace[1899171293] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:372; }","duration":"100.748617ms","start":"2026-05-14T07:24:58.392036Z","end":"2026-05-14T07:24:58.492785Z","steps":["trace[1899171293] 'agreement among raft nodes before linearized reading' (duration: 100.653052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:58.492818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.752778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-05-14T07:24:58.492873Z","caller":"traceutil/trace.go:171","msg":"trace[1408616354] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:372; }","duration":"966.831942ms","start":"2026-05-14T07:24:57.526027Z","end":"2026-05-14T07:24:58.492859Z","steps":["trace[1408616354] 'agreement among raft nodes before linearized reading' (duration: 966.733966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:58.492922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.526017Z","time spent":"966.894166ms","remote":"127.0.0.1:48530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4391,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-14T07:24:58.492771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.328432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"warn","ts":"2026-05-14T07:24:58.493093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.872545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:24:58.493143Z","caller":"traceutil/trace.go:171","msg":"trace[1938414865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:372; }","duration":"551.94781ms","start":"2026-05-14T07:24:57.941185Z","end":"2026-05-14T07:24:58.493133Z","steps":["trace[1938414865] 'agreement among raft nodes before linearized reading' (duration: 551.885517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:58.49317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.941168Z","time spent":"551.996003ms","remote":"127.0.0.1:48330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T07:24:58.49324Z","caller":"traceutil/trace.go:171","msg":"trace[346403445] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:372; }","duration":"971.711705ms","start":"2026-05-14T07:24:57.521413Z","end":"2026-05-14T07:24:58.493124Z","steps":["trace[346403445] 'agreement among raft nodes before linearized reading' (duration: 971.282499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:58.493251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.731836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"warn","ts":"2026-05-14T07:24:58.493364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.485932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-05-14T07:24:58.493392Z","caller":"traceutil/trace.go:171","msg":"trace[1531293003] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:372; }","duration":"971.527754ms","start":"2026-05-14T07:24:57.521856Z","end":"2026-05-14T07:24:58.493384Z","steps":["trace[1531293003] 'agreement among raft nodes before linearized reading' (duration: 971.478381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:58.493418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.521849Z","time spent":"971.563706ms","remote":"127.0.0.1:48530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4391,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-14T07:24:58.493431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.521405Z","time spent":"971.9513ms","remote":"127.0.0.1:48530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4391,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-14T07:24:58.494728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.524814Z","time spent":"966.126689ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2163,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:24:58.495995Z","caller":"traceutil/trace.go:171","msg":"trace[479064802] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:372; }","duration":"970.890285ms","start":"2026-05-14T07:24:57.522494Z","end":"2026-05-14T07:24:58.493384Z","steps":["trace[479064802] 'agreement among raft nodes before linearized reading' (duration: 970.714195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:58.496071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:57.522483Z","time spent":"973.573047ms","remote":"127.0.0.1:48530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4391,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-14T07:24:58.869417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.845962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-14T07:24:58.869515Z","caller":"traceutil/trace.go:171","msg":"trace[400866742] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"369.976576ms","start":"2026-05-14T07:24:58.499521Z","end":"2026-05-14T07:24:58.869498Z","steps":["trace[400866742] 'process raft request' (duration: 200.990015ms)","trace[400866742] 'compare' (duration: 168.673042ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:24:58.869593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:58.499511Z","time spent":"370.06293ms","remote":"127.0.0.1:48564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T07:24:58.878933Z","caller":"traceutil/trace.go:171","msg":"trace[1843991143] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"379.007808ms","start":"2026-05-14T07:24:58.499892Z","end":"2026-05-14T07:24:58.8789Z","steps":["trace[1843991143] 'process raft request' (duration: 378.803376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:58.879069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:58.499875Z","time spent":"379.131315ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T07:24:58.879224Z","caller":"traceutil/trace.go:171","msg":"trace[252166853] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"379.173788ms","start":"2026-05-14T07:24:58.500031Z","end":"2026-05-14T07:24:58.879205Z","steps":["trace[252166853] 'process raft request' (duration: 378.856579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:58.879313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:58.500008Z","time spent":"379.275794ms","remote":"127.0.0.1:48536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3378,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T07:24:58.879309Z","caller":"traceutil/trace.go:171","msg":"trace[704852999] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"379.319387ms","start":"2026-05-14T07:24:58.499963Z","end":"2026-05-14T07:24:58.879282Z","steps":["trace[704852999] 'process raft request' (duration: 378.889631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:58.87943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:58.499957Z","time spent":"379.404132ms","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:24:58.879007Z","caller":"traceutil/trace.go:171","msg":"trace[1129677799] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"379.074503ms","start":"2026-05-14T07:24:58.499904Z","end":"2026-05-14T07:24:58.878978Z","steps":["trace[1129677799] 'process raft request' (duration: 378.898392ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:58.879497Z","caller":"traceutil/trace.go:171","msg":"trace[789921889] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"378.965636ms","start":"2026-05-14T07:24:58.500436Z","end":"2026-05-14T07:24:58.879402Z","steps":["trace[789921889] 'process raft request' (duration: 378.506588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:58.879596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:58.500428Z","time spent":"379.119275ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":654,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T07:24:58.879802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:58.499896Z","time spent":"379.640136ms","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:24:58.879819Z","caller":"traceutil/trace.go:171","msg":"trace[1758723802] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"379.310826ms","start":"2026-05-14T07:24:58.500489Z","end":"2026-05-14T07:24:58.8798Z","steps":["trace[1758723802] 'process raft request' (duration: 378.723271ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:58.879885Z","caller":"traceutil/trace.go:171","msg":"trace[1040578702] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"379.38023ms","start":"2026-05-14T07:24:58.500494Z","end":"2026-05-14T07:24:58.879874Z","steps":["trace[1040578702] 'process raft request' (duration: 378.940574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:58.879916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:58.500478Z","time spent":"379.387391ms","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:24:58.879983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:58.500489Z","time spent":"379.425863ms","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:24:58.880475Z","caller":"traceutil/trace.go:171","msg":"trace[1603625802] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"379.37131ms","start":"2026-05-14T07:24:58.501084Z","end":"2026-05-14T07:24:58.880455Z","steps":["trace[1603625802] 'process raft request' (duration: 378.654777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:58.880545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:58.501078Z","time spent":"379.433554ms","remote":"127.0.0.1:48714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:24:58.882204Z","caller":"traceutil/trace.go:171","msg":"trace[1814607251] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"378.681739ms","start":"2026-05-14T07:24:58.501859Z","end":"2026-05-14T07:24:58.88054Z","steps":["trace[1814607251] 'process raft request' (duration: 378.242642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:58.882331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:58.501851Z","time spent":"380.431924ms","remote":"127.0.0.1:48412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T07:24:58.884978Z","caller":"traceutil/trace.go:171","msg":"trace[470145179] linearizableReadLoop","detail":"{readStateIndex:391; appliedIndex:382; }","duration":"383.402193ms","start":"2026-05-14T07:24:58.501548Z","end":"2026-05-14T07:24:58.88495Z","steps":["trace[470145179] 'read index received' (duration: 198.928891ms)","trace[470145179] 'applied index is now lower than readState.Index' (duration: 184.472222ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:24:58.88525Z","caller":"traceutil/trace.go:171","msg":"trace[987362968] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"383.110955ms","start":"2026-05-14T07:24:58.502117Z","end":"2026-05-14T07:24:58.885228Z","steps":["trace[987362968] 'process raft request' (duration: 378.358499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:58.885378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:58.502112Z","time spent":"383.203161ms","remote":"127.0.0.1:48536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:24:58.885261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.652248ms","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-14T07:24:58.88541Z","caller":"traceutil/trace.go:171","msg":"trace[339153034] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"382.678368ms","start":"2026-05-14T07:24:58.502715Z","end":"2026-05-14T07:24:58.885393Z","steps":["trace[339153034] 'process raft request' (duration: 382.129645ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:58.88545Z","caller":"traceutil/trace.go:171","msg":"trace[251381410] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:384; }","duration":"383.894792ms","start":"2026-05-14T07:24:58.501534Z","end":"2026-05-14T07:24:58.885429Z","steps":["trace[251381410] 'agreement among raft nodes before linearized reading' (duration: 383.619915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:58.885669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:58.501528Z","time spent":"384.121506ms","remote":"127.0.0.1:48564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-05-14T07:24:58.885847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:58.50271Z","time spent":"382.910443ms","remote":"127.0.0.1:48822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:24:59.019573Z","caller":"traceutil/trace.go:171","msg":"trace[1859740207] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"133.835389ms","start":"2026-05-14T07:24:58.885692Z","end":"2026-05-14T07:24:59.019528Z","steps":["trace[1859740207] 'process raft request' (duration: 103.306622ms)","trace[1859740207] 'compare' (duration: 30.263871ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:24:59.019624Z","caller":"traceutil/trace.go:171","msg":"trace[1823029519] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"133.284165ms","start":"2026-05-14T07:24:58.886318Z","end":"2026-05-14T07:24:59.019602Z","steps":["trace[1823029519] 'process raft request' (duration: 133.084383ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:24:59.019574Z","caller":"traceutil/trace.go:171","msg":"trace[2058098690] linearizableReadLoop","detail":"{readStateIndex:397; appliedIndex:394; }","duration":"129.489027ms","start":"2026-05-14T07:24:58.890064Z","end":"2026-05-14T07:24:59.019553Z","steps":["trace[2058098690] 'read index received' (duration: 15.392526ms)","trace[2058098690] 'applied index is now lower than readState.Index' (duration: 114.095641ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:24:59.019884Z","caller":"traceutil/trace.go:171","msg":"trace[1536038487] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"128.232662ms","start":"2026-05-14T07:24:58.891593Z","end":"2026-05-14T07:24:59.019826Z","steps":["trace[1536038487] 'process raft request' (duration: 127.927343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:59.019912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.619074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-05-14T07:24:59.020159Z","caller":"traceutil/trace.go:171","msg":"trace[699564138] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:388; }","duration":"130.105264ms","start":"2026-05-14T07:24:58.890033Z","end":"2026-05-14T07:24:59.020138Z","steps":["trace[699564138] 'agreement among raft nodes before linearized reading' (duration: 129.599384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:01.373271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.000944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-14T07:25:01.37329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.016415ms","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-14T07:25:01.373365Z","caller":"traceutil/trace.go:171","msg":"trace[428986576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:406; }","duration":"488.144152ms","start":"2026-05-14T07:25:00.885198Z","end":"2026-05-14T07:25:01.373342Z","steps":["trace[428986576] 'range keys from in-memory index tree' (duration: 487.825293ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:25:01.373384Z","caller":"traceutil/trace.go:171","msg":"trace[2129832202] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:406; }","duration":"489.164463ms","start":"2026-05-14T07:25:00.884197Z","end":"2026-05-14T07:25:01.373361Z","steps":["trace[2129832202] 'range keys from in-memory index tree' (duration: 488.914368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:01.373417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:00.885178Z","time spent":"488.229187ms","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-14T07:25:01.373434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:00.884174Z","time spent":"489.250349ms","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T07:25:01.373244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.123864ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T07:25:01.373549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.863345ms","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-14T07:25:01.373529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.595762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-05-14T07:25:01.373562Z","caller":"traceutil/trace.go:171","msg":"trace[501335828] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:406; }","duration":"642.465995ms","start":"2026-05-14T07:25:00.731087Z","end":"2026-05-14T07:25:01.373553Z","steps":["trace[501335828] 'range keys from in-memory index tree' (duration: 641.985845ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:25:01.373588Z","caller":"traceutil/trace.go:171","msg":"trace[2063629366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:406; }","duration":"487.934119ms","start":"2026-05-14T07:25:00.88564Z","end":"2026-05-14T07:25:01.373574Z","steps":["trace[2063629366] 'range keys from in-memory index tree' (duration: 487.76594ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:25:01.3736Z","caller":"traceutil/trace.go:171","msg":"trace[623591842] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:406; }","duration":"426.715909ms","start":"2026-05-14T07:25:00.946867Z","end":"2026-05-14T07:25:01.373583Z","steps":["trace[623591842] 'range keys from in-memory index tree' (duration: 426.466694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:01.373619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:00.885618Z","time spent":"487.994883ms","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T07:25:01.373643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:00.946843Z","time spent":"426.789544ms","remote":"127.0.0.1:48536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6987,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-05-14T07:25:01.373862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.546796ms","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-14T07:25:01.37391Z","caller":"traceutil/trace.go:171","msg":"trace[2032364643] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:406; }","duration":"488.585608ms","start":"2026-05-14T07:25:00.885295Z","end":"2026-05-14T07:25:01.37388Z","steps":["trace[2032364643] 'range keys from in-memory index tree' (duration: 488.497483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:01.374212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:00.885289Z","time spent":"488.634062ms","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-14T07:25:01.545698Z","caller":"traceutil/trace.go:171","msg":"trace[1812259704] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"167.302467ms","start":"2026-05-14T07:25:01.378375Z","end":"2026-05-14T07:25:01.545677Z","steps":["trace[1812259704] 'process raft request' (duration: 167.232473ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:25:01.545879Z","caller":"traceutil/trace.go:171","msg":"trace[992006355] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"167.552952ms","start":"2026-05-14T07:25:01.378315Z","end":"2026-05-14T07:25:01.545867Z","steps":["trace[992006355] 'process raft request' (duration: 121.235427ms)","trace[992006355] 'compare' (duration: 45.953723ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:25:01.546162Z","caller":"traceutil/trace.go:171","msg":"trace[2020038672] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"167.418534ms","start":"2026-05-14T07:25:01.378714Z","end":"2026-05-14T07:25:01.546133Z","steps":["trace[2020038672] 'process raft request' (duration: 166.935015ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:25:01.546183Z","caller":"traceutil/trace.go:171","msg":"trace[1616022099] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"165.567832ms","start":"2026-05-14T07:25:01.380592Z","end":"2026-05-14T07:25:01.54616Z","steps":["trace[1616022099] 'process raft request' (duration: 165.389471ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:25:01.664283Z","caller":"traceutil/trace.go:171","msg":"trace[1614622679] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"108.587123ms","start":"2026-05-14T07:25:01.55567Z","end":"2026-05-14T07:25:01.664257Z","steps":["trace[1614622679] 'process raft request' (duration: 106.7086ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:25:15.857435Z","caller":"traceutil/trace.go:171","msg":"trace[430259098] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"170.80039ms","start":"2026-05-14T07:25:15.686611Z","end":"2026-05-14T07:25:15.857411Z","steps":["trace[430259098] 'process raft request' (duration: 170.752512ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:25:15.858004Z","caller":"traceutil/trace.go:171","msg":"trace[1364452623] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"174.316206ms","start":"2026-05-14T07:25:15.68367Z","end":"2026-05-14T07:25:15.857986Z","steps":["trace[1364452623] 'process raft request' (duration: 100.732723ms)","trace[1364452623] 'compare' (duration: 72.83996ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:25:17.964804Z","caller":"traceutil/trace.go:171","msg":"trace[2063247646] linearizableReadLoop","detail":"{readStateIndex:498; appliedIndex:497; }","duration":"102.888586ms","start":"2026-05-14T07:25:17.861895Z","end":"2026-05-14T07:25:17.964783Z","steps":["trace[2063247646] 'read index received' (duration: 102.761829ms)","trace[2063247646] 'applied index is now lower than readState.Index' (duration: 125.777µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:25:17.964877Z","caller":"traceutil/trace.go:171","msg":"trace[344965990] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"278.718723ms","start":"2026-05-14T07:25:17.686126Z","end":"2026-05-14T07:25:17.964845Z","steps":["trace[344965990] 'process raft request' (duration: 278.534347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:17.964962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.044063ms","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-14T07:25:17.964996Z","caller":"traceutil/trace.go:171","msg":"trace[1353942642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:482; }","duration":"103.123521ms","start":"2026-05-14T07:25:17.861863Z","end":"2026-05-14T07:25:17.964987Z","steps":["trace[1353942642] 'agreement among raft nodes before linearized reading' (duration: 103.045083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:17.965149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.151422ms","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-14T07:25:17.965258Z","caller":"traceutil/trace.go:171","msg":"trace[531666617] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:482; }","duration":"102.2482ms","start":"2026-05-14T07:25:17.862958Z","end":"2026-05-14T07:25:17.965206Z","steps":["trace[531666617] 'agreement among raft nodes before linearized reading' (duration: 102.117883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:24.44172Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744667910140537,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-14T07:25:24.523737Z","caller":"traceutil/trace.go:171","msg":"trace[552734494] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"718.885564ms","start":"2026-05-14T07:25:23.804819Z","end":"2026-05-14T07:25:24.523705Z","steps":["trace[552734494] 'process raft request' (duration: 718.661147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:24.523909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:23.804794Z","time spent":"719.038471ms","remote":"127.0.0.1:48386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T07:25:25.169506Z","caller":"traceutil/trace.go:171","msg":"trace[1559931573] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.363202339s","start":"2026-05-14T07:25:23.80627Z","end":"2026-05-14T07:25:25.169473Z","steps":["trace[1559931573] 'process raft request' (duration: 1.363005432s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:25.169807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:23.806253Z","time spent":"1.363337698s","remote":"127.0.0.1:48386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T07:25:25.169802Z","caller":"traceutil/trace.go:171","msg":"trace[297617035] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.354181094s","start":"2026-05-14T07:25:23.815585Z","end":"2026-05-14T07:25:25.169766Z","steps":["trace[297617035] 'process raft request' (duration: 1.353852089s)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:25:25.169834Z","caller":"traceutil/trace.go:171","msg":"trace[19960691] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.333930544s","start":"2026-05-14T07:25:23.835847Z","end":"2026-05-14T07:25:25.169778Z","steps":["trace[19960691] 'process raft request' (duration: 1.333740327s)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:25:25.169955Z","caller":"traceutil/trace.go:171","msg":"trace[1774721630] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.354536529s","start":"2026-05-14T07:25:23.815392Z","end":"2026-05-14T07:25:25.169929Z","steps":["trace[1774721630] 'process raft request' (duration: 1.354002636s)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:25:25.170016Z","caller":"traceutil/trace.go:171","msg":"trace[260484271] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.349142539s","start":"2026-05-14T07:25:23.820859Z","end":"2026-05-14T07:25:25.170002Z","steps":["trace[260484271] 'process raft request' (duration: 1.348687165s)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:25:25.170039Z","caller":"traceutil/trace.go:171","msg":"trace[690116772] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.334093242s","start":"2026-05-14T07:25:23.835931Z","end":"2026-05-14T07:25:25.170025Z","steps":["trace[690116772] 'process raft request' (duration: 1.333694107s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:25.170076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:23.820852Z","time spent":"1.349196899s","remote":"127.0.0.1:48386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T07:25:25.170099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:23.835923Z","time spent":"1.334147702s","remote":"127.0.0.1:48386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T07:25:25.170054Z","caller":"traceutil/trace.go:171","msg":"trace[1558248589] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.333942295s","start":"2026-05-14T07:25:23.836033Z","end":"2026-05-14T07:25:25.169976Z","steps":["trace[1558248589] 'process raft request' (duration: 1.333674448s)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:25:25.170165Z","caller":"traceutil/trace.go:171","msg":"trace[1717960519] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:548; }","duration":"1.228935684s","start":"2026-05-14T07:25:23.94121Z","end":"2026-05-14T07:25:25.170146Z","steps":["trace[1717960519] 'read index received' (duration: 582.579027ms)","trace[1717960519] 'applied index is now lower than readState.Index' (duration: 646.355287ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:25:25.170206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:23.815375Z","time spent":"1.354659928s","remote":"127.0.0.1:48386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T07:25:25.170267Z","caller":"traceutil/trace.go:171","msg":"trace[1310574395] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"1.193005057s","start":"2026-05-14T07:25:23.977244Z","end":"2026-05-14T07:25:25.170249Z","steps":["trace[1310574395] 'process raft request' (duration: 1.19278781s)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:25:25.170289Z","caller":"traceutil/trace.go:171","msg":"trace[1708427285] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.304990955s","start":"2026-05-14T07:25:23.865289Z","end":"2026-05-14T07:25:25.17028Z","steps":["trace[1708427285] 'process raft request' (duration: 1.30458616s)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:25:25.170178Z","caller":"traceutil/trace.go:171","msg":"trace[603571280] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.330805652s","start":"2026-05-14T07:25:23.839315Z","end":"2026-05-14T07:25:25.170121Z","steps":["trace[603571280] 'process raft request' (duration: 1.330492576s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:25.170337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:23.836026Z","time spent":"1.334183662s","remote":"127.0.0.1:48386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T07:25:25.169966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:23.835828Z","time spent":"1.334087213s","remote":"127.0.0.1:48386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T07:25:25.169966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:23.81558Z","time spent":"1.354304203s","remote":"127.0.0.1:48386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T07:25:25.170347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:23.977222Z","time spent":"1.193090456s","remote":"127.0.0.1:48386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T07:25:25.170403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:23.86527Z","time spent":"1.305058604s","remote":"127.0.0.1:48386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T07:25:25.170351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.229139471s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:25:25.170496Z","caller":"traceutil/trace.go:171","msg":"trace[137341569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:542; }","duration":"1.229319828s","start":"2026-05-14T07:25:23.941157Z","end":"2026-05-14T07:25:25.170477Z","steps":["trace[137341569] 'agreement among raft nodes before linearized reading' (duration: 1.229091721s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:25.170544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:23.941134Z","time spent":"1.229399798s","remote":"127.0.0.1:48330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T07:25:25.170483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:23.839309Z","time spent":"1.331073729s","remote":"127.0.0.1:48386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T07:25:25.170215Z","caller":"traceutil/trace.go:171","msg":"trace[1491795944] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.283842738s","start":"2026-05-14T07:25:23.886338Z","end":"2026-05-14T07:25:25.17018Z","steps":["trace[1491795944] 'process raft request' (duration: 1.283606271s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:25.170676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:23.886299Z","time spent":"1.284322023s","remote":"127.0.0.1:48386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T07:25:25.525295Z","caller":"traceutil/trace.go:171","msg":"trace[1045387489] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"994.528378ms","start":"2026-05-14T07:25:24.530732Z","end":"2026-05-14T07:25:25.52526Z","steps":["trace[1045387489] 'process raft request' (duration: 992.151548ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:25:25.525302Z","caller":"traceutil/trace.go:171","msg":"trace[616758671] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:559; }","duration":"355.059126ms","start":"2026-05-14T07:25:25.170219Z","end":"2026-05-14T07:25:25.525278Z","steps":["trace[616758671] 'read index received' (duration: 352.741675ms)","trace[616758671] 'applied index is now lower than readState.Index' (duration: 2.316211ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:25:25.525435Z","caller":"traceutil/trace.go:171","msg":"trace[752384409] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"885.288353ms","start":"2026-05-14T07:25:24.640133Z","end":"2026-05-14T07:25:25.525422Z","steps":["trace[752384409] 'process raft request' (duration: 885.090325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:25.525559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.533684256s","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-14T07:25:25.525574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:24.640109Z","time spent":"885.388602ms","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:25:25.525614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.543672968s","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-14T07:25:25.525612Z","caller":"traceutil/trace.go:171","msg":"trace[1391649967] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:545; }","duration":"1.533763635s","start":"2026-05-14T07:25:23.991836Z","end":"2026-05-14T07:25:25.5256Z","steps":["trace[1391649967] 'agreement among raft nodes before linearized reading' (duration: 1.533648587s)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:25:25.525636Z","caller":"traceutil/trace.go:171","msg":"trace[301251502] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:545; }","duration":"1.543720878s","start":"2026-05-14T07:25:23.981908Z","end":"2026-05-14T07:25:25.525629Z","steps":["trace[301251502] 'agreement among raft nodes before linearized reading' (duration: 1.543688118s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:25.52566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:23.981896Z","time spent":"1.543759168s","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T07:25:25.525654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:23.991827Z","time spent":"1.533816915s","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-14T07:25:25.525574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.532239138s","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-14T07:25:25.525727Z","caller":"traceutil/trace.go:171","msg":"trace[888945996] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:545; }","duration":"1.532417846s","start":"2026-05-14T07:25:23.993303Z","end":"2026-05-14T07:25:25.525721Z","steps":["trace[888945996] 'agreement among raft nodes before linearized reading' (duration: 1.532231568s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:25.525746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:23.993294Z","time spent":"1.532446416s","remote":"127.0.0.1:48626","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-05-14T07:25:25.525803Z","caller":"traceutil/trace.go:171","msg":"trace[779352639] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"993.872687ms","start":"2026-05-14T07:25:24.531828Z","end":"2026-05-14T07:25:25.525701Z","steps":["trace[779352639] 'process raft request' (duration: 993.320094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:25.525967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:24.531805Z","time spent":"994.088564ms","remote":"127.0.0.1:48386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:25:25.525978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:24.530696Z","time spent":"994.683275ms","remote":"127.0.0.1:48880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T07:25:25.529921Z","caller":"traceutil/trace.go:171","msg":"trace[1443971055] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"345.577376ms","start":"2026-05-14T07:25:25.18433Z","end":"2026-05-14T07:25:25.529908Z","steps":["trace[1443971055] 'process raft request' (duration: 345.30147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:25.530001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:25.184301Z","time spent":"345.661565ms","remote":"127.0.0.1:48880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T07:25:25.530044Z","caller":"traceutil/trace.go:171","msg":"trace[2021297899] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"344.960214ms","start":"2026-05-14T07:25:25.185064Z","end":"2026-05-14T07:25:25.530024Z","steps":["trace[2021297899] 'process raft request' (duration: 344.793116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:25.530137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:25.185057Z","time spent":"345.040813ms","remote":"127.0.0.1:48880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T07:25:25.530382Z","caller":"traceutil/trace.go:171","msg":"trace[2056031273] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"345.250951ms","start":"2026-05-14T07:25:25.185114Z","end":"2026-05-14T07:25:25.530365Z","steps":["trace[2056031273] 'process raft request' (duration: 344.767517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:25.530467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:25.185109Z","time spent":"345.32098ms","remote":"127.0.0.1:48880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T07:25:25.530694Z","caller":"traceutil/trace.go:171","msg":"trace[881977222] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"337.695687ms","start":"2026-05-14T07:25:25.192983Z","end":"2026-05-14T07:25:25.530679Z","steps":["trace[881977222] 'process raft request' (duration: 337.384241ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:25:25.53073Z","caller":"traceutil/trace.go:171","msg":"trace[67038367] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"337.525659ms","start":"2026-05-14T07:25:25.193196Z","end":"2026-05-14T07:25:25.530721Z","steps":["trace[67038367] 'process raft request' (duration: 337.205903ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:25:25.531413Z","caller":"traceutil/trace.go:171","msg":"trace[1345731391] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"345.826133ms","start":"2026-05-14T07:25:25.185386Z","end":"2026-05-14T07:25:25.531213Z","steps":["trace[1345731391] 'process raft request' (duration: 344.828146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:25.531508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:25.193187Z","time spent":"338.28552ms","remote":"127.0.0.1:48880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T07:25:25.531629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:25.185376Z","time spent":"346.149009ms","remote":"127.0.0.1:48386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:25:25.531774Z","caller":"traceutil/trace.go:171","msg":"trace[439821896] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"333.962795ms","start":"2026-05-14T07:25:25.197792Z","end":"2026-05-14T07:25:25.531755Z","steps":["trace[439821896] 'process raft request' (duration: 332.673171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:25.532104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:25.197774Z","time spent":"334.081463ms","remote":"127.0.0.1:48386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:25:25.532237Z","caller":"traceutil/trace.go:171","msg":"trace[869153962] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"341.136983ms","start":"2026-05-14T07:25:25.191072Z","end":"2026-05-14T07:25:25.532209Z","steps":["trace[869153962] 'process raft request' (duration: 339.265777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:25.532549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:25.191059Z","time spent":"341.344821ms","remote":"127.0.0.1:48386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:25:25.532775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:25.192972Z","time spent":"337.766846ms","remote":"127.0.0.1:48386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:25:25.533013Z","caller":"traceutil/trace.go:171","msg":"trace[2067894345] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"336.916717ms","start":"2026-05-14T07:25:25.196073Z","end":"2026-05-14T07:25:25.532989Z","steps":["trace[2067894345] 'process raft request' (duration: 334.3534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:25.533109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:25.196044Z","time spent":"337.025725ms","remote":"127.0.0.1:48386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:25:25.551764Z","caller":"traceutil/trace.go:171","msg":"trace[527102835] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"300.55949ms","start":"2026-05-14T07:25:25.251179Z","end":"2026-05-14T07:25:25.551739Z","steps":["trace[527102835] 'process raft request' (duration: 300.038687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:25.551903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:25.251162Z","time spent":"300.677619ms","remote":"127.0.0.1:48386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T07:25:25.552453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.997996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:25:25.552532Z","caller":"traceutil/trace.go:171","msg":"trace[1842362161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:559; }","duration":"373.109075ms","start":"2026-05-14T07:25:25.179406Z","end":"2026-05-14T07:25:25.552515Z","steps":["trace[1842362161] 'agreement among raft nodes before linearized reading' (duration: 373.002586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:25.553469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:25.179392Z","time spent":"374.058843ms","remote":"127.0.0.1:48324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T07:25:32.025489Z","caller":"traceutil/trace.go:171","msg":"trace[2056967149] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"433.62197ms","start":"2026-05-14T07:25:31.591849Z","end":"2026-05-14T07:25:32.025471Z","steps":["trace[2056967149] 'read index received' (duration: 433.53435ms)","trace[2056967149] 'applied index is now lower than readState.Index' (duration: 86.72µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:25:32.025572Z","caller":"traceutil/trace.go:171","msg":"trace[708912235] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"438.543475ms","start":"2026-05-14T07:25:31.587022Z","end":"2026-05-14T07:25:32.025565Z","steps":["trace[708912235] 'process raft request' (duration: 438.276097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:32.025653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:31.587004Z","time spent":"438.586955ms","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:25:32.02579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.950038ms","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-14T07:25:32.025817Z","caller":"traceutil/trace.go:171","msg":"trace[836392818] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:630; }","duration":"434.023237ms","start":"2026-05-14T07:25:31.591787Z","end":"2026-05-14T07:25:32.02581Z","steps":["trace[836392818] 'agreement among raft nodes before linearized reading' (duration: 433.942947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:32.025838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:31.591777Z","time spent":"434.056717ms","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-14T07:25:32.357499Z","caller":"traceutil/trace.go:171","msg":"trace[1272325318] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"331.923415ms","start":"2026-05-14T07:25:32.025546Z","end":"2026-05-14T07:25:32.35747Z","steps":["trace[1272325318] 'read index received' (duration: 331.291689ms)","trace[1272325318] 'applied index is now lower than readState.Index' (duration: 630.696µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:25:32.357591Z","caller":"traceutil/trace.go:171","msg":"trace[313288202] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"533.749322ms","start":"2026-05-14T07:25:31.823803Z","end":"2026-05-14T07:25:32.357552Z","steps":["trace[313288202] 'process raft request' (duration: 533.003127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:32.357611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.587084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T07:25:32.357757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.850894ms","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-14T07:25:32.357813Z","caller":"traceutil/trace.go:171","msg":"trace[1095951584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"715.946994ms","start":"2026-05-14T07:25:31.641852Z","end":"2026-05-14T07:25:32.357799Z","steps":["trace[1095951584] 'agreement among raft nodes before linearized reading' (duration: 715.750755ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:25:32.357819Z","caller":"traceutil/trace.go:171","msg":"trace[1374559719] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"416.863293ms","start":"2026-05-14T07:25:31.940932Z","end":"2026-05-14T07:25:32.357795Z","steps":["trace[1374559719] 'agreement among raft nodes before linearized reading' (duration: 416.552975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:32.357863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:31.641837Z","time spent":"716.015134ms","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T07:25:32.35788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:31.940905Z","time spent":"416.957383ms","remote":"127.0.0.1:48324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T07:25:32.357882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:31.823759Z","time spent":"533.933511ms","remote":"127.0.0.1:48420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:25:32.357919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.788453ms","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-14T07:25:32.358046Z","caller":"traceutil/trace.go:171","msg":"trace[915806091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"765.950422ms","start":"2026-05-14T07:25:31.592068Z","end":"2026-05-14T07:25:32.358019Z","steps":["trace[915806091] 'agreement among raft nodes before linearized reading' (duration: 765.583394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:32.358109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:31.592064Z","time spent":"766.034831ms","remote":"127.0.0.1:48626","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-05-14T07:25:32.697116Z","caller":"traceutil/trace.go:171","msg":"trace[803678584] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"339.49666ms","start":"2026-05-14T07:25:32.35757Z","end":"2026-05-14T07:25:32.697066Z","steps":["trace[803678584] 'read index received' (duration: 304.826675ms)","trace[803678584] 'applied index is now lower than readState.Index' (duration: 34.668245ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:25:32.697252Z","caller":"traceutil/trace.go:171","msg":"trace[1566492275] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"668.229653ms","start":"2026-05-14T07:25:32.028988Z","end":"2026-05-14T07:25:32.697218Z","steps":["trace[1566492275] 'process raft request' (duration: 633.26085ms)","trace[1566492275] 'compare' (duration: 34.637975ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:25:32.697402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:32.028972Z","time spent":"668.345682ms","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:25:32.697492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.391781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wvrdp\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-14T07:25:32.697564Z","caller":"traceutil/trace.go:171","msg":"trace[2038581873] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wvrdp; range_end:; response_count:1; response_revision:632; }","duration":"550.52029ms","start":"2026-05-14T07:25:32.147024Z","end":"2026-05-14T07:25:32.697544Z","steps":["trace[2038581873] 'agreement among raft nodes before linearized reading' (duration: 550.218832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:32.697625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:32.146968Z","time spent":"550.64295ms","remote":"127.0.0.1:48536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-wvrdp\" "} {"level":"warn","ts":"2026-05-14T07:25:33.440262Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744667910140793,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T07:25:33.87405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"997.990556ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T07:25:33.874499Z","caller":"traceutil/trace.go:171","msg":"trace[1285632187] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.511754663s","start":"2026-05-14T07:25:32.362702Z","end":"2026-05-14T07:25:33.874457Z","steps":["trace[1285632187] 'process raft request' (duration: 513.23697ms)","trace[1285632187] 'compare' (duration: 997.506278ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:25:33.874665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:32.362677Z","time spent":"1.511887593s","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:25:33.874666Z","caller":"traceutil/trace.go:171","msg":"trace[910898882] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"1.511924352s","start":"2026-05-14T07:25:32.362713Z","end":"2026-05-14T07:25:33.874637Z","steps":["trace[910898882] 'process raft request' (duration: 1.511492565s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:33.874823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:32.362695Z","time spent":"1.512065302s","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:25:33.875011Z","caller":"traceutil/trace.go:171","msg":"trace[221730381] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.464990091s","start":"2026-05-14T07:25:32.410006Z","end":"2026-05-14T07:25:33.874996Z","steps":["trace[221730381] 'process raft request' (duration: 1.464540913s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:33.875062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:32.409978Z","time spent":"1.46506071s","remote":"127.0.0.1:48702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T07:25:33.941336Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744667910140793,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T07:25:34.40997Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.533951827s","expected-duration":"1s"} {"level":"info","ts":"2026-05-14T07:25:34.454114Z","caller":"traceutil/trace.go:171","msg":"trace[1243742713] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"1.514170995s","start":"2026-05-14T07:25:32.939918Z","end":"2026-05-14T07:25:34.454089Z","steps":["trace[1243742713] 'read index received' (duration: 1.470274995s)","trace[1243742713] 'applied index is now lower than readState.Index' (duration: 43.89481ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:25:34.454233Z","caller":"traceutil/trace.go:171","msg":"trace[498637000] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.741730087s","start":"2026-05-14T07:25:32.712492Z","end":"2026-05-14T07:25:34.454222Z","steps":["trace[498637000] 'process raft request' (duration: 1.697765308s)","trace[498637000] 'compare' (duration: 43.684411ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:25:34.454327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.514411124s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T07:25:34.454327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:32.712459Z","time spent":"1.741801357s","remote":"127.0.0.1:48536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:25:34.454326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.587623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:25:34.454361Z","caller":"traceutil/trace.go:171","msg":"trace[654901001] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"1.514480833s","start":"2026-05-14T07:25:32.93987Z","end":"2026-05-14T07:25:34.454351Z","steps":["trace[654901001] 'agreement among raft nodes before linearized reading' (duration: 1.514392053s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:34.454383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:32.93985Z","time spent":"1.514528983s","remote":"127.0.0.1:48330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T07:25:34.454394Z","caller":"traceutil/trace.go:171","msg":"trace[187828204] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:636; }","duration":"569.689913ms","start":"2026-05-14T07:25:33.884685Z","end":"2026-05-14T07:25:34.454375Z","steps":["trace[187828204] 'agreement among raft nodes before linearized reading' (duration: 569.568493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:34.454433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:33.884667Z","time spent":"569.757593ms","remote":"127.0.0.1:48708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-05-14T07:25:34.455357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.424526ms","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-14T07:25:34.455398Z","caller":"traceutil/trace.go:171","msg":"trace[656101534] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:636; }","duration":"425.368333ms","start":"2026-05-14T07:25:34.030018Z","end":"2026-05-14T07:25:34.455386Z","steps":["trace[656101534] 'agreement among raft nodes before linearized reading' (duration: 424.268567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:34.455425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:34.029998Z","time spent":"425.419643ms","remote":"127.0.0.1:48626","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-05-14T07:25:36.429874Z","caller":"traceutil/trace.go:171","msg":"trace[439376556] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"145.351359ms","start":"2026-05-14T07:25:36.284492Z","end":"2026-05-14T07:25:36.429844Z","steps":["trace[439376556] 'process raft request' (duration: 144.77915ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:25:47.506022Z","caller":"traceutil/trace.go:171","msg":"trace[1042233424] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:811; }","duration":"216.800985ms","start":"2026-05-14T07:25:47.289195Z","end":"2026-05-14T07:25:47.505996Z","steps":["trace[1042233424] 'read index received' (duration: 216.587414ms)","trace[1042233424] 'applied index is now lower than readState.Index' (duration: 212.551µs)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:25:47.506168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.939056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T07:25:47.506205Z","caller":"traceutil/trace.go:171","msg":"trace[1916892236] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:788; }","duration":"217.010076ms","start":"2026-05-14T07:25:47.289181Z","end":"2026-05-14T07:25:47.506191Z","steps":["trace[1916892236] 'agreement among raft nodes before linearized reading' (duration: 216.917656ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:25:47.506334Z","caller":"traceutil/trace.go:171","msg":"trace[1255192442] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"265.597017ms","start":"2026-05-14T07:25:47.240707Z","end":"2026-05-14T07:25:47.506304Z","steps":["trace[1255192442] 'process raft request' (duration: 265.095854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:48.38026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.622517ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T07:25:48.380532Z","caller":"traceutil/trace.go:171","msg":"trace[511245103] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"868.490872ms","start":"2026-05-14T07:25:47.512004Z","end":"2026-05-14T07:25:48.380495Z","steps":["trace[511245103] 'process raft request' (duration: 100.533372ms)","trace[511245103] 'compare' (duration: 767.347406ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:25:48.380617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:47.511975Z","time spent":"868.602173ms","remote":"127.0.0.1:48516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":779,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:25:48.380884Z","caller":"traceutil/trace.go:171","msg":"trace[817365968] linearizableReadLoop","detail":"{readStateIndex:815; appliedIndex:812; }","duration":"439.451921ms","start":"2026-05-14T07:25:47.941413Z","end":"2026-05-14T07:25:48.380865Z","steps":["trace[817365968] 'read index received' (duration: 438.00968ms)","trace[817365968] 'applied index is now lower than readState.Index' (duration: 1.440971ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:25:48.380909Z","caller":"traceutil/trace.go:171","msg":"trace[558900837] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"868.786815ms","start":"2026-05-14T07:25:47.512107Z","end":"2026-05-14T07:25:48.380893Z","steps":["trace[558900837] 'process raft request' (duration: 868.275911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:48.380968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:47.512085Z","time spent":"868.856205ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1288,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:25:48.380907Z","caller":"traceutil/trace.go:171","msg":"trace[882667027] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"866.947723ms","start":"2026-05-14T07:25:47.513949Z","end":"2026-05-14T07:25:48.380897Z","steps":["trace[882667027] 'process raft request' (duration: 866.832052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:48.381123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.929803ms","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-14T07:25:48.381161Z","caller":"traceutil/trace.go:171","msg":"trace[1293919975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:791; }","duration":"418.071134ms","start":"2026-05-14T07:25:47.963079Z","end":"2026-05-14T07:25:48.38115Z","steps":["trace[1293919975] 'agreement among raft nodes before linearized reading' (duration: 417.943493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:48.381188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:47.963052Z","time spent":"418.130014ms","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T07:25:48.381247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.884117ms","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-14T07:25:48.381172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:47.513937Z","time spent":"867.058314ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3852,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:25:48.381275Z","caller":"traceutil/trace.go:171","msg":"trace[661316717] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:791; }","duration":"413.934137ms","start":"2026-05-14T07:25:47.967334Z","end":"2026-05-14T07:25:48.381268Z","steps":["trace[661316717] 'agreement among raft nodes before linearized reading' (duration: 413.862547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:48.381293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:47.967324Z","time spent":"413.964348ms","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-14T07:25:48.381906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.027184ms","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-14T07:25:48.381949Z","caller":"traceutil/trace.go:171","msg":"trace[268627357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:791; }","duration":"407.072114ms","start":"2026-05-14T07:25:47.974869Z","end":"2026-05-14T07:25:48.381941Z","steps":["trace[268627357] 'agreement among raft nodes before linearized reading' (duration: 406.165768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:48.381969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:47.974846Z","time spent":"407.117974ms","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-14T07:25:48.382192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.78712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:25:48.382224Z","caller":"traceutil/trace.go:171","msg":"trace[1714309381] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:791; }","duration":"440.86719ms","start":"2026-05-14T07:25:47.941347Z","end":"2026-05-14T07:25:48.382215Z","steps":["trace[1714309381] 'agreement among raft nodes before linearized reading' (duration: 439.572671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:48.382243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:47.941318Z","time spent":"440.92028ms","remote":"127.0.0.1:48330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T07:25:52.756441Z","caller":"traceutil/trace.go:171","msg":"trace[1230327301] linearizableReadLoop","detail":"{readStateIndex:840; appliedIndex:839; }","duration":"350.549147ms","start":"2026-05-14T07:25:52.405871Z","end":"2026-05-14T07:25:52.756421Z","steps":["trace[1230327301] 'read index received' (duration: 350.419766ms)","trace[1230327301] 'applied index is now lower than readState.Index' (duration: 128.261µs)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:25:52.756684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.696639ms","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-14T07:25:52.756732Z","caller":"traceutil/trace.go:171","msg":"trace[70697516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:814; }","duration":"350.749849ms","start":"2026-05-14T07:25:52.405963Z","end":"2026-05-14T07:25:52.756713Z","steps":["trace[70697516] 'agreement among raft nodes before linearized reading' (duration: 350.601608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:52.756771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:52.405899Z","time spent":"350.863731ms","remote":"127.0.0.1:48626","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-05-14T07:25:52.756802Z","caller":"traceutil/trace.go:171","msg":"trace[1577321714] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"489.470331ms","start":"2026-05-14T07:25:52.267248Z","end":"2026-05-14T07:25:52.756719Z","steps":["trace[1577321714] 'process raft request' (duration: 489.037207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:52.757699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:52.267226Z","time spent":"490.40695ms","remote":"127.0.0.1:48596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:25:52.756903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.35503ms","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-14T07:25:52.75785Z","caller":"traceutil/trace.go:171","msg":"trace[1834502095] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:814; }","duration":"212.33899ms","start":"2026-05-14T07:25:52.545502Z","end":"2026-05-14T07:25:52.757841Z","steps":["trace[1834502095] 'agreement among raft nodes before linearized reading' (duration: 211.30639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:52.757017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.991042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-14T07:25:52.757973Z","caller":"traceutil/trace.go:171","msg":"trace[998318357] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:814; }","duration":"349.990821ms","start":"2026-05-14T07:25:52.407975Z","end":"2026-05-14T07:25:52.757966Z","steps":["trace[998318357] 'agreement among raft nodes before linearized reading' (duration: 348.915551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:52.758002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:52.407959Z","time spent":"350.034272ms","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-14T07:25:52.756903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.908601ms","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-14T07:25:52.75814Z","caller":"traceutil/trace.go:171","msg":"trace[1941086012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:814; }","duration":"352.304614ms","start":"2026-05-14T07:25:52.405828Z","end":"2026-05-14T07:25:52.758133Z","steps":["trace[1941086012] 'agreement among raft nodes before linearized reading' (duration: 350.725638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:25:52.758169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:25:52.405807Z","time spent":"352.352825ms","remote":"127.0.0.1:48626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-14T07:26:41.243416Z","caller":"traceutil/trace.go:171","msg":"trace[440398418] linearizableReadLoop","detail":"{readStateIndex:1143; appliedIndex:1142; }","duration":"184.952618ms","start":"2026-05-14T07:26:41.058445Z","end":"2026-05-14T07:26:41.243397Z","steps":["trace[440398418] 'read index received' (duration: 184.841924ms)","trace[440398418] 'applied index is now lower than readState.Index' (duration: 109.574µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:26:41.243535Z","caller":"traceutil/trace.go:171","msg":"trace[295472600] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"185.926259ms","start":"2026-05-14T07:26:41.057569Z","end":"2026-05-14T07:26:41.243495Z","steps":["trace[295472600] 'process raft request' (duration: 185.680161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:26:41.243607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.142734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-14T07:26:41.243636Z","caller":"traceutil/trace.go:171","msg":"trace[2125710623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1106; }","duration":"185.211426ms","start":"2026-05-14T07:26:41.058416Z","end":"2026-05-14T07:26:41.243628Z","steps":["trace[2125710623] 'agreement among raft nodes before linearized reading' (duration: 185.092682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:26:41.243685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.327785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-14T07:26:41.243738Z","caller":"traceutil/trace.go:171","msg":"trace[2043298997] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1106; }","duration":"180.416018ms","start":"2026-05-14T07:26:41.06331Z","end":"2026-05-14T07:26:41.243726Z","steps":["trace[2043298997] 'agreement among raft nodes before linearized reading' (duration: 180.254343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:26:41.243732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.00908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-14T07:26:41.243832Z","caller":"traceutil/trace.go:171","msg":"trace[1888969575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1106; }","duration":"185.142663ms","start":"2026-05-14T07:26:41.058671Z","end":"2026-05-14T07:26:41.243814Z","steps":["trace[1888969575] 'agreement among raft nodes before linearized reading' (duration: 184.887715ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:26:53.567871Z","caller":"traceutil/trace.go:171","msg":"trace[708921091] linearizableReadLoop","detail":"{readStateIndex:1185; appliedIndex:1184; }","duration":"269.46155ms","start":"2026-05-14T07:26:53.298387Z","end":"2026-05-14T07:26:53.567848Z","steps":["trace[708921091] 'read index received' (duration: 269.280893ms)","trace[708921091] 'applied index is now lower than readState.Index' (duration: 179.787µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:26:53.568005Z","caller":"traceutil/trace.go:171","msg":"trace[2129892452] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"276.279347ms","start":"2026-05-14T07:26:53.29166Z","end":"2026-05-14T07:26:53.567939Z","steps":["trace[2129892452] 'process raft request' (duration: 276.053149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:26:53.568114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.713019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-14T07:26:53.568161Z","caller":"traceutil/trace.go:171","msg":"trace[1476147136] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1146; }","duration":"269.807613ms","start":"2026-05-14T07:26:53.298343Z","end":"2026-05-14T07:26:53.568151Z","steps":["trace[1476147136] 'agreement among raft nodes before linearized reading' (duration: 269.640917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:26:53.568179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.107885ms","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-14T07:26:53.568223Z","caller":"traceutil/trace.go:171","msg":"trace[1338146967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1146; }","duration":"251.199568ms","start":"2026-05-14T07:26:53.317014Z","end":"2026-05-14T07:26:53.568213Z","steps":["trace[1338146967] 'agreement among raft nodes before linearized reading' (duration: 251.032222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:26:53.568186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.768345ms","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-14T07:26:53.568308Z","caller":"traceutil/trace.go:171","msg":"trace[322580609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1146; }","duration":"212.930671ms","start":"2026-05-14T07:26:53.355361Z","end":"2026-05-14T07:26:53.568292Z","steps":["trace[322580609] 'agreement among raft nodes before linearized reading' (duration: 212.704393ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:27:12.070408Z","caller":"traceutil/trace.go:171","msg":"trace[370914630] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"129.732009ms","start":"2026-05-14T07:27:11.940648Z","end":"2026-05-14T07:27:12.07038Z","steps":["trace[370914630] 'process raft request' (duration: 129.380215ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:27:16.369506Z","caller":"traceutil/trace.go:171","msg":"trace[1660510118] linearizableReadLoop","detail":"{readStateIndex:1266; appliedIndex:1265; }","duration":"217.798189ms","start":"2026-05-14T07:27:16.151688Z","end":"2026-05-14T07:27:16.369486Z","steps":["trace[1660510118] 'read index received' (duration: 217.611071ms)","trace[1660510118] 'applied index is now lower than readState.Index' (duration: 185.928µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:27:16.369621Z","caller":"traceutil/trace.go:171","msg":"trace[1720975442] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"228.780061ms","start":"2026-05-14T07:27:16.140781Z","end":"2026-05-14T07:27:16.369562Z","steps":["trace[1720975442] 'process raft request' (duration: 228.576423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:27:16.369706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.999586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T07:27:16.369754Z","caller":"traceutil/trace.go:171","msg":"trace[1301919654] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1222; }","duration":"218.119421ms","start":"2026-05-14T07:27:16.151623Z","end":"2026-05-14T07:27:16.369743Z","steps":["trace[1301919654] 'agreement among raft nodes before linearized reading' (duration: 217.943754ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:27:41.720059Z","caller":"traceutil/trace.go:171","msg":"trace[1757373627] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"259.431595ms","start":"2026-05-14T07:27:41.460596Z","end":"2026-05-14T07:27:41.720027Z","steps":["trace[1757373627] 'process raft request' (duration: 259.307599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:27:42.279986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.860062ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T07:27:42.280389Z","caller":"traceutil/trace.go:171","msg":"trace[691570422] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"285.450367ms","start":"2026-05-14T07:27:41.994912Z","end":"2026-05-14T07:27:42.280363Z","steps":["trace[691570422] 'process raft request' (duration: 95.137325ms)","trace[691570422] 'compare' (duration: 189.758147ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:27:42.280457Z","caller":"traceutil/trace.go:171","msg":"trace[299224859] linearizableReadLoop","detail":"{readStateIndex:1393; appliedIndex:1392; }","duration":"240.632249ms","start":"2026-05-14T07:27:42.0398Z","end":"2026-05-14T07:27:42.280432Z","steps":["trace[299224859] 'read index received' (duration: 50.268254ms)","trace[299224859] 'applied index is now lower than readState.Index' (duration: 190.360915ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:27:42.28067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.708064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-14T07:27:42.280748Z","caller":"traceutil/trace.go:171","msg":"trace[2051609128] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1342; }","duration":"284.776827ms","start":"2026-05-14T07:27:41.995918Z","end":"2026-05-14T07:27:42.280695Z","steps":["trace[2051609128] 'agreement among raft nodes before linearized reading' (duration: 284.594219ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:27:42.281277Z","caller":"traceutil/trace.go:171","msg":"trace[1660627613] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"117.353686ms","start":"2026-05-14T07:27:42.163909Z","end":"2026-05-14T07:27:42.281262Z","steps":["trace[1660627613] 'process raft request' (duration: 117.291483ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:27:42.281404Z","caller":"traceutil/trace.go:171","msg":"trace[1433227542] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"237.670176ms","start":"2026-05-14T07:27:42.043724Z","end":"2026-05-14T07:27:42.281394Z","steps":["trace[1433227542] 'process raft request' (duration: 236.369117ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:27:42.433402Z","caller":"traceutil/trace.go:171","msg":"trace[651690964] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"148.604404ms","start":"2026-05-14T07:27:42.284774Z","end":"2026-05-14T07:27:42.433378Z","steps":["trace[651690964] 'process raft request' (duration: 145.344537ms)"],"step_count":1}