{"level":"warn","ts":"2026-02-17T23:06:09.681386Z","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-17T23:06:09.683037Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227: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://199.204.45.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227: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-17T23:06:09.683165Z","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-17T23:06:09.683212Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-02-17T23:06:09.683965Z","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-17T23:06:09.684729Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-02-17T23:06:09.684932Z","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://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227: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-17T23:06:09.724307Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"36.227286ms"} {"level":"info","ts":"2026-02-17T23:06:09.73348Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-02-17T23:06:09.733625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-02-17T23:06:09.733655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-02-17T23:06:09.733718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-17T23:06:09.73376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-02-17T23:06:09.733933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-02-17T23:06:09.808507Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-17T23:06:09.885628Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-17T23:06:09.907754Z","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-17T23:06:09.910573Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-17T23:06:09.913047Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-17T23:06:09.913346Z","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-17T23:06:09.913379Z","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-17T23:06:09.915842Z","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-17T23:06:09.916146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-02-17T23:06:09.916694Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-02-17T23:06:09.952614Z","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-17T23:06:09.952779Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-17T23:06:09.9528Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-17T23:06:09.952877Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-02-17T23:06:09.952889Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-02-17T23:06:10.463942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-02-17T23:06:10.463988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-17T23:06:10.464011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-02-17T23:06:10.464032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-02-17T23:06:10.464041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-02-17T23:06:10.46405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-02-17T23:06:10.46406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-02-17T23:06:10.480133Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T23:06:10.480331Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-02-17T23:06:10.480981Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T23:06:10.481409Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T23:06:10.483937Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-17T23:06:10.483957Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-17T23:06:10.48528Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"info","ts":"2026-02-17T23:06:10.4871Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-17T23:06:10.491013Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T23:06:10.491076Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T23:06:10.491095Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-17T23:06:14.069037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.638479ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-17T23:06:14.069123Z","caller":"traceutil/trace.go:171","msg":"trace[1858686175] transaction","detail":"{read_only:false; response_revision:158; number_of_response:1; }","duration":"273.013802ms","start":"2026-02-17T23:06:13.796096Z","end":"2026-02-17T23:06:14.06911Z","steps":["trace[1858686175] 'process raft request' (duration: 127.966795ms)","trace[1858686175] 'compare' (duration: 144.520147ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:06:15.693258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.018697613s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-17T23:06:15.693761Z","caller":"traceutil/trace.go:171","msg":"trace[74888982] linearizableReadLoop","detail":"{readStateIndex:163; appliedIndex:162; }","duration":"1.460807609s","start":"2026-02-17T23:06:14.232939Z","end":"2026-02-17T23:06:15.693746Z","steps":["trace[74888982] 'read index received' (duration: 441.508644ms)","trace[74888982] 'applied index is now lower than readState.Index' (duration: 1.019297875s)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:06:15.693931Z","caller":"traceutil/trace.go:171","msg":"trace[989215665] transaction","detail":"{read_only:false; response_revision:159; number_of_response:1; }","duration":"1.621485125s","start":"2026-02-17T23:06:14.07242Z","end":"2026-02-17T23:06:15.693905Z","steps":["trace[989215665] 'process raft request' (duration: 602.094949ms)","trace[989215665] 'compare' (duration: 1.018609072s)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:06:15.694375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:06:14.072407Z","time spent":"1.621947193s","remote":"127.0.0.1:48316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":736,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-17T23:06:15.694015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.461076104s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:430"} {"level":"info","ts":"2026-02-17T23:06:15.694862Z","caller":"traceutil/trace.go:171","msg":"trace[2086174250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:159; }","duration":"1.46192843s","start":"2026-02-17T23:06:14.232924Z","end":"2026-02-17T23:06:15.694853Z","steps":["trace[2086174250] 'agreement among raft nodes before linearized reading' (duration: 1.461032263s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:15.695016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:06:14.232858Z","time spent":"1.462148964s","remote":"127.0.0.1:48230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T23:06:15.694248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.454545354s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-17T23:06:15.695455Z","caller":"traceutil/trace.go:171","msg":"trace[1172685281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:159; }","duration":"1.455760066s","start":"2026-02-17T23:06:14.239685Z","end":"2026-02-17T23:06:15.695445Z","steps":["trace[1172685281] 'agreement among raft nodes before linearized reading' (duration: 1.454505033s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:15.694272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.032421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-17T23:06:15.694292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.41993579s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-17T23:06:15.69431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.450170724s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-17T23:06:15.695686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:06:14.239674Z","time spent":"1.456002771s","remote":"127.0.0.1:48230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-17T23:06:15.695832Z","caller":"traceutil/trace.go:171","msg":"trace[1728202459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:159; }","duration":"173.61616ms","start":"2026-02-17T23:06:15.522205Z","end":"2026-02-17T23:06:15.695821Z","steps":["trace[1728202459] 'agreement among raft nodes before linearized reading' (duration: 172.057142ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:06:15.696085Z","caller":"traceutil/trace.go:171","msg":"trace[1592295290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:159; }","duration":"1.421746333s","start":"2026-02-17T23:06:14.27433Z","end":"2026-02-17T23:06:15.696077Z","steps":["trace[1592295290] 'agreement among raft nodes before linearized reading' (duration: 1.41995061s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:15.697086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:06:14.27432Z","time spent":"1.422756432s","remote":"127.0.0.1:47934","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-17T23:06:15.696242Z","caller":"traceutil/trace.go:171","msg":"trace[1696049042] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:159; }","duration":"1.45211315s","start":"2026-02-17T23:06:14.244121Z","end":"2026-02-17T23:06:15.696234Z","steps":["trace[1696049042] 'agreement among raft nodes before linearized reading' (duration: 1.450179344s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:15.697487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:06:14.244117Z","time spent":"1.453361553s","remote":"127.0.0.1:48230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-17T23:06:18.889015Z","caller":"traceutil/trace.go:171","msg":"trace[1916312452] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"108.445374ms","start":"2026-02-17T23:06:18.780551Z","end":"2026-02-17T23:06:18.888996Z","steps":["trace[1916312452] 'process raft request' (duration: 108.022526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:26.912219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.328972ms","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-02-17T23:06:26.912525Z","caller":"traceutil/trace.go:171","msg":"trace[1662275288] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:370; }","duration":"139.68384ms","start":"2026-02-17T23:06:26.772818Z","end":"2026-02-17T23:06:26.912502Z","steps":["trace[1662275288] 'range keys from in-memory index tree' (duration: 139.251351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:42.313801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.28635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4019"} {"level":"info","ts":"2026-02-17T23:06:42.313863Z","caller":"traceutil/trace.go:171","msg":"trace[1085248867] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:473; }","duration":"189.453753ms","start":"2026-02-17T23:06:42.124396Z","end":"2026-02-17T23:06:42.31385Z","steps":["trace[1085248867] 'range keys from in-memory index tree' (duration: 189.191657ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:06:42.968057Z","caller":"traceutil/trace.go:171","msg":"trace[974215847] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"110.945676ms","start":"2026-02-17T23:06:42.857091Z","end":"2026-02-17T23:06:42.968037Z","steps":["trace[974215847] 'process raft request' (duration: 110.755482ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:43.334203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.235341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:06:43.334248Z","caller":"traceutil/trace.go:171","msg":"trace[715879002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:475; }","duration":"202.308313ms","start":"2026-02-17T23:06:43.13193Z","end":"2026-02-17T23:06:43.334238Z","steps":["trace[715879002] 'range keys from in-memory index tree' (duration: 202.189811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:43.334291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.621327ms","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-17T23:06:43.334322Z","caller":"traceutil/trace.go:171","msg":"trace[437579407] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:475; }","duration":"216.707089ms","start":"2026-02-17T23:06:43.117606Z","end":"2026-02-17T23:06:43.334313Z","steps":["trace[437579407] 'range keys from in-memory index tree' (duration: 216.459874ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:06:45.624656Z","caller":"traceutil/trace.go:171","msg":"trace[839031361] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"214.533595ms","start":"2026-02-17T23:06:45.410106Z","end":"2026-02-17T23:06:45.62464Z","steps":["trace[839031361] 'process raft request' (duration: 214.450383ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:06:49.796638Z","caller":"traceutil/trace.go:171","msg":"trace[893381510] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"159.564425ms","start":"2026-02-17T23:06:49.637056Z","end":"2026-02-17T23:06:49.79662Z","steps":["trace[893381510] 'process raft request' (duration: 159.450092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:50.157238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.965371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-17T23:06:50.157286Z","caller":"traceutil/trace.go:171","msg":"trace[1855707431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:512; }","duration":"111.040032ms","start":"2026-02-17T23:06:50.046235Z","end":"2026-02-17T23:06:50.157275Z","steps":["trace[1855707431] 'range keys from in-memory index tree' (duration: 110.879158ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:50.157485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.854179ms","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-17T23:06:50.157502Z","caller":"traceutil/trace.go:171","msg":"trace[292456024] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:512; }","duration":"114.871469ms","start":"2026-02-17T23:06:50.042625Z","end":"2026-02-17T23:06:50.157497Z","steps":["trace[292456024] 'range keys from in-memory index tree' (duration: 114.777847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:51.883629Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.151100607s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T23:06:51.883996Z","caller":"traceutil/trace.go:171","msg":"trace[1999090532] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"1.151539617s","start":"2026-02-17T23:06:50.732441Z","end":"2026-02-17T23:06:51.88398Z","steps":["trace[1999090532] 'process raft request' (duration: 1.151376104s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:51.88424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:06:50.732424Z","time spent":"1.151604209s","remote":"127.0.0.1:48128","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-17T23:06:52.519485Z","caller":"traceutil/trace.go:171","msg":"trace[210106927] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:536; }","duration":"719.998095ms","start":"2026-02-17T23:06:51.799466Z","end":"2026-02-17T23:06:52.519464Z","steps":["trace[210106927] 'read index received' (duration: 85.109893ms)","trace[210106927] 'applied index is now lower than readState.Index' (duration: 634.887112ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:06:52.519995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.522047ms","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-02-17T23:06:52.520029Z","caller":"traceutil/trace.go:171","msg":"trace[1225367294] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:519; }","duration":"720.606669ms","start":"2026-02-17T23:06:51.799415Z","end":"2026-02-17T23:06:52.520021Z","steps":["trace[1225367294] 'agreement among raft nodes before linearized reading' (duration: 720.450265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:52.520053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:06:51.799386Z","time spent":"720.66169ms","remote":"127.0.0.1:48230","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-02-17T23:06:52.520054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.971064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:06:52.52011Z","caller":"traceutil/trace.go:171","msg":"trace[1944616916] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"388.069557ms","start":"2026-02-17T23:06:52.132027Z","end":"2026-02-17T23:06:52.520097Z","steps":["trace[1944616916] 'agreement among raft nodes before linearized reading' (duration: 387.953124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:52.520141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:06:52.132012Z","time spent":"388.124158ms","remote":"127.0.0.1:47934","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-02-17T23:06:52.52036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.414646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-17T23:06:52.520381Z","caller":"traceutil/trace.go:171","msg":"trace[1640817820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:519; }","duration":"324.442356ms","start":"2026-02-17T23:06:52.195931Z","end":"2026-02-17T23:06:52.520373Z","steps":["trace[1640817820] 'agreement among raft nodes before linearized reading' (duration: 324.362434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:52.520399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:06:52.195922Z","time spent":"324.472597ms","remote":"127.0.0.1:48230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T23:06:52.520474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.762663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-17T23:06:52.520488Z","caller":"traceutil/trace.go:171","msg":"trace[1338965020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:519; }","duration":"324.793805ms","start":"2026-02-17T23:06:52.19569Z","end":"2026-02-17T23:06:52.520483Z","steps":["trace[1338965020] 'agreement among raft nodes before linearized reading' (duration: 324.749984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:52.520507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:06:52.19568Z","time spent":"324.822265ms","remote":"127.0.0.1:48230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T23:06:52.52057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.755938ms","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-17T23:06:52.520584Z","caller":"traceutil/trace.go:171","msg":"trace[752102459] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:519; }","duration":"333.79295ms","start":"2026-02-17T23:06:52.186786Z","end":"2026-02-17T23:06:52.520579Z","steps":["trace[752102459] 'agreement among raft nodes before linearized reading' (duration: 333.753139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:52.520599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:06:52.186773Z","time spent":"333.82287ms","remote":"127.0.0.1:48230","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-17T23:06:55.04112Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656050781221414541,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T23:06:55.12004Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.961396302s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-17T23:06:55.19743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.063639364s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:06:55.197483Z","caller":"traceutil/trace.go:171","msg":"trace[17471655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"2.063735087s","start":"2026-02-17T23:06:53.133734Z","end":"2026-02-17T23:06:55.197469Z","steps":["trace[17471655] 'range keys from in-memory index tree' (duration: 2.063534772s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:55.197515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:06:53.133714Z","time spent":"2.063792349s","remote":"127.0.0.1:47934","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-17T23:06:55.198228Z","caller":"traceutil/trace.go:171","msg":"trace[672545267] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:541; }","duration":"657.966277ms","start":"2026-02-17T23:06:54.540254Z","end":"2026-02-17T23:06:55.19822Z","steps":["trace[672545267] 'read index received' (duration: 579.913508ms)","trace[672545267] 'applied index is now lower than readState.Index' (duration: 78.052179ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:06:55.198359Z","caller":"traceutil/trace.go:171","msg":"trace[424085793] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"2.039776379s","start":"2026-02-17T23:06:53.158573Z","end":"2026-02-17T23:06:55.19835Z","steps":["trace[424085793] 'process raft request' (duration: 1.961646808s)","trace[424085793] 'compare' (duration: 76.965804ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:06:55.198416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:06:53.158558Z","time spent":"2.0398178s","remote":"127.0.0.1:48230","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-17T23:06:55.1986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.343476ms","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-02-17T23:06:55.19862Z","caller":"traceutil/trace.go:171","msg":"trace[1984640218] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:524; }","duration":"658.387256ms","start":"2026-02-17T23:06:54.540227Z","end":"2026-02-17T23:06:55.198615Z","steps":["trace[1984640218] 'agreement among raft nodes before linearized reading' (duration: 658.303154ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:55.198637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:06:54.540215Z","time spent":"658.418966ms","remote":"127.0.0.1:48230","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-02-17T23:06:55.198737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.370468ms","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-17T23:06:55.198754Z","caller":"traceutil/trace.go:171","msg":"trace[785641833] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:524; }","duration":"635.404838ms","start":"2026-02-17T23:06:54.563344Z","end":"2026-02-17T23:06:55.198749Z","steps":["trace[785641833] 'agreement among raft nodes before linearized reading' (duration: 635.357427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:55.198769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:06:54.563333Z","time spent":"635.43253ms","remote":"127.0.0.1:48230","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-17T23:06:55.199006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.844024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-17T23:06:55.199026Z","caller":"traceutil/trace.go:171","msg":"trace[1425045963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:524; }","duration":"630.870234ms","start":"2026-02-17T23:06:54.56815Z","end":"2026-02-17T23:06:55.19902Z","steps":["trace[1425045963] 'agreement among raft nodes before linearized reading' (duration: 630.806463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:55.199042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:06:54.568148Z","time spent":"630.890135ms","remote":"127.0.0.1:48230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T23:06:55.199127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.982127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-17T23:06:55.199142Z","caller":"traceutil/trace.go:171","msg":"trace[943428837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:524; }","duration":"631.008358ms","start":"2026-02-17T23:06:54.56813Z","end":"2026-02-17T23:06:55.199138Z","steps":["trace[943428837] 'agreement among raft nodes before linearized reading' (duration: 630.966397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:55.199158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:06:54.568125Z","time spent":"631.028678ms","remote":"127.0.0.1:48230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T23:06:57.386272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.439012ms","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-17T23:06:57.386376Z","caller":"traceutil/trace.go:171","msg":"trace[1955628125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:540; }","duration":"144.536894ms","start":"2026-02-17T23:06:57.241787Z","end":"2026-02-17T23:06:57.386324Z","steps":["trace[1955628125] 'range keys from in-memory index tree' (duration: 144.302489ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:57.386751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.657913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-17T23:06:57.386782Z","caller":"traceutil/trace.go:171","msg":"trace[983937369] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:540; }","duration":"336.714374ms","start":"2026-02-17T23:06:57.050058Z","end":"2026-02-17T23:06:57.386772Z","steps":["trace[983937369] 'range keys from in-memory index tree' (duration: 336.606261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:57.386809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:06:57.050045Z","time spent":"336.756585ms","remote":"127.0.0.1:48056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-17T23:06:57.386917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.485836ms","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-17T23:06:57.387Z","caller":"traceutil/trace.go:171","msg":"trace[333566757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:540; }","duration":"145.587319ms","start":"2026-02-17T23:06:57.241387Z","end":"2026-02-17T23:06:57.386974Z","steps":["trace[333566757] 'range keys from in-memory index tree' (duration: 145.361133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:57.387156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.718021ms","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-17T23:06:57.38719Z","caller":"traceutil/trace.go:171","msg":"trace[1732278491] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:540; }","duration":"151.778542ms","start":"2026-02-17T23:06:57.235403Z","end":"2026-02-17T23:06:57.387181Z","steps":["trace[1732278491] 'range keys from in-memory index tree' (duration: 151.614988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:57.387266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.220487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:06:57.387288Z","caller":"traceutil/trace.go:171","msg":"trace[58595307] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"253.313239ms","start":"2026-02-17T23:06:57.133968Z","end":"2026-02-17T23:06:57.387281Z","steps":["trace[58595307] 'range keys from in-memory index tree' (duration: 253.152775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:57.387355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.148222ms","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-02-17T23:06:57.387388Z","caller":"traceutil/trace.go:171","msg":"trace[987405362] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:540; }","duration":"146.208423ms","start":"2026-02-17T23:06:57.24117Z","end":"2026-02-17T23:06:57.387378Z","steps":["trace[987405362] 'range keys from in-memory index tree' (duration: 146.05966ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:06:59.514191Z","caller":"traceutil/trace.go:171","msg":"trace[1945418340] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"106.684323ms","start":"2026-02-17T23:06:59.407485Z","end":"2026-02-17T23:06:59.514169Z","steps":["trace[1945418340] 'read index received' (duration: 14.610352ms)","trace[1945418340] 'applied index is now lower than readState.Index' (duration: 92.073001ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:06:59.514436Z","caller":"traceutil/trace.go:171","msg":"trace[1965091081] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"204.362355ms","start":"2026-02-17T23:06:59.310061Z","end":"2026-02-17T23:06:59.514423Z","steps":["trace[1965091081] 'process raft request' (duration: 112.067039ms)","trace[1965091081] 'compare' (duration: 91.950458ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:06:59.514689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.185915ms","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-17T23:06:59.514722Z","caller":"traceutil/trace.go:171","msg":"trace[333584098] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:588; }","duration":"107.254096ms","start":"2026-02-17T23:06:59.407458Z","end":"2026-02-17T23:06:59.514712Z","steps":["trace[333584098] 'agreement among raft nodes before linearized reading' (duration: 107.130183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:59.514904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.41543ms","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-17T23:06:59.514931Z","caller":"traceutil/trace.go:171","msg":"trace[1191574977] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:588; }","duration":"107.465081ms","start":"2026-02-17T23:06:59.407457Z","end":"2026-02-17T23:06:59.514922Z","steps":["trace[1191574977] 'agreement among raft nodes before linearized reading' (duration: 107.390059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:59.515102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.512009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-17T23:06:59.51513Z","caller":"traceutil/trace.go:171","msg":"trace[476159153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:588; }","duration":"103.558499ms","start":"2026-02-17T23:06:59.411562Z","end":"2026-02-17T23:06:59.51512Z","steps":["trace[476159153] 'agreement among raft nodes before linearized reading' (duration: 103.484198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:59.515615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.99171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-17T23:06:59.515645Z","caller":"traceutil/trace.go:171","msg":"trace[1940710937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:588; }","duration":"104.03625ms","start":"2026-02-17T23:06:59.411601Z","end":"2026-02-17T23:06:59.515637Z","steps":["trace[1940710937] 'agreement among raft nodes before linearized reading' (duration: 103.959629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:06:59.740276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.507063ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T23:06:59.740373Z","caller":"traceutil/trace.go:171","msg":"trace[310348470] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"213.99222ms","start":"2026-02-17T23:06:59.526369Z","end":"2026-02-17T23:06:59.740362Z","steps":["trace[310348470] 'process raft request' (duration: 88.345894ms)","trace[310348470] 'compare' (duration: 125.311528ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:06:59.740533Z","caller":"traceutil/trace.go:171","msg":"trace[1514349198] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"214.00509ms","start":"2026-02-17T23:06:59.526513Z","end":"2026-02-17T23:06:59.740518Z","steps":["trace[1514349198] 'process raft request' (duration: 213.821245ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:06:59.740626Z","caller":"traceutil/trace.go:171","msg":"trace[2087541765] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"211.328978ms","start":"2026-02-17T23:06:59.529291Z","end":"2026-02-17T23:06:59.74062Z","steps":["trace[2087541765] 'process raft request' (duration: 211.179975ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:06:59.740721Z","caller":"traceutil/trace.go:171","msg":"trace[1571137546] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"209.880363ms","start":"2026-02-17T23:06:59.530835Z","end":"2026-02-17T23:06:59.740715Z","steps":["trace[1571137546] 'process raft request' (duration: 209.76279ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:06:59.805675Z","caller":"traceutil/trace.go:171","msg":"trace[1287453860] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"139.204993ms","start":"2026-02-17T23:06:59.666451Z","end":"2026-02-17T23:06:59.805656Z","steps":["trace[1287453860] 'process raft request' (duration: 139.09248ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:06:59.93738Z","caller":"traceutil/trace.go:171","msg":"trace[682939143] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"110.095683ms","start":"2026-02-17T23:06:59.827265Z","end":"2026-02-17T23:06:59.93736Z","steps":["trace[682939143] 'process raft request' (duration: 72.044984ms)","trace[682939143] 'compare' (duration: 37.527407ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:07:00.64971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.223327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-17T23:07:00.649765Z","caller":"traceutil/trace.go:171","msg":"trace[1118295539] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:600; }","duration":"221.327589ms","start":"2026-02-17T23:07:00.428423Z","end":"2026-02-17T23:07:00.649751Z","steps":["trace[1118295539] 'range keys from in-memory index tree' (duration: 221.027822ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:07:01.780833Z","caller":"traceutil/trace.go:171","msg":"trace[1274748097] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"140.588086ms","start":"2026-02-17T23:07:01.640225Z","end":"2026-02-17T23:07:01.780813Z","steps":["trace[1274748097] 'read index received' (duration: 138.119228ms)","trace[1274748097] 'applied index is now lower than readState.Index' (duration: 2.467838ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:07:01.782089Z","caller":"traceutil/trace.go:171","msg":"trace[907932165] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"185.386359ms","start":"2026-02-17T23:07:01.596686Z","end":"2026-02-17T23:07:01.782072Z","steps":["trace[907932165] 'process raft request' (duration: 182.263006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:07:01.78255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.310026ms","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-02-17T23:07:01.782693Z","caller":"traceutil/trace.go:171","msg":"trace[859280290] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:607; }","duration":"142.4558ms","start":"2026-02-17T23:07:01.640184Z","end":"2026-02-17T23:07:01.78264Z","steps":["trace[859280290] 'agreement among raft nodes before linearized reading' (duration: 142.234825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:07:01.783343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.062664ms","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-02-17T23:07:01.783573Z","caller":"traceutil/trace.go:171","msg":"trace[1557269455] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:607; }","duration":"143.260948ms","start":"2026-02-17T23:07:01.640261Z","end":"2026-02-17T23:07:01.783522Z","steps":["trace[1557269455] 'agreement among raft nodes before linearized reading' (duration: 142.957881ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:07:05.305373Z","caller":"traceutil/trace.go:171","msg":"trace[1142767424] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"153.068813ms","start":"2026-02-17T23:07:05.152287Z","end":"2026-02-17T23:07:05.305356Z","steps":["trace[1142767424] 'read index received' (duration: 152.857438ms)","trace[1142767424] 'applied index is now lower than readState.Index' (duration: 210.675µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:07:05.305504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.200096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:07:05.305521Z","caller":"traceutil/trace.go:171","msg":"trace[476749875] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"153.254488ms","start":"2026-02-17T23:07:05.152261Z","end":"2026-02-17T23:07:05.305516Z","steps":["trace[476749875] 'agreement among raft nodes before linearized reading' (duration: 153.180026ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:07:05.305647Z","caller":"traceutil/trace.go:171","msg":"trace[114114620] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"180.494127ms","start":"2026-02-17T23:07:05.125133Z","end":"2026-02-17T23:07:05.305627Z","steps":["trace[114114620] 'process raft request' (duration: 180.081207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:07:05.64828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.507172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-17T23:07:05.648931Z","caller":"traceutil/trace.go:171","msg":"trace[124512681] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"219.904195ms","start":"2026-02-17T23:07:05.429016Z","end":"2026-02-17T23:07:05.64892Z","steps":["trace[124512681] 'process raft request' (duration: 219.870624ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:07:05.649167Z","caller":"traceutil/trace.go:171","msg":"trace[1860913174] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"330.416625ms","start":"2026-02-17T23:07:05.318744Z","end":"2026-02-17T23:07:05.649161Z","steps":["trace[1860913174] 'process raft request' (duration: 128.991941ms)","trace[1860913174] 'compare' (duration: 200.178845ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:07:05.650239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:07:05.318729Z","time spent":"331.490962ms","remote":"127.0.0.1:42178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2262,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T23:07:05.650392Z","caller":"traceutil/trace.go:171","msg":"trace[133825065] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"304.064488ms","start":"2026-02-17T23:07:05.346321Z","end":"2026-02-17T23:07:05.650386Z","steps":["trace[133825065] 'process raft request' (duration: 302.535042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:07:05.650552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:07:05.346305Z","time spent":"304.232462ms","remote":"127.0.0.1:42178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2261,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T23:07:05.858604Z","caller":"traceutil/trace.go:171","msg":"trace[1399402021] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"184.647286ms","start":"2026-02-17T23:07:05.673935Z","end":"2026-02-17T23:07:05.858582Z","steps":["trace[1399402021] 'process raft request' (duration: 127.626648ms)","trace[1399402021] 'compare' (duration: 56.485645ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:07:06.131877Z","caller":"traceutil/trace.go:171","msg":"trace[937679000] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"178.121889ms","start":"2026-02-17T23:07:05.953737Z","end":"2026-02-17T23:07:06.131859Z","steps":["trace[937679000] 'process raft request' (duration: 119.847488ms)","trace[937679000] 'compare' (duration: 58.20934ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:07:06.147556Z","caller":"traceutil/trace.go:171","msg":"trace[1950555938] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:668; }","duration":"169.812962ms","start":"2026-02-17T23:07:05.977729Z","end":"2026-02-17T23:07:06.147542Z","steps":["trace[1950555938] 'read index received' (duration: 95.864747ms)","trace[1950555938] 'applied index is now lower than readState.Index' (duration: 73.947695ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:07:06.147795Z","caller":"traceutil/trace.go:171","msg":"trace[1131166571] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"193.001905ms","start":"2026-02-17T23:07:05.954787Z","end":"2026-02-17T23:07:06.147789Z","steps":["trace[1131166571] 'process raft request' (duration: 192.665577ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:07:06.14795Z","caller":"traceutil/trace.go:171","msg":"trace[1108710355] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"193.085777ms","start":"2026-02-17T23:07:05.954858Z","end":"2026-02-17T23:07:06.147944Z","steps":["trace[1108710355] 'process raft request' (duration: 192.664717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:07:06.148068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.327394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-17T23:07:06.148088Z","caller":"traceutil/trace.go:171","msg":"trace[134129395] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:650; }","duration":"170.384556ms","start":"2026-02-17T23:07:05.977698Z","end":"2026-02-17T23:07:06.148083Z","steps":["trace[134129395] 'agreement among raft nodes before linearized reading' (duration: 170.308394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:07:06.148199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.239171ms","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-17T23:07:06.148215Z","caller":"traceutil/trace.go:171","msg":"trace[908777313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:650; }","duration":"159.267711ms","start":"2026-02-17T23:07:05.988943Z","end":"2026-02-17T23:07:06.148211Z","steps":["trace[908777313] 'agreement among raft nodes before linearized reading' (duration: 159.22704ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:07:06.374034Z","caller":"traceutil/trace.go:171","msg":"trace[79548204] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"176.488644ms","start":"2026-02-17T23:07:06.197528Z","end":"2026-02-17T23:07:06.374017Z","steps":["trace[79548204] 'process raft request' (duration: 86.2741ms)","trace[79548204] 'compare' (duration: 90.135772ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:07:06.392256Z","caller":"traceutil/trace.go:171","msg":"trace[2074901064] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"117.915495ms","start":"2026-02-17T23:07:06.274324Z","end":"2026-02-17T23:07:06.39224Z","steps":["trace[2074901064] 'process raft request' (duration: 117.678909ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:07:06.787484Z","caller":"traceutil/trace.go:171","msg":"trace[991466536] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"172.078418ms","start":"2026-02-17T23:07:06.61539Z","end":"2026-02-17T23:07:06.787468Z","steps":["trace[991466536] 'process raft request' (duration: 171.947025ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:07:07.034577Z","caller":"traceutil/trace.go:171","msg":"trace[2133310539] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"231.431407ms","start":"2026-02-17T23:07:06.803128Z","end":"2026-02-17T23:07:07.034559Z","steps":["trace[2133310539] 'process raft request' (duration: 202.173887ms)","trace[2133310539] 'compare' (duration: 29.164868ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:07:07.090875Z","caller":"traceutil/trace.go:171","msg":"trace[1091287530] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"280.00175ms","start":"2026-02-17T23:07:06.810856Z","end":"2026-02-17T23:07:07.090858Z","steps":["trace[1091287530] 'process raft request' (duration: 279.880987ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:07:07.30425Z","caller":"traceutil/trace.go:171","msg":"trace[361099289] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"169.836226ms","start":"2026-02-17T23:07:07.134397Z","end":"2026-02-17T23:07:07.304233Z","steps":["trace[361099289] 'read index received' (duration: 169.671333ms)","trace[361099289] 'applied index is now lower than readState.Index' (duration: 164.213µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:07:07.304359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.942539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:07:07.304379Z","caller":"traceutil/trace.go:171","msg":"trace[774273447] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:658; }","duration":"169.97922ms","start":"2026-02-17T23:07:07.134393Z","end":"2026-02-17T23:07:07.304372Z","steps":["trace[774273447] 'agreement among raft nodes before linearized reading' (duration: 169.904158ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:07:07.304572Z","caller":"traceutil/trace.go:171","msg":"trace[251143421] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"262.29053ms","start":"2026-02-17T23:07:07.04226Z","end":"2026-02-17T23:07:07.30455Z","steps":["trace[251143421] 'process raft request' (duration: 261.87719ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:07:08.121092Z","caller":"traceutil/trace.go:171","msg":"trace[89383926] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"172.228852ms","start":"2026-02-17T23:07:07.948842Z","end":"2026-02-17T23:07:08.12107Z","steps":["trace[89383926] 'process raft request' (duration: 172.077528ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:07:08.250036Z","caller":"traceutil/trace.go:171","msg":"trace[96955792] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"110.569025ms","start":"2026-02-17T23:07:08.139439Z","end":"2026-02-17T23:07:08.250008Z","steps":["trace[96955792] 'process raft request' (duration: 28.228568ms)","trace[96955792] 'compare' (duration: 82.184033ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:07:08.807347Z","caller":"traceutil/trace.go:171","msg":"trace[1458353323] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"128.528685ms","start":"2026-02-17T23:07:08.678803Z","end":"2026-02-17T23:07:08.807332Z","steps":["trace[1458353323] 'process raft request' (duration: 128.452643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:07:48.633455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.91241ms","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-17T23:07:48.633527Z","caller":"traceutil/trace.go:171","msg":"trace[1683855434] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:823; }","duration":"124.039504ms","start":"2026-02-17T23:07:48.509471Z","end":"2026-02-17T23:07:48.63351Z","steps":["trace[1683855434] 'range keys from in-memory index tree' (duration: 123.752026ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:07:48.917099Z","caller":"traceutil/trace.go:171","msg":"trace[136991118] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"273.675802ms","start":"2026-02-17T23:07:48.643393Z","end":"2026-02-17T23:07:48.917068Z","steps":["trace[136991118] 'process raft request' (duration: 273.643972ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:07:48.917372Z","caller":"traceutil/trace.go:171","msg":"trace[941001414] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"276.350901ms","start":"2026-02-17T23:07:48.641014Z","end":"2026-02-17T23:07:48.917365Z","steps":["trace[941001414] 'process raft request' (duration: 204.067513ms)","trace[941001414] 'compare' (duration: 71.810186ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:07:48.917455Z","caller":"traceutil/trace.go:171","msg":"trace[1073572214] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"275.038537ms","start":"2026-02-17T23:07:48.642412Z","end":"2026-02-17T23:07:48.91745Z","steps":["trace[1073572214] 'process raft request' (duration: 274.575225ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:07:55.215851Z","caller":"traceutil/trace.go:171","msg":"trace[1180561593] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"203.625489ms","start":"2026-02-17T23:07:55.012202Z","end":"2026-02-17T23:07:55.215828Z","steps":["trace[1180561593] 'process raft request' (duration: 129.915286ms)","trace[1180561593] 'compare' (duration: 72.977794ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:07:55.216163Z","caller":"traceutil/trace.go:171","msg":"trace[1611431568] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"203.858296ms","start":"2026-02-17T23:07:55.012283Z","end":"2026-02-17T23:07:55.216142Z","steps":["trace[1611431568] 'process raft request' (duration: 202.954582ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:07:56.669551Z","caller":"traceutil/trace.go:171","msg":"trace[1876034523] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"100.505838ms","start":"2026-02-17T23:07:56.569028Z","end":"2026-02-17T23:07:56.669534Z","steps":["trace[1876034523] 'process raft request' (duration: 100.405166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:07:57.446872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.237516ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T23:07:57.447555Z","caller":"traceutil/trace.go:171","msg":"trace[1664324070] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"222.594101ms","start":"2026-02-17T23:07:57.224935Z","end":"2026-02-17T23:07:57.447529Z","steps":["trace[1664324070] 'process raft request' (duration: 222.212231ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:07:57.448628Z","caller":"traceutil/trace.go:171","msg":"trace[1844696069] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"225.031716ms","start":"2026-02-17T23:07:57.22358Z","end":"2026-02-17T23:07:57.448611Z","steps":["trace[1844696069] 'process raft request' (duration: 71.920921ms)","trace[1844696069] 'compare' (duration: 151.02054ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:07:58.999831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.007697ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T23:07:58.999935Z","caller":"traceutil/trace.go:171","msg":"trace[912737632] linearizableReadLoop","detail":"{readStateIndex:885; appliedIndex:884; }","duration":"150.773098ms","start":"2026-02-17T23:07:58.849149Z","end":"2026-02-17T23:07:58.999923Z","steps":["trace[912737632] 'read index received' (duration: 8.546175ms)","trace[912737632] 'applied index is now lower than readState.Index' (duration: 142.226043ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:07:59.000036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.896121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:07:59.000058Z","caller":"traceutil/trace.go:171","msg":"trace[1112528130] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:852; }","duration":"150.954753ms","start":"2026-02-17T23:07:58.849097Z","end":"2026-02-17T23:07:59.000052Z","steps":["trace[1112528130] 'agreement among raft nodes before linearized reading' (duration: 150.867391ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:07:59.000331Z","caller":"traceutil/trace.go:171","msg":"trace[585123199] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"265.682221ms","start":"2026-02-17T23:07:58.73464Z","end":"2026-02-17T23:07:59.000322Z","steps":["trace[585123199] 'process raft request' (duration: 123.108219ms)","trace[585123199] 'compare' (duration: 141.926895ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:08:20.998066Z","caller":"traceutil/trace.go:171","msg":"trace[594801607] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"186.521024ms","start":"2026-02-17T23:08:20.811525Z","end":"2026-02-17T23:08:20.998046Z","steps":["trace[594801607] 'process raft request' (duration: 186.408041ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:08:21.533579Z","caller":"traceutil/trace.go:171","msg":"trace[1010108967] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"720.232551ms","start":"2026-02-17T23:08:20.81333Z","end":"2026-02-17T23:08:21.533562Z","steps":["trace[1010108967] 'process raft request' (duration: 638.375828ms)","trace[1010108967] 'compare' (duration: 80.667751ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:08:21.533681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:08:20.813282Z","time spent":"720.338323ms","remote":"127.0.0.1:48126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5218,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:08:21.66421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.408134ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-17T23:08:21.668886Z","caller":"traceutil/trace.go:171","msg":"trace[1744161732] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:990; }","duration":"534.702833ms","start":"2026-02-17T23:08:21.134166Z","end":"2026-02-17T23:08:21.668869Z","steps":["trace[1744161732] 'read index received' (duration: 317.492291ms)","trace[1744161732] 'applied index is now lower than readState.Index' (duration: 217.209102ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:08:21.669022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.849887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:08:21.670023Z","caller":"traceutil/trace.go:171","msg":"trace[476814202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:952; }","duration":"535.882275ms","start":"2026-02-17T23:08:21.134126Z","end":"2026-02-17T23:08:21.670008Z","steps":["trace[476814202] 'agreement among raft nodes before linearized reading' (duration: 534.858807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:08:21.673437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:08:21.134109Z","time spent":"539.318207ms","remote":"127.0.0.1:47934","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-02-17T23:08:21.669931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.992506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-17T23:08:21.669989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.039433ms","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-17T23:08:21.674984Z","caller":"traceutil/trace.go:171","msg":"trace[1391472417] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:952; }","duration":"319.107174ms","start":"2026-02-17T23:08:21.355865Z","end":"2026-02-17T23:08:21.674972Z","steps":["trace[1391472417] 'agreement among raft nodes before linearized reading' (duration: 314.018047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:08:21.675087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:08:21.355845Z","time spent":"319.227866ms","remote":"127.0.0.1:48230","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-17T23:08:21.676703Z","caller":"traceutil/trace.go:171","msg":"trace[1193412507] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:952; }","duration":"516.197775ms","start":"2026-02-17T23:08:21.157945Z","end":"2026-02-17T23:08:21.674143Z","steps":["trace[1193412507] 'agreement among raft nodes before linearized reading' (duration: 511.374595ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:08:21.678598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:08:21.157912Z","time spent":"520.674136ms","remote":"127.0.0.1:48230","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-17T23:08:22.433244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.454005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:08:22.433768Z","caller":"traceutil/trace.go:171","msg":"trace[2004890444] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:956; }","duration":"299.02824ms","start":"2026-02-17T23:08:22.134723Z","end":"2026-02-17T23:08:22.433752Z","steps":["trace[2004890444] 'range keys from in-memory index tree' (duration: 298.398994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:08:37.662324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.296029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:heapster\" ","response":"range_response_count:1 size:638"} {"level":"info","ts":"2026-02-17T23:08:37.662403Z","caller":"traceutil/trace.go:171","msg":"trace[835045843] range","detail":"{range_begin:/registry/clusterroles/system:heapster; range_end:; response_count:1; response_revision:1001; }","duration":"121.410753ms","start":"2026-02-17T23:08:37.540975Z","end":"2026-02-17T23:08:37.662385Z","steps":["trace[835045843] 'range keys from in-memory index tree' (duration: 121.200927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:08:37.662697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.533912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-aggregator\" ","response":"range_response_count:1 size:595"} {"level":"info","ts":"2026-02-17T23:08:37.662742Z","caller":"traceutil/trace.go:171","msg":"trace[1797938514] range","detail":"{range_begin:/registry/clusterroles/system:kube-aggregator; range_end:; response_count:1; response_revision:1001; }","duration":"101.596733ms","start":"2026-02-17T23:08:37.561134Z","end":"2026-02-17T23:08:37.662731Z","steps":["trace[1797938514] 'range keys from in-memory index tree' (duration: 101.45912ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:08:47.296879Z","caller":"traceutil/trace.go:171","msg":"trace[1400641136] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"103.982304ms","start":"2026-02-17T23:08:47.192875Z","end":"2026-02-17T23:08:47.296858Z","steps":["trace[1400641136] 'process raft request' (duration: 103.766058ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:08:50.399124Z","caller":"traceutil/trace.go:171","msg":"trace[498332902] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"116.730027ms","start":"2026-02-17T23:08:50.282358Z","end":"2026-02-17T23:08:50.399088Z","steps":["trace[498332902] 'process raft request' (duration: 44.074527ms)","trace[498332902] 'compare' (duration: 72.500636ms)"],"step_count":2}