{"level":"warn","ts":"2026-09-18T00:01:56.510973Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T00:01:56.511126Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.167: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.167:2380","--initial-cluster=instance=https://162.253.55.167:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.167:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.167:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-18T00:01:56.511189Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T00:01:56.511196Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.167:2380"]} {"level":"info","ts":"2026-09-18T00:01:56.51121Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-18T00:01:56.511847Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.167:2379"]} {"level":"info","ts":"2026-09-18T00:01:56.511977Z","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.167:2380"],"listen-peer-urls":["https://162.253.55.167:2380"],"advertise-client-urls":["https://162.253.55.167:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.167:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.167:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-18T00:01:56.520508Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.310202ms"} {"level":"info","ts":"2026-09-18T00:01:56.532379Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"83287f3a81241ad4","cluster-id":"185ed9b8ed398b9a"} {"level":"info","ts":"2026-09-18T00:01:56.532444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T00:01:56.53246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 became follower at term 0"} {"level":"info","ts":"2026-09-18T00:01:56.532469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 83287f3a81241ad4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T00:01:56.532485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 became follower at term 1"} {"level":"info","ts":"2026-09-18T00:01:56.532522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 switched to configuration voters=(9450943707288443604)"} {"level":"warn","ts":"2026-09-18T00:01:56.538647Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T00:01:56.540684Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T00:01:56.543715Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-18T00:01:56.547415Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"83287f3a81241ad4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T00:01:56.547919Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"83287f3a81241ad4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T00:01:56.548067Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-18T00:01:56.548159Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-18T00:01:56.548177Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-18T00:01:56.55141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 switched to configuration voters=(9450943707288443604)"} {"level":"info","ts":"2026-09-18T00:01:56.551507Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"185ed9b8ed398b9a","local-member-id":"83287f3a81241ad4","added-peer-id":"83287f3a81241ad4","added-peer-peer-urls":["https://162.253.55.167:2380"]} {"level":"info","ts":"2026-09-18T00:01:56.552015Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-18T00:01:56.552192Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"83287f3a81241ad4","initial-advertise-peer-urls":["https://162.253.55.167:2380"],"listen-peer-urls":["https://162.253.55.167:2380"],"advertise-client-urls":["https://162.253.55.167:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.167:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T00:01:56.552233Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T00:01:56.552306Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.167:2380"} {"level":"info","ts":"2026-09-18T00:01:56.552365Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.167:2380"} {"level":"info","ts":"2026-09-18T00:01:56.733514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T00:01:56.73362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T00:01:56.733656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 received MsgPreVoteResp from 83287f3a81241ad4 at term 1"} {"level":"info","ts":"2026-09-18T00:01:56.733676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 became candidate at term 2"} {"level":"info","ts":"2026-09-18T00:01:56.733687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 received MsgVoteResp from 83287f3a81241ad4 at term 2"} {"level":"info","ts":"2026-09-18T00:01:56.733743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 became leader at term 2"} {"level":"info","ts":"2026-09-18T00:01:56.733763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 83287f3a81241ad4 elected leader 83287f3a81241ad4 at term 2"} {"level":"info","ts":"2026-09-18T00:01:56.734999Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"83287f3a81241ad4","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.167:2379]}","request-path":"/0/members/83287f3a81241ad4/attributes","cluster-id":"185ed9b8ed398b9a","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T00:01:56.735185Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T00:01:56.735237Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T00:01:56.735318Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T00:01:56.73657Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"185ed9b8ed398b9a","local-member-id":"83287f3a81241ad4","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T00:01:56.736677Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T00:01:56.736713Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T00:01:56.738362Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.167:2379"} {"level":"info","ts":"2026-09-18T00:01:56.739392Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T00:01:56.739425Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T00:01:56.740564Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-18T00:02:01.267449Z","caller":"traceutil/trace.go:171","msg":"trace[518599149] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"104.98748ms","start":"2026-09-18T00:02:01.162424Z","end":"2026-09-18T00:02:01.267412Z","steps":["trace[518599149] 'process raft request' (duration: 73.160833ms)","trace[518599149] 'compare' (duration: 31.653983ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:02:02.08274Z","caller":"traceutil/trace.go:171","msg":"trace[1737257066] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"101.248807ms","start":"2026-09-18T00:02:01.981465Z","end":"2026-09-18T00:02:02.082714Z","steps":["trace[1737257066] 'process raft request' (duration: 101.196016ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:02.082796Z","caller":"traceutil/trace.go:171","msg":"trace[1797494049] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"125.966627ms","start":"2026-09-18T00:02:01.956785Z","end":"2026-09-18T00:02:02.082752Z","steps":["trace[1797494049] 'process raft request' (duration: 59.791914ms)","trace[1797494049] 'compare' (duration: 65.930209ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:02:02.276898Z","caller":"traceutil/trace.go:171","msg":"trace[1112620266] linearizableReadLoop","detail":"{readStateIndex:274; appliedIndex:273; }","duration":"187.035387ms","start":"2026-09-18T00:02:02.089832Z","end":"2026-09-18T00:02:02.276867Z","steps":["trace[1112620266] 'read index received' (duration: 131.071844ms)","trace[1112620266] 'applied index is now lower than readState.Index' (duration: 55.962183ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T00:02:02.277334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.434245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-09-18T00:02:02.277401Z","caller":"traceutil/trace.go:171","msg":"trace[994665886] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:268; }","duration":"187.561097ms","start":"2026-09-18T00:02:02.089825Z","end":"2026-09-18T00:02:02.277386Z","steps":["trace[994665886] 'agreement among raft nodes before linearized reading' (duration: 187.14501ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:02.277491Z","caller":"traceutil/trace.go:171","msg":"trace[1349581533] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"188.942141ms","start":"2026-09-18T00:02:02.088444Z","end":"2026-09-18T00:02:02.277386Z","steps":["trace[1349581533] 'process raft request' (duration: 132.558841ms)","trace[1349581533] 'compare' (duration: 55.620616ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T00:02:02.689306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.956554ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T00:02:02.689407Z","caller":"traceutil/trace.go:171","msg":"trace[1066361034] linearizableReadLoop","detail":"{readStateIndex:276; appliedIndex:275; }","duration":"366.889596ms","start":"2026-09-18T00:02:02.322502Z","end":"2026-09-18T00:02:02.689392Z","steps":["trace[1066361034] 'read index received' (duration: 90.22105ms)","trace[1066361034] 'applied index is now lower than readState.Index' (duration: 276.667146ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:02:02.689493Z","caller":"traceutil/trace.go:171","msg":"trace[964190425] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"407.461426ms","start":"2026-09-18T00:02:02.281973Z","end":"2026-09-18T00:02:02.689435Z","steps":["trace[964190425] 'process raft request' (duration: 130.80865ms)","trace[964190425] 'compare' (duration: 275.744521ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T00:02:02.689701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.807301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-09-18T00:02:02.689735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.969804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-18T00:02:02.689784Z","caller":"traceutil/trace.go:171","msg":"trace[787699920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:270; }","duration":"148.049955ms","start":"2026-09-18T00:02:02.541724Z","end":"2026-09-18T00:02:02.689774Z","steps":["trace[787699920] 'agreement among raft nodes before linearized reading' (duration: 147.964593ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:02.689796Z","caller":"traceutil/trace.go:171","msg":"trace[667414942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:270; }","duration":"156.956264ms","start":"2026-09-18T00:02:02.532811Z","end":"2026-09-18T00:02:02.689767Z","steps":["trace[667414942] 'agreement among raft nodes before linearized reading' (duration: 156.788331ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:02.689564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.07455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T00:02:02.689879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:02.281963Z","time spent":"407.670329ms","remote":"127.0.0.1:59710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":356,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T00:02:02.689976Z","caller":"traceutil/trace.go:171","msg":"trace[1861127282] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-controller; range_end:; response_count:0; response_revision:270; }","duration":"367.525149ms","start":"2026-09-18T00:02:02.322427Z","end":"2026-09-18T00:02:02.689952Z","steps":["trace[1861127282] 'agreement among raft nodes before linearized reading' (duration: 367.05021ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:02.690105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:02.322402Z","time spent":"367.64565ms","remote":"127.0.0.1:59574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/service-controller\" "} {"level":"info","ts":"2026-09-18T00:02:02.97081Z","caller":"traceutil/trace.go:171","msg":"trace[904414211] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"273.366248ms","start":"2026-09-18T00:02:02.697426Z","end":"2026-09-18T00:02:02.970792Z","steps":["trace[904414211] 'process raft request' (duration: 273.251866ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:02.970855Z","caller":"traceutil/trace.go:171","msg":"trace[1470032058] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"276.591084ms","start":"2026-09-18T00:02:02.694233Z","end":"2026-09-18T00:02:02.970824Z","steps":["trace[1470032058] 'process raft request' (duration: 272.240777ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:02.970918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.039961ms","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-09-18T00:02:02.970958Z","caller":"traceutil/trace.go:171","msg":"trace[624855870] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:274; }","duration":"203.122792ms","start":"2026-09-18T00:02:02.767824Z","end":"2026-09-18T00:02:02.970947Z","steps":["trace[624855870] 'agreement among raft nodes before linearized reading' (duration: 203.019981ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:02.970799Z","caller":"traceutil/trace.go:171","msg":"trace[37728499] linearizableReadLoop","detail":"{readStateIndex:280; appliedIndex:276; }","duration":"202.912428ms","start":"2026-09-18T00:02:02.767862Z","end":"2026-09-18T00:02:02.970774Z","steps":["trace[37728499] 'read index received' (duration: 2.739948ms)","trace[37728499] 'applied index is now lower than readState.Index' (duration: 200.17158ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:02:02.971138Z","caller":"traceutil/trace.go:171","msg":"trace[1263034843] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"273.603351ms","start":"2026-09-18T00:02:02.697523Z","end":"2026-09-18T00:02:02.971126Z","steps":["trace[1263034843] 'process raft request' (duration: 273.210725ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:03.136887Z","caller":"traceutil/trace.go:171","msg":"trace[725145400] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"117.630428ms","start":"2026-09-18T00:02:03.019223Z","end":"2026-09-18T00:02:03.136853Z","steps":["trace[725145400] 'process raft request' (duration: 89.547482ms)","trace[725145400] 'compare' (duration: 27.701078ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:02:03.137151Z","caller":"traceutil/trace.go:171","msg":"trace[629216577] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"116.041819ms","start":"2026-09-18T00:02:03.020887Z","end":"2026-09-18T00:02:03.136929Z","steps":["trace[629216577] 'process raft request' (duration: 115.785294ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:03.45045Z","caller":"traceutil/trace.go:171","msg":"trace[30578597] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"129.527277ms","start":"2026-09-18T00:02:03.320886Z","end":"2026-09-18T00:02:03.450413Z","steps":["trace[30578597] 'process raft request' (duration: 103.06765ms)","trace[30578597] 'compare' (duration: 25.691833ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:02:05.948422Z","caller":"traceutil/trace.go:171","msg":"trace[406125098] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"204.746606ms","start":"2026-09-18T00:02:05.743647Z","end":"2026-09-18T00:02:05.948394Z","steps":["trace[406125098] 'process raft request' (duration: 204.138435ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:06.186156Z","caller":"traceutil/trace.go:171","msg":"trace[1767278681] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"143.316861ms","start":"2026-09-18T00:02:06.042814Z","end":"2026-09-18T00:02:06.18613Z","steps":["trace[1767278681] 'process raft request' (duration: 140.837648ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:06.385006Z","caller":"traceutil/trace.go:171","msg":"trace[167330213] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"187.679334ms","start":"2026-09-18T00:02:06.197301Z","end":"2026-09-18T00:02:06.38498Z","steps":["trace[167330213] 'process raft request' (duration: 177.227801ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:06.868464Z","caller":"traceutil/trace.go:171","msg":"trace[1677276514] linearizableReadLoop","detail":"{readStateIndex:354; appliedIndex:353; }","duration":"227.147992ms","start":"2026-09-18T00:02:06.641287Z","end":"2026-09-18T00:02:06.868435Z","steps":["trace[1677276514] 'read index received' (duration: 215.5055ms)","trace[1677276514] 'applied index is now lower than readState.Index' (duration: 11.641152ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T00:02:06.868656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.349356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-09-18T00:02:06.868615Z","caller":"traceutil/trace.go:171","msg":"trace[1754194114] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"238.565881ms","start":"2026-09-18T00:02:06.629975Z","end":"2026-09-18T00:02:06.868541Z","steps":["trace[1754194114] 'process raft request' (duration: 226.808466ms)","trace[1754194114] 'compare' (duration: 11.520511ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:02:06.8687Z","caller":"traceutil/trace.go:171","msg":"trace[1972019296] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:341; }","duration":"227.433198ms","start":"2026-09-18T00:02:06.641254Z","end":"2026-09-18T00:02:06.868687Z","steps":["trace[1972019296] 'agreement among raft nodes before linearized reading' (duration: 227.324266ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:06.868727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.784834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-09-18T00:02:06.868836Z","caller":"traceutil/trace.go:171","msg":"trace[172669530] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:341; }","duration":"176.923175ms","start":"2026-09-18T00:02:06.691876Z","end":"2026-09-18T00:02:06.8688Z","steps":["trace[172669530] 'agreement among raft nodes before linearized reading' (duration: 176.757293ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:06.869031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.608348ms","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-09-18T00:02:06.869092Z","caller":"traceutil/trace.go:171","msg":"trace[1842281913] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:341; }","duration":"126.7216ms","start":"2026-09-18T00:02:06.742361Z","end":"2026-09-18T00:02:06.869082Z","steps":["trace[1842281913] 'agreement among raft nodes before linearized reading' (duration: 126.631409ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:07.226024Z","caller":"traceutil/trace.go:171","msg":"trace[1301303160] linearizableReadLoop","detail":"{readStateIndex:355; appliedIndex:354; }","duration":"332.115167ms","start":"2026-09-18T00:02:06.893869Z","end":"2026-09-18T00:02:07.225984Z","steps":["trace[1301303160] 'read index received' (duration: 330.50651ms)","trace[1301303160] 'applied index is now lower than readState.Index' (duration: 1.606217ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:02:07.22611Z","caller":"traceutil/trace.go:171","msg":"trace[844770709] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"351.820071ms","start":"2026-09-18T00:02:06.874267Z","end":"2026-09-18T00:02:07.226087Z","steps":["trace[844770709] 'process raft request' (duration: 350.187053ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:07.226259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:06.874232Z","time spent":"351.927993ms","remote":"127.0.0.1:59574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":172,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T00:02:07.226324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.869687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-09-18T00:02:07.226424Z","caller":"traceutil/trace.go:171","msg":"trace[2141000028] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:342; }","duration":"284.04048ms","start":"2026-09-18T00:02:06.942364Z","end":"2026-09-18T00:02:07.226405Z","steps":["trace[2141000028] 'agreement among raft nodes before linearized reading' (duration: 283.860826ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:07.226254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.372142ms","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-09-18T00:02:07.226696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.394178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-09-18T00:02:07.226702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.117843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-18T00:02:07.226761Z","caller":"traceutil/trace.go:171","msg":"trace[877929779] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:342; }","duration":"332.941383ms","start":"2026-09-18T00:02:06.893794Z","end":"2026-09-18T00:02:07.226735Z","steps":["trace[877929779] 'agreement among raft nodes before linearized reading' (duration: 332.338772ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:07.226767Z","caller":"traceutil/trace.go:171","msg":"trace[146548237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:342; }","duration":"240.201005ms","start":"2026-09-18T00:02:06.986551Z","end":"2026-09-18T00:02:07.226752Z","steps":["trace[146548237] 'agreement among raft nodes before linearized reading' (duration: 240.089173ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:07.226781Z","caller":"traceutil/trace.go:171","msg":"trace[695104697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:342; }","duration":"240.49157ms","start":"2026-09-18T00:02:06.986251Z","end":"2026-09-18T00:02:07.226742Z","steps":["trace[695104697] 'agreement among raft nodes before linearized reading' (duration: 240.404389ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:07.226797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.704981ms","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-09-18T00:02:07.226826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:06.893763Z","time spent":"333.046674ms","remote":"127.0.0.1:59574","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-09-18T00:02:07.226701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.179181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-09-18T00:02:07.226843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.04905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-09-18T00:02:07.227156Z","caller":"traceutil/trace.go:171","msg":"trace[254917827] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:342; }","duration":"134.409566ms","start":"2026-09-18T00:02:07.092727Z","end":"2026-09-18T00:02:07.227137Z","steps":["trace[254917827] 'agreement among raft nodes before linearized reading' (duration: 134.05184ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:07.227091Z","caller":"traceutil/trace.go:171","msg":"trace[1225033289] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:342; }","duration":"184.521637ms","start":"2026-09-18T00:02:07.042433Z","end":"2026-09-18T00:02:07.226954Z","steps":["trace[1225033289] 'agreement among raft nodes before linearized reading' (duration: 184.159341ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:07.226861Z","caller":"traceutil/trace.go:171","msg":"trace[880135887] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:342; }","duration":"233.840313ms","start":"2026-09-18T00:02:06.993005Z","end":"2026-09-18T00:02:07.226845Z","steps":["trace[880135887] 'agreement among raft nodes before linearized reading' (duration: 233.719111ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:11.531395Z","caller":"traceutil/trace.go:171","msg":"trace[545828252] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"191.468185ms","start":"2026-09-18T00:02:11.339898Z","end":"2026-09-18T00:02:11.531366Z","steps":["trace[545828252] 'process raft request' (duration: 183.951666ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:24.199681Z","caller":"traceutil/trace.go:171","msg":"trace[1231051710] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"253.630618ms","start":"2026-09-18T00:02:23.945951Z","end":"2026-09-18T00:02:24.199582Z","steps":["trace[1231051710] 'process raft request' (duration: 252.979228ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:43.896772Z","caller":"traceutil/trace.go:171","msg":"trace[191725458] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:568; }","duration":"377.046504ms","start":"2026-09-18T00:02:43.51969Z","end":"2026-09-18T00:02:43.896736Z","steps":["trace[191725458] 'read index received' (duration: 376.877372ms)","trace[191725458] 'applied index is now lower than readState.Index' (duration: 167.992µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:02:43.89689Z","caller":"traceutil/trace.go:171","msg":"trace[1531140996] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"379.351101ms","start":"2026-09-18T00:02:43.517475Z","end":"2026-09-18T00:02:43.896826Z","steps":["trace[1531140996] 'process raft request' (duration: 379.053616ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:43.897278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.555093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-18T00:02:43.897354Z","caller":"traceutil/trace.go:171","msg":"trace[1481066925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:547; }","duration":"377.705115ms","start":"2026-09-18T00:02:43.519634Z","end":"2026-09-18T00:02:43.897339Z","steps":["trace[1481066925] 'agreement among raft nodes before linearized reading' (duration: 377.281518ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:43.897396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:43.517444Z","time spent":"379.564103ms","remote":"127.0.0.1:59634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T00:02:43.897393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:43.519614Z","time spent":"377.767526ms","remote":"127.0.0.1:59634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-18T00:02:43.905451Z","caller":"traceutil/trace.go:171","msg":"trace[437308880] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"222.205768ms","start":"2026-09-18T00:02:43.683217Z","end":"2026-09-18T00:02:43.905423Z","steps":["trace[437308880] 'process raft request' (duration: 221.933504ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:43.905567Z","caller":"traceutil/trace.go:171","msg":"trace[110915800] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"222.088578ms","start":"2026-09-18T00:02:43.683455Z","end":"2026-09-18T00:02:43.905543Z","steps":["trace[110915800] 'process raft request' (duration: 221.825723ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:43.90574Z","caller":"traceutil/trace.go:171","msg":"trace[585867635] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"172.443089ms","start":"2026-09-18T00:02:43.733227Z","end":"2026-09-18T00:02:43.905671Z","steps":["trace[585867635] 'process raft request' (duration: 172.146774ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:43.905724Z","caller":"traceutil/trace.go:171","msg":"trace[1825196135] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"222.122877ms","start":"2026-09-18T00:02:43.683487Z","end":"2026-09-18T00:02:43.90561Z","steps":["trace[1825196135] 'process raft request' (duration: 221.837222ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:43.905947Z","caller":"traceutil/trace.go:171","msg":"trace[1894738807] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"172.603941ms","start":"2026-09-18T00:02:43.733289Z","end":"2026-09-18T00:02:43.905893Z","steps":["trace[1894738807] 'process raft request' (duration: 172.244845ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:43.906264Z","caller":"traceutil/trace.go:171","msg":"trace[843223550] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"165.697864ms","start":"2026-09-18T00:02:43.740552Z","end":"2026-09-18T00:02:43.90625Z","steps":["trace[843223550] 'process raft request' (duration: 165.558672ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:43.906483Z","caller":"traceutil/trace.go:171","msg":"trace[896359304] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"165.731964ms","start":"2026-09-18T00:02:43.740733Z","end":"2026-09-18T00:02:43.906465Z","steps":["trace[896359304] 'process raft request' (duration: 165.43862ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:43.906607Z","caller":"traceutil/trace.go:171","msg":"trace[447918580] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"161.907855ms","start":"2026-09-18T00:02:43.744683Z","end":"2026-09-18T00:02:43.906591Z","steps":["trace[447918580] 'process raft request' (duration: 161.54263ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:43.906821Z","caller":"traceutil/trace.go:171","msg":"trace[174617259] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"173.406833ms","start":"2026-09-18T00:02:43.73337Z","end":"2026-09-18T00:02:43.906777Z","steps":["trace[174617259] 'process raft request' (duration: 172.217665ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:43.906933Z","caller":"traceutil/trace.go:171","msg":"trace[398830790] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"161.384088ms","start":"2026-09-18T00:02:43.745536Z","end":"2026-09-18T00:02:43.90692Z","steps":["trace[398830790] 'process raft request' (duration: 160.89401ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:43.907096Z","caller":"traceutil/trace.go:171","msg":"trace[1032523992] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"139.754563ms","start":"2026-09-18T00:02:43.767311Z","end":"2026-09-18T00:02:43.907066Z","steps":["trace[1032523992] 'process raft request' (duration: 139.367136ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:43.907456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.88762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-18T00:02:43.907544Z","caller":"traceutil/trace.go:171","msg":"trace[2124155764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:560; }","duration":"163.013352ms","start":"2026-09-18T00:02:43.744516Z","end":"2026-09-18T00:02:43.90753Z","steps":["trace[2124155764] 'agreement among raft nodes before linearized reading' (duration: 162.743178ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:50.480955Z","caller":"traceutil/trace.go:171","msg":"trace[1028774447] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"149.462944ms","start":"2026-09-18T00:02:50.331456Z","end":"2026-09-18T00:02:50.480919Z","steps":["trace[1028774447] 'process raft request' (duration: 148.068303ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:50.685467Z","caller":"traceutil/trace.go:171","msg":"trace[1556804929] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"196.636582ms","start":"2026-09-18T00:02:50.488797Z","end":"2026-09-18T00:02:50.685434Z","steps":["trace[1556804929] 'process raft request' (duration: 112.894618ms)","trace[1556804929] 'compare' (duration: 83.623592ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T00:02:51.246617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.237141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T00:02:51.248292Z","caller":"traceutil/trace.go:171","msg":"trace[786591470] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"742.63879ms","start":"2026-09-18T00:02:50.504327Z","end":"2026-09-18T00:02:51.246966Z","steps":["trace[786591470] 'process raft request' (duration: 562.966773ms)","trace[786591470] 'compare' (duration: 178.899645ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T00:02:51.248481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:50.504199Z","time spent":"744.194435ms","remote":"127.0.0.1:59556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T00:02:51.249134Z","caller":"traceutil/trace.go:171","msg":"trace[841571416] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"687.692185ms","start":"2026-09-18T00:02:50.56132Z","end":"2026-09-18T00:02:51.249013Z","steps":["trace[841571416] 'process raft request' (duration: 685.38173ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:51.249232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:50.561296Z","time spent":"687.889358ms","remote":"127.0.0.1:59894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T00:02:52.441542Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1933346825639508346,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-18T00:02:52.942312Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1933346825639508346,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-18T00:02:53.13305Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.483605347s","expected-duration":"1s"} {"level":"info","ts":"2026-09-18T00:02:53.133922Z","caller":"traceutil/trace.go:171","msg":"trace[1456617281] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:688; }","duration":"1.193340762s","start":"2026-09-18T00:02:51.940558Z","end":"2026-09-18T00:02:53.133899Z","steps":["trace[1456617281] 'read index received' (duration: 1.192723182s)","trace[1456617281] 'applied index is now lower than readState.Index' (duration: 616.46µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:02:53.134017Z","caller":"traceutil/trace.go:171","msg":"trace[485932086] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"1.485687439s","start":"2026-09-18T00:02:51.648292Z","end":"2026-09-18T00:02:53.13398Z","steps":["trace[485932086] 'process raft request' (duration: 1.48516679s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:53.134146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.171467579s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-18T00:02:53.134175Z","caller":"traceutil/trace.go:171","msg":"trace[1689243533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:661; }","duration":"1.171554751s","start":"2026-09-18T00:02:51.962612Z","end":"2026-09-18T00:02:53.134167Z","steps":["trace[1689243533] 'agreement among raft nodes before linearized reading' (duration: 1.171437639s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:53.1342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:51.962586Z","time spent":"1.171608521s","remote":"127.0.0.1:59634","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-09-18T00:02:53.134222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:51.648254Z","time spent":"1.485854831s","remote":"127.0.0.1:59472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T00:02:53.13426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.193670886s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-18T00:02:53.13433Z","caller":"traceutil/trace.go:171","msg":"trace[2064050231] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:661; }","duration":"1.193822699s","start":"2026-09-18T00:02:51.940479Z","end":"2026-09-18T00:02:53.134301Z","steps":["trace[2064050231] 'agreement among raft nodes before linearized reading' (duration: 1.193519494s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:53.134391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:51.940446Z","time spent":"1.193935851s","remote":"127.0.0.1:59634","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-09-18T00:02:53.134445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.189718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-18T00:02:53.134474Z","caller":"traceutil/trace.go:171","msg":"trace[941318564] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:661; }","duration":"445.252429ms","start":"2026-09-18T00:02:52.689215Z","end":"2026-09-18T00:02:53.134467Z","steps":["trace[941318564] 'agreement among raft nodes before linearized reading' (duration: 445.179998ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:53.134494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:52.689193Z","time spent":"445.297139ms","remote":"127.0.0.1:59634","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":"warn","ts":"2026-09-18T00:02:53.134579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.168498963s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-18T00:02:53.134631Z","caller":"traceutil/trace.go:171","msg":"trace[1972373558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:661; }","duration":"1.168572934s","start":"2026-09-18T00:02:51.966044Z","end":"2026-09-18T00:02:53.134617Z","steps":["trace[1972373558] 'agreement among raft nodes before linearized reading' (duration: 1.168459332s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:53.134659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01249511s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T00:02:53.13466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:51.966032Z","time spent":"1.168621336s","remote":"127.0.0.1:59634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-18T00:02:53.134682Z","caller":"traceutil/trace.go:171","msg":"trace[2101198248] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:661; }","duration":"1.012546371s","start":"2026-09-18T00:02:52.12213Z","end":"2026-09-18T00:02:53.134676Z","steps":["trace[2101198248] 'agreement among raft nodes before linearized reading' (duration: 1.01251192s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:53.134699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:52.122114Z","time spent":"1.012580611s","remote":"127.0.0.1:59380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-18T00:02:54.87114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.316952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T00:02:54.871258Z","caller":"traceutil/trace.go:171","msg":"trace[133863175] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:682; }","duration":"218.517275ms","start":"2026-09-18T00:02:54.652718Z","end":"2026-09-18T00:02:54.871235Z","steps":["trace[133863175] 'range keys from in-memory index tree' (duration: 218.21102ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:57.143577Z","caller":"traceutil/trace.go:171","msg":"trace[1427713838] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"137.040822ms","start":"2026-09-18T00:02:57.006501Z","end":"2026-09-18T00:02:57.143542Z","steps":["trace[1427713838] 'process raft request' (duration: 134.402564ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:57.792613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.038294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-09-18T00:02:57.792728Z","caller":"traceutil/trace.go:171","msg":"trace[1667572622] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:738; }","duration":"103.248997ms","start":"2026-09-18T00:02:57.689428Z","end":"2026-09-18T00:02:57.792677Z","steps":["trace[1667572622] 'range keys from in-memory index tree' (duration: 102.582357ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:03:04.714169Z","caller":"traceutil/trace.go:171","msg":"trace[82840246] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"152.001714ms","start":"2026-09-18T00:03:04.562138Z","end":"2026-09-18T00:03:04.714139Z","steps":["trace[82840246] 'process raft request' (duration: 151.810882ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:03:11.682652Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1933346825639508767,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-18T00:03:11.895196Z","caller":"traceutil/trace.go:171","msg":"trace[1748711795] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"751.096275ms","start":"2026-09-18T00:03:11.14407Z","end":"2026-09-18T00:03:11.895167Z","steps":["trace[1748711795] 'process raft request' (duration: 750.857162ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:03:11.895383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:03:11.144052Z","time spent":"751.231607ms","remote":"127.0.0.1:59634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T00:03:11.903798Z","caller":"traceutil/trace.go:171","msg":"trace[786302152] linearizableReadLoop","detail":"{readStateIndex:866; appliedIndex:864; }","duration":"721.735127ms","start":"2026-09-18T00:03:11.18203Z","end":"2026-09-18T00:03:11.903765Z","steps":["trace[786302152] 'read index received' (duration: 712.882098ms)","trace[786302152] 'applied index is now lower than readState.Index' (duration: 8.852029ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:03:11.903932Z","caller":"traceutil/trace.go:171","msg":"trace[1054063503] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"311.90364ms","start":"2026-09-18T00:03:11.591994Z","end":"2026-09-18T00:03:11.903897Z","steps":["trace[1054063503] 'process raft request' (duration: 311.589214ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:03:11.904009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.946371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T00:03:11.904069Z","caller":"traceutil/trace.go:171","msg":"trace[1194965068] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:832; }","duration":"722.103624ms","start":"2026-09-18T00:03:11.181945Z","end":"2026-09-18T00:03:11.904049Z","steps":["trace[1194965068] 'agreement among raft nodes before linearized reading' (duration: 721.983032ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:03:11.904112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:03:11.181922Z","time spent":"722.176984ms","remote":"127.0.0.1:59860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-09-18T00:03:11.904177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.448417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-18T00:03:11.904266Z","caller":"traceutil/trace.go:171","msg":"trace[1654362133] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:832; }","duration":"519.581168ms","start":"2026-09-18T00:03:11.384664Z","end":"2026-09-18T00:03:11.904245Z","steps":["trace[1654362133] 'agreement among raft nodes before linearized reading' (duration: 519.314755ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:03:11.904307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:03:11.384644Z","time spent":"519.65339ms","remote":"127.0.0.1:59634","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-09-18T00:03:11.904304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.851122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-09-18T00:03:11.904304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:03:11.591951Z","time spent":"312.174854ms","remote":"127.0.0.1:59600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T00:03:11.904376Z","caller":"traceutil/trace.go:171","msg":"trace[1787436344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:832; }","duration":"486.942963ms","start":"2026-09-18T00:03:11.417408Z","end":"2026-09-18T00:03:11.904351Z","steps":["trace[1787436344] 'agreement among raft nodes before linearized reading' (duration: 486.658829ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:03:11.904428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:03:11.417396Z","time spent":"487.019144ms","remote":"127.0.0.1:59634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-18T00:03:11.904454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.426787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-18T00:03:11.904483Z","caller":"traceutil/trace.go:171","msg":"trace[1108749613] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:832; }","duration":"506.483058ms","start":"2026-09-18T00:03:11.39799Z","end":"2026-09-18T00:03:11.904473Z","steps":["trace[1108749613] 'agreement among raft nodes before linearized reading' (duration: 506.404537ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:03:11.904503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:03:11.397924Z","time spent":"506.572809ms","remote":"127.0.0.1:59634","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":"warn","ts":"2026-09-18T00:03:11.904483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.188265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-18T00:03:11.904584Z","caller":"traceutil/trace.go:171","msg":"trace[1239954280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:832; }","duration":"500.348928ms","start":"2026-09-18T00:03:11.404193Z","end":"2026-09-18T00:03:11.904542Z","steps":["trace[1239954280] 'agreement among raft nodes before linearized reading' (duration: 499.952082ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:03:11.904638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:03:11.404167Z","time spent":"500.46422ms","remote":"127.0.0.1:59634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-18T00:03:14.707618Z","caller":"traceutil/trace.go:171","msg":"trace[501791625] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"112.684165ms","start":"2026-09-18T00:03:14.594909Z","end":"2026-09-18T00:03:14.707593Z","steps":["trace[501791625] 'process raft request' (duration: 112.519193ms)"],"step_count":1}