{"level":"warn","ts":"2026-10-13T11:17:43.245518Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-13T11:17:43.245629Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.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://162.253.55.116:2380","--initial-cluster=instance=https://162.253.55.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.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-10-13T11:17:43.245713Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-13T11:17:43.245735Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.116:2380"]} {"level":"info","ts":"2026-10-13T11:17:43.245761Z","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-10-13T11:17:43.246631Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.116:2379"]} {"level":"info","ts":"2026-10-13T11:17:43.246732Z","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://162.253.55.116:2380"],"listen-peer-urls":["https://162.253.55.116:2380"],"advertise-client-urls":["https://162.253.55.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.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-10-13T11:17:43.258102Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"11.107425ms"} {"level":"info","ts":"2026-10-13T11:17:43.264311Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9755887b76c83964","cluster-id":"5ee78cd75a03071"} {"level":"info","ts":"2026-10-13T11:17:43.264386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 switched to configuration voters=()"} {"level":"info","ts":"2026-10-13T11:17:43.264402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 became follower at term 0"} {"level":"info","ts":"2026-10-13T11:17:43.264412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9755887b76c83964 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-13T11:17:43.264418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 became follower at term 1"} {"level":"info","ts":"2026-10-13T11:17:43.264458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 switched to configuration voters=(10904772136602712420)"} {"level":"warn","ts":"2026-10-13T11:17:43.272098Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-13T11:17:43.273344Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-13T11:17:43.274414Z","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-10-13T11:17:43.276543Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9755887b76c83964","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-13T11:17:43.276721Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9755887b76c83964","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-13T11:17:43.27683Z","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-10-13T11:17:43.277461Z","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-10-13T11:17:43.277524Z","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-10-13T11:17:43.277858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 switched to configuration voters=(10904772136602712420)"} {"level":"info","ts":"2026-10-13T11:17:43.278013Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5ee78cd75a03071","local-member-id":"9755887b76c83964","added-peer-id":"9755887b76c83964","added-peer-peer-urls":["https://162.253.55.116:2380"]} {"level":"info","ts":"2026-10-13T11:17:43.281377Z","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-10-13T11:17:43.281441Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.116:2380"} {"level":"info","ts":"2026-10-13T11:17:43.281465Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.116:2380"} {"level":"info","ts":"2026-10-13T11:17:43.281578Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9755887b76c83964","initial-advertise-peer-urls":["https://162.253.55.116:2380"],"listen-peer-urls":["https://162.253.55.116:2380"],"advertise-client-urls":["https://162.253.55.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-13T11:17:43.281617Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-13T11:17:44.264617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 is starting a new election at term 1"} {"level":"info","ts":"2026-10-13T11:17:44.26472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-13T11:17:44.264749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 received MsgPreVoteResp from 9755887b76c83964 at term 1"} {"level":"info","ts":"2026-10-13T11:17:44.264767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 became candidate at term 2"} {"level":"info","ts":"2026-10-13T11:17:44.264778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 received MsgVoteResp from 9755887b76c83964 at term 2"} {"level":"info","ts":"2026-10-13T11:17:44.264792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9755887b76c83964 became leader at term 2"} {"level":"info","ts":"2026-10-13T11:17:44.264805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9755887b76c83964 elected leader 9755887b76c83964 at term 2"} {"level":"info","ts":"2026-10-13T11:17:44.26697Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T11:17:44.267851Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9755887b76c83964","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.116:2379]}","request-path":"/0/members/9755887b76c83964/attributes","cluster-id":"5ee78cd75a03071","publish-timeout":"7s"} {"level":"info","ts":"2026-10-13T11:17:44.268112Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T11:17:44.268182Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T11:17:44.268277Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-13T11:17:44.268328Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-13T11:17:44.272525Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.116:2379"} {"level":"info","ts":"2026-10-13T11:17:44.272591Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-13T11:17:44.27685Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5ee78cd75a03071","local-member-id":"9755887b76c83964","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T11:17:44.277189Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T11:17:44.27732Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T11:17:49.583991Z","caller":"traceutil/trace.go:171","msg":"trace[537498149] linearizableReadLoop","detail":"{readStateIndex:247; appliedIndex:246; }","duration":"308.034595ms","start":"2026-10-13T11:17:49.275917Z","end":"2026-10-13T11:17:49.583951Z","steps":["trace[537498149] 'read index received' (duration: 223.505352ms)","trace[537498149] 'applied index is now lower than readState.Index' (duration: 84.527943ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T11:17:49.584049Z","caller":"traceutil/trace.go:171","msg":"trace[881881560] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"313.625768ms","start":"2026-10-13T11:17:49.27037Z","end":"2026-10-13T11:17:49.583996Z","steps":["trace[881881560] 'process raft request' (duration: 229.041034ms)","trace[881881560] 'compare' (duration: 84.41748ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T11:17:49.584203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.23445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-10-13T11:17:49.584286Z","caller":"traceutil/trace.go:171","msg":"trace[1847117523] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:241; }","duration":"308.391314ms","start":"2026-10-13T11:17:49.275881Z","end":"2026-10-13T11:17:49.584272Z","steps":["trace[1847117523] 'agreement among raft nodes before linearized reading' (duration: 308.209189ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T11:17:49.584321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T11:17:49.275866Z","time spent":"308.445545ms","remote":"127.0.0.1:55160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4040,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-10-13T11:17:49.584834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T11:17:49.270355Z","time spent":"313.820103ms","remote":"127.0.0.1:55044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-13T11:17:49.845808Z","caller":"traceutil/trace.go:171","msg":"trace[2100751548] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"569.86791ms","start":"2026-10-13T11:17:49.275912Z","end":"2026-10-13T11:17:49.84578Z","steps":["trace[2100751548] 'process raft request' (duration: 519.569683ms)","trace[2100751548] 'compare' (duration: 49.664332ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T11:17:49.84593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T11:17:49.275894Z","time spent":"569.983194ms","remote":"127.0.0.1:55076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":597,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-13T11:17:49.846285Z","caller":"traceutil/trace.go:171","msg":"trace[730369178] transaction","detail":"{read_only:false; number_of_response:0; response_revision:242; }","duration":"488.849172ms","start":"2026-10-13T11:17:49.35741Z","end":"2026-10-13T11:17:49.846259Z","steps":["trace[730369178] 'process raft request' (duration: 488.78654ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T11:17:49.846421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T11:17:49.357387Z","time spent":"488.971374ms","remote":"127.0.0.1:55160","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-13T11:17:49.84645Z","caller":"traceutil/trace.go:171","msg":"trace[836267782] linearizableReadLoop","detail":"{readStateIndex:249; appliedIndex:247; }","duration":"262.363248ms","start":"2026-10-13T11:17:49.584063Z","end":"2026-10-13T11:17:49.846426Z","steps":["trace[836267782] 'read index received' (duration: 211.467796ms)","trace[836267782] 'applied index is now lower than readState.Index' (duration: 50.893632ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T11:17:49.846592Z","caller":"traceutil/trace.go:171","msg":"trace[118194194] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"249.382118ms","start":"2026-10-13T11:17:49.597195Z","end":"2026-10-13T11:17:49.846577Z","steps":["trace[118194194] 'process raft request' (duration: 249.326937ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:17:49.846696Z","caller":"traceutil/trace.go:171","msg":"trace[1292889522] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"257.492282ms","start":"2026-10-13T11:17:49.589192Z","end":"2026-10-13T11:17:49.846685Z","steps":["trace[1292889522] 'process raft request' (duration: 257.225166ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T11:17:49.846699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.65126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-10-13T11:17:49.846808Z","caller":"traceutil/trace.go:171","msg":"trace[1477819286] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:246; }","duration":"570.778242ms","start":"2026-10-13T11:17:49.275966Z","end":"2026-10-13T11:17:49.846745Z","steps":["trace[1477819286] 'agreement among raft nodes before linearized reading' (duration: 570.566797ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:17:49.846886Z","caller":"traceutil/trace.go:171","msg":"trace[357470979] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"257.881381ms","start":"2026-10-13T11:17:49.588994Z","end":"2026-10-13T11:17:49.846876Z","steps":["trace[357470979] 'process raft request' (duration: 257.271377ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T11:17:49.846906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T11:17:49.275958Z","time spent":"570.929176ms","remote":"127.0.0.1:38842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-10-13T11:17:49.847317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.978354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-10-13T11:17:49.847431Z","caller":"traceutil/trace.go:171","msg":"trace[270518914] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"250.055854ms","start":"2026-10-13T11:17:49.596943Z","end":"2026-10-13T11:17:49.846999Z","steps":["trace[270518914] 'process raft request' (duration: 249.511681ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:17:49.848624Z","caller":"traceutil/trace.go:171","msg":"trace[478883853] transaction","detail":"{read_only:false; number_of_response:0; response_revision:247; }","duration":"249.023381ms","start":"2026-10-13T11:17:49.59824Z","end":"2026-10-13T11:17:49.847263Z","steps":["trace[478883853] 'process raft request' (duration: 248.98907ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:17:49.848727Z","caller":"traceutil/trace.go:171","msg":"trace[1214433525] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"249.902111ms","start":"2026-10-13T11:17:49.597366Z","end":"2026-10-13T11:17:49.847268Z","steps":["trace[1214433525] 'process raft request' (duration: 249.188494ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:17:49.848907Z","caller":"traceutil/trace.go:171","msg":"trace[1070675064] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:247; }","duration":"570.038195ms","start":"2026-10-13T11:17:49.277311Z","end":"2026-10-13T11:17:49.847349Z","steps":["trace[1070675064] 'agreement among raft nodes before linearized reading' (duration: 569.875151ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T11:17:49.848974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T11:17:49.277304Z","time spent":"571.648163ms","remote":"127.0.0.1:39030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-10-13T11:17:49.847547Z","caller":"traceutil/trace.go:171","msg":"trace[580648839] transaction","detail":"{read_only:false; number_of_response:0; response_revision:245; }","duration":"249.947483ms","start":"2026-10-13T11:17:49.597185Z","end":"2026-10-13T11:17:49.847133Z","steps":["trace[580648839] 'process raft request' (duration: 249.311377ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:18:27.553509Z","caller":"traceutil/trace.go:171","msg":"trace[2117504830] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"101.380951ms","start":"2026-10-13T11:18:27.452104Z","end":"2026-10-13T11:18:27.553485Z","steps":["trace[2117504830] 'process raft request' (duration: 100.876759ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:18:34.104594Z","caller":"traceutil/trace.go:171","msg":"trace[713614002] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"282.146744ms","start":"2026-10-13T11:18:33.822425Z","end":"2026-10-13T11:18:34.104572Z","steps":["trace[713614002] 'process raft request' (duration: 282.028101ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:18:34.508092Z","caller":"traceutil/trace.go:171","msg":"trace[1604751992] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"276.908338ms","start":"2026-10-13T11:18:34.231133Z","end":"2026-10-13T11:18:34.508064Z","steps":["trace[1604751992] 'process raft request' (duration: 236.689006ms)","trace[1604751992] 'compare' (duration: 40.09457ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T11:18:34.66287Z","caller":"traceutil/trace.go:171","msg":"trace[2122056455] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"431.641005ms","start":"2026-10-13T11:18:34.231183Z","end":"2026-10-13T11:18:34.662824Z","steps":["trace[2122056455] 'process raft request' (duration: 425.629752ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:18:34.663Z","caller":"traceutil/trace.go:171","msg":"trace[6638723] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"431.778607ms","start":"2026-10-13T11:18:34.231194Z","end":"2026-10-13T11:18:34.662973Z","steps":["trace[6638723] 'process raft request' (duration: 431.543582ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T11:18:34.663289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T11:18:34.231168Z","time spent":"431.789188ms","remote":"127.0.0.1:38878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T11:18:34.663317Z","caller":"traceutil/trace.go:171","msg":"trace[225097735] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:636; }","duration":"397.516156ms","start":"2026-10-13T11:18:34.265781Z","end":"2026-10-13T11:18:34.663297Z","steps":["trace[225097735] 'read index received' (duration: 202.066876ms)","trace[225097735] 'applied index is now lower than readState.Index' (duration: 195.44818ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T11:18:34.663412Z","caller":"traceutil/trace.go:171","msg":"trace[1621059388] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"431.83943ms","start":"2026-10-13T11:18:34.231561Z","end":"2026-10-13T11:18:34.6634Z","steps":["trace[1621059388] 'process raft request' (duration: 431.242045ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T11:18:34.663551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T11:18:34.231167Z","time spent":"431.88927ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T11:18:34.66362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.830573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T11:18:34.663673Z","caller":"traceutil/trace.go:171","msg":"trace[313298266] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"397.926246ms","start":"2026-10-13T11:18:34.265731Z","end":"2026-10-13T11:18:34.663657Z","steps":["trace[313298266] 'agreement among raft nodes before linearized reading' (duration: 397.840124ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T11:18:34.663683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.390792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-10-13T11:18:34.66372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T11:18:34.265712Z","time spent":"397.996928ms","remote":"127.0.0.1:54976","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-10-13T11:18:34.663727Z","caller":"traceutil/trace.go:171","msg":"trace[1433026941] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"109.487664ms","start":"2026-10-13T11:18:34.554225Z","end":"2026-10-13T11:18:34.663712Z","steps":["trace[1433026941] 'agreement among raft nodes before linearized reading' (duration: 109.31545ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T11:18:34.663751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.147272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-s5g8l\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-13T11:18:34.663938Z","caller":"traceutil/trace.go:171","msg":"trace[841206467] range","detail":"{range_begin:/registry/pods/kube-system/cilium-s5g8l; range_end:; response_count:1; response_revision:618; }","duration":"238.347777ms","start":"2026-10-13T11:18:34.425567Z","end":"2026-10-13T11:18:34.663915Z","steps":["trace[841206467] 'agreement among raft nodes before linearized reading' (duration: 237.968908ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T11:18:34.663952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.828637ms","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-10-13T11:18:34.664041Z","caller":"traceutil/trace.go:171","msg":"trace[663056705] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:618; }","duration":"178.97771ms","start":"2026-10-13T11:18:34.485038Z","end":"2026-10-13T11:18:34.664015Z","steps":["trace[663056705] 'agreement among raft nodes before linearized reading' (duration: 178.618352ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T11:18:34.663477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T11:18:34.231547Z","time spent":"431.891331ms","remote":"127.0.0.1:55044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-13T11:18:50.35561Z","caller":"traceutil/trace.go:171","msg":"trace[1064906029] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"138.621054ms","start":"2026-10-13T11:18:50.216959Z","end":"2026-10-13T11:18:50.35558Z","steps":["trace[1064906029] 'process raft request' (duration: 138.413999ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:19:38.11581Z","caller":"traceutil/trace.go:171","msg":"trace[82676350] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"193.460858ms","start":"2026-10-13T11:19:37.922289Z","end":"2026-10-13T11:19:38.11575Z","steps":["trace[82676350] 'process raft request' (duration: 193.359935ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:19:52.714159Z","caller":"traceutil/trace.go:171","msg":"trace[1352200515] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"144.469928ms","start":"2026-10-13T11:19:52.569669Z","end":"2026-10-13T11:19:52.714139Z","steps":["trace[1352200515] 'process raft request' (duration: 143.970816ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:19:52.991579Z","caller":"traceutil/trace.go:171","msg":"trace[648794251] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"178.270051ms","start":"2026-10-13T11:19:52.813288Z","end":"2026-10-13T11:19:52.991558Z","steps":["trace[648794251] 'process raft request' (duration: 178.140658ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T11:19:53.23595Z","caller":"traceutil/trace.go:171","msg":"trace[38389488] linearizableReadLoop","detail":"{readStateIndex:1191; appliedIndex:1190; }","duration":"195.639619ms","start":"2026-10-13T11:19:53.040295Z","end":"2026-10-13T11:19:53.235934Z","steps":["trace[38389488] 'read index received' (duration: 195.455335ms)","trace[38389488] 'applied index is now lower than readState.Index' (duration: 183.714µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T11:19:53.236028Z","caller":"traceutil/trace.go:171","msg":"trace[1715097691] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"202.219898ms","start":"2026-10-13T11:19:53.033799Z","end":"2026-10-13T11:19:53.236019Z","steps":["trace[1715097691] 'process raft request' (duration: 202.021134ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T11:19:53.236342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.036299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-10-13T11:19:53.236364Z","caller":"traceutil/trace.go:171","msg":"trace[1668420185] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1148; }","duration":"196.098151ms","start":"2026-10-13T11:19:53.04026Z","end":"2026-10-13T11:19:53.236358Z","steps":["trace[1668420185] 'agreement among raft nodes before linearized reading' (duration: 195.981408ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T11:19:53.236467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.238214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-13T11:19:53.236482Z","caller":"traceutil/trace.go:171","msg":"trace[61984253] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1148; }","duration":"147.287225ms","start":"2026-10-13T11:19:53.089191Z","end":"2026-10-13T11:19:53.236478Z","steps":["trace[61984253] 'agreement among raft nodes before linearized reading' (duration: 147.247875ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T11:19:53.236589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.438651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-13T11:19:53.236606Z","caller":"traceutil/trace.go:171","msg":"trace[1812393222] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1148; }","duration":"118.497672ms","start":"2026-10-13T11:19:53.118103Z","end":"2026-10-13T11:19:53.236601Z","steps":["trace[1812393222] 'agreement among raft nodes before linearized reading' (duration: 118.469132ms)"],"step_count":1}