{"level":"warn","ts":"2026-06-17T15:14:44.543515Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-17T15:14:44.543641Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116: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.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116: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-06-17T15:14:44.543762Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-17T15:14:44.543776Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-06-17T15:14:44.543797Z","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-06-17T15:14:44.544508Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-06-17T15:14:44.5446Z","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.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116: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-06-17T15:14:44.549613Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.729089ms"} {"level":"info","ts":"2026-06-17T15:14:44.557038Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-06-17T15:14:44.557116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-06-17T15:14:44.55717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-06-17T15:14:44.557183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-17T15:14:44.557197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-06-17T15:14:44.557261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-06-17T15:14:44.566265Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-17T15:14:44.568379Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-17T15:14:44.569739Z","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-06-17T15:14:44.572546Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-17T15:14:44.572823Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-17T15:14:44.572955Z","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-06-17T15:14:44.573037Z","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-06-17T15:14:44.573049Z","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-06-17T15:14:44.576057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-06-17T15:14:44.576301Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-06-17T15:14:44.577535Z","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-06-17T15:14:44.577614Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-06-17T15:14:44.577654Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-06-17T15:14:44.577793Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-17T15:14:44.577837Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-17T15:14:44.958469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-06-17T15:14:44.958597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-17T15:14:44.958664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-06-17T15:14:44.958692Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-06-17T15:14:44.958959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-06-17T15:14:44.959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-06-17T15:14:44.959025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-06-17T15:14:44.961005Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-17T15:14:44.96297Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-17T15:14:44.962975Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-06-17T15:14:44.963147Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-17T15:14:44.963436Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-17T15:14:44.963532Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-17T15:14:44.965514Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-06-17T15:14:44.965751Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-17T15:14:44.965813Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-17T15:14:44.967234Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-17T15:14:44.976912Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"warn","ts":"2026-06-17T15:14:47.159192Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853971230851124598,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-17T15:14:47.65936Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853971230851124598,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-17T15:14:47.680638Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.021808343s","expected-duration":"1s"} {"level":"info","ts":"2026-06-17T15:14:47.681091Z","caller":"traceutil/trace.go:171","msg":"trace[1823415693] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"1.022315953s","start":"2026-06-17T15:14:46.658751Z","end":"2026-06-17T15:14:47.681067Z","steps":["trace[1823415693] 'process raft request' (duration: 1.022105846s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:47.681633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:46.658741Z","time spent":"1.022408182s","remote":"127.0.0.1:35002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:14:48.699579Z","caller":"traceutil/trace.go:171","msg":"trace[1158387126] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:4; }","duration":"2.040715705s","start":"2026-06-17T15:14:46.658838Z","end":"2026-06-17T15:14:48.699554Z","steps":["trace[1158387126] 'read index received' (duration: 1.022173542s)","trace[1158387126] 'applied index is now lower than readState.Index' (duration: 1.018541023s)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:14:48.699709Z","caller":"traceutil/trace.go:171","msg":"trace[1251912873] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.962595093s","start":"2026-06-17T15:14:46.737087Z","end":"2026-06-17T15:14:48.699682Z","steps":["trace[1251912873] 'process raft request' (duration: 1.962223842s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:48.699796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:46.737042Z","time spent":"1.962698351s","remote":"127.0.0.1:35454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-17T15:14:48.699898Z","caller":"traceutil/trace.go:171","msg":"trace[107737493] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.962410268s","start":"2026-06-17T15:14:46.737385Z","end":"2026-06-17T15:14:48.699795Z","steps":["trace[107737493] 'process raft request' (duration: 1.961964302s)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:14:48.700005Z","caller":"traceutil/trace.go:171","msg":"trace[966688663] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.96244195s","start":"2026-06-17T15:14:46.737498Z","end":"2026-06-17T15:14:48.69994Z","steps":["trace[966688663] 'process raft request' (duration: 1.961939s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:48.700109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:46.737476Z","time spent":"1.962598513s","remote":"127.0.0.1:35454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-17T15:14:48.700158Z","caller":"traceutil/trace.go:171","msg":"trace[1077585089] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.962724693s","start":"2026-06-17T15:14:46.737423Z","end":"2026-06-17T15:14:48.700148Z","steps":["trace[1077585089] 'process raft request' (duration: 1.961965442s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:48.70023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:46.737409Z","time spent":"1.962781988s","remote":"127.0.0.1:35454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-17T15:14:48.70029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.029720952s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-17T15:14:48.700224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.041350207s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-17T15:14:48.700325Z","caller":"traceutil/trace.go:171","msg":"trace[952051693] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"2.029785508s","start":"2026-06-17T15:14:46.67053Z","end":"2026-06-17T15:14:48.700316Z","steps":["trace[952051693] 'agreement among raft nodes before linearized reading' (duration: 2.029741764s)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:14:48.700334Z","caller":"traceutil/trace.go:171","msg":"trace[286698621] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:10; }","duration":"2.041498649s","start":"2026-06-17T15:14:46.658825Z","end":"2026-06-17T15:14:48.700324Z","steps":["trace[286698621] 'agreement among raft nodes before linearized reading' (duration: 2.041321015s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:48.700358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:46.670521Z","time spent":"2.029830681s","remote":"127.0.0.1:35320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-06-17T15:14:48.700372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:46.658819Z","time spent":"2.041539142s","remote":"127.0.0.1:35018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":0,"response size":26,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-06-17T15:14:48.70046Z","caller":"traceutil/trace.go:171","msg":"trace[1106868858] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.963011236s","start":"2026-06-17T15:14:46.737436Z","end":"2026-06-17T15:14:48.700447Z","steps":["trace[1106868858] 'process raft request' (duration: 1.961978193s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:48.700034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:46.737369Z","time spent":"1.962598653s","remote":"127.0.0.1:35454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-17T15:14:48.700544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.539133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-17T15:14:48.70058Z","caller":"traceutil/trace.go:171","msg":"trace[1579494205] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10; }","duration":"770.594027ms","start":"2026-06-17T15:14:47.929977Z","end":"2026-06-17T15:14:48.700571Z","steps":["trace[1579494205] 'agreement among raft nodes before linearized reading' (duration: 770.546204ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:48.70061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:47.929963Z","time spent":"770.642021ms","remote":"127.0.0.1:34952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-17T15:14:48.700663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:46.737411Z","time spent":"1.963104634s","remote":"127.0.0.1:35454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-17T15:14:48.700738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.065916963s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-17T15:14:48.700788Z","caller":"traceutil/trace.go:171","msg":"trace[1956385128] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:10; }","duration":"1.065996199s","start":"2026-06-17T15:14:47.634783Z","end":"2026-06-17T15:14:48.700779Z","steps":["trace[1956385128] 'agreement among raft nodes before linearized reading' (duration: 1.065935394s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:48.700804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.963512206s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-17T15:14:48.700847Z","caller":"traceutil/trace.go:171","msg":"trace[1131935890] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.963556541s","start":"2026-06-17T15:14:46.737281Z","end":"2026-06-17T15:14:48.700838Z","steps":["trace[1131935890] 'agreement among raft nodes before linearized reading' (duration: 1.963487136s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:48.700881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:46.737265Z","time spent":"1.963609545s","remote":"127.0.0.1:35044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-06-17T15:14:48.701014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066754299s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-17T15:14:48.701046Z","caller":"traceutil/trace.go:171","msg":"trace[342356151] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:10; }","duration":"1.066836476s","start":"2026-06-17T15:14:47.634202Z","end":"2026-06-17T15:14:48.701038Z","steps":["trace[342356151] 'agreement among raft nodes before linearized reading' (duration: 1.066792593s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:48.701076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:47.634176Z","time spent":"1.066894652s","remote":"127.0.0.1:35294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":26,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"info","ts":"2026-06-17T15:14:48.700126Z","caller":"traceutil/trace.go:171","msg":"trace[81771041] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.950577257s","start":"2026-06-17T15:14:46.749541Z","end":"2026-06-17T15:14:48.700118Z","steps":["trace[81771041] 'process raft request' (duration: 1.949970028s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:48.701137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:46.749523Z","time spent":"1.951586208s","remote":"127.0.0.1:35106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-17T15:14:48.701207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.895864707s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-17T15:14:48.701242Z","caller":"traceutil/trace.go:171","msg":"trace[645608845] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.895929552s","start":"2026-06-17T15:14:46.805304Z","end":"2026-06-17T15:14:48.701234Z","steps":["trace[645608845] 'agreement among raft nodes before linearized reading' (duration: 1.895884458s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:48.701271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:46.805287Z","time spent":"1.895979256s","remote":"127.0.0.1:35044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-06-17T15:14:48.701382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.913194091s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-17T15:14:48.70142Z","caller":"traceutil/trace.go:171","msg":"trace[1430178742] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.913255116s","start":"2026-06-17T15:14:46.788149Z","end":"2026-06-17T15:14:48.701405Z","steps":["trace[1430178742] 'agreement among raft nodes before linearized reading' (duration: 1.913212613s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:48.70147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:46.788138Z","time spent":"1.913308621s","remote":"127.0.0.1:35044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-06-17T15:14:48.70159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.963434361s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-17T15:14:48.701625Z","caller":"traceutil/trace.go:171","msg":"trace[380788506] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"1.963512367s","start":"2026-06-17T15:14:46.738103Z","end":"2026-06-17T15:14:48.701616Z","steps":["trace[380788506] 'agreement among raft nodes before linearized reading' (duration: 1.963466543s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:48.701656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:46.738089Z","time spent":"1.96356026s","remote":"127.0.0.1:35034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-06-17T15:14:48.700819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:47.634772Z","time spent":"1.066040513s","remote":"127.0.0.1:35278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"info","ts":"2026-06-17T15:14:48.700017Z","caller":"traceutil/trace.go:171","msg":"trace[844819468] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.962082962s","start":"2026-06-17T15:14:46.737921Z","end":"2026-06-17T15:14:48.700004Z","steps":["trace[844819468] 'process raft request' (duration: 1.961541288s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:48.700853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.967216585s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-17T15:14:48.702069Z","caller":"traceutil/trace.go:171","msg":"trace[1100440508] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:0; response_revision:10; }","duration":"1.968431703s","start":"2026-06-17T15:14:46.733585Z","end":"2026-06-17T15:14:48.702017Z","steps":["trace[1100440508] 'agreement among raft nodes before linearized reading' (duration: 1.967251928s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:48.702185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:46.733552Z","time spent":"1.968615317s","remote":"127.0.0.1:34986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":26,"request content":"key:\"/registry/masterleases/199.204.45.116\" "} {"level":"info","ts":"2026-06-17T15:14:48.702819Z","caller":"traceutil/trace.go:171","msg":"trace[80252673] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"2.041855649s","start":"2026-06-17T15:14:46.660889Z","end":"2026-06-17T15:14:48.702745Z","steps":["trace[80252673] 'process raft request' (duration: 2.013924433s)","trace[80252673] 'compare' (duration: 24.378739ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:14:48.703065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:46.660879Z","time spent":"2.042054255s","remote":"127.0.0.1:35180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-17T15:14:48.702742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:46.737908Z","time spent":"1.963953853s","remote":"127.0.0.1:35372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-17T15:14:49.206674Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853971230851124610,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-17T15:14:49.365253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.009916ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-06-17T15:14:49.365343Z","caller":"traceutil/trace.go:171","msg":"trace[339723730] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"659.675172ms","start":"2026-06-17T15:14:48.705656Z","end":"2026-06-17T15:14:49.365331Z","steps":["trace[339723730] 'process raft request' (duration: 528.240051ms)","trace[339723730] 'compare' (duration: 130.910829ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:14:49.365381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:48.705631Z","time spent":"659.736887ms","remote":"127.0.0.1:35018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:14:49.366969Z","caller":"traceutil/trace.go:171","msg":"trace[1270195521] linearizableReadLoop","detail":"{readStateIndex:25; appliedIndex:13; }","duration":"660.964986ms","start":"2026-06-17T15:14:48.705995Z","end":"2026-06-17T15:14:49.36696Z","steps":["trace[1270195521] 'read index received' (duration: 527.901383ms)","trace[1270195521] 'applied index is now lower than readState.Index' (duration: 133.062563ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:14:49.367023Z","caller":"traceutil/trace.go:171","msg":"trace[531864229] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"660.122458ms","start":"2026-06-17T15:14:48.706893Z","end":"2026-06-17T15:14:49.367015Z","steps":["trace[531864229] 'process raft request' (duration: 659.807092ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:49.36705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:48.706875Z","time spent":"660.158361ms","remote":"127.0.0.1:35454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-17T15:14:49.367111Z","caller":"traceutil/trace.go:171","msg":"trace[1085787574] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"660.068704ms","start":"2026-06-17T15:14:48.707037Z","end":"2026-06-17T15:14:49.367105Z","steps":["trace[1085787574] 'process raft request' (duration: 659.714776ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:49.367136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:48.706983Z","time spent":"660.139229ms","remote":"127.0.0.1:35454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-17T15:14:49.367222Z","caller":"traceutil/trace.go:171","msg":"trace[34058466] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"659.591264ms","start":"2026-06-17T15:14:48.707602Z","end":"2026-06-17T15:14:49.367193Z","steps":["trace[34058466] 'process raft request' (duration: 659.16532ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:49.367241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:48.707581Z","time spent":"659.64984ms","remote":"127.0.0.1:35454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-17T15:14:49.367392Z","caller":"traceutil/trace.go:171","msg":"trace[1631820756] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"658.04104ms","start":"2026-06-17T15:14:48.709322Z","end":"2026-06-17T15:14:49.367363Z","steps":["trace[1631820756] 'process raft request' (duration: 657.556371ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:14:49.367472Z","caller":"traceutil/trace.go:171","msg":"trace[1380477491] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"658.057211ms","start":"2026-06-17T15:14:48.709406Z","end":"2026-06-17T15:14:49.367464Z","steps":["trace[1380477491] 'process raft request' (duration: 657.499116ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:49.367507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:48.709399Z","time spent":"658.094104ms","remote":"127.0.0.1:35320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-17T15:14:49.367537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:48.709309Z","time spent":"658.152419ms","remote":"127.0.0.1:35372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-17T15:14:49.367669Z","caller":"traceutil/trace.go:171","msg":"trace[502346886] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"652.964929ms","start":"2026-06-17T15:14:48.714698Z","end":"2026-06-17T15:14:49.367663Z","steps":["trace[502346886] 'process raft request' (duration: 652.22886ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:49.367705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:48.714687Z","time spent":"652.999833ms","remote":"127.0.0.1:35106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:14:49.36778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.787253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-17T15:14:49.367796Z","caller":"traceutil/trace.go:171","msg":"trace[948974308] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:21; }","duration":"661.88127ms","start":"2026-06-17T15:14:48.705908Z","end":"2026-06-17T15:14:49.36779Z","steps":["trace[948974308] 'agreement among raft nodes before linearized reading' (duration: 661.853357ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:14:49.367791Z","caller":"traceutil/trace.go:171","msg":"trace[1195308907] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"659.588906ms","start":"2026-06-17T15:14:48.70819Z","end":"2026-06-17T15:14:49.367779Z","steps":["trace[1195308907] 'process raft request' (duration: 658.589355ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:49.367819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:48.705886Z","time spent":"661.929664ms","remote":"127.0.0.1:35044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-06-17T15:14:49.367844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:48.708172Z","time spent":"659.64939ms","remote":"127.0.0.1:35044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-17T15:14:49.36792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.545213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-17T15:14:49.367937Z","caller":"traceutil/trace.go:171","msg":"trace[1845867344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21; }","duration":"661.579566ms","start":"2026-06-17T15:14:48.706353Z","end":"2026-06-17T15:14:49.367933Z","steps":["trace[1845867344] 'agreement among raft nodes before linearized reading' (duration: 661.554444ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:49.367949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:48.706344Z","time spent":"661.602488ms","remote":"127.0.0.1:34956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-17T15:14:49.368139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.76355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-17T15:14:49.368175Z","caller":"traceutil/trace.go:171","msg":"trace[772022151] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:21; }","duration":"456.835476ms","start":"2026-06-17T15:14:48.91133Z","end":"2026-06-17T15:14:49.368165Z","steps":["trace[772022151] 'agreement among raft nodes before linearized reading' (duration: 456.787932ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:49.3682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:48.911305Z","time spent":"456.888021ms","remote":"127.0.0.1:35196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-06-17T15:14:49.368306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.983262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-06-17T15:14:49.368325Z","caller":"traceutil/trace.go:171","msg":"trace[414549099] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:21; }","duration":"602.007004ms","start":"2026-06-17T15:14:48.766313Z","end":"2026-06-17T15:14:49.36832Z","steps":["trace[414549099] 'agreement among raft nodes before linearized reading' (duration: 601.971851ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:49.368338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:48.766281Z","time spent":"602.053237ms","remote":"127.0.0.1:35044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":372,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-06-17T15:14:49.368395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.338424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-mhhl5\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-06-17T15:14:49.368413Z","caller":"traceutil/trace.go:171","msg":"trace[141385718] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-mhhl5; range_end:; response_count:1; response_revision:21; }","duration":"659.365217ms","start":"2026-06-17T15:14:48.709043Z","end":"2026-06-17T15:14:49.368408Z","steps":["trace[141385718] 'agreement among raft nodes before linearized reading' (duration: 659.335564ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:49.368429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:48.709037Z","time spent":"659.387549ms","remote":"127.0.0.1:35180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":883,"request content":"key:\"/registry/certificatesigningrequests/csr-mhhl5\" "} {"level":"info","ts":"2026-06-17T15:14:49.368384Z","caller":"traceutil/trace.go:171","msg":"trace[741122187] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"660.062123ms","start":"2026-06-17T15:14:48.708263Z","end":"2026-06-17T15:14:49.368325Z","steps":["trace[741122187] 'process raft request' (duration: 658.548221ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:49.368509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.405702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-17T15:14:49.368529Z","caller":"traceutil/trace.go:171","msg":"trace[1419451422] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:21; }","duration":"660.463516ms","start":"2026-06-17T15:14:48.708061Z","end":"2026-06-17T15:14:49.368524Z","steps":["trace[1419451422] 'agreement among raft nodes before linearized reading' (duration: 660.432354ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:49.368543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:48.708042Z","time spent":"660.497739ms","remote":"127.0.0.1:35282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":26,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-06-17T15:14:49.368594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:48.708238Z","time spent":"660.258659ms","remote":"127.0.0.1:35294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-17T15:14:49.368844Z","caller":"traceutil/trace.go:171","msg":"trace[2051401191] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"660.280201ms","start":"2026-06-17T15:14:48.70855Z","end":"2026-06-17T15:14:49.36883Z","steps":["trace[2051401191] 'process raft request' (duration: 658.278708ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:49.36891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:48.708542Z","time spent":"660.337545ms","remote":"127.0.0.1:35454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-17T15:14:49.368936Z","caller":"traceutil/trace.go:171","msg":"trace[1904478440] transaction","detail":"{read_only:false; number_of_response:0; response_revision:18; }","duration":"659.669211ms","start":"2026-06-17T15:14:48.709258Z","end":"2026-06-17T15:14:49.368927Z","steps":["trace[1904478440] 'process raft request' (duration: 657.602083ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:49.368974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:48.709246Z","time spent":"659.712556ms","remote":"127.0.0.1:35044","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":26,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-17T15:14:49.369054Z","caller":"traceutil/trace.go:171","msg":"trace[2007441006] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"659.7665ms","start":"2026-06-17T15:14:48.709281Z","end":"2026-06-17T15:14:49.369047Z","steps":["trace[2007441006] 'process raft request' (duration: 657.562381ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:14:49.369084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:14:48.709272Z","time spent":"659.799742ms","remote":"127.0.0.1:35454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-17T15:14:49.778106Z","caller":"traceutil/trace.go:171","msg":"trace[1083955261] transaction","detail":"{read_only:false; response_revision:122; number_of_response:1; }","duration":"144.142458ms","start":"2026-06-17T15:14:49.633917Z","end":"2026-06-17T15:14:49.778059Z","steps":["trace[1083955261] 'process raft request' (duration: 120.845942ms)","trace[1083955261] 'compare' (duration: 23.067078ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:14:49.778076Z","caller":"traceutil/trace.go:171","msg":"trace[2000881254] linearizableReadLoop","detail":"{readStateIndex:127; appliedIndex:126; }","duration":"135.048354ms","start":"2026-06-17T15:14:49.642988Z","end":"2026-06-17T15:14:49.778036Z","steps":["trace[2000881254] 'read index received' (duration: 111.778129ms)","trace[2000881254] 'applied index is now lower than readState.Index' (duration: 23.262483ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:14:49.778402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.372159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-17T15:14:49.778517Z","caller":"traceutil/trace.go:171","msg":"trace[2132340300] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver; range_end:; response_count:0; response_revision:122; }","duration":"135.51838ms","start":"2026-06-17T15:14:49.642965Z","end":"2026-06-17T15:14:49.778483Z","steps":["trace[2132340300] 'agreement among raft nodes before linearized reading' (duration: 135.258879ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:14:56.733759Z","caller":"traceutil/trace.go:171","msg":"trace[2114511262] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"106.729132ms","start":"2026-06-17T15:14:56.626991Z","end":"2026-06-17T15:14:56.73372Z","steps":["trace[2114511262] 'process raft request' (duration: 106.513485ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:15:08.22734Z","caller":"traceutil/trace.go:171","msg":"trace[594289128] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:433; }","duration":"125.697776ms","start":"2026-06-17T15:15:08.101612Z","end":"2026-06-17T15:15:08.22731Z","steps":["trace[594289128] 'read index received' (duration: 125.393601ms)","trace[594289128] 'applied index is now lower than readState.Index' (duration: 302.495µs)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:15:08.227378Z","caller":"traceutil/trace.go:171","msg":"trace[1253578929] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"174.507794ms","start":"2026-06-17T15:15:08.05283Z","end":"2026-06-17T15:15:08.227338Z","steps":["trace[1253578929] 'process raft request' (duration: 174.301466ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:15:08.227592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.157843ms","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-06-17T15:15:08.227705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.051655ms","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-06-17T15:15:08.227847Z","caller":"traceutil/trace.go:171","msg":"trace[894730034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:422; }","duration":"126.244011ms","start":"2026-06-17T15:15:08.101572Z","end":"2026-06-17T15:15:08.227816Z","steps":["trace[894730034] 'agreement among raft nodes before linearized reading' (duration: 125.894342ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:15:08.227749Z","caller":"traceutil/trace.go:171","msg":"trace[1333764020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:422; }","duration":"120.380242ms","start":"2026-06-17T15:15:08.107351Z","end":"2026-06-17T15:15:08.227731Z","steps":["trace[1333764020] 'agreement among raft nodes before linearized reading' (duration: 120.126071ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:15:08.349206Z","caller":"traceutil/trace.go:171","msg":"trace[1297872266] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"115.56016ms","start":"2026-06-17T15:15:08.233616Z","end":"2026-06-17T15:15:08.349176Z","steps":["trace[1297872266] 'process raft request' (duration: 82.535208ms)","trace[1297872266] 'compare' (duration: 32.851868ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:15:10.536391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.319972ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T15:15:10.536507Z","caller":"traceutil/trace.go:171","msg":"trace[643223179] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:439; }","duration":"183.783794ms","start":"2026-06-17T15:15:10.352704Z","end":"2026-06-17T15:15:10.536488Z","steps":["trace[643223179] 'read index received' (duration: 64.244984ms)","trace[643223179] 'applied index is now lower than readState.Index' (duration: 119.53748ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:15:10.536538Z","caller":"traceutil/trace.go:171","msg":"trace[181207380] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"235.53808ms","start":"2026-06-17T15:15:10.300973Z","end":"2026-06-17T15:15:10.536511Z","steps":["trace[181207380] 'process raft request' (duration: 116.006221ms)","trace[181207380] 'compare' (duration: 119.186479ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:15:10.536737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.029339ms","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-06-17T15:15:10.536802Z","caller":"traceutil/trace.go:171","msg":"trace[432222894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:428; }","duration":"184.143763ms","start":"2026-06-17T15:15:10.352637Z","end":"2026-06-17T15:15:10.536781Z","steps":["trace[432222894] 'agreement among raft nodes before linearized reading' (duration: 183.931805ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:15:27.819488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.098702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:15:27.819802Z","caller":"traceutil/trace.go:171","msg":"trace[1777335115] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:498; }","duration":"565.487071ms","start":"2026-06-17T15:15:27.254286Z","end":"2026-06-17T15:15:27.819773Z","steps":["trace[1777335115] 'range keys from in-memory index tree' (duration: 564.993366ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:15:27.820068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:15:27.254258Z","time spent":"565.786974ms","remote":"127.0.0.1:34952","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-06-17T15:15:27.819272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.800167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12665"} {"level":"info","ts":"2026-06-17T15:15:27.820378Z","caller":"traceutil/trace.go:171","msg":"trace[350995007] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:498; }","duration":"392.966934ms","start":"2026-06-17T15:15:27.427377Z","end":"2026-06-17T15:15:27.820344Z","steps":["trace[350995007] 'range keys from in-memory index tree' (duration: 391.65479ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:15:27.82045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:15:27.427346Z","time spent":"393.084512ms","remote":"127.0.0.1:35038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":12687,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-06-17T15:15:30.964523Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853971230851125653,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-17T15:15:31.11694Z","caller":"traceutil/trace.go:171","msg":"trace[1000890493] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:554; }","duration":"652.506729ms","start":"2026-06-17T15:15:30.464407Z","end":"2026-06-17T15:15:31.116914Z","steps":["trace[1000890493] 'read index received' (duration: 634.399693ms)","trace[1000890493] 'applied index is now lower than readState.Index' (duration: 18.105926ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:15:31.117049Z","caller":"traceutil/trace.go:171","msg":"trace[1132531705] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"677.158531ms","start":"2026-06-17T15:15:30.439837Z","end":"2026-06-17T15:15:31.116995Z","steps":["trace[1132531705] 'process raft request' (duration: 658.916628ms)","trace[1132531705] 'compare' (duration: 18.037297ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:15:31.117132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.687916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:15:31.117189Z","caller":"traceutil/trace.go:171","msg":"trace[416531677] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:538; }","duration":"652.805262ms","start":"2026-06-17T15:15:30.464369Z","end":"2026-06-17T15:15:31.117174Z","steps":["trace[416531677] 'agreement among raft nodes before linearized reading' (duration: 652.695224ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:15:31.117228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:15:30.46435Z","time spent":"652.868121ms","remote":"127.0.0.1:35038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-06-17T15:15:31.11728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:15:30.439828Z","time spent":"677.326107ms","remote":"127.0.0.1:35196","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-06-17T15:15:31.117849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.313036ms","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-06-17T15:15:31.117925Z","caller":"traceutil/trace.go:171","msg":"trace[1405751861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:538; }","duration":"413.436293ms","start":"2026-06-17T15:15:30.704475Z","end":"2026-06-17T15:15:31.117912Z","steps":["trace[1405751861] 'agreement among raft nodes before linearized reading' (duration: 413.321516ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:15:31.117927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.510862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-06-17T15:15:31.117959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:15:30.704453Z","time spent":"413.498733ms","remote":"127.0.0.1:35196","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-06-17T15:15:31.11797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.37708ms","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-06-17T15:15:31.117988Z","caller":"traceutil/trace.go:171","msg":"trace[792645091] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:538; }","duration":"538.659129ms","start":"2026-06-17T15:15:30.579315Z","end":"2026-06-17T15:15:31.117974Z","steps":["trace[792645091] 'agreement among raft nodes before linearized reading' (duration: 538.470022ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:15:31.118011Z","caller":"traceutil/trace.go:171","msg":"trace[1835554346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:538; }","duration":"479.460718ms","start":"2026-06-17T15:15:30.63854Z","end":"2026-06-17T15:15:31.118Z","steps":["trace[1835554346] 'agreement among raft nodes before linearized reading' (duration: 479.37985ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:15:31.118022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:15:30.57924Z","time spent":"538.777227ms","remote":"127.0.0.1:34986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.116\" "} {"level":"warn","ts":"2026-06-17T15:15:31.118043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:15:30.63852Z","time spent":"479.516717ms","remote":"127.0.0.1:35196","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-06-17T15:15:38.556748Z","caller":"traceutil/trace.go:171","msg":"trace[842057235] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"868.991032ms","start":"2026-06-17T15:15:37.687718Z","end":"2026-06-17T15:15:38.556709Z","steps":["trace[842057235] 'process raft request' (duration: 868.629517ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:15:38.556977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:15:37.687681Z","time spent":"869.192711ms","remote":"127.0.0.1:35122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:15:38.613448Z","caller":"traceutil/trace.go:171","msg":"trace[1939902816] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:668; }","duration":"357.293051ms","start":"2026-06-17T15:15:38.256121Z","end":"2026-06-17T15:15:38.613414Z","steps":["trace[1939902816] 'read index received' (duration: 300.363203ms)","trace[1939902816] 'applied index is now lower than readState.Index' (duration: 56.928378ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:15:38.613633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.487848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:15:38.613673Z","caller":"traceutil/trace.go:171","msg":"trace[498291823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"357.605078ms","start":"2026-06-17T15:15:38.256052Z","end":"2026-06-17T15:15:38.613657Z","steps":["trace[498291823] 'agreement among raft nodes before linearized reading' (duration: 357.498299ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:15:38.613715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:15:38.256014Z","time spent":"357.691957ms","remote":"127.0.0.1:34956","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-06-17T15:15:38.613891Z","caller":"traceutil/trace.go:171","msg":"trace[886252848] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"788.972233ms","start":"2026-06-17T15:15:37.824614Z","end":"2026-06-17T15:15:38.613586Z","steps":["trace[886252848] 'process raft request' (duration: 788.030774ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:15:38.614902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:15:37.824589Z","time spent":"789.517116ms","remote":"127.0.0.1:35038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:15:39.674222Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853971230851125840,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-17T15:15:40.174978Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853971230851125840,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-17T15:15:40.481881Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.808742491s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-17T15:15:41.254829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000134497s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-17T15:15:41.255099Z","caller":"traceutil/trace.go:171","msg":"trace[2041060526] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000443905s","start":"2026-06-17T15:15:39.254627Z","end":"2026-06-17T15:15:41.255071Z","steps":["trace[2041060526] 'agreement among raft nodes before linearized reading' (duration: 2.000153657s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:15:41.25521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:15:39.254613Z","time spent":"2.000580334s","remote":"127.0.0.1:34952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/17 15:15:41 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-17T15:15:41.589116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.106948744s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-17T15:15:41.589232Z","caller":"traceutil/trace.go:171","msg":"trace[104244480] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:670; }","duration":"2.415466131s","start":"2026-06-17T15:15:39.173749Z","end":"2026-06-17T15:15:41.589215Z","steps":["trace[104244480] 'read index received' (duration: 1.308379798s)","trace[104244480] 'applied index is now lower than readState.Index' (duration: 1.107084603s)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:15:41.589361Z","caller":"traceutil/trace.go:171","msg":"trace[1914082987] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"2.916356278s","start":"2026-06-17T15:15:38.672996Z","end":"2026-06-17T15:15:41.589352Z","steps":["trace[1914082987] 'process raft request' (duration: 1.809084227s)","trace[1914082987] 'compare' (duration: 1.106422368s)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:15:41.589398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:15:38.672962Z","time spent":"2.916417727s","remote":"127.0.0.1:35282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-17T15:15:41.589655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.415911997s","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-06-17T15:15:41.589677Z","caller":"traceutil/trace.go:171","msg":"trace[2009074158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:648; }","duration":"2.415979426s","start":"2026-06-17T15:15:39.173691Z","end":"2026-06-17T15:15:41.589671Z","steps":["trace[2009074158] 'agreement among raft nodes before linearized reading' (duration: 2.415840298s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:15:41.589698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:15:39.17367Z","time spent":"2.416023967s","remote":"127.0.0.1:35196","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-06-17T15:15:41.592496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.013010023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-17T15:15:41.592484Z","caller":"traceutil/trace.go:171","msg":"trace[1118074643] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"1.039404483s","start":"2026-06-17T15:15:40.553013Z","end":"2026-06-17T15:15:41.592418Z","steps":["trace[1118074643] 'process raft request' (duration: 1.038777478s)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:15:41.592576Z","caller":"traceutil/trace.go:171","msg":"trace[2120626235] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:649; }","duration":"1.013134391s","start":"2026-06-17T15:15:40.579424Z","end":"2026-06-17T15:15:41.592558Z","steps":["trace[2120626235] 'agreement among raft nodes before linearized reading' (duration: 1.012938504s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:15:41.59263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:15:40.579411Z","time spent":"1.013210021s","remote":"127.0.0.1:34986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.116\" "} {"level":"warn","ts":"2026-06-17T15:15:41.592651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.40854449s","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-06-17T15:15:41.592703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:15:40.552984Z","time spent":"1.039598251s","remote":"127.0.0.1:35196","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-06-17T15:15:41.592736Z","caller":"traceutil/trace.go:171","msg":"trace[525128716] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:649; }","duration":"2.408687481s","start":"2026-06-17T15:15:39.184025Z","end":"2026-06-17T15:15:41.592712Z","steps":["trace[525128716] 'agreement among raft nodes before linearized reading' (duration: 2.408312953s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:15:41.5928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:15:39.183994Z","time spent":"2.408793549s","remote":"127.0.0.1:35196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-17T15:15:41.593006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.377692361s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-17T15:15:41.592958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.372989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:15:41.593038Z","caller":"traceutil/trace.go:171","msg":"trace[2123855460] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:649; }","duration":"2.37774953s","start":"2026-06-17T15:15:39.215282Z","end":"2026-06-17T15:15:41.593032Z","steps":["trace[2123855460] 'agreement among raft nodes before linearized reading' (duration: 2.377635882s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:15:41.593056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:15:39.215266Z","time spent":"2.377787011s","remote":"127.0.0.1:35196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-17T15:15:41.593106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.401095858s","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-06-17T15:15:41.593151Z","caller":"traceutil/trace.go:171","msg":"trace[725412958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:649; }","duration":"2.401161188s","start":"2026-06-17T15:15:39.191978Z","end":"2026-06-17T15:15:41.593139Z","steps":["trace[725412958] 'agreement among raft nodes before linearized reading' (duration: 2.40098372s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:15:41.59318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:15:39.191955Z","time spent":"2.401219689s","remote":"127.0.0.1:35196","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-06-17T15:15:41.593177Z","caller":"traceutil/trace.go:171","msg":"trace[1874193799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"337.696276ms","start":"2026-06-17T15:15:41.255421Z","end":"2026-06-17T15:15:41.593118Z","steps":["trace[1874193799] 'agreement among raft nodes before linearized reading' (duration: 337.17212ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:15:41.593279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:15:41.255394Z","time spent":"337.873314ms","remote":"127.0.0.1:34956","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-06-17T15:15:53.689106Z","caller":"traceutil/trace.go:171","msg":"trace[1997595825] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"182.425602ms","start":"2026-06-17T15:15:53.506626Z","end":"2026-06-17T15:15:53.689052Z","steps":["trace[1997595825] 'process raft request' (duration: 182.269531ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:16:09.383186Z","caller":"traceutil/trace.go:171","msg":"trace[1214994750] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"116.222337ms","start":"2026-06-17T15:16:09.266928Z","end":"2026-06-17T15:16:09.38315Z","steps":["trace[1214994750] 'process raft request' (duration: 98.781868ms)","trace[1214994750] 'compare' (duration: 17.299206ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:16:09.387253Z","caller":"traceutil/trace.go:171","msg":"trace[1533013197] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"119.452731ms","start":"2026-06-17T15:16:09.267768Z","end":"2026-06-17T15:16:09.387221Z","steps":["trace[1533013197] 'process raft request' (duration: 119.287758ms)"],"step_count":1}