{"level":"warn","ts":"2026-02-01T18:55:14.677595Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-01T18:55:14.677841Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.227: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://162.253.55.227:2380","--initial-cluster=instance=https://162.253.55.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.227: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-02-01T18:55:14.677995Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-01T18:55:14.678061Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-02-01T18:55:14.67814Z","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-02-01T18:55:14.678972Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"]} {"level":"info","ts":"2026-02-01T18:55:14.679262Z","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":2,"max-cpu-available":2,"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://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.227: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-02-01T18:55:14.682836Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.293866ms"} {"level":"info","ts":"2026-02-01T18:55:14.687389Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9bd37ed2859641e3","cluster-id":"47d0f4f0a1c3b0a9"} {"level":"info","ts":"2026-02-01T18:55:14.687488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=()"} {"level":"info","ts":"2026-02-01T18:55:14.687567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 0"} {"level":"info","ts":"2026-02-01T18:55:14.687619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9bd37ed2859641e3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-01T18:55:14.68767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 1"} {"level":"info","ts":"2026-02-01T18:55:14.687744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"warn","ts":"2026-02-01T18:55:14.695098Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-01T18:55:14.69731Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-01T18:55:14.700898Z","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-02-01T18:55:14.710995Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9bd37ed2859641e3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-01T18:55:14.718902Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9bd37ed2859641e3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-01T18:55:14.719143Z","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-02-01T18:55:14.719164Z","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-02-01T18:55:14.719175Z","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-02-01T18:55:14.722482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"info","ts":"2026-02-01T18:55:14.722618Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","added-peer-id":"9bd37ed2859641e3","added-peer-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-02-01T18:55:14.725769Z","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-02-01T18:55:14.726225Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9bd37ed2859641e3","initial-advertise-peer-urls":["https://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-01T18:55:14.726276Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-01T18:55:14.726412Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-02-01T18:55:14.726519Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-02-01T18:55:14.98815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 is starting a new election at term 1"} {"level":"info","ts":"2026-02-01T18:55:14.988267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-01T18:55:14.988328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgPreVoteResp from 9bd37ed2859641e3 at term 1"} {"level":"info","ts":"2026-02-01T18:55:14.988387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became candidate at term 2"} {"level":"info","ts":"2026-02-01T18:55:14.988432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgVoteResp from 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-02-01T18:55:14.98847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became leader at term 2"} {"level":"info","ts":"2026-02-01T18:55:14.988499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9bd37ed2859641e3 elected leader 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-02-01T18:55:14.98935Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:55:14.990437Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9bd37ed2859641e3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.227:2379]}","request-path":"/0/members/9bd37ed2859641e3/attributes","cluster-id":"47d0f4f0a1c3b0a9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-01T18:55:14.993128Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:55:14.993278Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:55:14.993332Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:55:14.993186Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-01T18:55:14.9932Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-01T18:55:14.995346Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.227:2379"} {"level":"info","ts":"2026-02-01T18:55:14.99842Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-01T18:55:14.998792Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-01T18:55:15.001596Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-01T18:55:17.767425Z","caller":"traceutil/trace.go:171","msg":"trace[1132746215] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"719.894303ms","start":"2026-02-01T18:55:17.047507Z","end":"2026-02-01T18:55:17.767401Z","steps":["trace[1132746215] 'read index received' (duration: 278.442µs)","trace[1132746215] 'applied index is now lower than readState.Index' (duration: 719.614821ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:17.767535Z","caller":"traceutil/trace.go:171","msg":"trace[2016138957] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"722.447822ms","start":"2026-02-01T18:55:17.045078Z","end":"2026-02-01T18:55:17.767526Z","steps":["trace[2016138957] 'process raft request' (duration: 722.0972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:17.768069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:17.045065Z","time spent":"722.497432ms","remote":"127.0.0.1:37098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:17.768579Z","caller":"traceutil/trace.go:171","msg":"trace[1982084630] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"721.029371ms","start":"2026-02-01T18:55:17.047536Z","end":"2026-02-01T18:55:17.768566Z","steps":["trace[1982084630] 'process raft request' (duration: 719.820803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:17.768636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:17.047532Z","time spent":"721.077822ms","remote":"127.0.0.1:37450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-01T18:55:17.768801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.285423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.227\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-01T18:55:17.768843Z","caller":"traceutil/trace.go:171","msg":"trace[934701254] range","detail":"{range_begin:/registry/masterleases/162.253.55.227; range_end:; response_count:0; response_revision:3; }","duration":"721.334913ms","start":"2026-02-01T18:55:17.047496Z","end":"2026-02-01T18:55:17.768831Z","steps":["trace[934701254] 'agreement among raft nodes before linearized reading' (duration: 721.243583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:17.768872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:17.047491Z","time spent":"721.374764ms","remote":"127.0.0.1:37058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/162.253.55.227\" "} {"level":"warn","ts":"2026-02-01T18:55:18.26776Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810070063284098,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:55:18.768143Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810070063284098,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:55:18.835585Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.068643863s","expected-duration":"1s"} {"level":"info","ts":"2026-02-01T18:55:18.836356Z","caller":"traceutil/trace.go:171","msg":"trace[111887606] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.784398967s","start":"2026-02-01T18:55:17.051923Z","end":"2026-02-01T18:55:18.836322Z","steps":["trace[111887606] 'process raft request' (duration: 1.784070464s)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:18.836398Z","caller":"traceutil/trace.go:171","msg":"trace[744460871] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.749072519s","start":"2026-02-01T18:55:17.087248Z","end":"2026-02-01T18:55:18.836321Z","steps":["trace[744460871] 'process raft request' (duration: 1.749035218s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:18.836489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:17.051919Z","time spent":"1.784514707s","remote":"127.0.0.1:37546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-01T18:55:18.836616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:17.087236Z","time spent":"1.74923543s","remote":"127.0.0.1:37286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-01T18:55:18.83677Z","caller":"traceutil/trace.go:171","msg":"trace[1312739636] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.784942001s","start":"2026-02-01T18:55:17.05182Z","end":"2026-02-01T18:55:18.836762Z","steps":["trace[1312739636] 'process raft request' (duration: 1.783999634s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:18.836805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:17.051814Z","time spent":"1.784975671s","remote":"127.0.0.1:37546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-01T18:55:18.836901Z","caller":"traceutil/trace.go:171","msg":"trace[1687961190] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.78500423s","start":"2026-02-01T18:55:17.051891Z","end":"2026-02-01T18:55:18.836895Z","steps":["trace[1687961190] 'process raft request' (duration: 1.784070704s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:18.836932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:17.051885Z","time spent":"1.785030921s","remote":"127.0.0.1:37546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-01T18:55:18.836947Z","caller":"traceutil/trace.go:171","msg":"trace[913542273] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.784974931s","start":"2026-02-01T18:55:17.05196Z","end":"2026-02-01T18:55:18.836935Z","steps":["trace[913542273] 'process raft request' (duration: 1.784170735s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:18.837071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:17.051954Z","time spent":"1.785024501s","remote":"127.0.0.1:37546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-01T18:55:18.837176Z","caller":"traceutil/trace.go:171","msg":"trace[1156687693] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:12; }","duration":"1.06966871s","start":"2026-02-01T18:55:17.7675Z","end":"2026-02-01T18:55:18.837169Z","steps":["trace[1156687693] 'read index received' (duration: 1.068789833s)","trace[1156687693] 'applied index is now lower than readState.Index' (duration: 878.397µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:18.837341Z","caller":"traceutil/trace.go:171","msg":"trace[279151734] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"1.063056893s","start":"2026-02-01T18:55:17.774276Z","end":"2026-02-01T18:55:18.837333Z","steps":["trace[279151734] 'process raft request' (duration: 1.062823711s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:18.837372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:17.774265Z","time spent":"1.063090283s","remote":"127.0.0.1:37450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-01T18:55:18.837355Z","caller":"traceutil/trace.go:171","msg":"trace[570468360] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.785356774s","start":"2026-02-01T18:55:17.051987Z","end":"2026-02-01T18:55:18.837343Z","steps":["trace[570468360] 'process raft request' (duration: 1.784216355s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:18.837492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.739336529s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-01T18:55:18.837531Z","caller":"traceutil/trace.go:171","msg":"trace[815027640] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.739389819s","start":"2026-02-01T18:55:17.098135Z","end":"2026-02-01T18:55:18.837525Z","steps":["trace[815027640] 'agreement among raft nodes before linearized reading' (duration: 1.739300988s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:18.837552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:17.098128Z","time spent":"1.739418499s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-01T18:55:18.83764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.744073193s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-01T18:55:18.837659Z","caller":"traceutil/trace.go:171","msg":"trace[723946457] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.744113064s","start":"2026-02-01T18:55:17.09354Z","end":"2026-02-01T18:55:18.837653Z","steps":["trace[723946457] 'agreement among raft nodes before linearized reading' (duration: 1.744084394s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:18.837675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:17.093523Z","time spent":"1.744148324s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-01T18:55:18.837439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:17.051984Z","time spent":"1.785430764s","remote":"127.0.0.1:37546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-01T18:55:18.837751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.746283719s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-01T18:55:18.837771Z","caller":"traceutil/trace.go:171","msg":"trace[2123726084] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.74630127s","start":"2026-02-01T18:55:17.091463Z","end":"2026-02-01T18:55:18.837765Z","steps":["trace[2123726084] 'agreement among raft nodes before linearized reading' (duration: 1.746274019s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:18.837787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:17.091453Z","time spent":"1.74632976s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-01T18:55:18.83787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.746540371s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-01T18:55:18.837886Z","caller":"traceutil/trace.go:171","msg":"trace[2114845926] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.746582431s","start":"2026-02-01T18:55:17.091299Z","end":"2026-02-01T18:55:18.837881Z","steps":["trace[2114845926] 'agreement among raft nodes before linearized reading' (duration: 1.746557511s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:18.837928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:17.091252Z","time spent":"1.746672712s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-01T18:55:18.838025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.756440152s","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-02-01T18:55:18.838049Z","caller":"traceutil/trace.go:171","msg":"trace[2009483777] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"1.756483103s","start":"2026-02-01T18:55:17.081557Z","end":"2026-02-01T18:55:18.83804Z","steps":["trace[2009483777] 'agreement among raft nodes before linearized reading' (duration: 1.756451543s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:18.838066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:17.081546Z","time spent":"1.756515454s","remote":"127.0.0.1:37114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-02-01T18:55:18.83815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.782973406s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-01T18:55:18.838166Z","caller":"traceutil/trace.go:171","msg":"trace[555747494] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"1.783006937s","start":"2026-02-01T18:55:17.055154Z","end":"2026-02-01T18:55:18.838161Z","steps":["trace[555747494] 'agreement among raft nodes before linearized reading' (duration: 1.782981027s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:18.838181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:17.055149Z","time spent":"1.783028447s","remote":"127.0.0.1:37418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-02-01T18:55:18.838275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.790496331s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-01T18:55:18.838294Z","caller":"traceutil/trace.go:171","msg":"trace[2097657377] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:11; }","duration":"1.790521381s","start":"2026-02-01T18:55:17.047767Z","end":"2026-02-01T18:55:18.838289Z","steps":["trace[2097657377] 'agreement among raft nodes before linearized reading' (duration: 1.790494761s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:18.838309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:17.047764Z","time spent":"1.790542411s","remote":"127.0.0.1:37090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-02-01T18:55:18.838395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.790744882s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-01T18:55:18.838411Z","caller":"traceutil/trace.go:171","msg":"trace[592901395] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.790770003s","start":"2026-02-01T18:55:17.047637Z","end":"2026-02-01T18:55:18.838407Z","steps":["trace[592901395] 'agreement among raft nodes before linearized reading' (duration: 1.790743803s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:18.838429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:17.047634Z","time spent":"1.790792283s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-01T18:55:18.838518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.067095472s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-01T18:55:18.838538Z","caller":"traceutil/trace.go:171","msg":"trace[179385059] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"1.067141102s","start":"2026-02-01T18:55:17.771389Z","end":"2026-02-01T18:55:18.83853Z","steps":["trace[179385059] 'agreement among raft nodes before linearized reading' (duration: 1.067114402s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:18.838557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:17.771373Z","time spent":"1.067179412s","remote":"127.0.0.1:37036","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-02-01T18:55:18.838944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.631039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-01T18:55:18.838971Z","caller":"traceutil/trace.go:171","msg":"trace[549788529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"434.711119ms","start":"2026-02-01T18:55:18.404252Z","end":"2026-02-01T18:55:18.838963Z","steps":["trace[549788529] 'agreement among raft nodes before linearized reading' (duration: 434.674579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:18.838995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:18.404231Z","time spent":"434.758969ms","remote":"127.0.0.1:37022","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-02-01T18:55:18.839163Z","caller":"traceutil/trace.go:171","msg":"trace[1679172371] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.753535471s","start":"2026-02-01T18:55:17.085611Z","end":"2026-02-01T18:55:18.839146Z","steps":["trace[1679172371] 'process raft request' (duration: 1.75061679s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:18.83932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:17.085596Z","time spent":"1.753655343s","remote":"127.0.0.1:37206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2847,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-01T18:55:18.844394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.408315ms","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-02-01T18:55:18.844485Z","caller":"traceutil/trace.go:171","msg":"trace[1797186781] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:11; }","duration":"897.515375ms","start":"2026-02-01T18:55:17.946947Z","end":"2026-02-01T18:55:18.844462Z","steps":["trace[1797186781] 'agreement among raft nodes before linearized reading' (duration: 897.330294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:18.847479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.29686ms","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-02-01T18:55:18.849406Z","caller":"traceutil/trace.go:171","msg":"trace[693862297] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:11; }","duration":"904.234814ms","start":"2026-02-01T18:55:17.945158Z","end":"2026-02-01T18:55:18.849392Z","steps":["trace[693862297] 'agreement among raft nodes before linearized reading' (duration: 902.267109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:18.849511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:17.945109Z","time spent":"904.390606ms","remote":"127.0.0.1:37372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-02-01T18:55:18.844509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:17.946933Z","time spent":"897.570655ms","remote":"127.0.0.1:37382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-02-01T18:55:25.248799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.39068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2879"} {"level":"info","ts":"2026-02-01T18:55:25.249799Z","caller":"traceutil/trace.go:171","msg":"trace[1614076856] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:309; }","duration":"101.406127ms","start":"2026-02-01T18:55:25.148373Z","end":"2026-02-01T18:55:25.249779Z","steps":["trace[1614076856] 'agreement among raft nodes before linearized reading' (duration: 100.39025ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:25.529818Z","caller":"traceutil/trace.go:171","msg":"trace[2121757692] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"114.001131ms","start":"2026-02-01T18:55:25.415798Z","end":"2026-02-01T18:55:25.529799Z","steps":["trace[2121757692] 'process raft request' (duration: 106.203989ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:25.53385Z","caller":"traceutil/trace.go:171","msg":"trace[790260250] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"117.536804ms","start":"2026-02-01T18:55:25.416304Z","end":"2026-02-01T18:55:25.533841Z","steps":["trace[790260250] 'process raft request' (duration: 113.290716ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:43.888645Z","caller":"traceutil/trace.go:171","msg":"trace[348344076] linearizableReadLoop","detail":"{readStateIndex:445; appliedIndex:444; }","duration":"210.845422ms","start":"2026-02-01T18:55:43.677777Z","end":"2026-02-01T18:55:43.888622Z","steps":["trace[348344076] 'read index received' (duration: 210.626941ms)","trace[348344076] 'applied index is now lower than readState.Index' (duration: 217.651µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:43.88893Z","caller":"traceutil/trace.go:171","msg":"trace[1177934575] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"372.676773ms","start":"2026-02-01T18:55:43.516241Z","end":"2026-02-01T18:55:43.888917Z","steps":["trace[1177934575] 'process raft request' (duration: 372.224151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:43.889035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:43.516141Z","time spent":"372.826164ms","remote":"127.0.0.1:37292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:43.889331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.548586ms","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-02-01T18:55:43.889391Z","caller":"traceutil/trace.go:171","msg":"trace[138033404] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:430; }","duration":"211.628777ms","start":"2026-02-01T18:55:43.677751Z","end":"2026-02-01T18:55:43.88938Z","steps":["trace[138033404] 'agreement among raft nodes before linearized reading' (duration: 211.542165ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:44.203696Z","caller":"traceutil/trace.go:171","msg":"trace[1981009551] linearizableReadLoop","detail":"{readStateIndex:446; appliedIndex:445; }","duration":"306.400484ms","start":"2026-02-01T18:55:43.897274Z","end":"2026-02-01T18:55:44.203674Z","steps":["trace[1981009551] 'read index received' (duration: 306.151133ms)","trace[1981009551] 'applied index is now lower than readState.Index' (duration: 248.501µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:44.20422Z","caller":"traceutil/trace.go:171","msg":"trace[2136803856] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"308.531415ms","start":"2026-02-01T18:55:43.895674Z","end":"2026-02-01T18:55:44.204205Z","steps":["trace[2136803856] 'process raft request' (duration: 307.811601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:44.204678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.35754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:44.204951Z","caller":"traceutil/trace.go:171","msg":"trace[1981955681] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:0; response_revision:431; }","duration":"307.687761ms","start":"2026-02-01T18:55:43.897239Z","end":"2026-02-01T18:55:44.204927Z","steps":["trace[1981955681] 'agreement among raft nodes before linearized reading' (duration: 306.529645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:44.204971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:43.895658Z","time spent":"308.608786ms","remote":"127.0.0.1:37292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:44.205716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:43.897226Z","time spent":"307.899372ms","remote":"127.0.0.1:37142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/kube-system/cilium-config\" "} {"level":"info","ts":"2026-02-01T18:55:46.410388Z","caller":"traceutil/trace.go:171","msg":"trace[1295968846] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"198.096076ms","start":"2026-02-01T18:55:46.212258Z","end":"2026-02-01T18:55:46.410354Z","steps":["trace[1295968846] 'process raft request' (duration: 197.966485ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:46.550648Z","caller":"traceutil/trace.go:171","msg":"trace[1538065898] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"117.242641ms","start":"2026-02-01T18:55:46.43339Z","end":"2026-02-01T18:55:46.550633Z","steps":["trace[1538065898] 'process raft request' (duration: 116.900368ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:47.044004Z","caller":"traceutil/trace.go:171","msg":"trace[1035557177] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"294.320069ms","start":"2026-02-01T18:55:46.749669Z","end":"2026-02-01T18:55:47.043989Z","steps":["trace[1035557177] 'process raft request' (duration: 294.240089ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:49.258073Z","caller":"traceutil/trace.go:171","msg":"trace[1259065616] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"205.092086ms","start":"2026-02-01T18:55:49.052963Z","end":"2026-02-01T18:55:49.258055Z","steps":["trace[1259065616] 'process raft request' (duration: 204.985866ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:49.258668Z","caller":"traceutil/trace.go:171","msg":"trace[2028383787] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:491; }","duration":"169.59289ms","start":"2026-02-01T18:55:49.089066Z","end":"2026-02-01T18:55:49.258659Z","steps":["trace[2028383787] 'read index received' (duration: 168.825246ms)","trace[2028383787] 'applied index is now lower than readState.Index' (duration: 766.664µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:49.258742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.66076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:49.258765Z","caller":"traceutil/trace.go:171","msg":"trace[1314603266] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:475; }","duration":"169.71514ms","start":"2026-02-01T18:55:49.089042Z","end":"2026-02-01T18:55:49.258757Z","steps":["trace[1314603266] 'agreement among raft nodes before linearized reading' (duration: 169.65212ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:49.796898Z","caller":"traceutil/trace.go:171","msg":"trace[1313265137] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:492; }","duration":"237.340215ms","start":"2026-02-01T18:55:49.559539Z","end":"2026-02-01T18:55:49.79688Z","steps":["trace[1313265137] 'read index received' (duration: 217.178123ms)","trace[1313265137] 'applied index is now lower than readState.Index' (duration: 20.161192ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:49.79744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.85375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:49.797184Z","caller":"traceutil/trace.go:171","msg":"trace[593723636] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"269.130301ms","start":"2026-02-01T18:55:49.528041Z","end":"2026-02-01T18:55:49.797171Z","steps":["trace[593723636] 'process raft request' (duration: 248.723319ms)","trace[593723636] 'compare' (duration: 20.02138ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:49.79748Z","caller":"traceutil/trace.go:171","msg":"trace[1592547904] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:0; response_revision:477; }","duration":"109.93022ms","start":"2026-02-01T18:55:49.687539Z","end":"2026-02-01T18:55:49.797469Z","steps":["trace[1592547904] 'agreement among raft nodes before linearized reading' (duration: 109.85327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:49.798365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.825803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-01T18:55:49.798401Z","caller":"traceutil/trace.go:171","msg":"trace[2090468667] range","detail":"{range_begin:/registry/masterleases/162.253.55.227; range_end:; response_count:1; response_revision:477; }","duration":"238.865684ms","start":"2026-02-01T18:55:49.559525Z","end":"2026-02-01T18:55:49.79839Z","steps":["trace[2090468667] 'agreement among raft nodes before linearized reading' (duration: 238.517232ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:50.044689Z","caller":"traceutil/trace.go:171","msg":"trace[578610700] linearizableReadLoop","detail":"{readStateIndex:495; appliedIndex:494; }","duration":"186.685723ms","start":"2026-02-01T18:55:49.857985Z","end":"2026-02-01T18:55:50.04467Z","steps":["trace[578610700] 'read index received' (duration: 103.741424ms)","trace[578610700] 'applied index is now lower than readState.Index' (duration: 82.943349ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:50.045133Z","caller":"traceutil/trace.go:171","msg":"trace[1533737014] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"189.272038ms","start":"2026-02-01T18:55:49.855759Z","end":"2026-02-01T18:55:50.045031Z","steps":["trace[1533737014] 'process raft request' (duration: 105.972007ms)","trace[1533737014] 'compare' (duration: 82.854039ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:50.045168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.162566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:50.045309Z","caller":"traceutil/trace.go:171","msg":"trace[1072240384] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:0; response_revision:478; }","duration":"187.333577ms","start":"2026-02-01T18:55:49.857959Z","end":"2026-02-01T18:55:50.045293Z","steps":["trace[1072240384] 'agreement among raft nodes before linearized reading' (duration: 187.102315ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:50.180705Z","caller":"traceutil/trace.go:171","msg":"trace[1940546840] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:495; }","duration":"125.719483ms","start":"2026-02-01T18:55:50.054955Z","end":"2026-02-01T18:55:50.180674Z","steps":["trace[1940546840] 'read index received' (duration: 112.495321ms)","trace[1940546840] 'applied index is now lower than readState.Index' (duration: 13.223222ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:50.180826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.880404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/local-path-provisioner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:50.180859Z","caller":"traceutil/trace.go:171","msg":"trace[1310708517] range","detail":"{range_begin:/registry/clusterrolebindings/local-path-provisioner; range_end:; response_count:0; response_revision:479; }","duration":"125.956154ms","start":"2026-02-01T18:55:50.054891Z","end":"2026-02-01T18:55:50.180847Z","steps":["trace[1310708517] 'agreement among raft nodes before linearized reading' (duration: 125.881324ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:50.181263Z","caller":"traceutil/trace.go:171","msg":"trace[2024209292] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"127.188322ms","start":"2026-02-01T18:55:50.054059Z","end":"2026-02-01T18:55:50.181247Z","steps":["trace[2024209292] 'process raft request' (duration: 113.391476ms)","trace[2024209292] 'compare' (duration: 13.116952ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:50.670535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.230111ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:55:50.67075Z","caller":"traceutil/trace.go:171","msg":"trace[1532632804] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"177.538978ms","start":"2026-02-01T18:55:50.493159Z","end":"2026-02-01T18:55:50.670698Z","steps":["trace[1532632804] 'process raft request' (duration: 38.930235ms)","trace[1532632804] 'compare' (duration: 138.100041ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:50.671141Z","caller":"traceutil/trace.go:171","msg":"trace[679850975] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"177.799989ms","start":"2026-02-01T18:55:50.493323Z","end":"2026-02-01T18:55:50.671123Z","steps":["trace[679850975] 'process raft request' (duration: 177.312617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:55.635683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.170204017s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:55.635754Z","caller":"traceutil/trace.go:171","msg":"trace[179880945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:513; }","duration":"1.170338558s","start":"2026-02-01T18:55:54.465398Z","end":"2026-02-01T18:55:55.635736Z","steps":["trace[179880945] 'range keys from in-memory index tree' (duration: 1.170135927s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:55.635792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:54.465379Z","time spent":"1.170404699s","remote":"127.0.0.1:37022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:55:55.636209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"985.743593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-d9bmq\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-01T18:55:55.636248Z","caller":"traceutil/trace.go:171","msg":"trace[534967948] range","detail":"{range_begin:/registry/pods/kube-system/cilium-d9bmq; range_end:; response_count:1; response_revision:513; }","duration":"985.822083ms","start":"2026-02-01T18:55:54.650414Z","end":"2026-02-01T18:55:55.636236Z","steps":["trace[534967948] 'range keys from in-memory index tree' (duration: 985.526171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:55.636277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:54.650391Z","time spent":"985.879723ms","remote":"127.0.0.1:37218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16679,"request content":"key:\"/registry/pods/kube-system/cilium-d9bmq\" "} {"level":"warn","ts":"2026-02-01T18:55:55.637044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.022366ms","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-02-01T18:55:55.637082Z","caller":"traceutil/trace.go:171","msg":"trace[788068258] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:513; }","duration":"953.086696ms","start":"2026-02-01T18:55:54.683984Z","end":"2026-02-01T18:55:55.637071Z","steps":["trace[788068258] 'range keys from in-memory index tree' (duration: 952.909355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:55.637113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:54.68397Z","time spent":"953.135306ms","remote":"127.0.0.1:37292","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-02-01T18:55:55.637253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.667308ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:55.637313Z","caller":"traceutil/trace.go:171","msg":"trace[1574873504] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:513; }","duration":"864.728288ms","start":"2026-02-01T18:55:54.772575Z","end":"2026-02-01T18:55:55.637303Z","steps":["trace[1574873504] 'range keys from in-memory index tree' (duration: 864.660328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:56.45189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.530032ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-01T18:55:56.452015Z","caller":"traceutil/trace.go:171","msg":"trace[1901611208] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:530; }","duration":"1.163304954s","start":"2026-02-01T18:55:55.288699Z","end":"2026-02-01T18:55:56.452004Z","steps":["trace[1901611208] 'read index received' (duration: 346.662182ms)","trace[1901611208] 'applied index is now lower than readState.Index' (duration: 816.642252ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:56.452442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.163735746s","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-02-01T18:55:56.452467Z","caller":"traceutil/trace.go:171","msg":"trace[1302274940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:513; }","duration":"1.163799296s","start":"2026-02-01T18:55:55.288659Z","end":"2026-02-01T18:55:56.452459Z","steps":["trace[1302274940] 'agreement among raft nodes before linearized reading' (duration: 1.163528295s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:56.452489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:55.288641Z","time spent":"1.163843566s","remote":"127.0.0.1:37292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-01T18:55:56.453681Z","caller":"traceutil/trace.go:171","msg":"trace[854159144] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"801.916624ms","start":"2026-02-01T18:55:55.651754Z","end":"2026-02-01T18:55:56.453671Z","steps":["trace[854159144] 'process raft request' (duration: 800.197205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:56.453741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:55.651734Z","time spent":"801.969615ms","remote":"127.0.0.1:37292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:56.876312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.799762ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:55:56.876378Z","caller":"traceutil/trace.go:171","msg":"trace[916015645] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:532; }","duration":"424.336478ms","start":"2026-02-01T18:55:56.45203Z","end":"2026-02-01T18:55:56.876367Z","steps":["trace[916015645] 'read index received' (duration: 68.347515ms)","trace[916015645] 'applied index is now lower than readState.Index' (duration: 355.988183ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:56.876496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.398606308s","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-02-01T18:55:56.876513Z","caller":"traceutil/trace.go:171","msg":"trace[367068307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:515; }","duration":"1.398625878s","start":"2026-02-01T18:55:55.477882Z","end":"2026-02-01T18:55:56.876508Z","steps":["trace[367068307] 'agreement among raft nodes before linearized reading' (duration: 1.398513347s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:56.876534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:55.477841Z","time spent":"1.398687638s","remote":"127.0.0.1:37292","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-02-01T18:55:56.876706Z","caller":"traceutil/trace.go:171","msg":"trace[545261485] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.222244641s","start":"2026-02-01T18:55:55.654453Z","end":"2026-02-01T18:55:56.876698Z","steps":["trace[545261485] 'process raft request' (duration: 866.006496ms)","trace[545261485] 'compare' (duration: 355.631171ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:56.876744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:55.654425Z","time spent":"1.222297821s","remote":"127.0.0.1:37218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:56.876844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.640628ms","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-02-01T18:55:56.876862Z","caller":"traceutil/trace.go:171","msg":"trace[266650625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:515; }","duration":"592.683118ms","start":"2026-02-01T18:55:56.284173Z","end":"2026-02-01T18:55:56.876856Z","steps":["trace[266650625] 'agreement among raft nodes before linearized reading' (duration: 592.632727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:56.876879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:56.284157Z","time spent":"592.717578ms","remote":"127.0.0.1:37292","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-02-01T18:55:56.876961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.236686998s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:56.876976Z","caller":"traceutil/trace.go:171","msg":"trace[1997122616] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:515; }","duration":"1.236702258s","start":"2026-02-01T18:55:55.640269Z","end":"2026-02-01T18:55:56.876972Z","steps":["trace[1997122616] 'agreement among raft nodes before linearized reading' (duration: 1.236678238s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:56.876993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:55.640232Z","time spent":"1.236757968s","remote":"127.0.0.1:37036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:55:58.015399Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.034470923s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-01T18:55:58.887642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000956145s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-01T18:55:58.887715Z","caller":"traceutil/trace.go:171","msg":"trace[1144990557] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001058756s","start":"2026-02-01T18:55:56.886639Z","end":"2026-02-01T18:55:58.887697Z","steps":["trace[1144990557] 'agreement among raft nodes before linearized reading' (duration: 2.000969755s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:58.88776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:56.886627Z","time spent":"2.001123577s","remote":"127.0.0.1:37022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/01 18:55:58 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-01T18:55:59.634527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.65348934s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:55:59.636315Z","caller":"traceutil/trace.go:171","msg":"trace[1150383223] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"2.74671517s","start":"2026-02-01T18:55:56.889585Z","end":"2026-02-01T18:55:59.6363Z","steps":["trace[1150383223] 'process raft request' (duration: 2.746691449s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:59.636407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:56.889578Z","time spent":"2.74680182s","remote":"127.0.0.1:37292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:59.636673Z","caller":"traceutil/trace.go:171","msg":"trace[1374481174] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"3.179485591s","start":"2026-02-01T18:55:56.457178Z","end":"2026-02-01T18:55:59.636663Z","steps":["trace[1374481174] 'process raft request' (duration: 523.793019ms)","trace[1374481174] 'compare' (duration: 2.651846972s)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:59.636789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:56.457164Z","time spent":"3.179600643s","remote":"127.0.0.1:37292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:59.636898Z","caller":"traceutil/trace.go:171","msg":"trace[1993172878] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:533; }","duration":"2.750231488s","start":"2026-02-01T18:55:56.886659Z","end":"2026-02-01T18:55:59.636891Z","steps":["trace[1993172878] 'read index received' (duration: 94.319885ms)","trace[1993172878] 'applied index is now lower than readState.Index' (duration: 2.655910873s)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:59.637026Z","caller":"traceutil/trace.go:171","msg":"trace[574422515] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"2.750264918s","start":"2026-02-01T18:55:56.886755Z","end":"2026-02-01T18:55:59.63702Z","steps":["trace[574422515] 'process raft request' (duration: 2.749474604s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:59.637106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:56.886747Z","time spent":"2.750334448s","remote":"127.0.0.1:37292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:59.637378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.180936768s","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-02-01T18:55:59.639505Z","caller":"traceutil/trace.go:171","msg":"trace[1593806431] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:518; }","duration":"1.183094299s","start":"2026-02-01T18:55:58.456401Z","end":"2026-02-01T18:55:59.639495Z","steps":["trace[1593806431] 'agreement among raft nodes before linearized reading' (duration: 1.180890147s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:59.639574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:58.456382Z","time spent":"1.183181759s","remote":"127.0.0.1:37292","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-02-01T18:55:59.639708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.632315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:59.640066Z","caller":"traceutil/trace.go:171","msg":"trace[4887563] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"752.015757ms","start":"2026-02-01T18:55:58.888034Z","end":"2026-02-01T18:55:59.64005Z","steps":["trace[4887563] 'agreement among raft nodes before linearized reading' (duration: 751.652325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:59.641746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:58.888017Z","time spent":"753.720346ms","remote":"127.0.0.1:37036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-01T18:56:00.870042Z","caller":"traceutil/trace.go:171","msg":"trace[1320209562] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"191.421275ms","start":"2026-02-01T18:56:00.678607Z","end":"2026-02-01T18:56:00.870028Z","steps":["trace[1320209562] 'process raft request' (duration: 188.763271ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:02.583886Z","caller":"traceutil/trace.go:171","msg":"trace[387156714] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:553; }","duration":"120.518867ms","start":"2026-02-01T18:56:02.463349Z","end":"2026-02-01T18:56:02.583867Z","steps":["trace[387156714] 'read index received' (duration: 73.760553ms)","trace[387156714] 'applied index is now lower than readState.Index' (duration: 46.757584ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:56:02.583968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.612118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:56:02.583988Z","caller":"traceutil/trace.go:171","msg":"trace[84484831] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"120.669328ms","start":"2026-02-01T18:56:02.463313Z","end":"2026-02-01T18:56:02.583982Z","steps":["trace[84484831] 'agreement among raft nodes before linearized reading' (duration: 120.613877ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:02.584149Z","caller":"traceutil/trace.go:171","msg":"trace[974691687] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"507.467711ms","start":"2026-02-01T18:56:02.076674Z","end":"2026-02-01T18:56:02.584141Z","steps":["trace[974691687] 'process raft request' (duration: 460.506467ms)","trace[974691687] 'compare' (duration: 46.13899ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:56:02.584201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:02.076655Z","time spent":"507.510592ms","remote":"127.0.0.1:37292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:56:05.384922Z","caller":"traceutil/trace.go:171","msg":"trace[1451791762] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"292.567713ms","start":"2026-02-01T18:56:05.092336Z","end":"2026-02-01T18:56:05.384904Z","steps":["trace[1451791762] 'read index received' (duration: 246.806497ms)","trace[1451791762] 'applied index is now lower than readState.Index' (duration: 45.760246ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:56:05.385098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.624134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-01T18:56:05.385116Z","caller":"traceutil/trace.go:171","msg":"trace[788986455] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:589; }","duration":"253.691814ms","start":"2026-02-01T18:56:05.131419Z","end":"2026-02-01T18:56:05.38511Z","steps":["trace[788986455] 'agreement among raft nodes before linearized reading' (duration: 253.581483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:05.385123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.759355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-01T18:56:05.385192Z","caller":"traceutil/trace.go:171","msg":"trace[1629500836] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:589; }","duration":"292.870056ms","start":"2026-02-01T18:56:05.092305Z","end":"2026-02-01T18:56:05.385175Z","steps":["trace[1629500836] 'agreement among raft nodes before linearized reading' (duration: 292.658334ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:57:24.884829Z","caller":"traceutil/trace.go:171","msg":"trace[1698472338] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"271.055631ms","start":"2026-02-01T18:57:24.613758Z","end":"2026-02-01T18:57:24.884814Z","steps":["trace[1698472338] 'process raft request' (duration: 270.944469ms)"],"step_count":1}