{"level":"warn","ts":"2026-02-11T01:44:52.585146Z","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-11T01:44:52.585289Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.240: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.240:2380","--initial-cluster=instance=https://199.204.45.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.240: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-11T01:44:52.58537Z","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-11T01:44:52.585397Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-02-11T01:44:52.585424Z","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-11T01:44:52.586034Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"]} {"level":"info","ts":"2026-02-11T01:44:52.586127Z","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.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.240: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-11T01:44:52.592057Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.71255ms"} {"level":"info","ts":"2026-02-11T01:44:52.622833Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e46287fb5aa4785","cluster-id":"cae704afa9813e87"} {"level":"info","ts":"2026-02-11T01:44:52.622964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=()"} {"level":"info","ts":"2026-02-11T01:44:52.623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 0"} {"level":"info","ts":"2026-02-11T01:44:52.623028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e46287fb5aa4785 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-11T01:44:52.623046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 1"} {"level":"info","ts":"2026-02-11T01:44:52.623097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"warn","ts":"2026-02-11T01:44:52.640576Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-11T01:44:52.652863Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-11T01:44:52.664562Z","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-11T01:44:52.670954Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e46287fb5aa4785","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-11T01:44:52.677298Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e46287fb5aa4785","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-11T01:44:52.677424Z","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-11T01:44:52.677462Z","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-11T01:44:52.67748Z","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-11T01:44:52.677636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"info","ts":"2026-02-11T01:44:52.677716Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","added-peer-id":"4e46287fb5aa4785","added-peer-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-02-11T01:44:52.705159Z","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-11T01:44:52.70555Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e46287fb5aa4785","initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-11T01:44:52.705586Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-11T01:44:52.708127Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-02-11T01:44:52.709276Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-02-11T01:44:53.132544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 is starting a new election at term 1"} {"level":"info","ts":"2026-02-11T01:44:53.132581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-11T01:44:53.132599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgPreVoteResp from 4e46287fb5aa4785 at term 1"} {"level":"info","ts":"2026-02-11T01:44:53.13261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became candidate at term 2"} {"level":"info","ts":"2026-02-11T01:44:53.132615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgVoteResp from 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-02-11T01:44:53.132624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became leader at term 2"} {"level":"info","ts":"2026-02-11T01:44:53.132631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e46287fb5aa4785 elected leader 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-02-11T01:44:53.144666Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e46287fb5aa4785","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.240:2379]}","request-path":"/0/members/4e46287fb5aa4785/attributes","cluster-id":"cae704afa9813e87","publish-timeout":"7s"} {"level":"info","ts":"2026-02-11T01:44:53.144813Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:44:53.146035Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T01:44:53.146313Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T01:44:53.146735Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:44:53.146838Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:44:53.146864Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:44:53.149247Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-11T01:44:53.160529Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-11T01:44:53.160589Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-11T01:44:53.161828Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.240:2379"} {"level":"warn","ts":"2026-02-11T01:45:05.375544Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697191837602367,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T01:45:05.438091Z","caller":"traceutil/trace.go:171","msg":"trace[516365563] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"633.658726ms","start":"2026-02-11T01:45:04.804397Z","end":"2026-02-11T01:45:05.438056Z","steps":["trace[516365563] 'process raft request' (duration: 633.532122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:05.438709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:04.804381Z","time spent":"633.799559ms","remote":"127.0.0.1:57874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4400,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:45:06.89642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.030719039s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:45:06.896772Z","caller":"traceutil/trace.go:171","msg":"trace[2101330580] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"2.031994025s","start":"2026-02-11T01:45:04.864756Z","end":"2026-02-11T01:45:06.89675Z","steps":["trace[2101330580] 'process raft request' (duration: 1.000446842s)","trace[2101330580] 'compare' (duration: 1.03045213s)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:45:06.896979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:04.864718Z","time spent":"2.032190221s","remote":"127.0.0.1:57958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:45:06.897758Z","caller":"traceutil/trace.go:171","msg":"trace[1309419895] linearizableReadLoop","detail":"{readStateIndex:371; appliedIndex:370; }","duration":"2.022420075s","start":"2026-02-11T01:45:04.875324Z","end":"2026-02-11T01:45:06.897744Z","steps":["trace[1309419895] 'read index received' (duration: 563.84991ms)","trace[1309419895] 'applied index is now lower than readState.Index' (duration: 1.458568775s)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:45:06.898053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.022719284s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-11T01:45:06.898179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.129386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-11T01:45:06.89822Z","caller":"traceutil/trace.go:171","msg":"trace[35476022] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:362; }","duration":"725.210588ms","start":"2026-02-11T01:45:06.172998Z","end":"2026-02-11T01:45:06.898209Z","steps":["trace[35476022] 'agreement among raft nodes before linearized reading' (duration: 725.162177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:06.898253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:06.172974Z","time spent":"725.270881ms","remote":"127.0.0.1:57736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.240\" "} {"level":"info","ts":"2026-02-11T01:45:06.898185Z","caller":"traceutil/trace.go:171","msg":"trace[909914204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:362; }","duration":"2.022875589s","start":"2026-02-11T01:45:04.875297Z","end":"2026-02-11T01:45:06.898172Z","steps":["trace[909914204] 'agreement among raft nodes before linearized reading' (duration: 2.022666823s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:06.898513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:04.875288Z","time spent":"2.02320674s","remote":"127.0.0.1:57958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T01:45:06.898059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.021844957s","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-11T01:45:06.898681Z","caller":"traceutil/trace.go:171","msg":"trace[1901007467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:362; }","duration":"2.022509998s","start":"2026-02-11T01:45:04.876158Z","end":"2026-02-11T01:45:06.898668Z","steps":["trace[1901007467] 'agreement among raft nodes before linearized reading' (duration: 2.021844947s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:06.89872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:04.87615Z","time spent":"2.022560549s","remote":"127.0.0.1:57958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-11T01:45:06.897744Z","caller":"traceutil/trace.go:171","msg":"trace[215870121] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"1.450161859s","start":"2026-02-11T01:45:05.447559Z","end":"2026-02-11T01:45:06.89772Z","steps":["trace[215870121] 'process raft request' (duration: 1.449956313s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:06.898896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:05.447538Z","time spent":"1.451308255s","remote":"127.0.0.1:58148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2881,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:45:06.898447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.501158928s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:45:06.899193Z","caller":"traceutil/trace.go:171","msg":"trace[427923263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:362; }","duration":"1.501942882s","start":"2026-02-11T01:45:05.397236Z","end":"2026-02-11T01:45:06.899179Z","steps":["trace[427923263] 'agreement among raft nodes before linearized reading' (duration: 1.501189889s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:06.898481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.951183626s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:45:06.899372Z","caller":"traceutil/trace.go:171","msg":"trace[1274076398] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:362; }","duration":"1.952074053s","start":"2026-02-11T01:45:04.947287Z","end":"2026-02-11T01:45:06.899361Z","steps":["trace[1274076398] 'agreement among raft nodes before linearized reading' (duration: 1.951179056s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:06.89811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.345096ms","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-11T01:45:06.905403Z","caller":"traceutil/trace.go:171","msg":"trace[316645079] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:362; }","duration":"201.6898ms","start":"2026-02-11T01:45:06.703698Z","end":"2026-02-11T01:45:06.905387Z","steps":["trace[316645079] 'agreement among raft nodes before linearized reading' (duration: 194.389997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:06.899756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:05.397216Z","time spent":"1.502132677s","remote":"127.0.0.1:57684","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-11T01:45:06.898147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.326327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-11T01:45:06.906353Z","caller":"traceutil/trace.go:171","msg":"trace[1791057583] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:362; }","duration":"565.561757ms","start":"2026-02-11T01:45:06.34078Z","end":"2026-02-11T01:45:06.906341Z","steps":["trace[1791057583] 'agreement among raft nodes before linearized reading' (duration: 557.345177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:06.906478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:06.340758Z","time spent":"565.706231ms","remote":"127.0.0.1:57958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T01:45:07.344791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.278196ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-11T01:45:07.345174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:06.904759Z","time spent":"440.410616ms","remote":"127.0.0.1:57736","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-11T01:45:07.351901Z","caller":"traceutil/trace.go:171","msg":"trace[1422485074] linearizableReadLoop","detail":"{readStateIndex:375; appliedIndex:372; }","duration":"439.488547ms","start":"2026-02-11T01:45:06.9124Z","end":"2026-02-11T01:45:07.351888Z","steps":["trace[1422485074] 'read index received' (duration: 224.140037ms)","trace[1422485074] 'applied index is now lower than readState.Index' (duration: 215.34789ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:45:07.352111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.704875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:45:07.352215Z","caller":"traceutil/trace.go:171","msg":"trace[650814202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:367; }","duration":"439.836449ms","start":"2026-02-11T01:45:06.912367Z","end":"2026-02-11T01:45:07.352204Z","steps":["trace[650814202] 'agreement among raft nodes before linearized reading' (duration: 439.690885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:07.352407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:06.912353Z","time spent":"440.044346ms","remote":"127.0.0.1:57692","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-11T01:45:07.353301Z","caller":"traceutil/trace.go:171","msg":"trace[1943581001] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"444.607785ms","start":"2026-02-11T01:45:06.908683Z","end":"2026-02-11T01:45:07.35329Z","steps":["trace[1943581001] 'process raft request' (duration: 443.021336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:07.353568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:06.908671Z","time spent":"444.78835ms","remote":"127.0.0.1:57958","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-11T01:45:07.353864Z","caller":"traceutil/trace.go:171","msg":"trace[1343466098] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"445.11404ms","start":"2026-02-11T01:45:06.90874Z","end":"2026-02-11T01:45:07.353854Z","steps":["trace[1343466098] 'process raft request' (duration: 443.058467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:07.354016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:06.908734Z","time spent":"445.234783ms","remote":"127.0.0.1:57958","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-11T01:45:07.35446Z","caller":"traceutil/trace.go:171","msg":"trace[1450906329] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"440.238602ms","start":"2026-02-11T01:45:06.914212Z","end":"2026-02-11T01:45:07.354451Z","steps":["trace[1450906329] 'process raft request' (duration: 437.612472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:07.354595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:06.914202Z","time spent":"440.346145ms","remote":"127.0.0.1:57874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5083,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:45:07.35488Z","caller":"traceutil/trace.go:171","msg":"trace[1768297569] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"440.429297ms","start":"2026-02-11T01:45:06.914396Z","end":"2026-02-11T01:45:07.354825Z","steps":["trace[1768297569] 'process raft request' (duration: 437.453366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:07.35501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:06.91439Z","time spent":"440.596453ms","remote":"127.0.0.1:57958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:45:07.355265Z","caller":"traceutil/trace.go:171","msg":"trace[2076091122] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"440.570393ms","start":"2026-02-11T01:45:06.914686Z","end":"2026-02-11T01:45:07.355256Z","steps":["trace[2076091122] 'process raft request' (duration: 437.183579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:07.355482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:06.91468Z","time spent":"440.751518ms","remote":"127.0.0.1:57958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:45:23.548242Z","caller":"traceutil/trace.go:171","msg":"trace[2109098810] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"112.255237ms","start":"2026-02-11T01:45:23.43594Z","end":"2026-02-11T01:45:23.548195Z","steps":["trace[2109098810] 'process raft request' (duration: 112.174115ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:23.686329Z","caller":"traceutil/trace.go:171","msg":"trace[213602846] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"134.377111ms","start":"2026-02-11T01:45:23.551934Z","end":"2026-02-11T01:45:23.686312Z","steps":["trace[213602846] 'process raft request' (duration: 88.960634ms)","trace[213602846] 'compare' (duration: 45.308105ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:45:26.611749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.680337ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:45:26.611846Z","caller":"traceutil/trace.go:171","msg":"trace[1700658135] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:495; }","duration":"214.105363ms","start":"2026-02-11T01:45:26.397724Z","end":"2026-02-11T01:45:26.611829Z","steps":["trace[1700658135] 'read index received' (duration: 51.191082ms)","trace[1700658135] 'applied index is now lower than readState.Index' (duration: 162.913181ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:45:26.611943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.213305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:45:26.611995Z","caller":"traceutil/trace.go:171","msg":"trace[2115999591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:480; }","duration":"214.266215ms","start":"2026-02-11T01:45:26.397705Z","end":"2026-02-11T01:45:26.611971Z","steps":["trace[2115999591] 'agreement among raft nodes before linearized reading' (duration: 214.169644ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:26.612661Z","caller":"traceutil/trace.go:171","msg":"trace[2054244383] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"226.434008ms","start":"2026-02-11T01:45:26.385747Z","end":"2026-02-11T01:45:26.612181Z","steps":["trace[2054244383] 'process raft request' (duration: 63.237182ms)","trace[2054244383] 'compare' (duration: 162.568145ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:45:28.377541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.613543ms","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-11T01:45:28.377614Z","caller":"traceutil/trace.go:171","msg":"trace[1342805537] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:481; }","duration":"545.702254ms","start":"2026-02-11T01:45:27.831901Z","end":"2026-02-11T01:45:28.377603Z","steps":["trace[1342805537] 'range keys from in-memory index tree' (duration: 545.600893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:28.377608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.197973ms","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-11T01:45:28.377646Z","caller":"traceutil/trace.go:171","msg":"trace[756699294] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:481; }","duration":"761.262844ms","start":"2026-02-11T01:45:27.616373Z","end":"2026-02-11T01:45:28.377636Z","steps":["trace[756699294] 'range keys from in-memory index tree' (duration: 761.088101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:28.377675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:27.616366Z","time spent":"761.300664ms","remote":"127.0.0.1:57958","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-11T01:45:28.377795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.238755ms","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-11T01:45:28.377812Z","caller":"traceutil/trace.go:171","msg":"trace[800324080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:481; }","duration":"754.286236ms","start":"2026-02-11T01:45:27.623521Z","end":"2026-02-11T01:45:28.377807Z","steps":["trace[800324080] 'range keys from in-memory index tree' (duration: 754.173584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:28.377826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:27.623504Z","time spent":"754.318386ms","remote":"127.0.0.1:57958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T01:45:28.377872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.519944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-11T01:45:28.377896Z","caller":"traceutil/trace.go:171","msg":"trace[845777600] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:481; }","duration":"769.573954ms","start":"2026-02-11T01:45:27.608313Z","end":"2026-02-11T01:45:28.377886Z","steps":["trace[845777600] 'range keys from in-memory index tree' (duration: 769.425912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:28.377914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:27.608294Z","time spent":"769.615724ms","remote":"127.0.0.1:57958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T01:45:28.377935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.094419ms","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-11T01:45:28.377956Z","caller":"traceutil/trace.go:171","msg":"trace[1046548346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:481; }","duration":"594.14538ms","start":"2026-02-11T01:45:27.783802Z","end":"2026-02-11T01:45:28.377947Z","steps":["trace[1046548346] 'range keys from in-memory index tree' (duration: 593.976767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:28.377972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:27.783783Z","time spent":"594.18481ms","remote":"127.0.0.1:57958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-11T01:45:28.853275Z","caller":"traceutil/trace.go:171","msg":"trace[899431546] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"621.964548ms","start":"2026-02-11T01:45:28.231289Z","end":"2026-02-11T01:45:28.853254Z","steps":["trace[899431546] 'process raft request' (duration: 621.852096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:28.853407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:28.231269Z","time spent":"622.070679ms","remote":"127.0.0.1:57958","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-11T01:45:28.911497Z","caller":"traceutil/trace.go:171","msg":"trace[922248802] linearizableReadLoop","detail":"{readStateIndex:502; appliedIndex:498; }","duration":"516.011913ms","start":"2026-02-11T01:45:28.395463Z","end":"2026-02-11T01:45:28.911475Z","steps":["trace[922248802] 'read index received' (duration: 458.053845ms)","trace[922248802] 'applied index is now lower than readState.Index' (duration: 57.957188ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:45:28.911744Z","caller":"traceutil/trace.go:171","msg":"trace[96003030] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"526.040864ms","start":"2026-02-11T01:45:28.385696Z","end":"2026-02-11T01:45:28.911736Z","steps":["trace[96003030] 'process raft request' (duration: 521.013478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:28.911823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:28.385677Z","time spent":"526.089154ms","remote":"127.0.0.1:57958","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-11T01:45:28.911967Z","caller":"traceutil/trace.go:171","msg":"trace[1235035014] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"526.195126ms","start":"2026-02-11T01:45:28.385763Z","end":"2026-02-11T01:45:28.911958Z","steps":["trace[1235035014] 'process raft request' (duration: 525.586725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:28.912022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:28.385759Z","time spent":"526.233577ms","remote":"127.0.0.1:57958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:45:28.912137Z","caller":"traceutil/trace.go:171","msg":"trace[631771680] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"526.302038ms","start":"2026-02-11T01:45:28.385821Z","end":"2026-02-11T01:45:28.912123Z","steps":["trace[631771680] 'process raft request' (duration: 525.590265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:28.912189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:28.385816Z","time spent":"526.346609ms","remote":"127.0.0.1:57958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:45:28.912308Z","caller":"traceutil/trace.go:171","msg":"trace[2067946946] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"518.168689ms","start":"2026-02-11T01:45:28.39413Z","end":"2026-02-11T01:45:28.912299Z","steps":["trace[2067946946] 'process raft request' (duration: 517.314115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:28.912352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:28.394115Z","time spent":"518.212659ms","remote":"127.0.0.1:57958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:45:28.912463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.99675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:45:28.912485Z","caller":"traceutil/trace.go:171","msg":"trace[1425254529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:486; }","duration":"517.03352ms","start":"2026-02-11T01:45:28.395445Z","end":"2026-02-11T01:45:28.912479Z","steps":["trace[1425254529] 'agreement among raft nodes before linearized reading' (duration: 516.995499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:28.912522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:28.395439Z","time spent":"517.06038ms","remote":"127.0.0.1:57684","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-11T01:45:28.912626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.916875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:45:28.912647Z","caller":"traceutil/trace.go:171","msg":"trace[1617333649] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:486; }","duration":"483.959087ms","start":"2026-02-11T01:45:28.428679Z","end":"2026-02-11T01:45:28.912638Z","steps":["trace[1617333649] 'agreement among raft nodes before linearized reading' (duration: 483.929746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:28.912664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:28.428662Z","time spent":"483.997147ms","remote":"127.0.0.1:57784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"info","ts":"2026-02-11T01:45:33.044889Z","caller":"traceutil/trace.go:171","msg":"trace[2011742367] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:530; }","duration":"110.160989ms","start":"2026-02-11T01:45:32.934709Z","end":"2026-02-11T01:45:33.04487Z","steps":["trace[2011742367] 'read index received' (duration: 5.649801ms)","trace[2011742367] 'applied index is now lower than readState.Index' (duration: 104.510348ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:45:33.045157Z","caller":"traceutil/trace.go:171","msg":"trace[1939490752] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"114.320124ms","start":"2026-02-11T01:45:32.930829Z","end":"2026-02-11T01:45:33.045149Z","steps":["trace[1939490752] 'process raft request' (duration: 96.748088ms)","trace[1939490752] 'compare' (duration: 17.20483ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:45:33.045314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.593947ms","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-11T01:45:33.045334Z","caller":"traceutil/trace.go:171","msg":"trace[1074584153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:514; }","duration":"110.642548ms","start":"2026-02-11T01:45:32.934686Z","end":"2026-02-11T01:45:33.045329Z","steps":["trace[1074584153] 'agreement among raft nodes before linearized reading' (duration: 110.566736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:33.045413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.717644ms","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-11T01:45:33.045428Z","caller":"traceutil/trace.go:171","msg":"trace[1518067771] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:514; }","duration":"108.743315ms","start":"2026-02-11T01:45:32.936681Z","end":"2026-02-11T01:45:33.045425Z","steps":["trace[1518067771] 'agreement among raft nodes before linearized reading' (duration: 108.706895ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:35.186385Z","caller":"traceutil/trace.go:171","msg":"trace[793907273] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"102.217066ms","start":"2026-02-11T01:45:35.084153Z","end":"2026-02-11T01:45:35.18637Z","steps":["trace[793907273] 'process raft request' (duration: 88.580497ms)","trace[793907273] 'compare' (duration: 13.307513ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:45:35.362661Z","caller":"traceutil/trace.go:171","msg":"trace[637450802] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"173.615357ms","start":"2026-02-11T01:45:35.189026Z","end":"2026-02-11T01:45:35.362642Z","steps":["trace[637450802] 'process raft request' (duration: 164.016101ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:37.087406Z","caller":"traceutil/trace.go:171","msg":"trace[1558536804] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:542; }","duration":"131.816347ms","start":"2026-02-11T01:45:36.95557Z","end":"2026-02-11T01:45:37.087386Z","steps":["trace[1558536804] 'read index received' (duration: 131.550982ms)","trace[1558536804] 'applied index is now lower than readState.Index' (duration: 264.475µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:45:37.087658Z","caller":"traceutil/trace.go:171","msg":"trace[534494864] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"197.709627ms","start":"2026-02-11T01:45:36.889938Z","end":"2026-02-11T01:45:37.087647Z","steps":["trace[534494864] 'process raft request' (duration: 197.274479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:37.0879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.318486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-11T01:45:37.087925Z","caller":"traceutil/trace.go:171","msg":"trace[685238255] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:525; }","duration":"132.382887ms","start":"2026-02-11T01:45:36.955533Z","end":"2026-02-11T01:45:37.087916Z","steps":["trace[685238255] 'agreement among raft nodes before linearized reading' (duration: 132.281256ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:37.48523Z","caller":"traceutil/trace.go:171","msg":"trace[515102779] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:543; }","duration":"296.512706ms","start":"2026-02-11T01:45:37.188699Z","end":"2026-02-11T01:45:37.485212Z","steps":["trace[515102779] 'read index received' (duration: 212.689798ms)","trace[515102779] 'applied index is now lower than readState.Index' (duration: 83.822258ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:45:37.485399Z","caller":"traceutil/trace.go:171","msg":"trace[1763315667] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"380.264403ms","start":"2026-02-11T01:45:37.105125Z","end":"2026-02-11T01:45:37.48539Z","steps":["trace[1763315667] 'process raft request' (duration: 296.255812ms)","trace[1763315667] 'compare' (duration: 83.708946ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:45:37.485471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:37.10511Z","time spent":"380.308434ms","remote":"127.0.0.1:57958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:45:37.485599Z","caller":"traceutil/trace.go:171","msg":"trace[610179395] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"380.172601ms","start":"2026-02-11T01:45:37.10542Z","end":"2026-02-11T01:45:37.485592Z","steps":["trace[610179395] 'process raft request' (duration: 379.765444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:37.48563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:37.105413Z","time spent":"380.199922ms","remote":"127.0.0.1:57958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:45:37.485757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.067737ms","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-11T01:45:37.485778Z","caller":"traceutil/trace.go:171","msg":"trace[1194361987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:527; }","duration":"297.120657ms","start":"2026-02-11T01:45:37.18865Z","end":"2026-02-11T01:45:37.485771Z","steps":["trace[1194361987] 'agreement among raft nodes before linearized reading' (duration: 297.040366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:37.48611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.7552ms","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-11T01:45:37.486132Z","caller":"traceutil/trace.go:171","msg":"trace[1562751634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:527; }","duration":"119.806671ms","start":"2026-02-11T01:45:37.366318Z","end":"2026-02-11T01:45:37.486124Z","steps":["trace[1562751634] 'agreement among raft nodes before linearized reading' (duration: 119.717179ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:37.977208Z","caller":"traceutil/trace.go:171","msg":"trace[494329110] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"294.098502ms","start":"2026-02-11T01:45:37.683088Z","end":"2026-02-11T01:45:37.977186Z","steps":["trace[494329110] 'process raft request' (duration: 293.95131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:39.89681Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697191837602723,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:45:40.397452Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697191837602723,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:45:40.897694Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697191837602723,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:45:41.414984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.019004479s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-11T01:45:41.415088Z","caller":"traceutil/trace.go:171","msg":"trace[1087827754] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.019153463s","start":"2026-02-11T01:45:39.395913Z","end":"2026-02-11T01:45:41.415067Z","steps":["trace[1087827754] 'agreement among raft nodes before linearized reading' (duration: 2.0190268s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:41.415148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:39.395901Z","time spent":"2.019230834s","remote":"127.0.0.1:57684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:45:41.415524Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697191837602723,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:45:41.91647Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697191837602723,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:45:42.249746Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.148629597s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T01:45:42.250286Z","caller":"traceutil/trace.go:171","msg":"trace[1413007162] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"3.14929228s","start":"2026-02-11T01:45:39.10097Z","end":"2026-02-11T01:45:42.250262Z","steps":["trace[1413007162] 'process raft request' (duration: 3.149139397s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:42.250585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:39.100941Z","time spent":"3.149523453s","remote":"127.0.0.1:57958","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-11T01:45:42.290081Z","caller":"traceutil/trace.go:171","msg":"trace[35252752] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:549; }","duration":"2.894116719s","start":"2026-02-11T01:45:39.395941Z","end":"2026-02-11T01:45:42.290058Z","steps":["trace[35252752] 'read index received' (duration: 2.854598237s)","trace[35252752] 'applied index is now lower than readState.Index' (duration: 39.517382ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:45:42.290335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.800102658s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-11T01:45:42.290382Z","caller":"traceutil/trace.go:171","msg":"trace[1680678589] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:531; }","duration":"2.800181918s","start":"2026-02-11T01:45:39.490174Z","end":"2026-02-11T01:45:42.290355Z","steps":["trace[1680678589] 'agreement among raft nodes before linearized reading' (duration: 2.800032626s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:42.290385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.782968401s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-11T01:45:42.290408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:39.490154Z","time spent":"2.80024677s","remote":"127.0.0.1:57958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-11T01:45:42.290439Z","caller":"traceutil/trace.go:171","msg":"trace[794053554] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:531; }","duration":"2.783062693s","start":"2026-02-11T01:45:39.507359Z","end":"2026-02-11T01:45:42.290422Z","steps":["trace[794053554] 'agreement among raft nodes before linearized reading' (duration: 2.782858019s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:42.290468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:39.507349Z","time spent":"2.783114404s","remote":"127.0.0.1:57958","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-11T01:45:42.290675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.6542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:45:42.290691Z","caller":"traceutil/trace.go:171","msg":"trace[2123284618] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:531; }","duration":"873.860515ms","start":"2026-02-11T01:45:41.416825Z","end":"2026-02-11T01:45:42.290686Z","steps":["trace[2123284618] 'agreement among raft nodes before linearized reading' (duration: 873.832734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:42.290703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:41.416809Z","time spent":"873.892105ms","remote":"127.0.0.1:57692","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-11T01:45:42.290835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.790038047s","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-11T01:45:42.290859Z","caller":"traceutil/trace.go:171","msg":"trace[1532299445] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:531; }","duration":"2.790102948s","start":"2026-02-11T01:45:39.50075Z","end":"2026-02-11T01:45:42.290853Z","steps":["trace[1532299445] 'agreement among raft nodes before linearized reading' (duration: 2.790014516s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:42.290879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:39.500743Z","time spent":"2.790131738s","remote":"127.0.0.1:57958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T01:45:42.290995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.799280514s","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-11T01:45:42.291011Z","caller":"traceutil/trace.go:171","msg":"trace[1282912301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:531; }","duration":"2.799318714s","start":"2026-02-11T01:45:39.491687Z","end":"2026-02-11T01:45:42.291006Z","steps":["trace[1282912301] 'agreement among raft nodes before linearized reading' (duration: 2.799266653s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:42.291027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:39.491677Z","time spent":"2.799346684s","remote":"127.0.0.1:57958","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":"info","ts":"2026-02-11T01:45:44.011062Z","caller":"traceutil/trace.go:171","msg":"trace[315181566] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"109.729232ms","start":"2026-02-11T01:45:43.901313Z","end":"2026-02-11T01:45:44.011042Z","steps":["trace[315181566] 'process raft request' (duration: 109.577519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:45.812232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.474159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-n8kt8\" ","response":"range_response_count:1 size:17171"} {"level":"info","ts":"2026-02-11T01:45:45.812298Z","caller":"traceutil/trace.go:171","msg":"trace[1911347046] range","detail":"{range_begin:/registry/pods/kube-system/cilium-n8kt8; range_end:; response_count:1; response_revision:548; }","duration":"903.570651ms","start":"2026-02-11T01:45:44.908711Z","end":"2026-02-11T01:45:45.812281Z","steps":["trace[1911347046] 'range keys from in-memory index tree' (duration: 903.344446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:45.812339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:44.908697Z","time spent":"903.631752ms","remote":"127.0.0.1:57874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17194,"request content":"key:\"/registry/pods/kube-system/cilium-n8kt8\" "} {"level":"warn","ts":"2026-02-11T01:45:45.812758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.565593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:45:45.812789Z","caller":"traceutil/trace.go:171","msg":"trace[1035586347] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:548; }","duration":"417.628935ms","start":"2026-02-11T01:45:45.395151Z","end":"2026-02-11T01:45:45.81278Z","steps":["trace[1035586347] 'range keys from in-memory index tree' (duration: 417.456501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:45.812815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:45.395123Z","time spent":"417.685985ms","remote":"127.0.0.1:57684","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-11T01:45:45.962771Z","caller":"traceutil/trace.go:171","msg":"trace[1609371602] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"131.709944ms","start":"2026-02-11T01:45:45.831044Z","end":"2026-02-11T01:45:45.962754Z","steps":["trace[1609371602] 'process raft request' (duration: 131.618052ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:46.29004Z","caller":"traceutil/trace.go:171","msg":"trace[145740578] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:569; }","duration":"113.314734ms","start":"2026-02-11T01:45:46.176709Z","end":"2026-02-11T01:45:46.290024Z","steps":["trace[145740578] 'read index received' (duration: 113.175691ms)","trace[145740578] 'applied index is now lower than readState.Index' (duration: 138.393µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:45:46.290158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.441756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-11T01:45:46.290174Z","caller":"traceutil/trace.go:171","msg":"trace[1964890805] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:551; }","duration":"113.506618ms","start":"2026-02-11T01:45:46.176662Z","end":"2026-02-11T01:45:46.290169Z","steps":["trace[1964890805] 'agreement among raft nodes before linearized reading' (duration: 113.418826ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:46.290333Z","caller":"traceutil/trace.go:171","msg":"trace[9155564] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"138.406549ms","start":"2026-02-11T01:45:46.151901Z","end":"2026-02-11T01:45:46.290308Z","steps":["trace[9155564] 'process raft request' (duration: 138.024832ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:47.731912Z","caller":"traceutil/trace.go:171","msg":"trace[2079613500] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"102.170415ms","start":"2026-02-11T01:45:47.629724Z","end":"2026-02-11T01:45:47.731894Z","steps":["trace[2079613500] 'process raft request' (duration: 45.861942ms)","trace[2079613500] 'compare' (duration: 56.224441ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:45:48.262343Z","caller":"traceutil/trace.go:171","msg":"trace[1901992562] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"115.518602ms","start":"2026-02-11T01:45:48.146804Z","end":"2026-02-11T01:45:48.262322Z","steps":["trace[1901992562] 'process raft request' (duration: 115.100233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:48.47377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.922775ms","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-11T01:45:48.473816Z","caller":"traceutil/trace.go:171","msg":"trace[594324795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:598; }","duration":"102.985626ms","start":"2026-02-11T01:45:48.37082Z","end":"2026-02-11T01:45:48.473806Z","steps":["trace[594324795] 'agreement among raft nodes before linearized reading' (duration: 102.902594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:48.474312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.465345ms","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-11T01:45:48.474387Z","caller":"traceutil/trace.go:171","msg":"trace[968481367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:598; }","duration":"103.572018ms","start":"2026-02-11T01:45:48.3708Z","end":"2026-02-11T01:45:48.474372Z","steps":["trace[968481367] 'agreement among raft nodes before linearized reading' (duration: 102.170529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:48.912903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.246459ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:45:48.913562Z","caller":"traceutil/trace.go:171","msg":"trace[1109160377] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"425.39562ms","start":"2026-02-11T01:45:48.488147Z","end":"2026-02-11T01:45:48.913542Z","steps":["trace[1109160377] 'process raft request' (duration: 425.298208ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:48.913544Z","caller":"traceutil/trace.go:171","msg":"trace[1645767364] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"428.489543ms","start":"2026-02-11T01:45:48.485023Z","end":"2026-02-11T01:45:48.913512Z","steps":["trace[1645767364] 'read index received' (duration: 84.479088ms)","trace[1645767364] 'applied index is now lower than readState.Index' (duration: 344.007395ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:45:48.913686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:48.488139Z","time spent":"425.470611ms","remote":"127.0.0.1:57958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:45:48.914081Z","caller":"traceutil/trace.go:171","msg":"trace[1912493559] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"428.986123ms","start":"2026-02-11T01:45:48.485083Z","end":"2026-02-11T01:45:48.914069Z","steps":["trace[1912493559] 'process raft request' (duration: 427.947031ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:48.914191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:48.485074Z","time spent":"429.042994ms","remote":"127.0.0.1:57744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5153,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:45:48.91466Z","caller":"traceutil/trace.go:171","msg":"trace[2137129495] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"426.615554ms","start":"2026-02-11T01:45:48.488033Z","end":"2026-02-11T01:45:48.914648Z","steps":["trace[2137129495] 'process raft request' (duration: 425.328018ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:48.914789Z","caller":"traceutil/trace.go:171","msg":"trace[432671305] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"464.238264ms","start":"2026-02-11T01:45:48.450536Z","end":"2026-02-11T01:45:48.914775Z","steps":["trace[432671305] 'process raft request' (duration: 119.025385ms)","trace[432671305] 'compare' (duration: 343.127046ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:45:48.914877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:48.450518Z","time spent":"464.326546ms","remote":"127.0.0.1:57958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:45:48.915036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.009623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:45:48.915073Z","caller":"traceutil/trace.go:171","msg":"trace[512963761] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"430.075124ms","start":"2026-02-11T01:45:48.484988Z","end":"2026-02-11T01:45:48.915063Z","steps":["trace[512963761] 'agreement among raft nodes before linearized reading' (duration: 430.016033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:48.915136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:48.484972Z","time spent":"430.156616ms","remote":"127.0.0.1:57692","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-11T01:45:48.915723Z","caller":"traceutil/trace.go:171","msg":"trace[1118739853] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"427.605304ms","start":"2026-02-11T01:45:48.488105Z","end":"2026-02-11T01:45:48.91571Z","steps":["trace[1118739853] 'process raft request' (duration: 425.305118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:48.916151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:48.488098Z","time spent":"427.667905ms","remote":"127.0.0.1:57958","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-11T01:45:48.917123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:48.488021Z","time spent":"426.694405ms","remote":"127.0.0.1:57958","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-11T01:45:49.354551Z","caller":"traceutil/trace.go:171","msg":"trace[264610183] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"440.733711ms","start":"2026-02-11T01:45:48.913791Z","end":"2026-02-11T01:45:49.354525Z","steps":["trace[264610183] 'read index received' (duration: 372.482575ms)","trace[264610183] 'applied index is now lower than readState.Index' (duration: 68.249506ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:45:49.35466Z","caller":"traceutil/trace.go:171","msg":"trace[1166032387] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"748.214759ms","start":"2026-02-11T01:45:48.606429Z","end":"2026-02-11T01:45:49.354644Z","steps":["trace[1166032387] 'process raft request' (duration: 679.836371ms)","trace[1166032387] 'compare' (duration: 68.150263ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:45:49.354746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:48.606409Z","time spent":"748.277891ms","remote":"127.0.0.1:57772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T01:45:49.354889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.401524ms","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-11T01:45:49.354913Z","caller":"traceutil/trace.go:171","msg":"trace[2037154744] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:604; }","duration":"595.477205ms","start":"2026-02-11T01:45:48.759429Z","end":"2026-02-11T01:45:49.354906Z","steps":["trace[2037154744] 'agreement among raft nodes before linearized reading' (duration: 595.365293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:49.354938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:48.75941Z","time spent":"595.524076ms","remote":"127.0.0.1:57958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T01:45:49.355048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.711366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-11T01:45:49.355065Z","caller":"traceutil/trace.go:171","msg":"trace[973855086] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:604; }","duration":"541.765687ms","start":"2026-02-11T01:45:48.813293Z","end":"2026-02-11T01:45:49.355059Z","steps":["trace[973855086] 'agreement among raft nodes before linearized reading' (duration: 541.721467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:49.355062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.130955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-11T01:45:49.355111Z","caller":"traceutil/trace.go:171","msg":"trace[104374420] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:604; }","duration":"743.200697ms","start":"2026-02-11T01:45:48.611895Z","end":"2026-02-11T01:45:49.355096Z","steps":["trace[104374420] 'agreement among raft nodes before linearized reading' (duration: 743.045354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:49.355152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:48.611886Z","time spent":"743.256258ms","remote":"127.0.0.1:57744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5609,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-02-11T01:45:49.355078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:48.813261Z","time spent":"541.813239ms","remote":"127.0.0.1:57744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4106,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-11T01:45:49.83347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.020838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:45:49.833599Z","caller":"traceutil/trace.go:171","msg":"trace[889264717] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"473.660924ms","start":"2026-02-11T01:45:49.359926Z","end":"2026-02-11T01:45:49.833587Z","steps":["trace[889264717] 'process raft request' (duration: 473.607303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:49.833647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:49.359906Z","time spent":"473.720666ms","remote":"127.0.0.1:57958","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-11T01:45:49.833781Z","caller":"traceutil/trace.go:171","msg":"trace[813849611] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"479.143907ms","start":"2026-02-11T01:45:49.354609Z","end":"2026-02-11T01:45:49.833753Z","steps":["trace[813849611] 'read index received' (duration: 178.793382ms)","trace[813849611] 'applied index is now lower than readState.Index' (duration: 300.347535ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:45:49.833999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.731992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-02-11T01:45:49.834022Z","caller":"traceutil/trace.go:171","msg":"trace[437958392] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:606; }","duration":"620.785124ms","start":"2026-02-11T01:45:49.213231Z","end":"2026-02-11T01:45:49.834017Z","steps":["trace[437958392] 'agreement among raft nodes before linearized reading' (duration: 620.711962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:49.83404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:49.213213Z","time spent":"620.822504ms","remote":"127.0.0.1:57744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13252,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"info","ts":"2026-02-11T01:45:49.834118Z","caller":"traceutil/trace.go:171","msg":"trace[114124111] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"906.722498ms","start":"2026-02-11T01:45:48.927389Z","end":"2026-02-11T01:45:49.834111Z","steps":["trace[114124111] 'process raft request' (duration: 606.005136ms)","trace[114124111] 'compare' (duration: 299.942977ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:45:49.834147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:48.927373Z","time spent":"906.76038ms","remote":"127.0.0.1:57744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5162,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:45:49.834285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.380142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-n8kt8\" ","response":"range_response_count:1 size:17918"} {"level":"warn","ts":"2026-02-11T01:45:49.834333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.959895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-11T01:45:49.834355Z","caller":"traceutil/trace.go:171","msg":"trace[574563410] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:606; }","duration":"821.038196ms","start":"2026-02-11T01:45:49.013311Z","end":"2026-02-11T01:45:49.834349Z","steps":["trace[574563410] 'agreement among raft nodes before linearized reading' (duration: 820.985535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:49.834372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:49.013293Z","time spent":"821.076117ms","remote":"127.0.0.1:57744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4356,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"info","ts":"2026-02-11T01:45:49.834419Z","caller":"traceutil/trace.go:171","msg":"trace[1059982390] range","detail":"{range_begin:/registry/pods/kube-system/cilium-n8kt8; range_end:; response_count:1; response_revision:606; }","duration":"697.481543ms","start":"2026-02-11T01:45:49.136859Z","end":"2026-02-11T01:45:49.83434Z","steps":["trace[1059982390] 'agreement among raft nodes before linearized reading' (duration: 697.209148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:49.834486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:49.136846Z","time spent":"697.627797ms","remote":"127.0.0.1:57874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17941,"request content":"key:\"/registry/pods/kube-system/cilium-n8kt8\" "} {"level":"info","ts":"2026-02-11T01:45:50.047348Z","caller":"traceutil/trace.go:171","msg":"trace[1357043890] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"213.473762ms","start":"2026-02-11T01:45:49.833859Z","end":"2026-02-11T01:45:50.047332Z","steps":["trace[1357043890] 'read index received' (duration: 188.161348ms)","trace[1357043890] 'applied index is now lower than readState.Index' (duration: 25.311784ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:45:50.047697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.964961ms","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-11T01:45:50.047784Z","caller":"traceutil/trace.go:171","msg":"trace[964787715] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:607; }","duration":"635.099085ms","start":"2026-02-11T01:45:49.412678Z","end":"2026-02-11T01:45:50.047777Z","steps":["trace[964787715] 'agreement among raft nodes before linearized reading' (duration: 634.87931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:50.048849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:49.412659Z","time spent":"636.181667ms","remote":"127.0.0.1:57744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4203,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"info","ts":"2026-02-11T01:45:50.0491Z","caller":"traceutil/trace.go:171","msg":"trace[2074173348] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"434.087655ms","start":"2026-02-11T01:45:49.615004Z","end":"2026-02-11T01:45:50.049092Z","steps":["trace[2074173348] 'process raft request' (duration: 407.007883ms)","trace[2074173348] 'compare' (duration: 25.06944ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:45:50.049219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:49.614985Z","time spent":"434.184387ms","remote":"127.0.0.1:57744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T01:45:50.049373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.734151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-11T01:45:50.049439Z","caller":"traceutil/trace.go:171","msg":"trace[1277767432] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:607; }","duration":"236.991776ms","start":"2026-02-11T01:45:49.812441Z","end":"2026-02-11T01:45:50.049432Z","steps":["trace[1277767432] 'agreement among raft nodes before linearized reading' (duration: 236.869513ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:50.06342Z","caller":"traceutil/trace.go:171","msg":"trace[300087402] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"214.149008ms","start":"2026-02-11T01:45:49.849256Z","end":"2026-02-11T01:45:50.063405Z","steps":["trace[300087402] 'process raft request' (duration: 214.061586ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:50.300576Z","caller":"traceutil/trace.go:171","msg":"trace[927541477] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"168.043095ms","start":"2026-02-11T01:45:50.132433Z","end":"2026-02-11T01:45:50.300476Z","steps":["trace[927541477] 'process raft request' (duration: 85.631435ms)","trace[927541477] 'compare' (duration: 82.266747ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:45:50.304817Z","caller":"traceutil/trace.go:171","msg":"trace[839983980] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:629; }","duration":"146.917336ms","start":"2026-02-11T01:45:50.157882Z","end":"2026-02-11T01:45:50.3048Z","steps":["trace[839983980] 'read index received' (duration: 60.190278ms)","trace[839983980] 'applied index is now lower than readState.Index' (duration: 86.726028ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:45:50.304909Z","caller":"traceutil/trace.go:171","msg":"trace[1708058131] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"147.092209ms","start":"2026-02-11T01:45:50.157809Z","end":"2026-02-11T01:45:50.304901Z","steps":["trace[1708058131] 'process raft request' (duration: 146.829014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:50.305128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.233472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-n8kt8\" ","response":"range_response_count:1 size:18080"} {"level":"info","ts":"2026-02-11T01:45:50.305156Z","caller":"traceutil/trace.go:171","msg":"trace[1475230481] range","detail":"{range_begin:/registry/pods/kube-system/cilium-n8kt8; range_end:; response_count:1; response_revision:612; }","duration":"147.283583ms","start":"2026-02-11T01:45:50.157864Z","end":"2026-02-11T01:45:50.305147Z","steps":["trace[1475230481] 'agreement among raft nodes before linearized reading' (duration: 147.15269ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:50.305295Z","caller":"traceutil/trace.go:171","msg":"trace[1503728522] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"125.781987ms","start":"2026-02-11T01:45:50.179507Z","end":"2026-02-11T01:45:50.305289Z","steps":["trace[1503728522] 'process raft request' (duration: 125.224726ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:50.511831Z","caller":"traceutil/trace.go:171","msg":"trace[1968882823] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"113.349159ms","start":"2026-02-11T01:45:50.398459Z","end":"2026-02-11T01:45:50.511808Z","steps":["trace[1968882823] 'read index received' (duration: 68.690253ms)","trace[1968882823] 'applied index is now lower than readState.Index' (duration: 44.657956ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:45:50.511936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.455101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:45:50.511977Z","caller":"traceutil/trace.go:171","msg":"trace[1025796210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"113.525313ms","start":"2026-02-11T01:45:50.39843Z","end":"2026-02-11T01:45:50.511955Z","steps":["trace[1025796210] 'agreement among raft nodes before linearized reading' (duration: 113.458252ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:50.512193Z","caller":"traceutil/trace.go:171","msg":"trace[2078130238] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"116.75378ms","start":"2026-02-11T01:45:50.395427Z","end":"2026-02-11T01:45:50.51218Z","steps":["trace[2078130238] 'process raft request' (duration: 71.712036ms)","trace[2078130238] 'compare' (duration: 44.393541ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:45:50.810344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.600588ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:45:50.810867Z","caller":"traceutil/trace.go:171","msg":"trace[489179872] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"116.479986ms","start":"2026-02-11T01:45:50.694377Z","end":"2026-02-11T01:45:50.810857Z","steps":["trace[489179872] 'read index received' (duration: 12.261565ms)","trace[489179872] 'applied index is now lower than readState.Index' (duration: 104.215801ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:45:50.812402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.018988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-11T01:45:50.812423Z","caller":"traceutil/trace.go:171","msg":"trace[1603033507] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:616; }","duration":"118.069058ms","start":"2026-02-11T01:45:50.694348Z","end":"2026-02-11T01:45:50.812417Z","steps":["trace[1603033507] 'agreement among raft nodes before linearized reading' (duration: 117.950646ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:50.812548Z","caller":"traceutil/trace.go:171","msg":"trace[179645608] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"179.769799ms","start":"2026-02-11T01:45:50.632772Z","end":"2026-02-11T01:45:50.812542Z","steps":["trace[179645608] 'process raft request' (duration: 73.912733ms)","trace[179645608] 'compare' (duration: 103.309533ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:45:51.190791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.687579ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:45:51.191267Z","caller":"traceutil/trace.go:171","msg":"trace[1224645992] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:636; }","duration":"267.935822ms","start":"2026-02-11T01:45:50.923317Z","end":"2026-02-11T01:45:51.191253Z","steps":["trace[1224645992] 'read index received' (duration: 78.730872ms)","trace[1224645992] 'applied index is now lower than readState.Index' (duration: 189.20425ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:45:51.191329Z","caller":"traceutil/trace.go:171","msg":"trace[1061023556] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"332.726085ms","start":"2026-02-11T01:45:50.858595Z","end":"2026-02-11T01:45:51.191321Z","steps":["trace[1061023556] 'process raft request' (duration: 143.444064ms)","trace[1061023556] 'compare' (duration: 188.496634ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:45:51.191369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:50.858585Z","time spent":"332.761846ms","remote":"127.0.0.1:57744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:45:51.191412Z","caller":"traceutil/trace.go:171","msg":"trace[913850840] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"311.377282ms","start":"2026-02-11T01:45:50.880016Z","end":"2026-02-11T01:45:51.191393Z","steps":["trace[913850840] 'process raft request' (duration: 310.986284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:51.191497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:50.879992Z","time spent":"311.456724ms","remote":"127.0.0.1:57744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188471,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:45:51.191578Z","caller":"traceutil/trace.go:171","msg":"trace[1904428798] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"321.955962ms","start":"2026-02-11T01:45:50.869617Z","end":"2026-02-11T01:45:51.191573Z","steps":["trace[1904428798] 'process raft request' (duration: 321.249667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:51.191589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.274389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-11T01:45:51.191597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:45:50.869602Z","time spent":"321.985113ms","remote":"127.0.0.1:57772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":696,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T01:45:51.191614Z","caller":"traceutil/trace.go:171","msg":"trace[901237080] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:619; }","duration":"268.32213ms","start":"2026-02-11T01:45:50.923284Z","end":"2026-02-11T01:45:51.191607Z","steps":["trace[901237080] 'agreement among raft nodes before linearized reading' (duration: 268.206298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:51.191906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.83988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-11T01:45:51.192008Z","caller":"traceutil/trace.go:171","msg":"trace[1857691582] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:619; }","duration":"267.896362ms","start":"2026-02-11T01:45:50.924034Z","end":"2026-02-11T01:45:51.19193Z","steps":["trace[1857691582] 'agreement among raft nodes before linearized reading' (duration: 267.773319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:51.195236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.696703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-02-11T01:45:51.195267Z","caller":"traceutil/trace.go:171","msg":"trace[283413819] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:619; }","duration":"181.748474ms","start":"2026-02-11T01:45:51.013507Z","end":"2026-02-11T01:45:51.195255Z","steps":["trace[283413819] 'agreement among raft nodes before linearized reading' (duration: 179.721812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:51.199516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.409319ms","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-11T01:45:51.199539Z","caller":"traceutil/trace.go:171","msg":"trace[548668487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:619; }","duration":"275.44176ms","start":"2026-02-11T01:45:50.924092Z","end":"2026-02-11T01:45:51.199534Z","steps":["trace[548668487] 'agreement among raft nodes before linearized reading' (duration: 274.559701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:51.202701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.165525ms","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-11T01:45:51.202777Z","caller":"traceutil/trace.go:171","msg":"trace[818212207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:619; }","duration":"276.237186ms","start":"2026-02-11T01:45:50.926512Z","end":"2026-02-11T01:45:51.202749Z","steps":["trace[818212207] 'agreement among raft nodes before linearized reading' (duration: 272.14148ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:46:00.161843Z","caller":"traceutil/trace.go:171","msg":"trace[1633458357] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"271.966148ms","start":"2026-02-11T01:45:59.88986Z","end":"2026-02-11T01:46:00.161826Z","steps":["trace[1633458357] 'process raft request' (duration: 271.873146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:04.569209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.352456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:46:04.569261Z","caller":"traceutil/trace.go:171","msg":"trace[1464395576] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:740; }","duration":"171.449528ms","start":"2026-02-11T01:46:04.397798Z","end":"2026-02-11T01:46:04.569248Z","steps":["trace[1464395576] 'range keys from in-memory index tree' (duration: 171.301414ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:46:09.131404Z","caller":"traceutil/trace.go:171","msg":"trace[2023654991] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"114.658792ms","start":"2026-02-11T01:46:09.016725Z","end":"2026-02-11T01:46:09.131383Z","steps":["trace[2023654991] 'process raft request' (duration: 114.453157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:09.646517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.12823ms","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-11T01:46:09.646587Z","caller":"traceutil/trace.go:171","msg":"trace[526868624] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:758; }","duration":"114.223272ms","start":"2026-02-11T01:46:09.532346Z","end":"2026-02-11T01:46:09.646569Z","steps":["trace[526868624] 'range keys from in-memory index tree' (duration: 114.027858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:09.646995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.196302ms","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-11T01:46:09.647033Z","caller":"traceutil/trace.go:171","msg":"trace[1506331826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:758; }","duration":"111.265224ms","start":"2026-02-11T01:46:09.535757Z","end":"2026-02-11T01:46:09.647022Z","steps":["trace[1506331826] 'range keys from in-memory index tree' (duration: 110.917606ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:46:36.211026Z","caller":"traceutil/trace.go:171","msg":"trace[671201214] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"144.513391ms","start":"2026-02-11T01:46:36.06648Z","end":"2026-02-11T01:46:36.210994Z","steps":["trace[671201214] 'process raft request' (duration: 143.073635ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:46:42.854653Z","caller":"traceutil/trace.go:171","msg":"trace[1385632733] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"149.075895ms","start":"2026-02-11T01:46:42.705551Z","end":"2026-02-11T01:46:42.854627Z","steps":["trace[1385632733] 'process raft request' (duration: 148.962612ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:46:44.103833Z","caller":"traceutil/trace.go:171","msg":"trace[1396206245] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"118.258245ms","start":"2026-02-11T01:46:43.985558Z","end":"2026-02-11T01:46:44.103817Z","steps":["trace[1396206245] 'process raft request' (duration: 74.124454ms)","trace[1396206245] 'compare' (duration: 43.941786ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:46:44.44418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.536093ms","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-11T01:46:44.44424Z","caller":"traceutil/trace.go:171","msg":"trace[1208992396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:855; }","duration":"169.628025ms","start":"2026-02-11T01:46:44.274597Z","end":"2026-02-11T01:46:44.444225Z","steps":["trace[1208992396] 'range keys from in-memory index tree' (duration: 169.384869ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:46:46.229365Z","caller":"traceutil/trace.go:171","msg":"trace[1573883488] linearizableReadLoop","detail":"{readStateIndex:893; appliedIndex:892; }","duration":"101.994379ms","start":"2026-02-11T01:46:46.127352Z","end":"2026-02-11T01:46:46.229346Z","steps":["trace[1573883488] 'read index received' (duration: 101.838184ms)","trace[1573883488] 'applied index is now lower than readState.Index' (duration: 155.265µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:46:46.22987Z","caller":"traceutil/trace.go:171","msg":"trace[1973200157] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"119.889023ms","start":"2026-02-11T01:46:46.109966Z","end":"2026-02-11T01:46:46.229855Z","steps":["trace[1973200157] 'process raft request' (duration: 119.274716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:46.230018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.651215ms","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-11T01:46:46.231787Z","caller":"traceutil/trace.go:171","msg":"trace[745176983] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:859; }","duration":"104.409421ms","start":"2026-02-11T01:46:46.127347Z","end":"2026-02-11T01:46:46.231756Z","steps":["trace[745176983] 'agreement among raft nodes before linearized reading' (duration: 102.562153ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:46:46.384053Z","caller":"traceutil/trace.go:171","msg":"trace[970794833] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"149.305487ms","start":"2026-02-11T01:46:46.234731Z","end":"2026-02-11T01:46:46.384036Z","steps":["trace[970794833] 'process raft request' (duration: 149.208055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:46.616071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.101877ms","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-11T01:46:46.616114Z","caller":"traceutil/trace.go:171","msg":"trace[1169916658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:861; }","duration":"138.181079ms","start":"2026-02-11T01:46:46.477923Z","end":"2026-02-11T01:46:46.616104Z","steps":["trace[1169916658] 'range keys from in-memory index tree' (duration: 137.989874ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:46:47.076678Z","caller":"traceutil/trace.go:171","msg":"trace[646929337] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"173.430108ms","start":"2026-02-11T01:46:46.903222Z","end":"2026-02-11T01:46:47.076652Z","steps":["trace[646929337] 'process raft request' (duration: 173.257974ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:48.42308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.963983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12727"} {"level":"info","ts":"2026-02-11T01:46:48.423151Z","caller":"traceutil/trace.go:171","msg":"trace[2553518] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:865; }","duration":"129.061255ms","start":"2026-02-11T01:46:48.294071Z","end":"2026-02-11T01:46:48.423132Z","steps":["trace[2553518] 'range keys from in-memory index tree' (duration: 128.878651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:48.945067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.130741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12727"} {"level":"info","ts":"2026-02-11T01:46:48.94518Z","caller":"traceutil/trace.go:171","msg":"trace[756845824] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:868; }","duration":"197.288835ms","start":"2026-02-11T01:46:48.747871Z","end":"2026-02-11T01:46:48.94516Z","steps":["trace[756845824] 'range keys from in-memory index tree' (duration: 196.922595ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:46:54.424896Z","caller":"traceutil/trace.go:171","msg":"trace[1805392542] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:920; }","duration":"218.749175ms","start":"2026-02-11T01:46:54.206127Z","end":"2026-02-11T01:46:54.424876Z","steps":["trace[1805392542] 'read index received' (duration: 156.413049ms)","trace[1805392542] 'applied index is now lower than readState.Index' (duration: 62.334626ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:46:54.425155Z","caller":"traceutil/trace.go:171","msg":"trace[994047382] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"221.533588ms","start":"2026-02-11T01:46:54.203595Z","end":"2026-02-11T01:46:54.425128Z","steps":["trace[994047382] 'process raft request' (duration: 158.964047ms)","trace[994047382] 'compare' (duration: 62.150641ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:46:54.425468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.28437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1490"} {"level":"info","ts":"2026-02-11T01:46:54.425584Z","caller":"traceutil/trace.go:171","msg":"trace[686598387] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:885; }","duration":"219.460624ms","start":"2026-02-11T01:46:54.206092Z","end":"2026-02-11T01:46:54.425553Z","steps":["trace[686598387] 'agreement among raft nodes before linearized reading' (duration: 219.058733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:54.425851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.172619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-11T01:46:54.425958Z","caller":"traceutil/trace.go:171","msg":"trace[1811341965] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:885; }","duration":"121.279342ms","start":"2026-02-11T01:46:54.304667Z","end":"2026-02-11T01:46:54.425947Z","steps":["trace[1811341965] 'agreement among raft nodes before linearized reading' (duration: 121.083007ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:46:54.638877Z","caller":"traceutil/trace.go:171","msg":"trace[2010711122] linearizableReadLoop","detail":"{readStateIndex:925; appliedIndex:924; }","duration":"135.581849ms","start":"2026-02-11T01:46:54.503272Z","end":"2026-02-11T01:46:54.638854Z","steps":["trace[2010711122] 'read index received' (duration: 97.392971ms)","trace[2010711122] 'applied index is now lower than readState.Index' (duration: 38.187778ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:46:54.639004Z","caller":"traceutil/trace.go:171","msg":"trace[553283270] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"137.17553ms","start":"2026-02-11T01:46:54.501813Z","end":"2026-02-11T01:46:54.638989Z","steps":["trace[553283270] 'process raft request' (duration: 98.915651ms)","trace[553283270] 'compare' (duration: 38.010363ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:46:54.639297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.98821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/local-path-storage/\" range_end:\"/registry/resourcequotas/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:46:54.639326Z","caller":"traceutil/trace.go:171","msg":"trace[1010312793] range","detail":"{range_begin:/registry/resourcequotas/local-path-storage/; range_end:/registry/resourcequotas/local-path-storage0; response_count:0; response_revision:889; }","duration":"136.060453ms","start":"2026-02-11T01:46:54.503257Z","end":"2026-02-11T01:46:54.639317Z","steps":["trace[1010312793] 'agreement among raft nodes before linearized reading' (duration: 135.92836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:54.639537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.81389ms","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-11T01:46:54.639559Z","caller":"traceutil/trace.go:171","msg":"trace[590349159] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:889; }","duration":"113.97955ms","start":"2026-02-11T01:46:54.525573Z","end":"2026-02-11T01:46:54.639552Z","steps":["trace[590349159] 'agreement among raft nodes before linearized reading' (duration: 113.890308ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:46:54.944915Z","caller":"traceutil/trace.go:171","msg":"trace[1644687349] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"205.99777ms","start":"2026-02-11T01:46:54.738849Z","end":"2026-02-11T01:46:54.944847Z","steps":["trace[1644687349] 'process raft request' (duration: 205.405253ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:46:55.347882Z","caller":"traceutil/trace.go:171","msg":"trace[56018428] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:931; }","duration":"156.063128ms","start":"2026-02-11T01:46:55.191794Z","end":"2026-02-11T01:46:55.347857Z","steps":["trace[56018428] 'read index received' (duration: 152.099743ms)","trace[56018428] 'applied index is now lower than readState.Index' (duration: 3.961605ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:46:55.348307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.468549ms","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-11T01:46:55.348399Z","caller":"traceutil/trace.go:171","msg":"trace[788842597] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:896; }","duration":"156.613903ms","start":"2026-02-11T01:46:55.191773Z","end":"2026-02-11T01:46:55.348386Z","steps":["trace[788842597] 'agreement among raft nodes before linearized reading' (duration: 156.280945ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:46:55.348983Z","caller":"traceutil/trace.go:171","msg":"trace[1334925610] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"247.296432ms","start":"2026-02-11T01:46:55.101644Z","end":"2026-02-11T01:46:55.348941Z","steps":["trace[1334925610] 'process raft request' (duration: 242.193857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:56.631646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.340775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-11T01:46:56.631922Z","caller":"traceutil/trace.go:171","msg":"trace[337535376] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:899; }","duration":"187.644733ms","start":"2026-02-11T01:46:56.444262Z","end":"2026-02-11T01:46:56.631907Z","steps":["trace[337535376] 'range keys from in-memory index tree' (duration: 187.237892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:58.729361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.032013ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:46:58.729535Z","caller":"traceutil/trace.go:171","msg":"trace[1392407643] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"201.449549ms","start":"2026-02-11T01:46:58.528064Z","end":"2026-02-11T01:46:58.729513Z","steps":["trace[1392407643] 'process raft request' (duration: 31.19573ms)","trace[1392407643] 'compare' (duration: 169.90368ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:47:01.121856Z","caller":"traceutil/trace.go:171","msg":"trace[221805794] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"110.912806ms","start":"2026-02-11T01:47:01.010913Z","end":"2026-02-11T01:47:01.121826Z","steps":["trace[221805794] 'process raft request' (duration: 110.504744ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:47:01.510157Z","caller":"traceutil/trace.go:171","msg":"trace[104666191] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"105.09188ms","start":"2026-02-11T01:47:01.405043Z","end":"2026-02-11T01:47:01.510135Z","steps":["trace[104666191] 'process raft request' (duration: 104.595957ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:47:02.101142Z","caller":"traceutil/trace.go:171","msg":"trace[136496097] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"102.257099ms","start":"2026-02-11T01:47:01.998863Z","end":"2026-02-11T01:47:02.10112Z","steps":["trace[136496097] 'process raft request' (duration: 102.205007ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:47:02.101594Z","caller":"traceutil/trace.go:171","msg":"trace[1240184470] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"107.681523ms","start":"2026-02-11T01:47:01.993886Z","end":"2026-02-11T01:47:02.101568Z","steps":["trace[1240184470] 'process raft request' (duration: 97.09121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:47:02.840939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.238314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/default\" ","response":"range_response_count:1 size:171"} {"level":"info","ts":"2026-02-11T01:47:02.841103Z","caller":"traceutil/trace.go:171","msg":"trace[1153563038] range","detail":"{range_begin:/registry/serviceaccounts/default/default; range_end:; response_count:1; response_revision:931; }","duration":"122.448179ms","start":"2026-02-11T01:47:02.718634Z","end":"2026-02-11T01:47:02.841082Z","steps":["trace[1153563038] 'range keys from in-memory index tree' (duration: 122.135992ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:47:03.682118Z","caller":"traceutil/trace.go:171","msg":"trace[1722676113] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"154.579899ms","start":"2026-02-11T01:47:03.527505Z","end":"2026-02-11T01:47:03.682085Z","steps":["trace[1722676113] 'process raft request' (duration: 154.348622ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:47:06.508882Z","caller":"traceutil/trace.go:171","msg":"trace[1951753791] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:984; }","duration":"113.435199ms","start":"2026-02-11T01:47:06.395423Z","end":"2026-02-11T01:47:06.508858Z","steps":["trace[1951753791] 'read index received' (duration: 113.284705ms)","trace[1951753791] 'applied index is now lower than readState.Index' (duration: 149.354µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:47:06.509142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.673346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-11T01:47:06.509288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:47:06.182715Z","time spent":"326.566656ms","remote":"127.0.0.1:57736","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-11T01:47:06.509227Z","caller":"traceutil/trace.go:171","msg":"trace[1071537680] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:945; }","duration":"113.83099ms","start":"2026-02-11T01:47:06.395379Z","end":"2026-02-11T01:47:06.50921Z","steps":["trace[1071537680] 'agreement among raft nodes before linearized reading' (duration: 113.668106ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:47:16.095807Z","caller":"traceutil/trace.go:171","msg":"trace[389827642] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1018; }","duration":"299.008652ms","start":"2026-02-11T01:47:15.79677Z","end":"2026-02-11T01:47:16.095779Z","steps":["trace[389827642] 'read index received' (duration: 298.629212ms)","trace[389827642] 'applied index is now lower than readState.Index' (duration: 378µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:47:16.097935Z","caller":"traceutil/trace.go:171","msg":"trace[1364629099] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"820.773567ms","start":"2026-02-11T01:47:15.277145Z","end":"2026-02-11T01:47:16.097918Z","steps":["trace[1364629099] 'process raft request' (duration: 818.31147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:47:16.0982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:47:15.277129Z","time spent":"820.989233ms","remote":"127.0.0.1:57958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:47:16.098672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.897101ms","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-11T01:47:16.098871Z","caller":"traceutil/trace.go:171","msg":"trace[356570462] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:978; }","duration":"302.122688ms","start":"2026-02-11T01:47:15.796733Z","end":"2026-02-11T01:47:16.098856Z","steps":["trace[356570462] 'agreement among raft nodes before linearized reading' (duration: 301.83364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:47:16.099043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:47:15.796702Z","time spent":"302.327193ms","remote":"127.0.0.1:57958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T01:47:16.099354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.067018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:47:16.099543Z","caller":"traceutil/trace.go:171","msg":"trace[881835375] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:978; }","duration":"100.290974ms","start":"2026-02-11T01:47:15.999239Z","end":"2026-02-11T01:47:16.09953Z","steps":["trace[881835375] 'agreement among raft nodes before linearized reading' (duration: 100.083588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:47:16.277347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.170402ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:47:16.277658Z","caller":"traceutil/trace.go:171","msg":"trace[155653726] linearizableReadLoop","detail":"{readStateIndex:1020; appliedIndex:1019; }","duration":"172.316775ms","start":"2026-02-11T01:47:16.105324Z","end":"2026-02-11T01:47:16.277641Z","steps":["trace[155653726] 'read index received' (duration: 49.70652ms)","trace[155653726] 'applied index is now lower than readState.Index' (duration: 122.609615ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:47:16.277712Z","caller":"traceutil/trace.go:171","msg":"trace[1916576346] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"173.512507ms","start":"2026-02-11T01:47:16.104192Z","end":"2026-02-11T01:47:16.277705Z","steps":["trace[1916576346] 'process raft request' (duration: 50.894523ms)","trace[1916576346] 'compare' (duration: 122.105941ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:47:16.277747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.421717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:47:16.279206Z","caller":"traceutil/trace.go:171","msg":"trace[340630061] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:979; }","duration":"173.899588ms","start":"2026-02-11T01:47:16.105299Z","end":"2026-02-11T01:47:16.279198Z","steps":["trace[340630061] 'agreement among raft nodes before linearized reading' (duration: 172.430458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:47:16.806441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.745409ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:47:16.806533Z","caller":"traceutil/trace.go:171","msg":"trace[1201270509] linearizableReadLoop","detail":"{readStateIndex:1022; appliedIndex:1021; }","duration":"409.151984ms","start":"2026-02-11T01:47:16.397367Z","end":"2026-02-11T01:47:16.806519Z","steps":["trace[1201270509] 'read index received' (duration: 56.217719ms)","trace[1201270509] 'applied index is now lower than readState.Index' (duration: 352.933175ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:47:16.806614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.245546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:47:16.806786Z","caller":"traceutil/trace.go:171","msg":"trace[173053243] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:980; }","duration":"409.463012ms","start":"2026-02-11T01:47:16.397313Z","end":"2026-02-11T01:47:16.806776Z","steps":["trace[173053243] 'agreement among raft nodes before linearized reading' (duration: 409.246526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:47:16.806938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:47:16.397293Z","time spent":"409.632527ms","remote":"127.0.0.1:57684","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-11T01:47:16.807407Z","caller":"traceutil/trace.go:171","msg":"trace[338658621] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"453.6323ms","start":"2026-02-11T01:47:16.353758Z","end":"2026-02-11T01:47:16.80739Z","steps":["trace[338658621] 'process raft request' (duration: 99.868862ms)","trace[338658621] 'compare' (duration: 352.655467ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:47:16.80756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:47:16.353745Z","time spent":"453.700901ms","remote":"127.0.0.1:57736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:47:17.363999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.352544ms","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-11T01:47:17.364051Z","caller":"traceutil/trace.go:171","msg":"trace[1585017230] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:980; }","duration":"340.449476ms","start":"2026-02-11T01:47:17.02359Z","end":"2026-02-11T01:47:17.364039Z","steps":["trace[1585017230] 'range keys from in-memory index tree' (duration: 340.226661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:47:17.364075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:47:17.023569Z","time spent":"340.500028ms","remote":"127.0.0.1:57958","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":"warn","ts":"2026-02-11T01:47:17.364351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.276802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-11T01:47:17.365244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.600576ms","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-11T01:47:17.365915Z","caller":"traceutil/trace.go:171","msg":"trace[871612199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:980; }","duration":"258.268195ms","start":"2026-02-11T01:47:17.107597Z","end":"2026-02-11T01:47:17.365865Z","steps":["trace[871612199] 'range keys from in-memory index tree' (duration: 257.477223ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:47:17.364379Z","caller":"traceutil/trace.go:171","msg":"trace[338020535] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:980; }","duration":"340.329503ms","start":"2026-02-11T01:47:17.024043Z","end":"2026-02-11T01:47:17.364372Z","steps":["trace[338020535] 'range keys from in-memory index tree' (duration: 340.161949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:47:17.37036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:47:17.024031Z","time spent":"346.309978ms","remote":"127.0.0.1:57958","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":"info","ts":"2026-02-11T01:47:17.606168Z","caller":"traceutil/trace.go:171","msg":"trace[1688509882] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"236.074507ms","start":"2026-02-11T01:47:17.370072Z","end":"2026-02-11T01:47:17.606147Z","steps":["trace[1688509882] 'process raft request' (duration: 235.950523ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:47:17.750105Z","caller":"traceutil/trace.go:171","msg":"trace[671050294] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"376.906804ms","start":"2026-02-11T01:47:17.373172Z","end":"2026-02-11T01:47:17.750079Z","steps":["trace[671050294] 'process raft request' (duration: 293.51361ms)","trace[671050294] 'compare' (duration: 83.129546ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:47:17.750693Z","caller":"traceutil/trace.go:171","msg":"trace[2103889276] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"375.600429ms","start":"2026-02-11T01:47:17.375066Z","end":"2026-02-11T01:47:17.750667Z","steps":["trace[2103889276] 'process raft request' (duration: 374.971361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:47:17.750894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:47:17.375055Z","time spent":"375.713962ms","remote":"127.0.0.1:57958","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-11T01:47:17.751888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:47:17.373161Z","time spent":"377.123429ms","remote":"127.0.0.1:57958","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-11T01:47:28.15503Z","caller":"traceutil/trace.go:171","msg":"trace[118444426] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1058; }","duration":"105.180639ms","start":"2026-02-11T01:47:28.049825Z","end":"2026-02-11T01:47:28.155005Z","steps":["trace[118444426] 'read index received' (duration: 104.967252ms)","trace[118444426] 'applied index is now lower than readState.Index' (duration: 212.307µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:47:28.155158Z","caller":"traceutil/trace.go:171","msg":"trace[1022237109] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"172.103696ms","start":"2026-02-11T01:47:27.983038Z","end":"2026-02-11T01:47:28.155142Z","steps":["trace[1022237109] 'process raft request' (duration: 171.803898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:47:28.155488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.647812ms","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-11T01:47:28.155526Z","caller":"traceutil/trace.go:171","msg":"trace[349324827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1014; }","duration":"105.725934ms","start":"2026-02-11T01:47:28.04979Z","end":"2026-02-11T01:47:28.155516Z","steps":["trace[349324827] 'agreement among raft nodes before linearized reading' (duration: 105.541419ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:47:28.283049Z","caller":"traceutil/trace.go:171","msg":"trace[2083071189] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"118.553149ms","start":"2026-02-11T01:47:28.164472Z","end":"2026-02-11T01:47:28.283025Z","steps":["trace[2083071189] 'process raft request' (duration: 59.870551ms)","trace[2083071189] 'compare' (duration: 58.521764ms)"],"step_count":2}