{"level":"warn","ts":"2026-09-29T03:37:24.278713Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-29T03:37:24.278849Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.86: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.86:2380","--initial-cluster=instance=https://162.253.55.86:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.86:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.86: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-09-29T03:37:24.279074Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-29T03:37:24.279097Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.86:2380"]} {"level":"info","ts":"2026-09-29T03:37:24.279177Z","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-09-29T03:37:24.28046Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.86:2379"]} {"level":"info","ts":"2026-09-29T03:37:24.280631Z","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.86:2380"],"listen-peer-urls":["https://162.253.55.86:2380"],"advertise-client-urls":["https://162.253.55.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.86: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-09-29T03:37:24.286327Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.306343ms"} {"level":"info","ts":"2026-09-29T03:37:24.29815Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d01cde7b2aa18f95","cluster-id":"9e5d9cb67aed7785"} {"level":"info","ts":"2026-09-29T03:37:24.298309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 switched to configuration voters=()"} {"level":"info","ts":"2026-09-29T03:37:24.298335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 became follower at term 0"} {"level":"info","ts":"2026-09-29T03:37:24.298354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d01cde7b2aa18f95 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-29T03:37:24.298369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 became follower at term 1"} {"level":"info","ts":"2026-09-29T03:37:24.298416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 switched to configuration voters=(14996105479814483861)"} {"level":"warn","ts":"2026-09-29T03:37:24.308838Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-29T03:37:24.314601Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-29T03:37:24.318977Z","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-09-29T03:37:24.323588Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d01cde7b2aa18f95","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-29T03:37:24.323859Z","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-09-29T03:37:24.32393Z","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-09-29T03:37:24.323937Z","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-09-29T03:37:24.324097Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d01cde7b2aa18f95","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-29T03:37:24.3246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 switched to configuration voters=(14996105479814483861)"} {"level":"info","ts":"2026-09-29T03:37:24.324886Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9e5d9cb67aed7785","local-member-id":"d01cde7b2aa18f95","added-peer-id":"d01cde7b2aa18f95","added-peer-peer-urls":["https://162.253.55.86:2380"]} {"level":"info","ts":"2026-09-29T03:37:24.334062Z","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-09-29T03:37:24.334285Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d01cde7b2aa18f95","initial-advertise-peer-urls":["https://162.253.55.86:2380"],"listen-peer-urls":["https://162.253.55.86:2380"],"advertise-client-urls":["https://162.253.55.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-29T03:37:24.334309Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-29T03:37:24.334379Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.86:2380"} {"level":"info","ts":"2026-09-29T03:37:24.334386Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.86:2380"} {"level":"info","ts":"2026-09-29T03:37:24.599409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 is starting a new election at term 1"} {"level":"info","ts":"2026-09-29T03:37:24.599598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-29T03:37:24.599666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 received MsgPreVoteResp from d01cde7b2aa18f95 at term 1"} {"level":"info","ts":"2026-09-29T03:37:24.599708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 became candidate at term 2"} {"level":"info","ts":"2026-09-29T03:37:24.599754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 received MsgVoteResp from d01cde7b2aa18f95 at term 2"} {"level":"info","ts":"2026-09-29T03:37:24.599786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 became leader at term 2"} {"level":"info","ts":"2026-09-29T03:37:24.599841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d01cde7b2aa18f95 elected leader d01cde7b2aa18f95 at term 2"} {"level":"info","ts":"2026-09-29T03:37:24.600846Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-29T03:37:24.601655Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d01cde7b2aa18f95","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.86:2379]}","request-path":"/0/members/d01cde7b2aa18f95/attributes","cluster-id":"9e5d9cb67aed7785","publish-timeout":"7s"} {"level":"info","ts":"2026-09-29T03:37:24.601811Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-29T03:37:24.601945Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-29T03:37:24.602154Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-29T03:37:24.602199Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-29T03:37:24.602332Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9e5d9cb67aed7785","local-member-id":"d01cde7b2aa18f95","cluster-version":"3.5"} {"level":"info","ts":"2026-09-29T03:37:24.602506Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-29T03:37:24.602581Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-29T03:37:24.605311Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-29T03:37:24.605837Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.86:2379"} {"level":"info","ts":"2026-09-29T03:37:33.427358Z","caller":"traceutil/trace.go:171","msg":"trace[1839259041] linearizableReadLoop","detail":"{readStateIndex:316; appliedIndex:315; }","duration":"181.302757ms","start":"2026-09-29T03:37:33.246024Z","end":"2026-09-29T03:37:33.427327Z","steps":["trace[1839259041] 'read index received' (duration: 181.273216ms)","trace[1839259041] 'applied index is now lower than readState.Index' (duration: 28.181µs)"],"step_count":2} {"level":"warn","ts":"2026-09-29T03:37:33.427593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.521688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-09-29T03:37:33.427445Z","caller":"traceutil/trace.go:171","msg":"trace[1643710540] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"221.748285ms","start":"2026-09-29T03:37:33.205673Z","end":"2026-09-29T03:37:33.427422Z","steps":["trace[1643710540] 'process raft request' (duration: 221.456322ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:37:33.427778Z","caller":"traceutil/trace.go:171","msg":"trace[2039146853] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:307; }","duration":"181.80965ms","start":"2026-09-29T03:37:33.245944Z","end":"2026-09-29T03:37:33.427754Z","steps":["trace[2039146853] 'agreement among raft nodes before linearized reading' (duration: 181.552818ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:37:33.427996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.281611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-09-29T03:37:33.428033Z","caller":"traceutil/trace.go:171","msg":"trace[1810753953] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:307; }","duration":"127.383042ms","start":"2026-09-29T03:37:33.300641Z","end":"2026-09-29T03:37:33.428024Z","steps":["trace[1810753953] 'agreement among raft nodes before linearized reading' (duration: 127.326601ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:37:33.428028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.935598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-09-29T03:37:33.428113Z","caller":"traceutil/trace.go:171","msg":"trace[201643920] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:307; }","duration":"132.041898ms","start":"2026-09-29T03:37:33.296052Z","end":"2026-09-29T03:37:33.428094Z","steps":["trace[201643920] 'agreement among raft nodes before linearized reading' (duration: 131.874857ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:37:33.946508Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10346352651156212957,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-29T03:37:33.95515Z","caller":"traceutil/trace.go:171","msg":"trace[900166124] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"520.874291ms","start":"2026-09-29T03:37:33.434254Z","end":"2026-09-29T03:37:33.955129Z","steps":["trace[900166124] 'process raft request' (duration: 520.76399ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:37:33.95565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T03:37:33.434235Z","time spent":"520.965542ms","remote":"127.0.0.1:35726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-29T03:37:34.2329Z","caller":"traceutil/trace.go:171","msg":"trace[1867563175] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"796.808806ms","start":"2026-09-29T03:37:33.43606Z","end":"2026-09-29T03:37:34.232869Z","steps":["trace[1867563175] 'process raft request' (duration: 796.563587ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:37:34.233172Z","caller":"traceutil/trace.go:171","msg":"trace[922165409] linearizableReadLoop","detail":"{readStateIndex:320; appliedIndex:317; }","duration":"787.013447ms","start":"2026-09-29T03:37:33.446137Z","end":"2026-09-29T03:37:34.23315Z","steps":["trace[922165409] 'read index received' (duration: 508.991617ms)","trace[922165409] 'applied index is now lower than readState.Index' (duration: 278.02085ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T03:37:34.233188Z","caller":"traceutil/trace.go:171","msg":"trace[2132592510] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"796.863645ms","start":"2026-09-29T03:37:33.436289Z","end":"2026-09-29T03:37:34.233153Z","steps":["trace[2132592510] 'process raft request' (duration: 796.513742ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:37:34.233483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.307068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-09-29T03:37:34.23356Z","caller":"traceutil/trace.go:171","msg":"trace[128817174] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:311; }","duration":"787.409851ms","start":"2026-09-29T03:37:33.446121Z","end":"2026-09-29T03:37:34.233531Z","steps":["trace[128817174] 'agreement among raft nodes before linearized reading' (duration: 787.202334ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:37:34.233602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T03:37:33.446113Z","time spent":"787.479023ms","remote":"127.0.0.1:35846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-09-29T03:37:34.233658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T03:37:33.436259Z","time spent":"797.103783ms","remote":"127.0.0.1:35818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4354,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T03:37:34.233995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T03:37:33.436039Z","time spent":"796.94964ms","remote":"127.0.0.1:35726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2162,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T03:37:34.234032Z","caller":"traceutil/trace.go:171","msg":"trace[1611398297] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"796.71782ms","start":"2026-09-29T03:37:33.437301Z","end":"2026-09-29T03:37:34.234018Z","steps":["trace[1611398297] 'process raft request' (duration: 795.748954ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:37:34.234111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T03:37:33.437262Z","time spent":"796.807702ms","remote":"127.0.0.1:35890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2259,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T03:37:34.23949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.046812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-09-29T03:37:34.239602Z","caller":"traceutil/trace.go:171","msg":"trace[1312424362] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:312; }","duration":"741.210597ms","start":"2026-09-29T03:37:33.498363Z","end":"2026-09-29T03:37:34.239574Z","steps":["trace[1312424362] 'agreement among raft nodes before linearized reading' (duration: 741.017269ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:37:34.239653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T03:37:33.498344Z","time spent":"741.29919ms","remote":"127.0.0.1:35846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":227,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"info","ts":"2026-09-29T03:37:34.240084Z","caller":"traceutil/trace.go:171","msg":"trace[1883575688] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"280.418557ms","start":"2026-09-29T03:37:33.959647Z","end":"2026-09-29T03:37:34.240066Z","steps":["trace[1883575688] 'process raft request' (duration: 279.584776ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:37:34.240532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.292773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-09-29T03:37:34.240564Z","caller":"traceutil/trace.go:171","msg":"trace[1456276235] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:312; }","duration":"593.359384ms","start":"2026-09-29T03:37:33.647194Z","end":"2026-09-29T03:37:34.240554Z","steps":["trace[1456276235] 'agreement among raft nodes before linearized reading' (duration: 593.301232ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:37:34.2406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T03:37:33.647176Z","time spent":"593.411466ms","remote":"127.0.0.1:35846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-09-29T03:37:34.240727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.641995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-09-29T03:37:34.240894Z","caller":"traceutil/trace.go:171","msg":"trace[1438096507] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:312; }","duration":"643.671586ms","start":"2026-09-29T03:37:33.597076Z","end":"2026-09-29T03:37:34.240748Z","steps":["trace[1438096507] 'agreement among raft nodes before linearized reading' (duration: 643.618134ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:37:34.240933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T03:37:33.597018Z","time spent":"643.903703ms","remote":"127.0.0.1:35846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-09-29T03:37:34.241102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.417915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-09-29T03:37:34.241137Z","caller":"traceutil/trace.go:171","msg":"trace[1582235888] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:312; }","duration":"694.494137ms","start":"2026-09-29T03:37:33.546632Z","end":"2026-09-29T03:37:34.241126Z","steps":["trace[1582235888] 'agreement among raft nodes before linearized reading' (duration: 694.427305ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:37:34.241162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T03:37:33.546609Z","time spent":"694.545528ms","remote":"127.0.0.1:35846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":258,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"info","ts":"2026-09-29T03:38:04.117934Z","caller":"traceutil/trace.go:171","msg":"trace[1666018456] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"133.522461ms","start":"2026-09-29T03:38:03.984388Z","end":"2026-09-29T03:38:04.11791Z","steps":["trace[1666018456] 'process raft request' (duration: 129.263274ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:38:04.119431Z","caller":"traceutil/trace.go:171","msg":"trace[585976714] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"127.424023ms","start":"2026-09-29T03:38:03.991985Z","end":"2026-09-29T03:38:04.119409Z","steps":["trace[585976714] 'process raft request' (duration: 127.141694ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:38:04.304773Z","caller":"traceutil/trace.go:171","msg":"trace[800654548] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"143.560531ms","start":"2026-09-29T03:38:04.161195Z","end":"2026-09-29T03:38:04.304756Z","steps":["trace[800654548] 'process raft request' (duration: 135.279214ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:38:04.304862Z","caller":"traceutil/trace.go:171","msg":"trace[1605390168] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"129.561629ms","start":"2026-09-29T03:38:04.175293Z","end":"2026-09-29T03:38:04.304855Z","steps":["trace[1605390168] 'process raft request' (duration: 129.143178ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:38:04.304989Z","caller":"traceutil/trace.go:171","msg":"trace[671940969] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"122.731932ms","start":"2026-09-29T03:38:04.182234Z","end":"2026-09-29T03:38:04.304965Z","steps":["trace[671940969] 'process raft request' (duration: 122.27606ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:38:08.817049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.50128ms","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-09-29T03:38:08.817174Z","caller":"traceutil/trace.go:171","msg":"trace[1642271621] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:586; }","duration":"315.646055ms","start":"2026-09-29T03:38:08.501511Z","end":"2026-09-29T03:38:08.817157Z","steps":["trace[1642271621] 'range keys from in-memory index tree' (duration: 315.48085ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:38:08.817489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.701743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-29T03:38:08.817584Z","caller":"traceutil/trace.go:171","msg":"trace[188016512] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"253.08548ms","start":"2026-09-29T03:38:08.564481Z","end":"2026-09-29T03:38:08.817567Z","steps":["trace[188016512] 'read index received' (duration: 143.09565ms)","trace[188016512] 'applied index is now lower than readState.Index' (duration: 109.98878ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T03:38:08.81764Z","caller":"traceutil/trace.go:171","msg":"trace[1332412517] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"344.992345ms","start":"2026-09-29T03:38:08.472614Z","end":"2026-09-29T03:38:08.817606Z","steps":["trace[1332412517] 'process raft request' (duration: 234.909892ms)","trace[1332412517] 'compare' (duration: 109.552289ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T03:38:08.817739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.251444ms","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-09-29T03:38:08.817767Z","caller":"traceutil/trace.go:171","msg":"trace[1139581617] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:587; }","duration":"253.323366ms","start":"2026-09-29T03:38:08.564434Z","end":"2026-09-29T03:38:08.817757Z","steps":["trace[1139581617] 'agreement among raft nodes before linearized reading' (duration: 253.197302ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:38:08.817798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T03:38:08.472543Z","time spent":"345.17067ms","remote":"127.0.0.1:35902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T03:38:08.825729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.459634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-29T03:38:08.825754Z","caller":"traceutil/trace.go:171","msg":"trace[1013649898] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"161.837693ms","start":"2026-09-29T03:38:08.663896Z","end":"2026-09-29T03:38:08.825733Z","steps":["trace[1013649898] 'process raft request' (duration: 161.304578ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:38:08.825789Z","caller":"traceutil/trace.go:171","msg":"trace[1742982836] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:588; }","duration":"236.545566ms","start":"2026-09-29T03:38:08.589229Z","end":"2026-09-29T03:38:08.825775Z","steps":["trace[1742982836] 'agreement among raft nodes before linearized reading' (duration: 236.437583ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:38:10.430505Z","caller":"traceutil/trace.go:171","msg":"trace[310065308] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"127.040315ms","start":"2026-09-29T03:38:10.303446Z","end":"2026-09-29T03:38:10.430486Z","steps":["trace[310065308] 'process raft request' (duration: 126.612194ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:38:11.196224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.301844ms","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-09-29T03:38:11.196319Z","caller":"traceutil/trace.go:171","msg":"trace[398032494] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:602; }","duration":"365.431926ms","start":"2026-09-29T03:38:10.830866Z","end":"2026-09-29T03:38:11.196298Z","steps":["trace[398032494] 'range keys from in-memory index tree' (duration: 365.121618ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:38:11.19639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.64365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-29T03:38:11.196416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T03:38:10.830856Z","time spent":"365.54731ms","remote":"127.0.0.1:35902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-29T03:38:11.196512Z","caller":"traceutil/trace.go:171","msg":"trace[1448505851] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:602; }","duration":"363.787894ms","start":"2026-09-29T03:38:10.832699Z","end":"2026-09-29T03:38:11.196487Z","steps":["trace[1448505851] 'range keys from in-memory index tree' (duration: 363.484577ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:38:11.196545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.956105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dp2lg\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-29T03:38:11.196637Z","caller":"traceutil/trace.go:171","msg":"trace[1194400252] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dp2lg; range_end:; response_count:1; response_revision:602; }","duration":"277.069878ms","start":"2026-09-29T03:38:10.919543Z","end":"2026-09-29T03:38:11.196613Z","steps":["trace[1194400252] 'range keys from in-memory index tree' (duration: 276.770091ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:38:11.19657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T03:38:10.832689Z","time spent":"363.868226ms","remote":"127.0.0.1:35902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-29T03:38:11.19755Z","caller":"traceutil/trace.go:171","msg":"trace[1437512185] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"156.013045ms","start":"2026-09-29T03:38:11.041213Z","end":"2026-09-29T03:38:11.197226Z","steps":["trace[1437512185] 'process raft request' (duration: 151.666513ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:38:11.196245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.746736ms","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-09-29T03:38:11.198586Z","caller":"traceutil/trace.go:171","msg":"trace[2118792481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:602; }","duration":"377.095016ms","start":"2026-09-29T03:38:10.821392Z","end":"2026-09-29T03:38:11.198487Z","steps":["trace[2118792481] 'range keys from in-memory index tree' (duration: 374.536011ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:38:11.198669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T03:38:10.821373Z","time spent":"377.26384ms","remote":"127.0.0.1:35902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}