{"level":"warn","ts":"2026-02-08T22:13:29.661234Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-08T22:13:29.661306Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.218: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.218:2380","--initial-cluster=instance=https://162.253.55.218:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.218:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.218: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-02-08T22:13:29.661432Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-08T22:13:29.661447Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.218:2380"]} {"level":"info","ts":"2026-02-08T22:13:29.661461Z","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-02-08T22:13:29.661959Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.218:2379"]} {"level":"info","ts":"2026-02-08T22:13:29.662025Z","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":2,"max-cpu-available":2,"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.218:2380"],"listen-peer-urls":["https://162.253.55.218:2380"],"advertise-client-urls":["https://162.253.55.218:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.218:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.218: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-02-08T22:13:29.740468Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"78.268633ms"} {"level":"info","ts":"2026-02-08T22:13:29.747467Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"bb7d59f4041c8abe","cluster-id":"5330ca29224af3c4"} {"level":"info","ts":"2026-02-08T22:13:29.747556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=()"} {"level":"info","ts":"2026-02-08T22:13:29.747592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became follower at term 0"} {"level":"info","ts":"2026-02-08T22:13:29.747648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft bb7d59f4041c8abe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-08T22:13:29.747675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became follower at term 1"} {"level":"info","ts":"2026-02-08T22:13:29.747737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=(13510053361757227710)"} {"level":"warn","ts":"2026-02-08T22:13:29.756142Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-08T22:13:29.767183Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-08T22:13:29.772041Z","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-02-08T22:13:29.775001Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"bb7d59f4041c8abe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-08T22:13:29.778209Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"bb7d59f4041c8abe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-08T22:13:29.778391Z","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-02-08T22:13:29.779544Z","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-02-08T22:13:29.779577Z","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-02-08T22:13:29.779973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=(13510053361757227710)"} {"level":"info","ts":"2026-02-08T22:13:29.780058Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5330ca29224af3c4","local-member-id":"bb7d59f4041c8abe","added-peer-id":"bb7d59f4041c8abe","added-peer-peer-urls":["https://162.253.55.218:2380"]} {"level":"info","ts":"2026-02-08T22:13:29.783567Z","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-02-08T22:13:29.78489Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.218:2380"} {"level":"info","ts":"2026-02-08T22:13:29.785002Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.218:2380"} {"level":"info","ts":"2026-02-08T22:13:29.788447Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"bb7d59f4041c8abe","initial-advertise-peer-urls":["https://162.253.55.218:2380"],"listen-peer-urls":["https://162.253.55.218:2380"],"advertise-client-urls":["https://162.253.55.218:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.218:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-08T22:13:29.788491Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-08T22:13:29.849966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe is starting a new election at term 1"} {"level":"info","ts":"2026-02-08T22:13:29.849988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became pre-candidate at term 1"} {"level":"info","ts":"2026-02-08T22:13:29.850002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe received MsgPreVoteResp from bb7d59f4041c8abe at term 1"} {"level":"info","ts":"2026-02-08T22:13:29.850012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became candidate at term 2"} {"level":"info","ts":"2026-02-08T22:13:29.850017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe received MsgVoteResp from bb7d59f4041c8abe at term 2"} {"level":"info","ts":"2026-02-08T22:13:29.850025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became leader at term 2"} {"level":"info","ts":"2026-02-08T22:13:29.850031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: bb7d59f4041c8abe elected leader bb7d59f4041c8abe at term 2"} {"level":"info","ts":"2026-02-08T22:13:29.853227Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:13:29.859654Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"bb7d59f4041c8abe","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.218:2379]}","request-path":"/0/members/bb7d59f4041c8abe/attributes","cluster-id":"5330ca29224af3c4","publish-timeout":"7s"} {"level":"info","ts":"2026-02-08T22:13:29.859673Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T22:13:29.86103Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T22:13:29.862446Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-08T22:13:29.867009Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.218:2379"} {"level":"info","ts":"2026-02-08T22:13:29.867953Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-08T22:13:29.867968Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-08T22:13:29.868086Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5330ca29224af3c4","local-member-id":"bb7d59f4041c8abe","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:13:29.876072Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:13:29.876101Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:13:48.296558Z","caller":"traceutil/trace.go:171","msg":"trace[1722044762] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"291.431142ms","start":"2026-02-08T22:13:48.005117Z","end":"2026-02-08T22:13:48.296549Z","steps":["trace[1722044762] 'process raft request' (duration: 291.074874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:13:48.297134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.315472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-08T22:13:48.297242Z","caller":"traceutil/trace.go:171","msg":"trace[383902165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:456; }","duration":"237.467105ms","start":"2026-02-08T22:13:48.059764Z","end":"2026-02-08T22:13:48.297231Z","steps":["trace[383902165] 'agreement among raft nodes before linearized reading' (duration: 237.330872ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:13:48.29636Z","caller":"traceutil/trace.go:171","msg":"trace[547028131] linearizableReadLoop","detail":"{readStateIndex:466; appliedIndex:465; }","duration":"236.523863ms","start":"2026-02-08T22:13:48.059819Z","end":"2026-02-08T22:13:48.296343Z","steps":["trace[547028131] 'read index received' (duration: 236.307688ms)","trace[547028131] 'applied index is now lower than readState.Index' (duration: 215.485µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:13:48.943354Z","caller":"traceutil/trace.go:171","msg":"trace[1217645367] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"636.883223ms","start":"2026-02-08T22:13:48.30645Z","end":"2026-02-08T22:13:48.943333Z","steps":["trace[1217645367] 'process raft request' (duration: 636.74205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:13:48.943897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:13:48.306436Z","time spent":"636.986606ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:13:48.948926Z","caller":"traceutil/trace.go:171","msg":"trace[903821098] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"641.141915ms","start":"2026-02-08T22:13:48.307769Z","end":"2026-02-08T22:13:48.948911Z","steps":["trace[903821098] 'process raft request' (duration: 640.822948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:13:48.949011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:13:48.307755Z","time spent":"641.213647ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:13:48.949372Z","caller":"traceutil/trace.go:171","msg":"trace[1349294935] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"640.205403ms","start":"2026-02-08T22:13:48.309156Z","end":"2026-02-08T22:13:48.949362Z","steps":["trace[1349294935] 'process raft request' (duration: 639.696851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:13:48.949431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:13:48.309122Z","time spent":"640.278455ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:14:03.555768Z","caller":"traceutil/trace.go:171","msg":"trace[2118830324] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"103.475797ms","start":"2026-02-08T22:14:03.452269Z","end":"2026-02-08T22:14:03.555745Z","steps":["trace[2118830324] 'process raft request' (duration: 91.51801ms)","trace[2118830324] 'compare' (duration: 11.703841ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:14:06.250179Z","caller":"traceutil/trace.go:171","msg":"trace[1221585404] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"422.688447ms","start":"2026-02-08T22:14:05.827458Z","end":"2026-02-08T22:14:06.250147Z","steps":["trace[1221585404] 'process raft request' (duration: 422.480472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:06.25108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:05.827441Z","time spent":"423.528696ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:14:06.44946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.978819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:14:06.449532Z","caller":"traceutil/trace.go:171","msg":"trace[1290553913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"169.124443ms","start":"2026-02-08T22:14:06.28039Z","end":"2026-02-08T22:14:06.449514Z","steps":["trace[1290553913] 'range keys from in-memory index tree' (duration: 168.911918ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:08.695264Z","caller":"traceutil/trace.go:171","msg":"trace[1261403807] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:554; }","duration":"416.98657ms","start":"2026-02-08T22:14:08.278252Z","end":"2026-02-08T22:14:08.695239Z","steps":["trace[1261403807] 'read index received' (duration: 416.788115ms)","trace[1261403807] 'applied index is now lower than readState.Index' (duration: 197.335µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:14:08.695809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.374518ms","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-02-08T22:14:08.695852Z","caller":"traceutil/trace.go:171","msg":"trace[535778919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:541; }","duration":"171.428749ms","start":"2026-02-08T22:14:08.52441Z","end":"2026-02-08T22:14:08.695839Z","steps":["trace[535778919] 'agreement among raft nodes before linearized reading' (duration: 171.266885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:08.696225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.967273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:14:08.695547Z","caller":"traceutil/trace.go:171","msg":"trace[2118878849] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"552.033585ms","start":"2026-02-08T22:14:08.143502Z","end":"2026-02-08T22:14:08.695535Z","steps":["trace[2118878849] 'process raft request' (duration: 551.616005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:08.696578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:08.143487Z","time spent":"552.960226ms","remote":"127.0.0.1:42294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:14:08.696383Z","caller":"traceutil/trace.go:171","msg":"trace[581622008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:541; }","duration":"418.159897ms","start":"2026-02-08T22:14:08.278212Z","end":"2026-02-08T22:14:08.696372Z","steps":["trace[581622008] 'agreement among raft nodes before linearized reading' (duration: 417.977733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:08.696706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:08.278199Z","time spent":"418.493255ms","remote":"127.0.0.1:42158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-08T22:14:10.864598Z","caller":"traceutil/trace.go:171","msg":"trace[660681252] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"140.789243ms","start":"2026-02-08T22:14:10.723779Z","end":"2026-02-08T22:14:10.864568Z","steps":["trace[660681252] 'process raft request' (duration: 140.614868ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:19.735997Z","caller":"traceutil/trace.go:171","msg":"trace[1133216615] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:683; }","duration":"432.118412ms","start":"2026-02-08T22:14:19.303857Z","end":"2026-02-08T22:14:19.735975Z","steps":["trace[1133216615] 'read index received' (duration: 431.973069ms)","trace[1133216615] 'applied index is now lower than readState.Index' (duration: 144.743µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:14:19.73652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.018793ms","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-02-08T22:14:19.736551Z","caller":"traceutil/trace.go:171","msg":"trace[1457172865] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:666; }","duration":"430.076044ms","start":"2026-02-08T22:14:19.306469Z","end":"2026-02-08T22:14:19.736545Z","steps":["trace[1457172865] 'agreement among raft nodes before linearized reading' (duration: 429.957821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:19.736571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:19.306441Z","time spent":"430.124125ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T22:14:19.736617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.704086ms","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-02-08T22:14:19.736764Z","caller":"traceutil/trace.go:171","msg":"trace[2036937811] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"448.727262ms","start":"2026-02-08T22:14:19.288027Z","end":"2026-02-08T22:14:19.736755Z","steps":["trace[2036937811] 'process raft request' (duration: 447.847241ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:19.736782Z","caller":"traceutil/trace.go:171","msg":"trace[1987204813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:666; }","duration":"432.880811ms","start":"2026-02-08T22:14:19.303828Z","end":"2026-02-08T22:14:19.736709Z","steps":["trace[1987204813] 'agreement among raft nodes before linearized reading' (duration: 432.42829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:19.736857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:19.288019Z","time spent":"448.762522ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:14:19.736876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:19.303816Z","time spent":"433.047324ms","remote":"127.0.0.1:42374","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-02-08T22:14:19.935766Z","caller":"traceutil/trace.go:171","msg":"trace[1692264091] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"183.12004ms","start":"2026-02-08T22:14:19.752624Z","end":"2026-02-08T22:14:19.935744Z","steps":["trace[1692264091] 'process raft request' (duration: 103.552368ms)","trace[1692264091] 'compare' (duration: 79.49182ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:14:20.395992Z","caller":"traceutil/trace.go:171","msg":"trace[910220509] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:685; }","duration":"480.630008ms","start":"2026-02-08T22:14:19.915331Z","end":"2026-02-08T22:14:20.395961Z","steps":["trace[910220509] 'read index received' (duration: 426.63989ms)","trace[910220509] 'applied index is now lower than readState.Index' (duration: 53.988078ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:14:20.396115Z","caller":"traceutil/trace.go:171","msg":"trace[836209213] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"640.11095ms","start":"2026-02-08T22:14:19.755997Z","end":"2026-02-08T22:14:20.396108Z","steps":["trace[836209213] 'process raft request' (duration: 586.090042ms)","trace[836209213] 'compare' (duration: 53.483945ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:14:20.396214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:19.75598Z","time spent":"640.154751ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:14:20.396314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.591943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:14:20.396386Z","caller":"traceutil/trace.go:171","msg":"trace[1807719786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:668; }","duration":"109.732947ms","start":"2026-02-08T22:14:20.286636Z","end":"2026-02-08T22:14:20.396369Z","steps":["trace[1807719786] 'agreement among raft nodes before linearized reading' (duration: 109.584163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:20.396707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.387197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bgxm6\" ","response":"range_response_count:1 size:18250"} {"level":"info","ts":"2026-02-08T22:14:20.396748Z","caller":"traceutil/trace.go:171","msg":"trace[869495469] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bgxm6; range_end:; response_count:1; response_revision:668; }","duration":"481.437818ms","start":"2026-02-08T22:14:19.915283Z","end":"2026-02-08T22:14:20.396721Z","steps":["trace[869495469] 'agreement among raft nodes before linearized reading' (duration: 481.321345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:20.396781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:19.91523Z","time spent":"481.53168ms","remote":"127.0.0.1:42294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18273,"request content":"key:\"/registry/pods/kube-system/cilium-bgxm6\" "} {"level":"info","ts":"2026-02-08T22:14:28.825928Z","caller":"traceutil/trace.go:171","msg":"trace[1432599383] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"364.057387ms","start":"2026-02-08T22:14:28.461852Z","end":"2026-02-08T22:14:28.82591Z","steps":["trace[1432599383] 'process raft request' (duration: 363.926074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:28.826068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:28.461833Z","time spent":"364.16211ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:14:29.074425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.300381ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-08T22:14:30.610227Z","caller":"traceutil/trace.go:171","msg":"trace[792469006] linearizableReadLoop","detail":"{readStateIndex:760; appliedIndex:759; }","duration":"333.679799ms","start":"2026-02-08T22:14:30.276533Z","end":"2026-02-08T22:14:30.610213Z","steps":["trace[792469006] 'read index received' (duration: 333.592257ms)","trace[792469006] 'applied index is now lower than readState.Index' (duration: 86.962µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:14:30.610323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.776991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:14:30.610342Z","caller":"traceutil/trace.go:171","msg":"trace[42997559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:739; }","duration":"333.834772ms","start":"2026-02-08T22:14:30.276501Z","end":"2026-02-08T22:14:30.610336Z","steps":["trace[42997559] 'agreement among raft nodes before linearized reading' (duration: 333.77043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:30.610358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:30.276488Z","time spent":"333.866873ms","remote":"127.0.0.1:42142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-08T22:14:30.610417Z","caller":"traceutil/trace.go:171","msg":"trace[774268119] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"635.575392ms","start":"2026-02-08T22:14:29.97482Z","end":"2026-02-08T22:14:30.610396Z","steps":["trace[774268119] 'process raft request' (duration: 635.300676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:30.61052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:29.974804Z","time spent":"635.659694ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:14:31.535715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.715805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:14:31.535761Z","caller":"traceutil/trace.go:171","msg":"trace[522852731] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:739; }","duration":"799.800468ms","start":"2026-02-08T22:14:30.73595Z","end":"2026-02-08T22:14:31.535751Z","steps":["trace[522852731] 'count revisions from in-memory index tree' (duration: 799.638914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:31.535783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:30.735934Z","time spent":"799.843428ms","remote":"127.0.0.1:42278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-02-08T22:14:31.53602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.879894ms","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-02-08T22:14:31.536034Z","caller":"traceutil/trace.go:171","msg":"trace[495934167] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:739; }","duration":"706.918415ms","start":"2026-02-08T22:14:30.829111Z","end":"2026-02-08T22:14:31.536029Z","steps":["trace[495934167] 'range keys from in-memory index tree' (duration: 706.797462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:31.536046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:30.829098Z","time spent":"706.945046ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T22:14:31.536328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.283657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-08T22:14:31.536325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.223926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-08T22:14:31.536343Z","caller":"traceutil/trace.go:171","msg":"trace[63580596] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:739; }","duration":"447.324719ms","start":"2026-02-08T22:14:31.089014Z","end":"2026-02-08T22:14:31.536339Z","steps":["trace[63580596] 'range keys from in-memory index tree' (duration: 447.178835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:31.536359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:31.088999Z","time spent":"447.356289ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-08T22:14:31.536366Z","caller":"traceutil/trace.go:171","msg":"trace[1904663448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:739; }","duration":"447.274537ms","start":"2026-02-08T22:14:31.08908Z","end":"2026-02-08T22:14:31.536354Z","steps":["trace[1904663448] 'range keys from in-memory index tree' (duration: 447.126713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:31.536443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:31.089077Z","time spent":"447.315218ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T22:14:31.536467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.81195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:14:31.53648Z","caller":"traceutil/trace.go:171","msg":"trace[613036392] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:739; }","duration":"256.857182ms","start":"2026-02-08T22:14:31.279619Z","end":"2026-02-08T22:14:31.536476Z","steps":["trace[613036392] 'range keys from in-memory index tree' (duration: 256.750749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:32.007856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.15915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-08T22:14:32.007932Z","caller":"traceutil/trace.go:171","msg":"trace[732999436] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:742; }","duration":"173.241382ms","start":"2026-02-08T22:14:31.834672Z","end":"2026-02-08T22:14:32.007914Z","steps":["trace[732999436] 'range keys from in-memory index tree' (duration: 173.013317ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:47.419571Z","caller":"traceutil/trace.go:171","msg":"trace[1753444551] linearizableReadLoop","detail":"{readStateIndex:816; appliedIndex:815; }","duration":"140.153661ms","start":"2026-02-08T22:14:47.279403Z","end":"2026-02-08T22:14:47.419557Z","steps":["trace[1753444551] 'read index received' (duration: 140.016738ms)","trace[1753444551] 'applied index is now lower than readState.Index' (duration: 136.403µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:14:47.419821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.395916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:14:47.41988Z","caller":"traceutil/trace.go:171","msg":"trace[695087513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:791; }","duration":"140.501189ms","start":"2026-02-08T22:14:47.279365Z","end":"2026-02-08T22:14:47.419867Z","steps":["trace[695087513] 'agreement among raft nodes before linearized reading' (duration: 140.359996ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:47.419821Z","caller":"traceutil/trace.go:171","msg":"trace[692638735] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"347.718192ms","start":"2026-02-08T22:14:47.072096Z","end":"2026-02-08T22:14:47.419814Z","steps":["trace[692638735] 'process raft request' (duration: 347.375814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:47.420095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:47.07208Z","time spent":"347.977677ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:14:49.27125Z","caller":"traceutil/trace.go:171","msg":"trace[2091958495] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:820; }","duration":"497.957225ms","start":"2026-02-08T22:14:48.773266Z","end":"2026-02-08T22:14:49.271224Z","steps":["trace[2091958495] 'read index received' (duration: 497.706279ms)","trace[2091958495] 'applied index is now lower than readState.Index' (duration: 249.736µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:14:49.271432Z","caller":"traceutil/trace.go:171","msg":"trace[1595859746] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"564.938474ms","start":"2026-02-08T22:14:48.706479Z","end":"2026-02-08T22:14:49.271417Z","steps":["trace[1595859746] 'process raft request' (duration: 564.523235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:49.271597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.323782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-08T22:14:49.27161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:48.706459Z","time spent":"565.041577ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:14:49.271658Z","caller":"traceutil/trace.go:171","msg":"trace[1719770820] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:796; }","duration":"498.421005ms","start":"2026-02-08T22:14:48.77322Z","end":"2026-02-08T22:14:49.271641Z","steps":["trace[1719770820] 'agreement among raft nodes before linearized reading' (duration: 498.321903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:49.271704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:48.773204Z","time spent":"498.485786ms","remote":"127.0.0.1:42538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-02-08T22:14:49.322491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.677304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:14:49.322556Z","caller":"traceutil/trace.go:171","msg":"trace[369714246] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:796; }","duration":"278.771796ms","start":"2026-02-08T22:14:49.043772Z","end":"2026-02-08T22:14:49.322544Z","steps":["trace[369714246] 'agreement among raft nodes before linearized reading' (duration: 278.679684ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:50.269817Z","caller":"traceutil/trace.go:171","msg":"trace[185064977] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"119.003545ms","start":"2026-02-08T22:14:50.15079Z","end":"2026-02-08T22:14:50.269794Z","steps":["trace[185064977] 'process raft request' (duration: 118.876372ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:51.415771Z","caller":"traceutil/trace.go:171","msg":"trace[955484091] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"128.394179ms","start":"2026-02-08T22:14:51.287348Z","end":"2026-02-08T22:14:51.415742Z","steps":["trace[955484091] 'process raft request' (duration: 128.254916ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:03.568509Z","caller":"traceutil/trace.go:171","msg":"trace[196739021] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:914; }","duration":"292.059643ms","start":"2026-02-08T22:15:03.276434Z","end":"2026-02-08T22:15:03.568493Z","steps":["trace[196739021] 'read index received' (duration: 291.92875ms)","trace[196739021] 'applied index is now lower than readState.Index' (duration: 130.353µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:15:03.568737Z","caller":"traceutil/trace.go:171","msg":"trace[626385506] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"312.148324ms","start":"2026-02-08T22:15:03.256583Z","end":"2026-02-08T22:15:03.568732Z","steps":["trace[626385506] 'process raft request' (duration: 311.820087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:03.568805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:03.256562Z","time spent":"312.198506ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:15:03.568913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.482682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:03.568929Z","caller":"traceutil/trace.go:171","msg":"trace[637424187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:885; }","duration":"292.537244ms","start":"2026-02-08T22:15:03.276386Z","end":"2026-02-08T22:15:03.568924Z","steps":["trace[637424187] 'agreement among raft nodes before linearized reading' (duration: 292.511303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:03.569041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.04327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.218\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-08T22:15:03.569053Z","caller":"traceutil/trace.go:171","msg":"trace[271195618] range","detail":"{range_begin:/registry/masterleases/162.253.55.218; range_end:; response_count:1; response_revision:885; }","duration":"191.077022ms","start":"2026-02-08T22:15:03.377973Z","end":"2026-02-08T22:15:03.56905Z","steps":["trace[271195618] 'agreement among raft nodes before linearized reading' (duration: 191.02094ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:03.753501Z","caller":"traceutil/trace.go:171","msg":"trace[2110133082] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"180.423818ms","start":"2026-02-08T22:15:03.573057Z","end":"2026-02-08T22:15:03.75348Z","steps":["trace[2110133082] 'process raft request' (duration: 180.10806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:04.292329Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9997600018567666319,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T22:15:04.69405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.57907ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T22:15:04.69423Z","caller":"traceutil/trace.go:171","msg":"trace[44827583] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:918; }","duration":"902.39776ms","start":"2026-02-08T22:15:03.791816Z","end":"2026-02-08T22:15:04.694214Z","steps":["trace[44827583] 'read index received' (duration: 761.593784ms)","trace[44827583] 'applied index is now lower than readState.Index' (duration: 140.803126ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:15:04.694491Z","caller":"traceutil/trace.go:171","msg":"trace[1744584286] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"935.994263ms","start":"2026-02-08T22:15:03.758484Z","end":"2026-02-08T22:15:04.694478Z","steps":["trace[1744584286] 'process raft request' (duration: 794.91904ms)","trace[1744584286] 'compare' (duration: 139.714071ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:04.69455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:03.758474Z","time spent":"936.044234ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:15:04.694992Z","caller":"traceutil/trace.go:171","msg":"trace[511431901] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"935.998412ms","start":"2026-02-08T22:15:03.758981Z","end":"2026-02-08T22:15:04.69498Z","steps":["trace[511431901] 'process raft request' (duration: 935.149462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:04.695056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:03.758976Z","time spent":"936.046233ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:15:04.695192Z","caller":"traceutil/trace.go:171","msg":"trace[87781950] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"934.616951ms","start":"2026-02-08T22:15:03.760567Z","end":"2026-02-08T22:15:04.695184Z","steps":["trace[87781950] 'process raft request' (duration: 933.613427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:04.695279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:03.760549Z","time spent":"934.698123ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:15:04.695381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.562867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-08T22:15:04.695407Z","caller":"traceutil/trace.go:171","msg":"trace[537641250] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:890; }","duration":"903.611959ms","start":"2026-02-08T22:15:03.791786Z","end":"2026-02-08T22:15:04.695398Z","steps":["trace[537641250] 'agreement among raft nodes before linearized reading' (duration: 903.557897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:04.695432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:03.791774Z","time spent":"903.652209ms","remote":"127.0.0.1:42282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-08T22:15:04.695685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.782956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-08T22:15:04.695718Z","caller":"traceutil/trace.go:171","msg":"trace[1913610201] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:890; }","duration":"378.838457ms","start":"2026-02-08T22:15:04.316868Z","end":"2026-02-08T22:15:04.695707Z","steps":["trace[1913610201] 'agreement among raft nodes before linearized reading' (duration: 378.705243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:04.695744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:04.316854Z","time spent":"378.884158ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-08T22:15:05.030923Z","caller":"traceutil/trace.go:171","msg":"trace[2139437805] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:921; }","duration":"324.9794ms","start":"2026-02-08T22:15:04.705921Z","end":"2026-02-08T22:15:05.0309Z","steps":["trace[2139437805] 'read index received' (duration: 231.05789ms)","trace[2139437805] 'applied index is now lower than readState.Index' (duration: 93.92055ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:05.031109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.161574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-08T22:15:05.031139Z","caller":"traceutil/trace.go:171","msg":"trace[664774041] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:891; }","duration":"325.219435ms","start":"2026-02-08T22:15:04.70591Z","end":"2026-02-08T22:15:05.03113Z","steps":["trace[664774041] 'agreement among raft nodes before linearized reading' (duration: 325.077302ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:05.031156Z","caller":"traceutil/trace.go:171","msg":"trace[717411349] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"328.42756ms","start":"2026-02-08T22:15:04.702708Z","end":"2026-02-08T22:15:05.031135Z","steps":["trace[717411349] 'process raft request' (duration: 234.327006ms)","trace[717411349] 'compare' (duration: 93.738815ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:05.031175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:04.705904Z","time spent":"325.260626ms","remote":"127.0.0.1:42386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-02-08T22:15:05.031249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:04.7027Z","time spent":"328.497692ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:15:13.204894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.087871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-08T22:15:13.204945Z","caller":"traceutil/trace.go:171","msg":"trace[1729183032] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:911; }","duration":"154.161063ms","start":"2026-02-08T22:15:13.050774Z","end":"2026-02-08T22:15:13.204935Z","steps":["trace[1729183032] 'range keys from in-memory index tree' (duration: 154.00136ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:14.079934Z","caller":"traceutil/trace.go:171","msg":"trace[2002894502] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"282.865911ms","start":"2026-02-08T22:15:13.797062Z","end":"2026-02-08T22:15:14.079928Z","steps":["trace[2002894502] 'process raft request' (duration: 282.590315ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:14.079761Z","caller":"traceutil/trace.go:171","msg":"trace[1108804173] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:946; }","duration":"215.718508ms","start":"2026-02-08T22:15:13.864027Z","end":"2026-02-08T22:15:14.079746Z","steps":["trace[1108804173] 'read index received' (duration: 215.572555ms)","trace[1108804173] 'applied index is now lower than readState.Index' (duration: 145.353µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:14.080194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.156458ms","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-02-08T22:15:14.080211Z","caller":"traceutil/trace.go:171","msg":"trace[129309369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:914; }","duration":"216.203139ms","start":"2026-02-08T22:15:13.864003Z","end":"2026-02-08T22:15:14.080206Z","steps":["trace[129309369] 'agreement among raft nodes before linearized reading' (duration: 216.121347ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:14.894655Z","caller":"traceutil/trace.go:171","msg":"trace[1419168543] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"115.157377ms","start":"2026-02-08T22:15:14.779484Z","end":"2026-02-08T22:15:14.894642Z","steps":["trace[1419168543] 'process raft request' (duration: 56.776765ms)","trace[1419168543] 'compare' (duration: 58.131346ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:15:14.89474Z","caller":"traceutil/trace.go:171","msg":"trace[1147189541] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"113.822787ms","start":"2026-02-08T22:15:14.780913Z","end":"2026-02-08T22:15:14.894736Z","steps":["trace[1147189541] 'process raft request' (duration: 113.54475ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:15.4249Z","caller":"traceutil/trace.go:171","msg":"trace[1343050797] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"210.516028ms","start":"2026-02-08T22:15:15.214369Z","end":"2026-02-08T22:15:15.424885Z","steps":["trace[1343050797] 'process raft request' (duration: 210.420276ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:16.9019Z","caller":"traceutil/trace.go:171","msg":"trace[1595619246] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"120.118681ms","start":"2026-02-08T22:15:16.78177Z","end":"2026-02-08T22:15:16.901888Z","steps":["trace[1595619246] 'process raft request' (duration: 119.947827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:17.44918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.528363ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T22:15:17.449337Z","caller":"traceutil/trace.go:171","msg":"trace[22018499] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"544.252389ms","start":"2026-02-08T22:15:16.905066Z","end":"2026-02-08T22:15:17.449319Z","steps":["trace[22018499] 'process raft request' (duration: 87.344067ms)","trace[22018499] 'compare' (duration: 456.323718ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:17.449406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:16.905058Z","time spent":"544.31236ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:15:17.451101Z","caller":"traceutil/trace.go:171","msg":"trace[261978516] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:954; }","duration":"173.316793ms","start":"2026-02-08T22:15:17.277769Z","end":"2026-02-08T22:15:17.451086Z","steps":["trace[261978516] 'read index received' (duration: 27.38µs)","trace[261978516] 'applied index is now lower than readState.Index' (duration: 173.285293ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:15:17.452237Z","caller":"traceutil/trace.go:171","msg":"trace[1495440382] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"545.824855ms","start":"2026-02-08T22:15:16.906399Z","end":"2026-02-08T22:15:17.452224Z","steps":["trace[1495440382] 'process raft request' (duration: 542.884897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:17.452368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:16.906392Z","time spent":"545.873646ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:15:17.452527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.754316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:17.452556Z","caller":"traceutil/trace.go:171","msg":"trace[548073628] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:923; }","duration":"174.782127ms","start":"2026-02-08T22:15:17.277764Z","end":"2026-02-08T22:15:17.452547Z","steps":["trace[548073628] 'agreement among raft nodes before linearized reading' (duration: 174.737206ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:17.649228Z","caller":"traceutil/trace.go:171","msg":"trace[1891547229] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"187.777936ms","start":"2026-02-08T22:15:17.461431Z","end":"2026-02-08T22:15:17.649209Z","steps":["trace[1891547229] 'process raft request' (duration: 169.384623ms)","trace[1891547229] 'compare' (duration: 18.124977ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:15:18.158309Z","caller":"traceutil/trace.go:171","msg":"trace[1535032681] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"153.268063ms","start":"2026-02-08T22:15:18.005021Z","end":"2026-02-08T22:15:18.15829Z","steps":["trace[1535032681] 'process raft request' (duration: 152.951846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:18.417832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.310597ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T22:15:18.4179Z","caller":"traceutil/trace.go:171","msg":"trace[542896524] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:958; }","duration":"138.055732ms","start":"2026-02-08T22:15:18.279833Z","end":"2026-02-08T22:15:18.417889Z","steps":["trace[542896524] 'read index received' (duration: 27.02µs)","trace[542896524] 'applied index is now lower than readState.Index' (duration: 138.028012ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:18.417988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.154145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:18.418005Z","caller":"traceutil/trace.go:171","msg":"trace[1013580546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:926; }","duration":"138.193116ms","start":"2026-02-08T22:15:18.279805Z","end":"2026-02-08T22:15:18.417998Z","steps":["trace[1013580546] 'agreement among raft nodes before linearized reading' (duration: 138.113084ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:18.418103Z","caller":"traceutil/trace.go:171","msg":"trace[634656137] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"256.438864ms","start":"2026-02-08T22:15:18.161656Z","end":"2026-02-08T22:15:18.418095Z","steps":["trace[634656137] 'process raft request' (duration: 94.79887ms)","trace[634656137] 'compare' (duration: 161.188994ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:15:19.202904Z","caller":"traceutil/trace.go:171","msg":"trace[685165445] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"290.20401ms","start":"2026-02-08T22:15:18.912684Z","end":"2026-02-08T22:15:19.202888Z","steps":["trace[685165445] 'process raft request' (duration: 290.104078ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:19.576919Z","caller":"traceutil/trace.go:171","msg":"trace[1324191756] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"112.316192ms","start":"2026-02-08T22:15:19.464581Z","end":"2026-02-08T22:15:19.576898Z","steps":["trace[1324191756] 'process raft request' (duration: 112.144838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:19.83913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.534267ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T22:15:19.839223Z","caller":"traceutil/trace.go:171","msg":"trace[1474706108] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"185.628487ms","start":"2026-02-08T22:15:19.653579Z","end":"2026-02-08T22:15:19.839208Z","steps":["trace[1474706108] 'read index received' (duration: 31.901µs)","trace[1474706108] 'applied index is now lower than readState.Index' (duration: 185.595156ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:15:19.839274Z","caller":"traceutil/trace.go:171","msg":"trace[1210839503] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"367.63993ms","start":"2026-02-08T22:15:19.471613Z","end":"2026-02-08T22:15:19.839252Z","steps":["trace[1210839503] 'process raft request' (duration: 130.905668ms)","trace[1210839503] 'compare' (duration: 236.364654ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:19.839378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:19.471597Z","time spent":"367.725813ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:15:19.839386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.802731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-08T22:15:19.839416Z","caller":"traceutil/trace.go:171","msg":"trace[734212318] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:929; }","duration":"185.866983ms","start":"2026-02-08T22:15:19.653539Z","end":"2026-02-08T22:15:19.839406Z","steps":["trace[734212318] 'agreement among raft nodes before linearized reading' (duration: 185.711619ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:21.661337Z","caller":"traceutil/trace.go:171","msg":"trace[1424602002] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:965; }","duration":"383.099215ms","start":"2026-02-08T22:15:21.278216Z","end":"2026-02-08T22:15:21.661315Z","steps":["trace[1424602002] 'read index received' (duration: 382.907051ms)","trace[1424602002] 'applied index is now lower than readState.Index' (duration: 190.904µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:15:21.661642Z","caller":"traceutil/trace.go:171","msg":"trace[455364917] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"450.30452ms","start":"2026-02-08T22:15:21.211326Z","end":"2026-02-08T22:15:21.66163Z","steps":["trace[455364917] 'process raft request' (duration: 449.8551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:21.661764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:21.211309Z","time spent":"450.370551ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:15:21.662055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.835422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:21.662114Z","caller":"traceutil/trace.go:171","msg":"trace[1858711548] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:932; }","duration":"383.917294ms","start":"2026-02-08T22:15:21.278187Z","end":"2026-02-08T22:15:21.662104Z","steps":["trace[1858711548] 'agreement among raft nodes before linearized reading' (duration: 383.839072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:21.662142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:21.278176Z","time spent":"383.960465ms","remote":"127.0.0.1:42142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-08T22:15:22.076703Z","caller":"traceutil/trace.go:171","msg":"trace[1180433568] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:966; }","duration":"233.014375ms","start":"2026-02-08T22:15:21.843655Z","end":"2026-02-08T22:15:22.076669Z","steps":["trace[1180433568] 'read index received' (duration: 155.3082ms)","trace[1180433568] 'applied index is now lower than readState.Index' (duration: 77.705275ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:15:22.076918Z","caller":"traceutil/trace.go:171","msg":"trace[823671925] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"408.294363ms","start":"2026-02-08T22:15:21.66861Z","end":"2026-02-08T22:15:22.076904Z","steps":["trace[823671925] 'process raft request' (duration: 330.398774ms)","trace[823671925] 'compare' (duration: 77.510531ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:22.077007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:21.668593Z","time spent":"408.353334ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:15:22.077246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.5871ms","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-02-08T22:15:22.077276Z","caller":"traceutil/trace.go:171","msg":"trace[692671877] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:933; }","duration":"233.645201ms","start":"2026-02-08T22:15:21.843622Z","end":"2026-02-08T22:15:22.077267Z","steps":["trace[692671877] 'agreement among raft nodes before linearized reading' (duration: 233.522678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:22.077462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.690239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-08T22:15:22.077496Z","caller":"traceutil/trace.go:171","msg":"trace[1883074493] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:933; }","duration":"202.749741ms","start":"2026-02-08T22:15:21.874738Z","end":"2026-02-08T22:15:22.077488Z","steps":["trace[1883074493] 'agreement among raft nodes before linearized reading' (duration: 202.655008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:38.902404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.434379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-08T22:15:38.902577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.95925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T22:15:38.902703Z","caller":"traceutil/trace.go:171","msg":"trace[1573987236] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1047; }","duration":"486.256647ms","start":"2026-02-08T22:15:38.416423Z","end":"2026-02-08T22:15:38.902679Z","steps":["trace[1573987236] 'count revisions from in-memory index tree' (duration: 485.810566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:38.902806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:38.416402Z","time spent":"486.382949ms","remote":"127.0.0.1:42364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-02-08T22:15:38.902957Z","caller":"traceutil/trace.go:171","msg":"trace[1657081835] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1047; }","duration":"757.528551ms","start":"2026-02-08T22:15:38.144943Z","end":"2026-02-08T22:15:38.902471Z","steps":["trace[1657081835] 'range keys from in-memory index tree' (duration: 757.289916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:38.90319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:38.144889Z","time spent":"758.284209ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T22:15:38.904248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.948527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-08T22:15:38.904534Z","caller":"traceutil/trace.go:171","msg":"trace[73452482] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1047; }","duration":"757.027099ms","start":"2026-02-08T22:15:38.147259Z","end":"2026-02-08T22:15:38.904286Z","steps":["trace[73452482] 'range keys from in-memory index tree' (duration: 756.844375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:38.904587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:38.147243Z","time spent":"757.331605ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-08T22:15:38.905116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.02272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-08T22:15:38.905159Z","caller":"traceutil/trace.go:171","msg":"trace[266716569] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1047; }","duration":"754.086553ms","start":"2026-02-08T22:15:38.151062Z","end":"2026-02-08T22:15:38.905149Z","steps":["trace[266716569] 'range keys from in-memory index tree' (duration: 753.894538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:38.905311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:38.150992Z","time spent":"754.306827ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-08T22:15:38.905646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.794348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T22:15:38.905688Z","caller":"traceutil/trace.go:171","msg":"trace[757292305] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1047; }","duration":"744.888459ms","start":"2026-02-08T22:15:38.16079Z","end":"2026-02-08T22:15:38.905678Z","steps":["trace[757292305] 'count revisions from in-memory index tree' (duration: 744.748877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:38.905988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:38.160782Z","time spent":"745.189327ms","remote":"127.0.0.1:42620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-08T22:15:38.903968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.973525ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:39.033723Z","caller":"traceutil/trace.go:171","msg":"trace[1297455011] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1047; }","duration":"446.677586ms","start":"2026-02-08T22:15:38.586977Z","end":"2026-02-08T22:15:39.033655Z","steps":["trace[1297455011] 'range keys from in-memory index tree' (duration: 316.964835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:39.036147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.293574ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-08T22:15:39.036713Z","caller":"traceutil/trace.go:171","msg":"trace[1321619857] linearizableReadLoop","detail":"{readStateIndex:1085; appliedIndex:1084; }","duration":"532.454038ms","start":"2026-02-08T22:15:38.504244Z","end":"2026-02-08T22:15:39.036698Z","steps":["trace[1321619857] 'read index received' (duration: 133.529879ms)","trace[1321619857] 'applied index is now lower than readState.Index' (duration: 398.923229ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:39.036977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.720224ms","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-02-08T22:15:39.037054Z","caller":"traceutil/trace.go:171","msg":"trace[482760987] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1047; }","duration":"532.832267ms","start":"2026-02-08T22:15:38.504211Z","end":"2026-02-08T22:15:39.037043Z","steps":["trace[482760987] 'agreement among raft nodes before linearized reading' (duration: 532.658103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:39.03712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:38.504192Z","time spent":"532.919029ms","remote":"127.0.0.1:42374","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-02-08T22:15:39.042086Z","caller":"traceutil/trace.go:171","msg":"trace[20361914] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"376.419421ms","start":"2026-02-08T22:15:38.665648Z","end":"2026-02-08T22:15:39.042068Z","steps":["trace[20361914] 'process raft request' (duration: 370.75211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:39.042352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:38.665602Z","time spent":"376.532714ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:15:39.047912Z","caller":"traceutil/trace.go:171","msg":"trace[1927519279] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"133.197731ms","start":"2026-02-08T22:15:38.914703Z","end":"2026-02-08T22:15:39.0479Z","steps":["trace[1927519279] 'process raft request' (duration: 133.177731ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:39.048579Z","caller":"traceutil/trace.go:171","msg":"trace[2116923160] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"135.563296ms","start":"2026-02-08T22:15:38.912957Z","end":"2026-02-08T22:15:39.04852Z","steps":["trace[2116923160] 'process raft request' (duration: 129.101257ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:39.048618Z","caller":"traceutil/trace.go:171","msg":"trace[241507695] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"133.975809ms","start":"2026-02-08T22:15:38.914636Z","end":"2026-02-08T22:15:39.048611Z","steps":["trace[241507695] 'process raft request' (duration: 133.225442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:16:23.360728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.26286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T22:16:23.361032Z","caller":"traceutil/trace.go:171","msg":"trace[228784702] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1228; }","duration":"108.615387ms","start":"2026-02-08T22:16:23.252398Z","end":"2026-02-08T22:16:23.361013Z","steps":["trace[228784702] 'count revisions from in-memory index tree' (duration: 108.181027ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:16:29.55261Z","caller":"traceutil/trace.go:171","msg":"trace[655500172] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"109.628369ms","start":"2026-02-08T22:16:29.442956Z","end":"2026-02-08T22:16:29.552585Z","steps":["trace[655500172] 'process raft request' (duration: 109.576138ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:16:29.552681Z","caller":"traceutil/trace.go:171","msg":"trace[1874375703] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"111.613645ms","start":"2026-02-08T22:16:29.440995Z","end":"2026-02-08T22:16:29.552608Z","steps":["trace[1874375703] 'process raft request' (duration: 58.574825ms)","trace[1874375703] 'compare' (duration: 52.713413ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:16:29.552904Z","caller":"traceutil/trace.go:171","msg":"trace[422985119] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"110.08797ms","start":"2026-02-08T22:16:29.442808Z","end":"2026-02-08T22:16:29.552896Z","steps":["trace[422985119] 'process raft request' (duration: 109.684861ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:16:57.959275Z","caller":"traceutil/trace.go:171","msg":"trace[2133912983] linearizableReadLoop","detail":"{readStateIndex:1424; appliedIndex:1423; }","duration":"114.537962ms","start":"2026-02-08T22:16:57.844717Z","end":"2026-02-08T22:16:57.959255Z","steps":["trace[2133912983] 'read index received' (duration: 87.732523ms)","trace[2133912983] 'applied index is now lower than readState.Index' (duration: 26.804129ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:16:57.960398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.672938ms","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-02-08T22:16:57.962103Z","caller":"traceutil/trace.go:171","msg":"trace[166550734] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1371; }","duration":"117.447968ms","start":"2026-02-08T22:16:57.844641Z","end":"2026-02-08T22:16:57.962089Z","steps":["trace[166550734] 'agreement among raft nodes before linearized reading' (duration: 115.556326ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:16:57.961685Z","caller":"traceutil/trace.go:171","msg":"trace[1758269826] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"195.478374ms","start":"2026-02-08T22:16:57.766187Z","end":"2026-02-08T22:16:57.961665Z","steps":["trace[1758269826] 'process raft request' (duration: 166.317422ms)","trace[1758269826] 'compare' (duration: 26.339309ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:17:19.85336Z","caller":"traceutil/trace.go:171","msg":"trace[1484124224] transaction","detail":"{read_only:false; response_revision:1458; number_of_response:1; }","duration":"109.794936ms","start":"2026-02-08T22:17:19.743539Z","end":"2026-02-08T22:17:19.853334Z","steps":["trace[1484124224] 'process raft request' (duration: 109.627582ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:17:20.343249Z","caller":"traceutil/trace.go:171","msg":"trace[1784982997] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"127.232778ms","start":"2026-02-08T22:17:20.215987Z","end":"2026-02-08T22:17:20.34322Z","steps":["trace[1784982997] 'process raft request' (duration: 84.631204ms)","trace[1784982997] 'compare' (duration: 41.501799ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:18:52.428845Z","caller":"traceutil/trace.go:171","msg":"trace[1262771003] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"197.907356ms","start":"2026-02-08T22:18:52.230916Z","end":"2026-02-08T22:18:52.428823Z","steps":["trace[1262771003] 'process raft request' (duration: 160.90658ms)","trace[1262771003] 'compare' (duration: 36.785371ms)"],"step_count":2}