{"level":"warn","ts":"2026-05-19T18:21:18.643271Z","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-19T18:21:18.643412Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.168:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.168:2380","--initial-cluster=instance=https://199.19.213.168:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.168:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.168: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-19T18:21:18.643488Z","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-19T18:21:18.643505Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.168:2380"]} {"level":"info","ts":"2026-05-19T18:21:18.643526Z","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-19T18:21:18.644466Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.168:2379"]} {"level":"info","ts":"2026-05-19T18:21:18.644569Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.168:2380"],"listen-peer-urls":["https://199.19.213.168:2380"],"advertise-client-urls":["https://199.19.213.168:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.168:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.168: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-19T18:21:18.650768Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.947446ms"} {"level":"info","ts":"2026-05-19T18:21:18.66227Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8ae391decac9b376","cluster-id":"d9b32c15a2c66ac"} {"level":"info","ts":"2026-05-19T18:21:18.66237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T18:21:18.662394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 became follower at term 0"} {"level":"info","ts":"2026-05-19T18:21:18.662405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8ae391decac9b376 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T18:21:18.662416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 became follower at term 1"} {"level":"info","ts":"2026-05-19T18:21:18.662455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 switched to configuration voters=(10008003183018357622)"} {"level":"warn","ts":"2026-05-19T18:21:18.668737Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T18:21:18.669789Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T18:21:18.670779Z","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-19T18:21:18.672629Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8ae391decac9b376","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T18:21:18.672963Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8ae391decac9b376","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T18:21:18.673009Z","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-19T18:21:18.673082Z","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-19T18:21:18.673094Z","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-19T18:21:18.674612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 switched to configuration voters=(10008003183018357622)"} {"level":"info","ts":"2026-05-19T18:21:18.67476Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d9b32c15a2c66ac","local-member-id":"8ae391decac9b376","added-peer-id":"8ae391decac9b376","added-peer-peer-urls":["https://199.19.213.168:2380"]} {"level":"info","ts":"2026-05-19T18:21:18.677075Z","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-19T18:21:18.677189Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.168:2380"} {"level":"info","ts":"2026-05-19T18:21:18.677302Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.168:2380"} {"level":"info","ts":"2026-05-19T18:21:18.677483Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8ae391decac9b376","initial-advertise-peer-urls":["https://199.19.213.168:2380"],"listen-peer-urls":["https://199.19.213.168:2380"],"advertise-client-urls":["https://199.19.213.168:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.168:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T18:21:18.677551Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T18:21:19.263113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T18:21:19.263171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T18:21:19.263211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 received MsgPreVoteResp from 8ae391decac9b376 at term 1"} {"level":"info","ts":"2026-05-19T18:21:19.26323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 became candidate at term 2"} {"level":"info","ts":"2026-05-19T18:21:19.263241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 received MsgVoteResp from 8ae391decac9b376 at term 2"} {"level":"info","ts":"2026-05-19T18:21:19.263257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 became leader at term 2"} {"level":"info","ts":"2026-05-19T18:21:19.263276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8ae391decac9b376 elected leader 8ae391decac9b376 at term 2"} {"level":"info","ts":"2026-05-19T18:21:19.265073Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8ae391decac9b376","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.168:2379]}","request-path":"/0/members/8ae391decac9b376/attributes","cluster-id":"d9b32c15a2c66ac","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T18:21:19.265181Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T18:21:19.265289Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T18:21:19.265388Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T18:21:19.26557Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T18:21:19.265625Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T18:21:19.266227Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d9b32c15a2c66ac","local-member-id":"8ae391decac9b376","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T18:21:19.266389Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T18:21:19.266425Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T18:21:19.267757Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T18:21:19.268554Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.168:2379"} {"level":"info","ts":"2026-05-19T18:21:22.053486Z","caller":"traceutil/trace.go:171","msg":"trace[1573894424] linearizableReadLoop","detail":"{readStateIndex:75; appliedIndex:74; }","duration":"263.137197ms","start":"2026-05-19T18:21:21.790327Z","end":"2026-05-19T18:21:22.053464Z","steps":["trace[1573894424] 'read index received' (duration: 262.974323ms)","trace[1573894424] 'applied index is now lower than readState.Index' (duration: 162.074µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:21:22.053616Z","caller":"traceutil/trace.go:171","msg":"trace[1234232253] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"263.853917ms","start":"2026-05-19T18:21:21.789753Z","end":"2026-05-19T18:21:22.053607Z","steps":["trace[1234232253] 'process raft request' (duration: 263.618417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:22.053862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.508788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T18:21:22.053937Z","caller":"traceutil/trace.go:171","msg":"trace[881266764] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:71; }","duration":"263.632489ms","start":"2026-05-19T18:21:21.790294Z","end":"2026-05-19T18:21:22.053926Z","steps":["trace[881266764] 'agreement among raft nodes before linearized reading' (duration: 263.465535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:22.054168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.571381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T18:21:22.054224Z","caller":"traceutil/trace.go:171","msg":"trace[1420147384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:71; }","duration":"203.653658ms","start":"2026-05-19T18:21:21.850556Z","end":"2026-05-19T18:21:22.05421Z","steps":["trace[1420147384] 'agreement among raft nodes before linearized reading' (duration: 203.519407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:22.054219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.100253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T18:21:22.054371Z","caller":"traceutil/trace.go:171","msg":"trace[1765923595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:71; }","duration":"160.3018ms","start":"2026-05-19T18:21:21.894047Z","end":"2026-05-19T18:21:22.054349Z","steps":["trace[1765923595] 'agreement among raft nodes before linearized reading' (duration: 160.075681ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:21:24.163852Z","caller":"traceutil/trace.go:171","msg":"trace[2094056498] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"100.293238ms","start":"2026-05-19T18:21:24.063507Z","end":"2026-05-19T18:21:24.1638Z","steps":["trace[2094056498] 'process raft request' (duration: 31.190628ms)","trace[2094056498] 'compare' (duration: 68.695215ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:21:24.434553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.428849ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:21:24.434784Z","caller":"traceutil/trace.go:171","msg":"trace[1445170588] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"230.380065ms","start":"2026-05-19T18:21:24.204358Z","end":"2026-05-19T18:21:24.434738Z","steps":["trace[1445170588] 'process raft request' (duration: 65.658496ms)","trace[1445170588] 'compare' (duration: 163.152235ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:21:24.434848Z","caller":"traceutil/trace.go:171","msg":"trace[878514044] linearizableReadLoop","detail":"{readStateIndex:239; appliedIndex:238; }","duration":"182.228121ms","start":"2026-05-19T18:21:24.252593Z","end":"2026-05-19T18:21:24.434821Z","steps":["trace[878514044] 'read index received' (duration: 17.405063ms)","trace[878514044] 'applied index is now lower than readState.Index' (duration: 164.820428ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:21:24.435004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.405486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18b10a81307d6678\" ","response":"range_response_count:1 size:653"} {"level":"info","ts":"2026-05-19T18:21:24.435045Z","caller":"traceutil/trace.go:171","msg":"trace[1268908914] range","detail":"{range_begin:/registry/events/default/instance.18b10a81307d6678; range_end:; response_count:1; response_revision:233; }","duration":"182.496684ms","start":"2026-05-19T18:21:24.252536Z","end":"2026-05-19T18:21:24.435033Z","steps":["trace[1268908914] 'agreement among raft nodes before linearized reading' (duration: 182.363633ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:21:24.435468Z","caller":"traceutil/trace.go:171","msg":"trace[927305780] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"182.553139ms","start":"2026-05-19T18:21:24.252885Z","end":"2026-05-19T18:21:24.435438Z","steps":["trace[927305780] 'process raft request' (duration: 181.871511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:28.611694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.732547ms","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-19T18:21:28.611769Z","caller":"traceutil/trace.go:171","msg":"trace[1958240208] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:342; }","duration":"226.914563ms","start":"2026-05-19T18:21:28.384842Z","end":"2026-05-19T18:21:28.611757Z","steps":["trace[1958240208] 'range keys from in-memory index tree' (duration: 226.638019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:28.611762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.05918ms","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-19T18:21:28.611818Z","caller":"traceutil/trace.go:171","msg":"trace[2073454356] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:342; }","duration":"127.114535ms","start":"2026-05-19T18:21:28.484686Z","end":"2026-05-19T18:21:28.611801Z","steps":["trace[2073454356] 'range keys from in-memory index tree' (duration: 126.984933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:28.611837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.0966ms","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-19T18:21:28.612274Z","caller":"traceutil/trace.go:171","msg":"trace[733297135] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:342; }","duration":"176.581942ms","start":"2026-05-19T18:21:28.435666Z","end":"2026-05-19T18:21:28.612248Z","steps":["trace[733297135] 'range keys from in-memory index tree' (duration: 176.016753ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:29.135449Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12931697384073185569,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T18:21:29.370224Z","caller":"traceutil/trace.go:171","msg":"trace[1046313346] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"748.749542ms","start":"2026-05-19T18:21:28.621453Z","end":"2026-05-19T18:21:29.370203Z","steps":["trace[1046313346] 'process raft request' (duration: 748.633752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:29.370346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:21:28.621432Z","time spent":"748.858731ms","remote":"127.0.0.1:56870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T18:21:30.175978Z","caller":"traceutil/trace.go:171","msg":"trace[262907234] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"1.552995063s","start":"2026-05-19T18:21:28.622931Z","end":"2026-05-19T18:21:30.175926Z","steps":["trace[262907234] 'process raft request' (duration: 1.498175538s)","trace[262907234] 'compare' (duration: 54.135636ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:21:30.176063Z","caller":"traceutil/trace.go:171","msg":"trace[615121587] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"1.552834399s","start":"2026-05-19T18:21:28.623186Z","end":"2026-05-19T18:21:30.176021Z","steps":["trace[615121587] 'process raft request' (duration: 1.552714639s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:30.176143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.5412542s","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-19T18:21:30.175998Z","caller":"traceutil/trace.go:171","msg":"trace[638630171] linearizableReadLoop","detail":"{readStateIndex:357; appliedIndex:353; }","duration":"1.541101557s","start":"2026-05-19T18:21:28.634863Z","end":"2026-05-19T18:21:30.175965Z","steps":["trace[638630171] 'read index received' (duration: 735.271559ms)","trace[638630171] 'applied index is now lower than readState.Index' (duration: 805.828848ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:21:30.176244Z","caller":"traceutil/trace.go:171","msg":"trace[183925741] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:346; }","duration":"1.541384981s","start":"2026-05-19T18:21:28.634835Z","end":"2026-05-19T18:21:30.17622Z","steps":["trace[183925741] 'agreement among raft nodes before linearized reading' (duration: 1.541231498s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:30.176302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:21:28.634824Z","time spent":"1.541463428s","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"info","ts":"2026-05-19T18:21:30.176043Z","caller":"traceutil/trace.go:171","msg":"trace[1425761216] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"1.552983111s","start":"2026-05-19T18:21:28.622997Z","end":"2026-05-19T18:21:30.17598Z","steps":["trace[1425761216] 'process raft request' (duration: 1.552810676s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:30.17656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:21:28.623174Z","time spent":"1.552999963s","remote":"127.0.0.1:56736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:21:30.176719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:21:28.622919Z","time spent":"1.553270006s","remote":"127.0.0.1:56736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:21:30.176857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:21:28.622991Z","time spent":"1.553364975s","remote":"127.0.0.1:56736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:21:30.676769Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12931697384073185576,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T18:21:31.251962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.13542ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:21:31.252473Z","caller":"traceutil/trace.go:171","msg":"trace[1747656753] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"1.069319687s","start":"2026-05-19T18:21:30.183128Z","end":"2026-05-19T18:21:31.252448Z","steps":["trace[1747656753] 'process raft request' (duration: 1.069097638s)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:21:31.252515Z","caller":"traceutil/trace.go:171","msg":"trace[1034370542] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"1.876172875s","start":"2026-05-19T18:21:29.376316Z","end":"2026-05-19T18:21:31.252489Z","steps":["trace[1034370542] 'process raft request' (duration: 1.665417562s)","trace[1034370542] 'compare' (duration: 209.687841ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:21:31.252513Z","caller":"traceutil/trace.go:171","msg":"trace[1957245120] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"1.874703418s","start":"2026-05-19T18:21:29.37767Z","end":"2026-05-19T18:21:31.252373Z","steps":["trace[1957245120] 'process raft request' (duration: 1.874406553s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:31.252583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:21:30.1831Z","time spent":"1.069431337s","remote":"127.0.0.1:56736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2264,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:21:31.252751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:21:29.37766Z","time spent":"1.8749537s","remote":"127.0.0.1:56840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:21:31.252797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:21:29.376294Z","time spent":"1.876411056s","remote":"127.0.0.1:56518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T18:21:31.253115Z","caller":"traceutil/trace.go:171","msg":"trace[241022700] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"1.068657761s","start":"2026-05-19T18:21:30.184438Z","end":"2026-05-19T18:21:31.253095Z","steps":["trace[241022700] 'process raft request' (duration: 1.067952149s)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:21:31.253179Z","caller":"traceutil/trace.go:171","msg":"trace[1033114794] linearizableReadLoop","detail":"{readStateIndex:361; appliedIndex:357; }","duration":"1.076874234s","start":"2026-05-19T18:21:30.176276Z","end":"2026-05-19T18:21:31.25315Z","steps":["trace[1033114794] 'read index received' (duration: 865.480794ms)","trace[1033114794] 'applied index is now lower than readState.Index' (duration: 211.391129ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:21:31.253196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:21:30.184424Z","time spent":"1.068731277s","remote":"127.0.0.1:56736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:21:31.253141Z","caller":"traceutil/trace.go:171","msg":"trace[667108291] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"113.454894ms","start":"2026-05-19T18:21:31.139656Z","end":"2026-05-19T18:21:31.253111Z","steps":["trace[667108291] 'process raft request' (duration: 113.346544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:31.253306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.366541943s","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-19T18:21:31.253358Z","caller":"traceutil/trace.go:171","msg":"trace[10369512] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:351; }","duration":"2.366628101s","start":"2026-05-19T18:21:28.88672Z","end":"2026-05-19T18:21:31.253348Z","steps":["trace[10369512] 'agreement among raft nodes before linearized reading' (duration: 2.366536913s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:31.253389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:21:28.8867Z","time spent":"2.366682195s","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-05-19T18:21:31.25339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.55456046s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T18:21:31.253434Z","caller":"traceutil/trace.go:171","msg":"trace[2072152452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:351; }","duration":"2.554607635s","start":"2026-05-19T18:21:28.698815Z","end":"2026-05-19T18:21:31.253423Z","steps":["trace[2072152452] 'agreement among raft nodes before linearized reading' (duration: 2.554537799s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:31.253473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:21:28.698785Z","time spent":"2.554681431s","remote":"127.0.0.1:56678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T18:21:31.253559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.568741673s","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-19T18:21:31.253592Z","caller":"traceutil/trace.go:171","msg":"trace[1169897988] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:351; }","duration":"2.568799509s","start":"2026-05-19T18:21:28.684784Z","end":"2026-05-19T18:21:31.253584Z","steps":["trace[1169897988] 'agreement among raft nodes before linearized reading' (duration: 2.568745484s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:31.253618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:21:28.684768Z","time spent":"2.568844372s","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-05-19T18:21:31.253673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.417960398s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-05-19T18:21:31.253693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.784394698s","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-19T18:21:31.253729Z","caller":"traceutil/trace.go:171","msg":"trace[22663293] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:351; }","duration":"1.784533981s","start":"2026-05-19T18:21:29.469186Z","end":"2026-05-19T18:21:31.25372Z","steps":["trace[22663293] 'agreement among raft nodes before linearized reading' (duration: 1.784397999s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:31.253757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:21:29.469168Z","time spent":"1.784582134s","remote":"127.0.0.1:56604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-05-19T18:21:31.253745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.073770465s","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":"warn","ts":"2026-05-19T18:21:31.253787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.467519473s","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-19T18:21:31.253812Z","caller":"traceutil/trace.go:171","msg":"trace[1623788379] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:351; }","duration":"1.073871193s","start":"2026-05-19T18:21:30.179921Z","end":"2026-05-19T18:21:31.253792Z","steps":["trace[1623788379] 'agreement among raft nodes before linearized reading' (duration: 1.073748932s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:31.253906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.518461997s","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-19T18:21:31.253939Z","caller":"traceutil/trace.go:171","msg":"trace[195079543] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:351; }","duration":"2.518529333s","start":"2026-05-19T18:21:28.735401Z","end":"2026-05-19T18:21:31.253931Z","steps":["trace[195079543] 'agreement among raft nodes before linearized reading' (duration: 2.518440895s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:31.25396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.417118012s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-19T18:21:31.25397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:21:28.73537Z","time spent":"2.518590658s","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"info","ts":"2026-05-19T18:21:31.253993Z","caller":"traceutil/trace.go:171","msg":"trace[1434648765] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:351; }","duration":"1.417177017s","start":"2026-05-19T18:21:29.836808Z","end":"2026-05-19T18:21:31.253985Z","steps":["trace[1434648765] 'agreement among raft nodes before linearized reading' (duration: 1.417119402s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:31.254018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:21:29.83679Z","time spent":"1.417221201s","remote":"127.0.0.1:56678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T18:21:31.253935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:21:30.179886Z","time spent":"1.073967161s","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-05-19T18:21:31.254074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.880078514s","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-19T18:21:31.254131Z","caller":"traceutil/trace.go:171","msg":"trace[1631781090] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:351; }","duration":"1.880160141s","start":"2026-05-19T18:21:29.373957Z","end":"2026-05-19T18:21:31.254117Z","steps":["trace[1631781090] 'agreement among raft nodes before linearized reading' (duration: 1.880068353s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:31.254158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.567448765s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-19T18:21:31.254178Z","caller":"traceutil/trace.go:171","msg":"trace[303287660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:351; }","duration":"1.56750471s","start":"2026-05-19T18:21:29.686667Z","end":"2026-05-19T18:21:31.254172Z","steps":["trace[303287660] 'agreement among raft nodes before linearized reading' (duration: 1.567462706s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:31.254198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:21:29.686648Z","time spent":"1.567545254s","remote":"127.0.0.1:56678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T18:21:31.254203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:21:29.373944Z","time spent":"1.880248019s","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-05-19T18:21:31.253823Z","caller":"traceutil/trace.go:171","msg":"trace[401428150] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:351; }","duration":"2.4675972s","start":"2026-05-19T18:21:28.786217Z","end":"2026-05-19T18:21:31.253815Z","steps":["trace[401428150] 'agreement among raft nodes before linearized reading' (duration: 2.467526814s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:31.254314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:21:28.786194Z","time spent":"2.468110614s","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"info","ts":"2026-05-19T18:21:31.253722Z","caller":"traceutil/trace.go:171","msg":"trace[792731340] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:351; }","duration":"2.418038585s","start":"2026-05-19T18:21:28.83567Z","end":"2026-05-19T18:21:31.253709Z","steps":["trace[792731340] 'agreement among raft nodes before linearized reading' (duration: 2.417936656s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:31.254473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:21:28.83565Z","time spent":"2.418814412s","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-05-19T18:21:31.254508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.300759199s","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-19T18:21:31.254552Z","caller":"traceutil/trace.go:171","msg":"trace[505395777] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:351; }","duration":"2.300836246s","start":"2026-05-19T18:21:28.953703Z","end":"2026-05-19T18:21:31.25454Z","steps":["trace[505395777] 'agreement among raft nodes before linearized reading' (duration: 2.30076408s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:31.254591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:21:28.953686Z","time spent":"2.300896461s","remote":"127.0.0.1:56678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T18:21:31.255066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.318889063s","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-19T18:21:31.255266Z","caller":"traceutil/trace.go:171","msg":"trace[1101060219] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:351; }","duration":"2.318997883s","start":"2026-05-19T18:21:28.93611Z","end":"2026-05-19T18:21:31.255108Z","steps":["trace[1101060219] 'agreement among raft nodes before linearized reading' (duration: 2.317063836s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:31.255474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:21:28.936084Z","time spent":"2.319271196s","remote":"127.0.0.1:56620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-05-19T18:21:32.26761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.725472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:21:32.267662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.147324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-05-19T18:21:32.267764Z","caller":"traceutil/trace.go:171","msg":"trace[2032804913] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:389; }","duration":"225.296606ms","start":"2026-05-19T18:21:32.042444Z","end":"2026-05-19T18:21:32.26774Z","steps":["trace[2032804913] 'range keys from in-memory index tree' (duration: 225.056876ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:21:32.267699Z","caller":"traceutil/trace.go:171","msg":"trace[1427191543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:389; }","duration":"194.851973ms","start":"2026-05-19T18:21:32.072831Z","end":"2026-05-19T18:21:32.267683Z","steps":["trace[1427191543] 'range keys from in-memory index tree' (duration: 194.664636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:33.028519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.069921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-05-19T18:21:33.028614Z","caller":"traceutil/trace.go:171","msg":"trace[1420786904] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:394; }","duration":"102.214404ms","start":"2026-05-19T18:21:32.926377Z","end":"2026-05-19T18:21:33.028591Z","steps":["trace[1420786904] 'range keys from in-memory index tree' (duration: 101.951001ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:21:33.214182Z","caller":"traceutil/trace.go:171","msg":"trace[1280901662] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"101.828997ms","start":"2026-05-19T18:21:33.112325Z","end":"2026-05-19T18:21:33.214154Z","steps":["trace[1280901662] 'process raft request' (duration: 23.325057ms)","trace[1280901662] 'compare' (duration: 78.39989ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:21:33.217579Z","caller":"traceutil/trace.go:171","msg":"trace[1393804290] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"104.806176ms","start":"2026-05-19T18:21:33.112754Z","end":"2026-05-19T18:21:33.21756Z","steps":["trace[1393804290] 'process raft request' (duration: 104.710588ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:21:33.598969Z","caller":"traceutil/trace.go:171","msg":"trace[1232561261] linearizableReadLoop","detail":"{readStateIndex:412; appliedIndex:411; }","duration":"313.17109ms","start":"2026-05-19T18:21:33.285757Z","end":"2026-05-19T18:21:33.598928Z","steps":["trace[1232561261] 'read index received' (duration: 253.571944ms)","trace[1232561261] 'applied index is now lower than readState.Index' (duration: 59.597436ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:21:33.599101Z","caller":"traceutil/trace.go:171","msg":"trace[970935537] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"323.127189ms","start":"2026-05-19T18:21:33.275941Z","end":"2026-05-19T18:21:33.599068Z","steps":["trace[970935537] 'process raft request' (duration: 263.346247ms)","trace[970935537] 'compare' (duration: 59.41143ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:21:33.59939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:21:33.275924Z","time spent":"323.412105ms","remote":"127.0.0.1:56678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:21:33.599465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.489788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T18:21:33.599568Z","caller":"traceutil/trace.go:171","msg":"trace[738913169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:399; }","duration":"313.6193ms","start":"2026-05-19T18:21:33.285923Z","end":"2026-05-19T18:21:33.599542Z","steps":["trace[738913169] 'agreement among raft nodes before linearized reading' (duration: 313.463866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:33.59964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.796818ms","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-19T18:21:33.599675Z","caller":"traceutil/trace.go:171","msg":"trace[1702357284] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:399; }","duration":"312.850892ms","start":"2026-05-19T18:21:33.286814Z","end":"2026-05-19T18:21:33.599665Z","steps":["trace[1702357284] 'agreement among raft nodes before linearized reading' (duration: 312.789757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:33.599697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:21:33.286801Z","time spent":"312.890946ms","remote":"127.0.0.1:56678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T18:21:33.59916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.385138ms","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-19T18:21:33.599782Z","caller":"traceutil/trace.go:171","msg":"trace[840291266] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:399; }","duration":"314.071778ms","start":"2026-05-19T18:21:33.285705Z","end":"2026-05-19T18:21:33.599777Z","steps":["trace[840291266] 'agreement among raft nodes before linearized reading' (duration: 313.379428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:33.599811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:21:33.285679Z","time spent":"314.127364ms","remote":"127.0.0.1:56678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T18:21:33.599633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:21:33.285911Z","time spent":"313.708957ms","remote":"127.0.0.1:56678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-19T18:21:37.00397Z","caller":"traceutil/trace.go:171","msg":"trace[1083844580] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"289.083755ms","start":"2026-05-19T18:21:36.714862Z","end":"2026-05-19T18:21:37.003946Z","steps":["trace[1083844580] 'process raft request' (duration: 288.828273ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:21:53.96478Z","caller":"traceutil/trace.go:171","msg":"trace[172045564] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:528; }","duration":"216.032658ms","start":"2026-05-19T18:21:53.748718Z","end":"2026-05-19T18:21:53.964751Z","steps":["trace[172045564] 'read index received' (duration: 215.764324ms)","trace[172045564] 'applied index is now lower than readState.Index' (duration: 267.164µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:21:53.96482Z","caller":"traceutil/trace.go:171","msg":"trace[1496430287] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"222.308005ms","start":"2026-05-19T18:21:53.742476Z","end":"2026-05-19T18:21:53.964784Z","steps":["trace[1496430287] 'process raft request' (duration: 222.036621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:53.965049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.315243ms","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-19T18:21:53.965107Z","caller":"traceutil/trace.go:171","msg":"trace[836458317] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:512; }","duration":"216.38942ms","start":"2026-05-19T18:21:53.748703Z","end":"2026-05-19T18:21:53.965093Z","steps":["trace[836458317] 'agreement among raft nodes before linearized reading' (duration: 216.237996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:53.965045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.232187ms","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-19T18:21:53.965156Z","caller":"traceutil/trace.go:171","msg":"trace[1647442445] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:512; }","duration":"194.410953ms","start":"2026-05-19T18:21:53.770734Z","end":"2026-05-19T18:21:53.965145Z","steps":["trace[1647442445] 'agreement among raft nodes before linearized reading' (duration: 194.236107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:53.965129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.784701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T18:21:53.965258Z","caller":"traceutil/trace.go:171","msg":"trace[1195913418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:512; }","duration":"129.970768ms","start":"2026-05-19T18:21:53.835259Z","end":"2026-05-19T18:21:53.96523Z","steps":["trace[1195913418] 'agreement among raft nodes before linearized reading' (duration: 129.760019ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:21:54.869619Z","caller":"traceutil/trace.go:171","msg":"trace[1242689800] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"152.732875ms","start":"2026-05-19T18:21:54.716862Z","end":"2026-05-19T18:21:54.869594Z","steps":["trace[1242689800] 'process raft request' (duration: 152.600146ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:21:54.87032Z","caller":"traceutil/trace.go:171","msg":"trace[1615712091] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"182.377401ms","start":"2026-05-19T18:21:54.687621Z","end":"2026-05-19T18:21:54.869998Z","steps":["trace[1615712091] 'process raft request' (duration: 181.273212ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:21:54.870535Z","caller":"traceutil/trace.go:171","msg":"trace[389802271] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"182.787138ms","start":"2026-05-19T18:21:54.687271Z","end":"2026-05-19T18:21:54.870058Z","steps":["trace[389802271] 'process raft request' (duration: 173.963228ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:21:54.870022Z","caller":"traceutil/trace.go:171","msg":"trace[1179532646] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"158.244267ms","start":"2026-05-19T18:21:54.711763Z","end":"2026-05-19T18:21:54.870007Z","steps":["trace[1179532646] 'process raft request' (duration: 157.341782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:55.771018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.603141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:21:55.771209Z","caller":"traceutil/trace.go:171","msg":"trace[1130392789] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"595.335751ms","start":"2026-05-19T18:21:55.175849Z","end":"2026-05-19T18:21:55.771184Z","steps":["trace[1130392789] 'process raft request' (duration: 36.730758ms)","trace[1130392789] 'compare' (duration: 557.408205ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:21:55.771314Z","caller":"traceutil/trace.go:171","msg":"trace[2029349740] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"591.220331ms","start":"2026-05-19T18:21:55.180079Z","end":"2026-05-19T18:21:55.771299Z","steps":["trace[2029349740] 'process raft request' (duration: 591.09586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:55.771347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:21:55.175808Z","time spent":"595.442914ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:21:55.771396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:21:55.180049Z","time spent":"591.303086ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65412,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:21:55.77836Z","caller":"traceutil/trace.go:171","msg":"trace[1558266955] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"561.75865ms","start":"2026-05-19T18:21:55.216572Z","end":"2026-05-19T18:21:55.778331Z","steps":["trace[1558266955] 'process raft request' (duration: 560.664563ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:21:55.778431Z","caller":"traceutil/trace.go:171","msg":"trace[1378355814] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:590; }","duration":"208.045407ms","start":"2026-05-19T18:21:55.570356Z","end":"2026-05-19T18:21:55.778401Z","steps":["trace[1378355814] 'read index received' (duration: 199.930637ms)","trace[1378355814] 'applied index is now lower than readState.Index' (duration: 8.11405ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:21:55.778479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:21:55.21655Z","time spent":"561.878022ms","remote":"127.0.0.1:56678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:21:55.778559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.195767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:21:55.778597Z","caller":"traceutil/trace.go:171","msg":"trace[1411622045] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"208.299839ms","start":"2026-05-19T18:21:55.570287Z","end":"2026-05-19T18:21:55.778587Z","steps":["trace[1411622045] 'agreement among raft nodes before linearized reading' (duration: 208.210925ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:21:55.77862Z","caller":"traceutil/trace.go:171","msg":"trace[724234268] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"390.976806ms","start":"2026-05-19T18:21:55.387625Z","end":"2026-05-19T18:21:55.778602Z","steps":["trace[724234268] 'process raft request' (duration: 390.375707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:21:55.778726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:21:55.387595Z","time spent":"391.096238ms","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T18:22:02.521725Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12931697384073186144,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T18:22:02.588973Z","caller":"traceutil/trace.go:171","msg":"trace[1788285357] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"580.154534ms","start":"2026-05-19T18:22:02.008793Z","end":"2026-05-19T18:22:02.588948Z","steps":["trace[1788285357] 'process raft request' (duration: 579.972024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:02.589147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:02.008763Z","time spent":"580.296206ms","remote":"127.0.0.1:56604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:22:02.618824Z","caller":"traceutil/trace.go:171","msg":"trace[1501843759] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:648; }","duration":"597.415873ms","start":"2026-05-19T18:22:02.021382Z","end":"2026-05-19T18:22:02.618798Z","steps":["trace[1501843759] 'read index received' (duration: 567.426247ms)","trace[1501843759] 'applied index is now lower than readState.Index' (duration: 29.988846ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:22:02.618896Z","caller":"traceutil/trace.go:171","msg":"trace[1476746225] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"335.212707ms","start":"2026-05-19T18:22:02.283652Z","end":"2026-05-19T18:22:02.618865Z","steps":["trace[1476746225] 'process raft request' (duration: 314.818514ms)","trace[1476746225] 'compare' (duration: 20.205813ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:22:02.619053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.65803ms","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-19T18:22:02.619059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:02.283631Z","time spent":"335.336181ms","remote":"127.0.0.1:56678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:22:02.619096Z","caller":"traceutil/trace.go:171","msg":"trace[160656510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"597.746775ms","start":"2026-05-19T18:22:02.021337Z","end":"2026-05-19T18:22:02.619084Z","steps":["trace[160656510] 'agreement among raft nodes before linearized reading' (duration: 597.596983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:02.619136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:02.021318Z","time spent":"597.811081ms","remote":"127.0.0.1:56678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T18:22:02.619153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.634511ms","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-19T18:22:02.619214Z","caller":"traceutil/trace.go:171","msg":"trace[713844560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"565.728917ms","start":"2026-05-19T18:22:02.053471Z","end":"2026-05-19T18:22:02.619199Z","steps":["trace[713844560] 'agreement among raft nodes before linearized reading' (duration: 565.534587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:02.619252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:02.053458Z","time spent":"565.788263ms","remote":"127.0.0.1:56678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T18:22:02.619222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.64127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-19T18:22:02.619308Z","caller":"traceutil/trace.go:171","msg":"trace[712871849] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"551.778904ms","start":"2026-05-19T18:22:02.067509Z","end":"2026-05-19T18:22:02.619287Z","steps":["trace[712871849] 'agreement among raft nodes before linearized reading' (duration: 551.534597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:02.619324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.329854ms","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-19T18:22:02.619353Z","caller":"traceutil/trace.go:171","msg":"trace[1755674705] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"552.376042ms","start":"2026-05-19T18:22:02.066968Z","end":"2026-05-19T18:22:02.619344Z","steps":["trace[1755674705] 'agreement among raft nodes before linearized reading' (duration: 552.300156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:02.619358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:02.067489Z","time spent":"551.860379ms","remote":"127.0.0.1:56678","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-19T18:22:02.619387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:02.066958Z","time spent":"552.423659ms","remote":"127.0.0.1:56678","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-19T18:22:03.494711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.029987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:22:03.494871Z","caller":"traceutil/trace.go:171","msg":"trace[996787210] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:651; }","duration":"794.056273ms","start":"2026-05-19T18:22:02.700802Z","end":"2026-05-19T18:22:03.494858Z","steps":["trace[996787210] 'read index received' (duration: 455.776099ms)","trace[996787210] 'applied index is now lower than readState.Index' (duration: 338.279294ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:22:03.494875Z","caller":"traceutil/trace.go:171","msg":"trace[1131567202] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"872.110398ms","start":"2026-05-19T18:22:02.622743Z","end":"2026-05-19T18:22:03.494854Z","steps":["trace[1131567202] 'process raft request' (duration: 533.877461ms)","trace[1131567202] 'compare' (duration: 337.772161ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:22:03.494951Z","caller":"traceutil/trace.go:171","msg":"trace[1014880146] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"871.498282ms","start":"2026-05-19T18:22:02.623446Z","end":"2026-05-19T18:22:03.494945Z","steps":["trace[1014880146] 'process raft request' (duration: 871.33319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:03.494991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:02.623434Z","time spent":"871.53577ms","remote":"127.0.0.1:56678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:22:03.494995Z","caller":"traceutil/trace.go:171","msg":"trace[945306742] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"871.037755ms","start":"2026-05-19T18:22:02.623928Z","end":"2026-05-19T18:22:03.494966Z","steps":["trace[945306742] 'process raft request' (duration: 870.899881ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:03.495043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:02.622729Z","time spent":"872.225513ms","remote":"127.0.0.1:56678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:22:03.494929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.13491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.168\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-19T18:22:03.495158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:02.623906Z","time spent":"871.166179ms","remote":"127.0.0.1:56678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:22:03.495184Z","caller":"traceutil/trace.go:171","msg":"trace[326084026] range","detail":"{range_begin:/registry/masterleases/199.19.213.168; range_end:; response_count:1; response_revision:635; }","duration":"794.414955ms","start":"2026-05-19T18:22:02.700759Z","end":"2026-05-19T18:22:03.495174Z","steps":["trace[326084026] 'agreement among raft nodes before linearized reading' (duration: 794.141599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:03.495213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:02.700742Z","time spent":"794.464392ms","remote":"127.0.0.1:56498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.168\" "} {"level":"info","ts":"2026-05-19T18:22:05.356683Z","caller":"traceutil/trace.go:171","msg":"trace[1503361806] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"172.383726ms","start":"2026-05-19T18:22:05.184267Z","end":"2026-05-19T18:22:05.356651Z","steps":["trace[1503361806] 'process raft request' (duration: 172.254482ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:22:16.919721Z","caller":"traceutil/trace.go:171","msg":"trace[770184536] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"203.173638ms","start":"2026-05-19T18:22:16.716518Z","end":"2026-05-19T18:22:16.919692Z","steps":["trace[770184536] 'process raft request' (duration: 202.901226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:19.595429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-m4xxf\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-19T18:22:19.595524Z","caller":"traceutil/trace.go:171","msg":"trace[1147864595] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-m4xxf; range_end:; response_count:1; response_revision:789; }","duration":"502.576156ms","start":"2026-05-19T18:22:19.092925Z","end":"2026-05-19T18:22:19.595501Z","steps":["trace[1147864595] 'range keys from in-memory index tree' (duration: 502.261873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:19.595567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:19.092904Z","time spent":"502.653924ms","remote":"127.0.0.1:56604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-m4xxf\" "} {"level":"info","ts":"2026-05-19T18:22:19.703161Z","caller":"traceutil/trace.go:171","msg":"trace[1429727066] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"101.335049ms","start":"2026-05-19T18:22:19.601788Z","end":"2026-05-19T18:22:19.703123Z","steps":["trace[1429727066] 'process raft request' (duration: 101.201602ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:22:19.70318Z","caller":"traceutil/trace.go:171","msg":"trace[463991895] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"102.464023ms","start":"2026-05-19T18:22:19.600692Z","end":"2026-05-19T18:22:19.703156Z","steps":["trace[463991895] 'process raft request' (duration: 102.154351ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:22:23.322458Z","caller":"traceutil/trace.go:171","msg":"trace[609992574] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"166.258341ms","start":"2026-05-19T18:22:23.156171Z","end":"2026-05-19T18:22:23.322429Z","steps":["trace[609992574] 'process raft request' (duration: 127.76163ms)","trace[609992574] 'compare' (duration: 38.390643ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:22:47.565806Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12931697384073186887,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T18:22:47.77588Z","caller":"traceutil/trace.go:171","msg":"trace[940515198] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"850.970209ms","start":"2026-05-19T18:22:46.924881Z","end":"2026-05-19T18:22:47.775852Z","steps":["trace[940515198] 'process raft request' (duration: 850.787647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:47.776205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:46.924856Z","time spent":"851.264392ms","remote":"127.0.0.1:56678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:22:47.780368Z","caller":"traceutil/trace.go:171","msg":"trace[1689619784] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1035; }","duration":"715.228201ms","start":"2026-05-19T18:22:47.065121Z","end":"2026-05-19T18:22:47.780349Z","steps":["trace[1689619784] 'read index received' (duration: 710.539843ms)","trace[1689619784] 'applied index is now lower than readState.Index' (duration: 4.687278ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:22:47.780423Z","caller":"traceutil/trace.go:171","msg":"trace[1854153562] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"708.126462ms","start":"2026-05-19T18:22:47.072268Z","end":"2026-05-19T18:22:47.780395Z","steps":["trace[1854153562] 'process raft request' (duration: 707.98763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:47.780543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.408064ms","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-19T18:22:47.780581Z","caller":"traceutil/trace.go:171","msg":"trace[172293671] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1006; }","duration":"715.493574ms","start":"2026-05-19T18:22:47.065077Z","end":"2026-05-19T18:22:47.780571Z","steps":["trace[172293671] 'agreement among raft nodes before linearized reading' (duration: 715.364583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:47.780596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:47.07225Z","time spent":"708.253783ms","remote":"127.0.0.1:56678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:22:47.780616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:47.065059Z","time spent":"715.549206ms","remote":"127.0.0.1:56678","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-19T18:22:47.780603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.959954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:22:47.78063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.775178ms","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-19T18:22:47.780665Z","caller":"traceutil/trace.go:171","msg":"trace[590800760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1006; }","duration":"210.068866ms","start":"2026-05-19T18:22:47.570581Z","end":"2026-05-19T18:22:47.78065Z","steps":["trace[590800760] 'agreement among raft nodes before linearized reading' (duration: 209.962945ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:22:47.780726Z","caller":"traceutil/trace.go:171","msg":"trace[360915504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1006; }","duration":"703.865629ms","start":"2026-05-19T18:22:47.076815Z","end":"2026-05-19T18:22:47.78068Z","steps":["trace[360915504] 'agreement among raft nodes before linearized reading' (duration: 703.707788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:47.780778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:47.076804Z","time spent":"703.962351ms","remote":"127.0.0.1:56678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-19T18:22:57.124017Z","caller":"traceutil/trace.go:171","msg":"trace[815967829] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"140.63634ms","start":"2026-05-19T18:22:56.983347Z","end":"2026-05-19T18:22:57.123983Z","steps":["trace[815967829] 'process raft request' (duration: 140.472967ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:24:10.379744Z","caller":"traceutil/trace.go:171","msg":"trace[605779405] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"254.200392ms","start":"2026-05-19T18:24:10.125522Z","end":"2026-05-19T18:24:10.379723Z","steps":["trace[605779405] 'process raft request' (duration: 253.858943ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:24:10.486072Z","caller":"traceutil/trace.go:171","msg":"trace[965275599] linearizableReadLoop","detail":"{readStateIndex:1373; appliedIndex:1371; }","duration":"101.515329ms","start":"2026-05-19T18:24:10.384526Z","end":"2026-05-19T18:24:10.486041Z","steps":["trace[965275599] 'read index received' (duration: 89.515585ms)","trace[965275599] 'applied index is now lower than readState.Index' (duration: 11.998464ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:24:10.486153Z","caller":"traceutil/trace.go:171","msg":"trace[1250826533] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"102.03909ms","start":"2026-05-19T18:24:10.384082Z","end":"2026-05-19T18:24:10.486121Z","steps":["trace[1250826533] 'process raft request' (duration: 101.757024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:24:10.486453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.888632ms","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-19T18:24:10.486502Z","caller":"traceutil/trace.go:171","msg":"trace[1704076675] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1322; }","duration":"101.999227ms","start":"2026-05-19T18:24:10.384488Z","end":"2026-05-19T18:24:10.486487Z","steps":["trace[1704076675] 'agreement among raft nodes before linearized reading' (duration: 101.713291ms)"],"step_count":1}