{"level":"warn","ts":"2026-04-07T12:15:43.764625Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T12:15:43.764709Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.200: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.200:2380","--initial-cluster=instance=https://162.253.55.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.200: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-04-07T12:15:43.764771Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T12:15:43.764782Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-04-07T12:15:43.764801Z","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-04-07T12:15:43.765584Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"]} {"level":"info","ts":"2026-04-07T12:15:43.765654Z","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.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.200: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-04-07T12:15:43.770353Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.319499ms"} {"level":"info","ts":"2026-04-07T12:15:43.775531Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e10bb51ed7578852","cluster-id":"aa14459abd2161c3"} {"level":"info","ts":"2026-04-07T12:15:43.775602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T12:15:43.775623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 0"} {"level":"info","ts":"2026-04-07T12:15:43.775639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e10bb51ed7578852 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T12:15:43.775649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 1"} {"level":"info","ts":"2026-04-07T12:15:43.775706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"warn","ts":"2026-04-07T12:15:43.781906Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T12:15:43.783094Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T12:15:43.784212Z","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-04-07T12:15:43.786671Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e10bb51ed7578852","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T12:15:43.786934Z","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-04-07T12:15:43.78692Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e10bb51ed7578852","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T12:15:43.787012Z","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-04-07T12:15:43.787025Z","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-04-07T12:15:43.788216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"info","ts":"2026-04-07T12:15:43.788415Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","added-peer-id":"e10bb51ed7578852","added-peer-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-04-07T12:15:43.793422Z","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-04-07T12:15:43.794036Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e10bb51ed7578852","initial-advertise-peer-urls":["https://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T12:15:43.794112Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T12:15:43.794827Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-04-07T12:15:43.794859Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-04-07T12:15:44.676188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T12:15:44.676315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T12:15:44.676377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgPreVoteResp from e10bb51ed7578852 at term 1"} {"level":"info","ts":"2026-04-07T12:15:44.676503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became candidate at term 2"} {"level":"info","ts":"2026-04-07T12:15:44.676572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgVoteResp from e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-04-07T12:15:44.67671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became leader at term 2"} {"level":"info","ts":"2026-04-07T12:15:44.676764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e10bb51ed7578852 elected leader e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-04-07T12:15:44.67781Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:15:44.678551Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T12:15:44.678602Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T12:15:44.678571Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e10bb51ed7578852","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.200:2379]}","request-path":"/0/members/e10bb51ed7578852/attributes","cluster-id":"aa14459abd2161c3","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T12:15:44.679055Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T12:15:44.679082Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T12:15:44.679942Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:15:44.680238Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:15:44.680382Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:15:44.680709Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.200:2379"} {"level":"info","ts":"2026-04-07T12:15:44.681358Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T12:15:49.724287Z","caller":"traceutil/trace.go:171","msg":"trace[658785679] linearizableReadLoop","detail":"{readStateIndex:233; appliedIndex:232; }","duration":"204.278139ms","start":"2026-04-07T12:15:49.519983Z","end":"2026-04-07T12:15:49.724261Z","steps":["trace[658785679] 'read index received' (duration: 173.770949ms)","trace[658785679] 'applied index is now lower than readState.Index' (duration: 30.50618ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:15:49.724359Z","caller":"traceutil/trace.go:171","msg":"trace[365683697] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"246.749154ms","start":"2026-04-07T12:15:49.47756Z","end":"2026-04-07T12:15:49.72431Z","steps":["trace[365683697] 'process raft request' (duration: 216.180232ms)","trace[365683697] 'compare' (duration: 30.338717ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:15:49.724473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.595016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-07T12:15:49.724544Z","caller":"traceutil/trace.go:171","msg":"trace[2077253330] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:227; }","duration":"258.718359ms","start":"2026-04-07T12:15:49.465813Z","end":"2026-04-07T12:15:49.724531Z","steps":["trace[2077253330] 'agreement among raft nodes before linearized reading' (duration: 258.571386ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:15:49.856148Z","caller":"traceutil/trace.go:171","msg":"trace[1166690274] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"273.849706ms","start":"2026-04-07T12:15:49.582279Z","end":"2026-04-07T12:15:49.856129Z","steps":["trace[1166690274] 'process raft request' (duration: 273.786324ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:15:49.856224Z","caller":"traceutil/trace.go:171","msg":"trace[1059677612] transaction","detail":"{read_only:false; number_of_response:0; response_revision:229; }","duration":"293.348714ms","start":"2026-04-07T12:15:49.562802Z","end":"2026-04-07T12:15:49.856151Z","steps":["trace[1059677612] 'process raft request' (duration: 293.241082ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:15:49.856348Z","caller":"traceutil/trace.go:171","msg":"trace[195819389] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"335.035251ms","start":"2026-04-07T12:15:49.521176Z","end":"2026-04-07T12:15:49.856211Z","steps":["trace[195819389] 'process raft request' (duration: 330.901235ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:15:49.856769Z","caller":"traceutil/trace.go:171","msg":"trace[986058569] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"333.487785ms","start":"2026-04-07T12:15:49.523254Z","end":"2026-04-07T12:15:49.856742Z","steps":["trace[986058569] 'process raft request' (duration: 332.734738ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:15:49.856859Z","caller":"traceutil/trace.go:171","msg":"trace[1521783342] transaction","detail":"{read_only:false; number_of_response:0; response_revision:230; }","duration":"274.254545ms","start":"2026-04-07T12:15:49.582563Z","end":"2026-04-07T12:15:49.856818Z","steps":["trace[1521783342] 'process raft request' (duration: 273.542919ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:15:49.856941Z","caller":"traceutil/trace.go:171","msg":"trace[1690413955] transaction","detail":"{read_only:false; number_of_response:0; response_revision:230; }","duration":"274.268546ms","start":"2026-04-07T12:15:49.582663Z","end":"2026-04-07T12:15:49.856931Z","steps":["trace[1690413955] 'process raft request' (duration: 274.097742ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:15:49.856978Z","caller":"traceutil/trace.go:171","msg":"trace[627894802] linearizableReadLoop","detail":"{readStateIndex:241; appliedIndex:233; }","duration":"132.595644ms","start":"2026-04-07T12:15:49.724362Z","end":"2026-04-07T12:15:49.856958Z","steps":["trace[627894802] 'read index received' (duration: 127.739222ms)","trace[627894802] 'applied index is now lower than readState.Index' (duration: 4.855551ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:15:49.857063Z","caller":"traceutil/trace.go:171","msg":"trace[1024097940] transaction","detail":"{read_only:false; number_of_response:0; response_revision:231; }","duration":"273.309614ms","start":"2026-04-07T12:15:49.583746Z","end":"2026-04-07T12:15:49.857056Z","steps":["trace[1024097940] 'process raft request' (duration: 273.168671ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:15:49.85716Z","caller":"traceutil/trace.go:171","msg":"trace[1417638065] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"274.271366ms","start":"2026-04-07T12:15:49.58288Z","end":"2026-04-07T12:15:49.857152Z","steps":["trace[1417638065] 'process raft request' (duration: 273.931548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:15:49.85716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.67277ms","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-04-07T12:15:49.857214Z","caller":"traceutil/trace.go:171","msg":"trace[835415577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:231; }","duration":"201.767041ms","start":"2026-04-07T12:15:49.655432Z","end":"2026-04-07T12:15:49.857199Z","steps":["trace[835415577] 'agreement among raft nodes before linearized reading' (duration: 201.636508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:15:49.857426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.357078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:15:49.857496Z","caller":"traceutil/trace.go:171","msg":"trace[720119586] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:231; }","duration":"308.45176ms","start":"2026-04-07T12:15:49.549027Z","end":"2026-04-07T12:15:49.857479Z","steps":["trace[720119586] 'agreement among raft nodes before linearized reading' (duration: 308.356328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:15:49.857521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.100129ms","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-04-07T12:15:49.857558Z","caller":"traceutil/trace.go:171","msg":"trace[618091590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:231; }","duration":"202.15868ms","start":"2026-04-07T12:15:49.655388Z","end":"2026-04-07T12:15:49.857546Z","steps":["trace[618091590] 'agreement among raft nodes before linearized reading' (duration: 202.095658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:15:49.857565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:15:49.549013Z","time spent":"308.526152ms","remote":"127.0.0.1:57642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T12:15:49.858053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:15:49.521149Z","time spent":"335.322678ms","remote":"127.0.0.1:57474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T12:15:49.858515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:15:49.523235Z","time spent":"333.629797ms","remote":"127.0.0.1:57642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:15:49.85915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.289157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-07T12:15:49.859184Z","caller":"traceutil/trace.go:171","msg":"trace[2033509856] transaction","detail":"{read_only:false; response_revision:232; number_of_response:1; }","duration":"131.269094ms","start":"2026-04-07T12:15:49.727872Z","end":"2026-04-07T12:15:49.859141Z","steps":["trace[2033509856] 'process raft request' (duration: 131.071779ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:15:49.859201Z","caller":"traceutil/trace.go:171","msg":"trace[263237285] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:232; }","duration":"132.407879ms","start":"2026-04-07T12:15:49.726781Z","end":"2026-04-07T12:15:49.859189Z","steps":["trace[263237285] 'agreement among raft nodes before linearized reading' (duration: 132.286176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:15:49.859252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.948889ms","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-04-07T12:15:49.859321Z","caller":"traceutil/trace.go:171","msg":"trace[2683109] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:232; }","duration":"108.107322ms","start":"2026-04-07T12:15:49.751198Z","end":"2026-04-07T12:15:49.859306Z","steps":["trace[2683109] 'agreement among raft nodes before linearized reading' (duration: 107.955958ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:16:14.303636Z","caller":"traceutil/trace.go:171","msg":"trace[1324772051] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"166.047492ms","start":"2026-04-07T12:16:14.137567Z","end":"2026-04-07T12:16:14.303615Z","steps":["trace[1324772051] 'process raft request' (duration: 165.94992ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:16:14.310008Z","caller":"traceutil/trace.go:171","msg":"trace[1655170314] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"168.963778ms","start":"2026-04-07T12:16:14.141027Z","end":"2026-04-07T12:16:14.309991Z","steps":["trace[1655170314] 'process raft request' (duration: 168.388635ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:16:14.310278Z","caller":"traceutil/trace.go:171","msg":"trace[718715003] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"159.141623ms","start":"2026-04-07T12:16:14.151103Z","end":"2026-04-07T12:16:14.310244Z","steps":["trace[718715003] 'process raft request' (duration: 158.5692ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:16:14.310336Z","caller":"traceutil/trace.go:171","msg":"trace[1313029098] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"168.714052ms","start":"2026-04-07T12:16:14.141582Z","end":"2026-04-07T12:16:14.310296Z","steps":["trace[1313029098] 'process raft request' (duration: 167.926234ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:16:14.310537Z","caller":"traceutil/trace.go:171","msg":"trace[1158731513] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"154.086966ms","start":"2026-04-07T12:16:14.156442Z","end":"2026-04-07T12:16:14.310529Z","steps":["trace[1158731513] 'process raft request' (duration: 153.480043ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:16:14.31058Z","caller":"traceutil/trace.go:171","msg":"trace[517253049] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"154.103957ms","start":"2026-04-07T12:16:14.156462Z","end":"2026-04-07T12:16:14.310566Z","steps":["trace[517253049] 'process raft request' (duration: 153.583185ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:16:14.310655Z","caller":"traceutil/trace.go:171","msg":"trace[106701788] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"159.512142ms","start":"2026-04-07T12:16:14.151084Z","end":"2026-04-07T12:16:14.310596Z","steps":["trace[106701788] 'process raft request' (duration: 158.753335ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:16:14.3108Z","caller":"traceutil/trace.go:171","msg":"trace[974141059] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"153.844861ms","start":"2026-04-07T12:16:14.156948Z","end":"2026-04-07T12:16:14.310793Z","steps":["trace[974141059] 'process raft request' (duration: 153.35515ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:16:14.311131Z","caller":"traceutil/trace.go:171","msg":"trace[358086045] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"153.431821ms","start":"2026-04-07T12:16:14.157676Z","end":"2026-04-07T12:16:14.311108Z","steps":["trace[358086045] 'process raft request' (duration: 153.116894ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:16:14.311329Z","caller":"traceutil/trace.go:171","msg":"trace[955566774] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"114.678171ms","start":"2026-04-07T12:16:14.196639Z","end":"2026-04-07T12:16:14.311317Z","steps":["trace[955566774] 'process raft request' (duration: 114.377624ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:16:14.311526Z","caller":"traceutil/trace.go:171","msg":"trace[1949678710] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"109.149836ms","start":"2026-04-07T12:16:14.202366Z","end":"2026-04-07T12:16:14.311516Z","steps":["trace[1949678710] 'process raft request' (duration: 108.724435ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:16:20.528679Z","caller":"traceutil/trace.go:171","msg":"trace[141944975] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"183.41883ms","start":"2026-04-07T12:16:20.345186Z","end":"2026-04-07T12:16:20.528605Z","steps":["trace[141944975] 'read index received' (duration: 180.454073ms)","trace[141944975] 'applied index is now lower than readState.Index' (duration: 2.963577ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:16:20.52888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.690507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:16:20.528924Z","caller":"traceutil/trace.go:171","msg":"trace[967779559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"183.82596ms","start":"2026-04-07T12:16:20.345077Z","end":"2026-04-07T12:16:20.528903Z","steps":["trace[967779559] 'agreement among raft nodes before linearized reading' (duration: 183.680717ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:16:20.529235Z","caller":"traceutil/trace.go:171","msg":"trace[1165035273] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"312.246847ms","start":"2026-04-07T12:16:20.216949Z","end":"2026-04-07T12:16:20.529196Z","steps":["trace[1165035273] 'process raft request' (duration: 309.234828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:16:20.52943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:16:20.216926Z","time spent":"312.400751ms","remote":"127.0.0.1:57486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:16:20.712804Z","caller":"traceutil/trace.go:171","msg":"trace[154998899] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"126.868472ms","start":"2026-04-07T12:16:20.585919Z","end":"2026-04-07T12:16:20.712788Z","steps":["trace[154998899] 'process raft request' (duration: 126.7705ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:16:36.322886Z","caller":"traceutil/trace.go:171","msg":"trace[211828604] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"178.240002ms","start":"2026-04-07T12:16:36.144627Z","end":"2026-04-07T12:16:36.322867Z","steps":["trace[211828604] 'process raft request' (duration: 178.196771ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:16:36.323264Z","caller":"traceutil/trace.go:171","msg":"trace[393332079] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"243.719084ms","start":"2026-04-07T12:16:36.079535Z","end":"2026-04-07T12:16:36.323254Z","steps":["trace[393332079] 'process raft request' (duration: 181.563667ms)","trace[393332079] 'compare' (duration: 61.592154ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:16:36.323303Z","caller":"traceutil/trace.go:171","msg":"trace[1182028011] linearizableReadLoop","detail":"{readStateIndex:767; appliedIndex:766; }","duration":"182.219342ms","start":"2026-04-07T12:16:36.141052Z","end":"2026-04-07T12:16:36.323272Z","steps":["trace[1182028011] 'read index received' (duration: 120.054845ms)","trace[1182028011] 'applied index is now lower than readState.Index' (duration: 62.161747ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:16:36.323542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.076583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-07T12:16:36.323642Z","caller":"traceutil/trace.go:171","msg":"trace[962910416] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:739; }","duration":"244.181835ms","start":"2026-04-07T12:16:36.079417Z","end":"2026-04-07T12:16:36.323599Z","steps":["trace[962910416] 'agreement among raft nodes before linearized reading' (duration: 243.96967ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:17:10.47393Z","caller":"traceutil/trace.go:171","msg":"trace[558890155] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"158.236091ms","start":"2026-04-07T12:17:10.315672Z","end":"2026-04-07T12:17:10.473908Z","steps":["trace[558890155] 'process raft request' (duration: 158.034646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:17:10.654429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.112357ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:17:10.654525Z","caller":"traceutil/trace.go:171","msg":"trace[218847403] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:991; }","duration":"155.767332ms","start":"2026-04-07T12:17:10.498733Z","end":"2026-04-07T12:17:10.654501Z","steps":["trace[218847403] 'read index received' (duration: 21.283905ms)","trace[218847403] 'applied index is now lower than readState.Index' (duration: 134.482697ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:17:10.654595Z","caller":"traceutil/trace.go:171","msg":"trace[333378882] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"170.086663ms","start":"2026-04-07T12:17:10.484502Z","end":"2026-04-07T12:17:10.654588Z","steps":["trace[333378882] 'process raft request' (duration: 35.613107ms)","trace[333378882] 'compare' (duration: 134.030975ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:17:10.654797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.061368ms","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-04-07T12:17:10.654822Z","caller":"traceutil/trace.go:171","msg":"trace[682231126] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:955; }","duration":"156.110039ms","start":"2026-04-07T12:17:10.498706Z","end":"2026-04-07T12:17:10.654816Z","steps":["trace[682231126] 'agreement among raft nodes before linearized reading' (duration: 156.017617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:17:10.65499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.386271ms","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-04-07T12:17:10.655073Z","caller":"traceutil/trace.go:171","msg":"trace[2098072240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:955; }","duration":"152.508565ms","start":"2026-04-07T12:17:10.502543Z","end":"2026-04-07T12:17:10.655051Z","steps":["trace[2098072240] 'agreement among raft nodes before linearized reading' (duration: 152.271789ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:18:02.203008Z","caller":"traceutil/trace.go:171","msg":"trace[1931359689] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"219.744542ms","start":"2026-04-07T12:18:01.983244Z","end":"2026-04-07T12:18:02.202988Z","steps":["trace[1931359689] 'process raft request' (duration: 219.606379ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:18:02.323778Z","caller":"traceutil/trace.go:171","msg":"trace[45551998] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"116.063058ms","start":"2026-04-07T12:18:02.207683Z","end":"2026-04-07T12:18:02.323746Z","steps":["trace[45551998] 'process raft request' (duration: 110.533857ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:18:02.370808Z","caller":"traceutil/trace.go:171","msg":"trace[465932850] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"138.044361ms","start":"2026-04-07T12:18:02.232735Z","end":"2026-04-07T12:18:02.370779Z","steps":["trace[465932850] 'process raft request' (duration: 137.208122ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:18:02.370882Z","caller":"traceutil/trace.go:171","msg":"trace[305168305] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"162.017271ms","start":"2026-04-07T12:18:02.208847Z","end":"2026-04-07T12:18:02.370864Z","steps":["trace[305168305] 'process raft request' (duration: 160.926136ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:18:02.37126Z","caller":"traceutil/trace.go:171","msg":"trace[1542175726] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"160.145776ms","start":"2026-04-07T12:18:02.211098Z","end":"2026-04-07T12:18:02.371244Z","steps":["trace[1542175726] 'process raft request' (duration: 158.801105ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:18:02.997998Z","caller":"traceutil/trace.go:171","msg":"trace[951326621] linearizableReadLoop","detail":"{readStateIndex:1248; appliedIndex:1247; }","duration":"102.070405ms","start":"2026-04-07T12:18:02.89591Z","end":"2026-04-07T12:18:02.99798Z","steps":["trace[951326621] 'read index received' (duration: 101.925652ms)","trace[951326621] 'applied index is now lower than readState.Index' (duration: 143.343µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:18:02.998037Z","caller":"traceutil/trace.go:171","msg":"trace[1795307884] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"267.1708ms","start":"2026-04-07T12:18:02.730841Z","end":"2026-04-07T12:18:02.998011Z","steps":["trace[1795307884] 'process raft request' (duration: 267.017926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:18:02.998158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.24194ms","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-04-07T12:18:02.998185Z","caller":"traceutil/trace.go:171","msg":"trace[914478260] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1199; }","duration":"102.315731ms","start":"2026-04-07T12:18:02.895863Z","end":"2026-04-07T12:18:02.998179Z","steps":["trace[914478260] 'agreement among raft nodes before linearized reading' (duration: 102.202908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:18:03.094538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.340864ms","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-04-07T12:18:03.094601Z","caller":"traceutil/trace.go:171","msg":"trace[1764795337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1199; }","duration":"105.466277ms","start":"2026-04-07T12:18:02.989122Z","end":"2026-04-07T12:18:03.094589Z","steps":["trace[1764795337] 'agreement among raft nodes before linearized reading' (duration: 105.323452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:18:03.130095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.047133ms","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-04-07T12:18:03.130173Z","caller":"traceutil/trace.go:171","msg":"trace[1979312539] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1203; }","duration":"128.150506ms","start":"2026-04-07T12:18:03.002004Z","end":"2026-04-07T12:18:03.130154Z","steps":["trace[1979312539] 'agreement among raft nodes before linearized reading' (duration: 127.953282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:18:03.130184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.987186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-07T12:18:03.130242Z","caller":"traceutil/trace.go:171","msg":"trace[2122779788] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1203; }","duration":"124.065079ms","start":"2026-04-07T12:18:03.006163Z","end":"2026-04-07T12:18:03.130229Z","steps":["trace[2122779788] 'agreement among raft nodes before linearized reading' (duration: 123.921516ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:18:17.21001Z","caller":"traceutil/trace.go:171","msg":"trace[1578564541] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"143.169178ms","start":"2026-04-07T12:18:17.066812Z","end":"2026-04-07T12:18:17.209981Z","steps":["trace[1578564541] 'process raft request' (duration: 79.190068ms)","trace[1578564541] 'compare' (duration: 63.586551ms)"],"step_count":2}