{"level":"warn","ts":"2026-03-09T01:33:42.666056Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-09T01:33:42.666131Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.140: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.140:2380","--initial-cluster=instance=https://162.253.55.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.140: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-03-09T01:33:42.666189Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-09T01:33:42.666204Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.140:2380"]} {"level":"info","ts":"2026-03-09T01:33:42.66622Z","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-03-09T01:33:42.666818Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"]} {"level":"info","ts":"2026-03-09T01:33:42.666883Z","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.140:2380"],"listen-peer-urls":["https://162.253.55.140:2380"],"advertise-client-urls":["https://162.253.55.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.140: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-03-09T01:33:42.670698Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.626175ms"} {"level":"info","ts":"2026-03-09T01:33:42.679197Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cf809824ab95ac81","cluster-id":"6f89f5fbef18dc61"} {"level":"info","ts":"2026-03-09T01:33:42.679297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=()"} {"level":"info","ts":"2026-03-09T01:33:42.679326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became follower at term 0"} {"level":"info","ts":"2026-03-09T01:33:42.679348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cf809824ab95ac81 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-09T01:33:42.6794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became follower at term 1"} {"level":"info","ts":"2026-03-09T01:33:42.679455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=(14952118046135004289)"} {"level":"warn","ts":"2026-03-09T01:33:42.691508Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-09T01:33:42.692743Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-09T01:33:42.693854Z","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-03-09T01:33:42.696234Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cf809824ab95ac81","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-09T01:33:42.696368Z","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-03-09T01:33:42.696451Z","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-03-09T01:33:42.69646Z","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-03-09T01:33:42.696769Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cf809824ab95ac81","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-09T01:33:42.698926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 switched to configuration voters=(14952118046135004289)"} {"level":"info","ts":"2026-03-09T01:33:42.699652Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6f89f5fbef18dc61","local-member-id":"cf809824ab95ac81","added-peer-id":"cf809824ab95ac81","added-peer-peer-urls":["https://162.253.55.140:2380"]} {"level":"info","ts":"2026-03-09T01:33:42.701906Z","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-03-09T01:33:42.702151Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cf809824ab95ac81","initial-advertise-peer-urls":["https://162.253.55.140:2380"],"listen-peer-urls":["https://162.253.55.140:2380"],"advertise-client-urls":["https://162.253.55.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-09T01:33:42.702194Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-09T01:33:42.702279Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.140:2380"} {"level":"info","ts":"2026-03-09T01:33:42.702303Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.140:2380"} {"level":"info","ts":"2026-03-09T01:33:43.579926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 is starting a new election at term 1"} {"level":"info","ts":"2026-03-09T01:33:43.579996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-09T01:33:43.580044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 received MsgPreVoteResp from cf809824ab95ac81 at term 1"} {"level":"info","ts":"2026-03-09T01:33:43.580076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became candidate at term 2"} {"level":"info","ts":"2026-03-09T01:33:43.580092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 received MsgVoteResp from cf809824ab95ac81 at term 2"} {"level":"info","ts":"2026-03-09T01:33:43.580112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf809824ab95ac81 became leader at term 2"} {"level":"info","ts":"2026-03-09T01:33:43.58013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cf809824ab95ac81 elected leader cf809824ab95ac81 at term 2"} {"level":"info","ts":"2026-03-09T01:33:43.581081Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cf809824ab95ac81","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.140:2379]}","request-path":"/0/members/cf809824ab95ac81/attributes","cluster-id":"6f89f5fbef18dc61","publish-timeout":"7s"} {"level":"info","ts":"2026-03-09T01:33:43.581135Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-09T01:33:43.581192Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-09T01:33:43.581197Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T01:33:43.581366Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-09T01:33:43.581446Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-09T01:33:43.58198Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6f89f5fbef18dc61","local-member-id":"cf809824ab95ac81","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T01:33:43.582138Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T01:33:43.582176Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T01:33:43.584353Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-09T01:33:43.584738Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.140:2379"} {"level":"info","ts":"2026-03-09T01:33:48.149239Z","caller":"traceutil/trace.go:171","msg":"trace[631605996] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"133.692761ms","start":"2026-03-09T01:33:48.015509Z","end":"2026-03-09T01:33:48.149201Z","steps":["trace[631605996] 'process raft request' (duration: 133.465921ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:49.144316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.305731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T01:33:49.144371Z","caller":"traceutil/trace.go:171","msg":"trace[205276470] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:222; }","duration":"445.370314ms","start":"2026-03-09T01:33:48.698985Z","end":"2026-03-09T01:33:49.144355Z","steps":["trace[205276470] 'range keys from in-memory index tree' (duration: 445.26766ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:49.144383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.929032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-09T01:33:49.1444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:48.698981Z","time spent":"445.413086ms","remote":"127.0.0.1:35744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-03-09T01:33:49.144452Z","caller":"traceutil/trace.go:171","msg":"trace[1518349041] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:222; }","duration":"678.036657ms","start":"2026-03-09T01:33:48.466399Z","end":"2026-03-09T01:33:49.144435Z","steps":["trace[1518349041] 'range keys from in-memory index tree' (duration: 677.832178ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:49.144497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:48.466378Z","time spent":"678.107821ms","remote":"127.0.0.1:35744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-09T01:33:49.14426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.357693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-03-09T01:33:49.144656Z","caller":"traceutil/trace.go:171","msg":"trace[2125401814] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:222; }","duration":"445.794322ms","start":"2026-03-09T01:33:48.698845Z","end":"2026-03-09T01:33:49.144639Z","steps":["trace[2125401814] 'range keys from in-memory index tree' (duration: 445.28601ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:49.144655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.164433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T01:33:49.144781Z","caller":"traceutil/trace.go:171","msg":"trace[314885125] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:222; }","duration":"520.31233ms","start":"2026-03-09T01:33:48.624419Z","end":"2026-03-09T01:33:49.144732Z","steps":["trace[314885125] 'range keys from in-memory index tree' (duration: 520.09275ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:49.144691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:48.69883Z","time spent":"445.852225ms","remote":"127.0.0.1:35850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-03-09T01:33:49.144846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:48.6244Z","time spent":"520.435214ms","remote":"127.0.0.1:35908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" "} {"level":"warn","ts":"2026-03-09T01:33:49.14517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.312325ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-03-09T01:33:49.145306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:48.701255Z","time spent":"444.044664ms","remote":"127.0.0.1:50714","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-09T01:33:49.196339Z","caller":"traceutil/trace.go:171","msg":"trace[467398657] transaction","detail":"{read_only:false; number_of_response:0; response_revision:222; }","duration":"395.678212ms","start":"2026-03-09T01:33:48.800636Z","end":"2026-03-09T01:33:49.196314Z","steps":["trace[467398657] 'process raft request' (duration: 395.589368ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:33:49.196384Z","caller":"traceutil/trace.go:171","msg":"trace[714376773] linearizableReadLoop","detail":"{readStateIndex:229; appliedIndex:228; }","duration":"227.238171ms","start":"2026-03-09T01:33:48.969062Z","end":"2026-03-09T01:33:49.196301Z","steps":["trace[714376773] 'read index received' (duration: 227.184988ms)","trace[714376773] 'applied index is now lower than readState.Index' (duration: 51.863µs)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:33:49.196519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.4471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-09T01:33:49.196601Z","caller":"traceutil/trace.go:171","msg":"trace[2053898205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:222; }","duration":"227.568805ms","start":"2026-03-09T01:33:48.969013Z","end":"2026-03-09T01:33:49.196582Z","steps":["trace[2053898205] 'agreement among raft nodes before linearized reading' (duration: 227.383927ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:49.197104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:48.800616Z","time spent":"395.794308ms","remote":"127.0.0.1:50820","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-03-09T01:33:49.231847Z","caller":"traceutil/trace.go:171","msg":"trace[1858620911] transaction","detail":"{read_only:false; response_revision:223; number_of_response:1; }","duration":"220.623889ms","start":"2026-03-09T01:33:49.0112Z","end":"2026-03-09T01:33:49.231824Z","steps":["trace[1858620911] 'process raft request' (duration: 220.470242ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:33:49.231998Z","caller":"traceutil/trace.go:171","msg":"trace[2002550558] transaction","detail":"{read_only:false; number_of_response:0; response_revision:225; }","duration":"220.566078ms","start":"2026-03-09T01:33:49.011423Z","end":"2026-03-09T01:33:49.231989Z","steps":["trace[2002550558] 'process raft request' (duration: 220.41216ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:33:49.232026Z","caller":"traceutil/trace.go:171","msg":"trace[268937205] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"220.669112ms","start":"2026-03-09T01:33:49.011301Z","end":"2026-03-09T01:33:49.23197Z","steps":["trace[268937205] 'process raft request' (duration: 220.500954ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:33:49.232068Z","caller":"traceutil/trace.go:171","msg":"trace[97915348] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"219.587044ms","start":"2026-03-09T01:33:49.012435Z","end":"2026-03-09T01:33:49.232022Z","steps":["trace[97915348] 'process raft request' (duration: 219.448277ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:33:49.232165Z","caller":"traceutil/trace.go:171","msg":"trace[2082827229] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"220.869471ms","start":"2026-03-09T01:33:49.011285Z","end":"2026-03-09T01:33:49.232155Z","steps":["trace[2082827229] 'process raft request' (duration: 220.487643ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:49.232482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.086414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T01:33:49.232526Z","caller":"traceutil/trace.go:171","msg":"trace[1312831633] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:228; }","duration":"261.203358ms","start":"2026-03-09T01:33:48.971311Z","end":"2026-03-09T01:33:49.232514Z","steps":["trace[1312831633] 'agreement among raft nodes before linearized reading' (duration: 261.126605ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:33:49.341238Z","caller":"traceutil/trace.go:171","msg":"trace[778046002] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"103.306857ms","start":"2026-03-09T01:33:49.237901Z","end":"2026-03-09T01:33:49.341208Z","steps":["trace[778046002] 'process raft request' (duration: 34.663129ms)","trace[778046002] 'compare' (duration: 68.424287ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:33:49.34127Z","caller":"traceutil/trace.go:171","msg":"trace[246826123] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"103.294396ms","start":"2026-03-09T01:33:49.237945Z","end":"2026-03-09T01:33:49.341239Z","steps":["trace[246826123] 'process raft request' (duration: 103.203191ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:33:49.341409Z","caller":"traceutil/trace.go:171","msg":"trace[955757384] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"103.223573ms","start":"2026-03-09T01:33:49.238128Z","end":"2026-03-09T01:33:49.341352Z","steps":["trace[955757384] 'process raft request' (duration: 103.100998ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:33:49.341451Z","caller":"traceutil/trace.go:171","msg":"trace[1070500399] linearizableReadLoop","detail":"{readStateIndex:242; appliedIndex:237; }","duration":"102.658578ms","start":"2026-03-09T01:33:49.238772Z","end":"2026-03-09T01:33:49.341431Z","steps":["trace[1070500399] 'read index received' (duration: 33.670964ms)","trace[1070500399] 'applied index is now lower than readState.Index' (duration: 68.986483ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:33:49.341636Z","caller":"traceutil/trace.go:171","msg":"trace[1442646908] transaction","detail":"{read_only:false; response_revision:232; number_of_response:1; }","duration":"103.60863ms","start":"2026-03-09T01:33:49.238005Z","end":"2026-03-09T01:33:49.341614Z","steps":["trace[1442646908] 'process raft request' (duration: 103.192831ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:49.341764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.046239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T01:33:49.341808Z","caller":"traceutil/trace.go:171","msg":"trace[16329382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:234; }","duration":"104.133833ms","start":"2026-03-09T01:33:49.237664Z","end":"2026-03-09T01:33:49.341798Z","steps":["trace[16329382] 'agreement among raft nodes before linearized reading' (duration: 103.960205ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:49.34195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.85455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"warn","ts":"2026-03-09T01:33:49.342057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.108548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-03-09T01:33:49.342107Z","caller":"traceutil/trace.go:171","msg":"trace[673005040] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:234; }","duration":"101.185902ms","start":"2026-03-09T01:33:49.240908Z","end":"2026-03-09T01:33:49.342094Z","steps":["trace[673005040] 'agreement among raft nodes before linearized reading' (duration: 101.103708ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:33:49.341552Z","caller":"traceutil/trace.go:171","msg":"trace[55480794] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"103.051265ms","start":"2026-03-09T01:33:49.238489Z","end":"2026-03-09T01:33:49.341541Z","steps":["trace[55480794] 'process raft request' (duration: 102.776363ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:33:49.342058Z","caller":"traceutil/trace.go:171","msg":"trace[1049335012] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:234; }","duration":"107.997776ms","start":"2026-03-09T01:33:49.234036Z","end":"2026-03-09T01:33:49.342034Z","steps":["trace[1049335012] 'agreement among raft nodes before linearized reading' (duration: 107.62803ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:49.341988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.216077ms","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-03-09T01:33:49.342247Z","caller":"traceutil/trace.go:171","msg":"trace[930449611] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:234; }","duration":"104.485509ms","start":"2026-03-09T01:33:49.237752Z","end":"2026-03-09T01:33:49.342238Z","steps":["trace[930449611] 'agreement among raft nodes before linearized reading' (duration: 104.194916ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:33:52.40062Z","caller":"traceutil/trace.go:171","msg":"trace[969705033] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"305.807084ms","start":"2026-03-09T01:33:52.094791Z","end":"2026-03-09T01:33:52.400598Z","steps":["trace[969705033] 'process raft request' (duration: 305.683909ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:52.400781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:52.094758Z","time spent":"305.945919ms","remote":"127.0.0.1:50862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-09T01:33:54.61857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.952053ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-09T01:33:54.618623Z","caller":"traceutil/trace.go:171","msg":"trace[239336577] linearizableReadLoop","detail":"{readStateIndex:316; appliedIndex:315; }","duration":"825.087786ms","start":"2026-03-09T01:33:53.793527Z","end":"2026-03-09T01:33:54.618615Z","steps":["trace[239336577] 'read index received' (duration: 360.019078ms)","trace[239336577] 'applied index is now lower than readState.Index' (duration: 465.068098ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:33:54.618683Z","caller":"traceutil/trace.go:171","msg":"trace[1161534184] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"862.259106ms","start":"2026-03-09T01:33:53.756419Z","end":"2026-03-09T01:33:54.618678Z","steps":["trace[1161534184] 'process raft request' (duration: 397.168777ms)","trace[1161534184] 'compare' (duration: 464.729124ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:33:54.618707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:53.756396Z","time spent":"862.298378ms","remote":"127.0.0.1:50808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-09T01:33:54.618809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.291135ms","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-03-09T01:33:54.618824Z","caller":"traceutil/trace.go:171","msg":"trace[1147043369] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:303; }","duration":"825.344617ms","start":"2026-03-09T01:33:53.793473Z","end":"2026-03-09T01:33:54.618817Z","steps":["trace[1147043369] 'agreement among raft nodes before linearized reading' (duration: 825.308415ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:54.618836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:53.79344Z","time spent":"825.393419ms","remote":"127.0.0.1:50862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-03-09T01:33:54.618902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.050759ms","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-03-09T01:33:54.618913Z","caller":"traceutil/trace.go:171","msg":"trace[1885540868] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:303; }","duration":"776.090641ms","start":"2026-03-09T01:33:53.842819Z","end":"2026-03-09T01:33:54.618909Z","steps":["trace[1885540868] 'agreement among raft nodes before linearized reading' (duration: 776.07061ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:54.618923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:53.842802Z","time spent":"776.118932ms","remote":"127.0.0.1:50862","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-03-09T01:33:54.619061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.233246ms","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-03-09T01:33:54.619074Z","caller":"traceutil/trace.go:171","msg":"trace[556653881] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:303; }","duration":"375.277608ms","start":"2026-03-09T01:33:54.243793Z","end":"2026-03-09T01:33:54.61907Z","steps":["trace[556653881] 'agreement among raft nodes before linearized reading' (duration: 375.255416ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:54.619086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:54.243772Z","time spent":"375.31124ms","remote":"127.0.0.1:50862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-03-09T01:33:54.619146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.760442ms","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-03-09T01:33:54.619157Z","caller":"traceutil/trace.go:171","msg":"trace[200532590] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:303; }","duration":"575.793574ms","start":"2026-03-09T01:33:54.04336Z","end":"2026-03-09T01:33:54.619153Z","steps":["trace[200532590] 'agreement among raft nodes before linearized reading' (duration: 575.771213ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:54.619169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:54.043349Z","time spent":"575.817104ms","remote":"127.0.0.1:50862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-03-09T01:33:54.61922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.544252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-03-09T01:33:54.619204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.304757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-03-09T01:33:54.619255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.349713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-03-09T01:33:54.619261Z","caller":"traceutil/trace.go:171","msg":"trace[509006070] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:303; }","duration":"525.447833ms","start":"2026-03-09T01:33:54.093801Z","end":"2026-03-09T01:33:54.619249Z","steps":["trace[509006070] 'agreement among raft nodes before linearized reading' (duration: 525.302617ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:54.619288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.027377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-03-09T01:33:54.619293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:54.093777Z","time spent":"525.511975ms","remote":"127.0.0.1:50862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"info","ts":"2026-03-09T01:33:54.619306Z","caller":"traceutil/trace.go:171","msg":"trace[1929433317] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:303; }","duration":"725.044258ms","start":"2026-03-09T01:33:53.894257Z","end":"2026-03-09T01:33:54.619301Z","steps":["trace[1929433317] 'agreement among raft nodes before linearized reading' (duration: 725.020917ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:54.619319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:53.894187Z","time spent":"725.128442ms","remote":"127.0.0.1:50862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-03-09T01:33:54.619719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.500129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"warn","ts":"2026-03-09T01:33:54.619729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.114245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"info","ts":"2026-03-09T01:33:54.61974Z","caller":"traceutil/trace.go:171","msg":"trace[2116894129] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:303; }","duration":"466.5404ms","start":"2026-03-09T01:33:54.153195Z","end":"2026-03-09T01:33:54.619736Z","steps":["trace[2116894129] 'agreement among raft nodes before linearized reading' (duration: 466.501319ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:33:54.619749Z","caller":"traceutil/trace.go:171","msg":"trace[916985208] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:303; }","duration":"467.159307ms","start":"2026-03-09T01:33:54.152583Z","end":"2026-03-09T01:33:54.619743Z","steps":["trace[916985208] 'agreement among raft nodes before linearized reading' (duration: 467.116435ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:54.619754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:54.153184Z","time spent":"466.567172ms","remote":"127.0.0.1:50836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7326,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-03-09T01:33:54.619766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:54.152576Z","time spent":"467.186477ms","remote":"127.0.0.1:50836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5359,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-03-09T01:33:54.619776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.653156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-03-09T01:33:54.619836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.188487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"warn","ts":"2026-03-09T01:33:54.619843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.52155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4663"} {"level":"info","ts":"2026-03-09T01:33:54.619852Z","caller":"traceutil/trace.go:171","msg":"trace[481054168] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:303; }","duration":"467.220409ms","start":"2026-03-09T01:33:54.152627Z","end":"2026-03-09T01:33:54.619847Z","steps":["trace[481054168] 'agreement among raft nodes before linearized reading' (duration: 467.177887ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:33:54.619841Z","caller":"traceutil/trace.go:171","msg":"trace[76943637] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:303; }","duration":"475.768251ms","start":"2026-03-09T01:33:54.144058Z","end":"2026-03-09T01:33:54.619826Z","steps":["trace[76943637] 'agreement among raft nodes before linearized reading' (duration: 475.630135ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:54.61987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:54.152617Z","time spent":"467.248751ms","remote":"127.0.0.1:50836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6988,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-03-09T01:33:54.619879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.633254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-03-09T01:33:54.61989Z","caller":"traceutil/trace.go:171","msg":"trace[129996099] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:303; }","duration":"466.657006ms","start":"2026-03-09T01:33:54.15323Z","end":"2026-03-09T01:33:54.619887Z","steps":["trace[129996099] 'agreement among raft nodes before linearized reading' (duration: 466.637525ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:54.619885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:54.144033Z","time spent":"475.845314ms","remote":"127.0.0.1:50862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-03-09T01:33:54.619902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:54.153225Z","time spent":"466.673906ms","remote":"127.0.0.1:50836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-03-09T01:33:54.619235Z","caller":"traceutil/trace.go:171","msg":"trace[1440853289] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:303; }","duration":"625.607145ms","start":"2026-03-09T01:33:53.993624Z","end":"2026-03-09T01:33:54.619231Z","steps":["trace[1440853289] 'agreement among raft nodes before linearized reading' (duration: 625.583394ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:54.619957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:53.9936Z","time spent":"626.354426ms","remote":"127.0.0.1:50862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":241,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"info","ts":"2026-03-09T01:33:54.619269Z","caller":"traceutil/trace.go:171","msg":"trace[1448085754] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:303; }","duration":"675.400535ms","start":"2026-03-09T01:33:53.943864Z","end":"2026-03-09T01:33:54.619264Z","steps":["trace[1448085754] 'agreement among raft nodes before linearized reading' (duration: 675.379545ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:54.619991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:53.943843Z","time spent":"676.146046ms","remote":"127.0.0.1:50862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"info","ts":"2026-03-09T01:33:54.619858Z","caller":"traceutil/trace.go:171","msg":"trace[1524818627] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:303; }","duration":"466.573452ms","start":"2026-03-09T01:33:54.15328Z","end":"2026-03-09T01:33:54.619853Z","steps":["trace[1524818627] 'agreement among raft nodes before linearized reading' (duration: 466.546711ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:54.620038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:54.153259Z","time spent":"466.776141ms","remote":"127.0.0.1:50836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4686,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-03-09T01:33:54.620078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.692808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-03-09T01:33:54.620088Z","caller":"traceutil/trace.go:171","msg":"trace[2006143191] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:303; }","duration":"467.72428ms","start":"2026-03-09T01:33:54.15236Z","end":"2026-03-09T01:33:54.620084Z","steps":["trace[2006143191] 'agreement among raft nodes before linearized reading' (duration: 467.703909ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:54.620098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:54.15235Z","time spent":"467.746321ms","remote":"127.0.0.1:35850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-03-09T01:33:54.620382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.241077ms","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-03-09T01:33:54.620401Z","caller":"traceutil/trace.go:171","msg":"trace[888799976] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:303; }","duration":"427.278349ms","start":"2026-03-09T01:33:54.193118Z","end":"2026-03-09T01:33:54.620396Z","steps":["trace[888799976] 'agreement among raft nodes before linearized reading' (duration: 426.331199ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:54.620414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:54.193098Z","time spent":"427.313191ms","remote":"127.0.0.1:50862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-03-09T01:33:55.131032Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12430388864668375520,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-09T01:33:55.603037Z","caller":"traceutil/trace.go:171","msg":"trace[718282866] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"975.744604ms","start":"2026-03-09T01:33:54.627274Z","end":"2026-03-09T01:33:55.603019Z","steps":["trace[718282866] 'process raft request' (duration: 975.63842ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:55.603267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:54.627262Z","time spent":"975.827168ms","remote":"127.0.0.1:50836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T01:33:56.22689Z","caller":"traceutil/trace.go:171","msg":"trace[1406746248] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"1.594336584s","start":"2026-03-09T01:33:54.632525Z","end":"2026-03-09T01:33:56.226862Z","steps":["trace[1406746248] 'process raft request' (duration: 1.593574413s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:56.227044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:54.632505Z","time spent":"1.594458489s","remote":"127.0.0.1:35930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-09T01:33:56.227243Z","caller":"traceutil/trace.go:171","msg":"trace[891127035] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"1.594346785s","start":"2026-03-09T01:33:54.632886Z","end":"2026-03-09T01:33:56.227233Z","steps":["trace[891127035] 'process raft request' (duration: 1.593923577s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:56.227291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:54.632879Z","time spent":"1.594386857s","remote":"127.0.0.1:50862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-09T01:33:56.227451Z","caller":"traceutil/trace.go:171","msg":"trace[547350851] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"1.594524733s","start":"2026-03-09T01:33:54.632911Z","end":"2026-03-09T01:33:56.227435Z","steps":["trace[547350851] 'process raft request' (duration: 1.594156067s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:56.227862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:54.632896Z","time spent":"1.594855457s","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T01:33:56.22806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.59757727s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-03-09T01:33:56.228102Z","caller":"traceutil/trace.go:171","msg":"trace[1101928923] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:313; }","duration":"1.597646743s","start":"2026-03-09T01:33:54.630446Z","end":"2026-03-09T01:33:56.228093Z","steps":["trace[1101928923] 'agreement among raft nodes before linearized reading' (duration: 1.597551289s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:56.228133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:54.63044Z","time spent":"1.597684755s","remote":"127.0.0.1:50820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4392,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-03-09T01:33:56.22815Z","caller":"traceutil/trace.go:171","msg":"trace[181448074] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"1.59472508s","start":"2026-03-09T01:33:54.633416Z","end":"2026-03-09T01:33:56.228141Z","steps":["trace[181448074] 'process raft request' (duration: 1.594186318s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:56.228212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:54.633405Z","time spent":"1.594773121s","remote":"127.0.0.1:50820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4355,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T01:33:56.227935Z","caller":"traceutil/trace.go:171","msg":"trace[715522793] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"1.594335124s","start":"2026-03-09T01:33:54.633587Z","end":"2026-03-09T01:33:56.227922Z","steps":["trace[715522793] 'process raft request' (duration: 1.594182867s)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:33:56.228459Z","caller":"traceutil/trace.go:171","msg":"trace[1202791660] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"1.593631983s","start":"2026-03-09T01:33:54.634806Z","end":"2026-03-09T01:33:56.228438Z","steps":["trace[1202791660] 'process raft request' (duration: 1.593004357s)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:33:56.228466Z","caller":"traceutil/trace.go:171","msg":"trace[282915622] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"927.031413ms","start":"2026-03-09T01:33:55.301416Z","end":"2026-03-09T01:33:56.228447Z","steps":["trace[282915622] 'process raft request' (duration: 926.46236ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:56.228426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:54.633558Z","time spent":"1.594770791s","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T01:33:56.228558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:54.634799Z","time spent":"1.593705517s","remote":"127.0.0.1:50744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-09T01:33:56.22859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:55.3014Z","time spent":"927.131038ms","remote":"127.0.0.1:35744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T01:33:56.227952Z","caller":"traceutil/trace.go:171","msg":"trace[866653934] linearizableReadLoop","detail":"{readStateIndex:325; appliedIndex:316; }","duration":"1.597467056s","start":"2026-03-09T01:33:54.630463Z","end":"2026-03-09T01:33:56.22793Z","steps":["trace[866653934] 'read index received' (duration: 972.525988ms)","trace[866653934] 'applied index is now lower than readState.Index' (duration: 624.939968ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:33:56.22797Z","caller":"traceutil/trace.go:171","msg":"trace[391638612] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"1.594651426s","start":"2026-03-09T01:33:54.633311Z","end":"2026-03-09T01:33:56.227963Z","steps":["trace[391638612] 'process raft request' (duration: 1.59401251s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:56.230019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:54.633303Z","time spent":"1.59667315s","remote":"127.0.0.1:35808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T01:33:56.230162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.597212634s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-03-09T01:33:56.230106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.599600354s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-03-09T01:33:56.228911Z","caller":"traceutil/trace.go:171","msg":"trace[802345982] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"1.593682835s","start":"2026-03-09T01:33:54.635213Z","end":"2026-03-09T01:33:56.228896Z","steps":["trace[802345982] 'process raft request' (duration: 1.592629031s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:56.230253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.597391922s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-03-09T01:33:56.230376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.11133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-09T01:33:56.230459Z","caller":"traceutil/trace.go:171","msg":"trace[717881356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:313; }","duration":"477.276047ms","start":"2026-03-09T01:33:55.753173Z","end":"2026-03-09T01:33:56.230449Z","steps":["trace[717881356] 'agreement among raft nodes before linearized reading' (duration: 477.11603ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:56.230454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:54.635204Z","time spent":"1.595135546s","remote":"127.0.0.1:35730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1251,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T01:33:56.230479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.428684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"warn","ts":"2026-03-09T01:33:56.230493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:55.753159Z","time spent":"477.325389ms","remote":"127.0.0.1:35744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-09T01:33:56.230496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.162765ms","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-03-09T01:33:56.230539Z","caller":"traceutil/trace.go:171","msg":"trace[690460570] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:313; }","duration":"623.519058ms","start":"2026-03-09T01:33:55.606986Z","end":"2026-03-09T01:33:56.230505Z","steps":["trace[690460570] 'agreement among raft nodes before linearized reading' (duration: 623.461695ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:33:56.230427Z","caller":"traceutil/trace.go:171","msg":"trace[1130009758] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:313; }","duration":"1.59759243s","start":"2026-03-09T01:33:54.632821Z","end":"2026-03-09T01:33:56.230414Z","steps":["trace[1130009758] 'agreement among raft nodes before linearized reading' (duration: 1.597325249s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:56.230573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:55.606971Z","time spent":"623.595591ms","remote":"127.0.0.1:50836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-03-09T01:33:56.230567Z","caller":"traceutil/trace.go:171","msg":"trace[279823258] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:313; }","duration":"791.300122ms","start":"2026-03-09T01:33:55.43925Z","end":"2026-03-09T01:33:56.23055Z","steps":["trace[279823258] 'agreement among raft nodes before linearized reading' (duration: 791.148865ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:56.230619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.597888802s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"warn","ts":"2026-03-09T01:33:56.23062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:55.439231Z","time spent":"791.379344ms","remote":"127.0.0.1:35744","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-03-09T01:33:56.230641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.968445ms","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-03-09T01:33:56.23065Z","caller":"traceutil/trace.go:171","msg":"trace[1173897108] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:313; }","duration":"1.597923563s","start":"2026-03-09T01:33:54.632715Z","end":"2026-03-09T01:33:56.230639Z","steps":["trace[1173897108] 'agreement among raft nodes before linearized reading' (duration: 1.597868361s)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:33:56.230667Z","caller":"traceutil/trace.go:171","msg":"trace[250247011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:313; }","duration":"935.037907ms","start":"2026-03-09T01:33:55.295622Z","end":"2026-03-09T01:33:56.23066Z","steps":["trace[250247011] 'agreement among raft nodes before linearized reading' (duration: 934.986645ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:56.230677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:54.632712Z","time spent":"1.597959705s","remote":"127.0.0.1:50820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4392,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-09T01:33:56.230707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:55.295602Z","time spent":"935.09855ms","remote":"127.0.0.1:35744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-09T01:33:56.230663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:54.632814Z","time spent":"1.597780817s","remote":"127.0.0.1:50862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-03-09T01:33:56.230423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.446595ms","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-03-09T01:33:56.230721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.597799018s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"warn","ts":"2026-03-09T01:33:56.230791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.598077349s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-03-09T01:33:56.230817Z","caller":"traceutil/trace.go:171","msg":"trace[608571180] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:313; }","duration":"1.598111401s","start":"2026-03-09T01:33:54.632698Z","end":"2026-03-09T01:33:56.230809Z","steps":["trace[608571180] 'agreement among raft nodes before linearized reading' (duration: 1.59807238s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:56.230832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.598145163s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-03-09T01:33:56.230823Z","caller":"traceutil/trace.go:171","msg":"trace[2100267855] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:313; }","duration":"1.597919803s","start":"2026-03-09T01:33:54.632876Z","end":"2026-03-09T01:33:56.230796Z","steps":["trace[2100267855] 'agreement among raft nodes before linearized reading' (duration: 1.597704904s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:56.23084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:54.632694Z","time spent":"1.598140792s","remote":"127.0.0.1:50820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4392,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-03-09T01:33:56.230854Z","caller":"traceutil/trace.go:171","msg":"trace[781880982] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:313; }","duration":"1.598180074s","start":"2026-03-09T01:33:54.632667Z","end":"2026-03-09T01:33:56.230847Z","steps":["trace[781880982] 'agreement among raft nodes before linearized reading' (duration: 1.598139622s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:56.230877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:54.632658Z","time spent":"1.598213256s","remote":"127.0.0.1:50820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4392,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-09T01:33:56.230876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:54.632869Z","time spent":"1.597997117s","remote":"127.0.0.1:50862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"info","ts":"2026-03-09T01:33:56.230239Z","caller":"traceutil/trace.go:171","msg":"trace[173691495] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:313; }","duration":"1.597294787s","start":"2026-03-09T01:33:54.632905Z","end":"2026-03-09T01:33:56.2302Z","steps":["trace[173691495] 'agreement among raft nodes before linearized reading' (duration: 1.597163902s)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:33:56.230775Z","caller":"traceutil/trace.go:171","msg":"trace[1409112029] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:313; }","duration":"790.818381ms","start":"2026-03-09T01:33:55.439947Z","end":"2026-03-09T01:33:56.230766Z","steps":["trace[1409112029] 'agreement among raft nodes before linearized reading' (duration: 790.449315ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:33:56.230936Z","caller":"traceutil/trace.go:171","msg":"trace[577733976] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:313; }","duration":"1.600443519s","start":"2026-03-09T01:33:54.630486Z","end":"2026-03-09T01:33:56.230929Z","steps":["trace[577733976] 'agreement among raft nodes before linearized reading' (duration: 1.599575314s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:56.230349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.597435093s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-03-09T01:33:56.23102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:55.439936Z","time spent":"791.075031ms","remote":"127.0.0.1:35744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-09T01:33:56.231032Z","caller":"traceutil/trace.go:171","msg":"trace[796820677] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:313; }","duration":"1.598132752s","start":"2026-03-09T01:33:54.632885Z","end":"2026-03-09T01:33:56.231018Z","steps":["trace[796820677] 'agreement among raft nodes before linearized reading' (duration: 1.597421113s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:33:56.231028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:54.630483Z","time spent":"1.600535753s","remote":"127.0.0.1:50820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4392,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-09T01:33:56.231079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:54.632871Z","time spent":"1.598195845s","remote":"127.0.0.1:50862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-03-09T01:33:56.237958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:33:54.632899Z","time spent":"1.598069929s","remote":"127.0.0.1:50862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"info","ts":"2026-03-09T01:34:18.147789Z","caller":"traceutil/trace.go:171","msg":"trace[1344878339] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"372.966437ms","start":"2026-03-09T01:34:17.7748Z","end":"2026-03-09T01:34:18.147766Z","steps":["trace[1344878339] 'process raft request' (duration: 372.821013ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:34:18.147947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:34:17.774785Z","time spent":"373.084371ms","remote":"127.0.0.1:50820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4567,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T01:34:23.984252Z","caller":"traceutil/trace.go:171","msg":"trace[854743378] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"161.663601ms","start":"2026-03-09T01:34:23.822567Z","end":"2026-03-09T01:34:23.98423Z","steps":["trace[854743378] 'process raft request' (duration: 161.524477ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:34:24.745387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.532033ms","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-03-09T01:34:24.745487Z","caller":"traceutil/trace.go:171","msg":"trace[243407062] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:515; }","duration":"380.638677ms","start":"2026-03-09T01:34:24.364824Z","end":"2026-03-09T01:34:24.745462Z","steps":["trace[243407062] 'range keys from in-memory index tree' (duration: 380.4278ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:34:24.745474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.963324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-09T01:34:24.745551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:34:24.364788Z","time spent":"380.749581ms","remote":"127.0.0.1:35744","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-03-09T01:34:24.745756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.89936ms","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-03-09T01:34:24.745416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.409107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-09T01:34:24.745787Z","caller":"traceutil/trace.go:171","msg":"trace[973682676] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:515; }","duration":"392.035104ms","start":"2026-03-09T01:34:24.353744Z","end":"2026-03-09T01:34:24.745779Z","steps":["trace[973682676] 'range keys from in-memory index tree' (duration: 391.764596ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:34:24.745815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:34:24.353721Z","time spent":"392.087556ms","remote":"127.0.0.1:35744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-09T01:34:24.745816Z","caller":"traceutil/trace.go:171","msg":"trace[1212389296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:515; }","duration":"378.829501ms","start":"2026-03-09T01:34:24.366975Z","end":"2026-03-09T01:34:24.745804Z","steps":["trace[1212389296] 'range keys from in-memory index tree' (duration: 378.313134ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:34:24.745858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:34:24.366968Z","time spent":"378.875072ms","remote":"127.0.0.1:35744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-09T01:34:24.745567Z","caller":"traceutil/trace.go:171","msg":"trace[162773058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:515; }","duration":"370.059376ms","start":"2026-03-09T01:34:24.375485Z","end":"2026-03-09T01:34:24.745545Z","steps":["trace[162773058] 'range keys from in-memory index tree' (duration: 369.919392ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:34:24.745952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:34:24.375478Z","time spent":"370.464848ms","remote":"127.0.0.1:35744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-09T01:34:25.687436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.891398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T01:34:25.687496Z","caller":"traceutil/trace.go:171","msg":"trace[1878133111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"617.015982ms","start":"2026-03-09T01:34:25.070467Z","end":"2026-03-09T01:34:25.687483Z","steps":["trace[1878133111] 'range keys from in-memory index tree' (duration: 616.761364ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:34:25.687519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:34:25.070431Z","time spent":"617.083374ms","remote":"127.0.0.1:50628","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-03-09T01:34:28.268724Z","caller":"traceutil/trace.go:171","msg":"trace[1451068918] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"225.592921ms","start":"2026-03-09T01:34:28.043096Z","end":"2026-03-09T01:34:28.268689Z","steps":["trace[1451068918] 'process raft request' (duration: 225.399045ms)"],"step_count":1}