{"level":"warn","ts":"2026-02-08T21:40:59.407044Z","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-08T21:40:59.41225Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.74:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.74:2380","--initial-cluster=instance=https://162.253.55.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.74: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-08T21:40:59.412524Z","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-08T21:40:59.412584Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.74:2380"]} {"level":"info","ts":"2026-02-08T21:40:59.413136Z","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-08T21:40:59.413736Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"]} {"level":"info","ts":"2026-02-08T21:40:59.415359Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.74:2380"],"listen-peer-urls":["https://162.253.55.74:2380"],"advertise-client-urls":["https://162.253.55.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.74: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-08T21:40:59.42269Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.084035ms"} {"level":"info","ts":"2026-02-08T21:40:59.432466Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"30aa8136fb61a722","cluster-id":"e7ea3889c4872560"} {"level":"info","ts":"2026-02-08T21:40:59.432636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=()"} {"level":"info","ts":"2026-02-08T21:40:59.432716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 0"} {"level":"info","ts":"2026-02-08T21:40:59.432759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 30aa8136fb61a722 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-08T21:40:59.432817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 1"} {"level":"info","ts":"2026-02-08T21:40:59.432902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"warn","ts":"2026-02-08T21:40:59.448465Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-08T21:40:59.473601Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-08T21:40:59.493909Z","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-08T21:40:59.502717Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"30aa8136fb61a722","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-08T21:40:59.505188Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"30aa8136fb61a722","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-08T21:40:59.50612Z","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-08T21:40:59.506158Z","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-08T21:40:59.506166Z","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-08T21:40:59.507944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"info","ts":"2026-02-08T21:40:59.508376Z","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-08T21:40:59.50864Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"30aa8136fb61a722","initial-advertise-peer-urls":["https://162.253.55.74:2380"],"listen-peer-urls":["https://162.253.55.74:2380"],"advertise-client-urls":["https://162.253.55.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-08T21:40:59.508742Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-08T21:40:59.508893Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-02-08T21:40:59.508953Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-02-08T21:40:59.509048Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e7ea3889c4872560","local-member-id":"30aa8136fb61a722","added-peer-id":"30aa8136fb61a722","added-peer-peer-urls":["https://162.253.55.74:2380"]} {"level":"info","ts":"2026-02-08T21:40:59.733916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 is starting a new election at term 1"} {"level":"info","ts":"2026-02-08T21:40:59.733981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-08T21:40:59.733999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgPreVoteResp from 30aa8136fb61a722 at term 1"} {"level":"info","ts":"2026-02-08T21:40:59.734037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became candidate at term 2"} {"level":"info","ts":"2026-02-08T21:40:59.734047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgVoteResp from 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-02-08T21:40:59.734055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became leader at term 2"} {"level":"info","ts":"2026-02-08T21:40:59.734085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 30aa8136fb61a722 elected leader 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-02-08T21:40:59.737452Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:40:59.738592Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"30aa8136fb61a722","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.74:2379]}","request-path":"/0/members/30aa8136fb61a722/attributes","cluster-id":"e7ea3889c4872560","publish-timeout":"7s"} {"level":"info","ts":"2026-02-08T21:40:59.738844Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T21:40:59.740663Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-08T21:40:59.740804Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T21:40:59.741009Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e7ea3889c4872560","local-member-id":"30aa8136fb61a722","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:40:59.741442Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:40:59.741494Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:40:59.742787Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.74:2379"} {"level":"info","ts":"2026-02-08T21:40:59.74821Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-08T21:40:59.74823Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-08T21:41:18.447728Z","caller":"traceutil/trace.go:171","msg":"trace[2083688082] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"242.707391ms","start":"2026-02-08T21:41:18.205002Z","end":"2026-02-08T21:41:18.44771Z","steps":["trace[2083688082] 'process raft request' (duration: 242.605909ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:41:30.238204Z","caller":"traceutil/trace.go:171","msg":"trace[1892126101] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:488; }","duration":"373.963566ms","start":"2026-02-08T21:41:29.864222Z","end":"2026-02-08T21:41:30.238186Z","steps":["trace[1892126101] 'read index received' (duration: 373.821363ms)","trace[1892126101] 'applied index is now lower than readState.Index' (duration: 141.453µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:41:30.238304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.060368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-08T21:41:30.238341Z","caller":"traceutil/trace.go:171","msg":"trace[194765292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:474; }","duration":"374.13037ms","start":"2026-02-08T21:41:29.864205Z","end":"2026-02-08T21:41:30.238335Z","steps":["trace[194765292] 'agreement among raft nodes before linearized reading' (duration: 374.054729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:30.238364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:29.864196Z","time spent":"374.162751ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-08T21:41:30.238407Z","caller":"traceutil/trace.go:171","msg":"trace[2004938650] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"386.085982ms","start":"2026-02-08T21:41:29.852299Z","end":"2026-02-08T21:41:30.238385Z","steps":["trace[2004938650] 'process raft request' (duration: 385.787385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:30.238823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:29.852286Z","time spent":"386.168043ms","remote":"127.0.0.1:37710","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-08T21:41:32.841669Z","caller":"traceutil/trace.go:171","msg":"trace[828852600] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:495; }","duration":"201.135133ms","start":"2026-02-08T21:41:32.640518Z","end":"2026-02-08T21:41:32.841653Z","steps":["trace[828852600] 'read index received' (duration: 201.00914ms)","trace[828852600] 'applied index is now lower than readState.Index' (duration: 125.333µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:41:32.841813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.279086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-08T21:41:32.841835Z","caller":"traceutil/trace.go:171","msg":"trace[1158230824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:481; }","duration":"201.331437ms","start":"2026-02-08T21:41:32.640496Z","end":"2026-02-08T21:41:32.841827Z","steps":["trace[1158230824] 'agreement among raft nodes before linearized reading' (duration: 201.219284ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:41:32.842043Z","caller":"traceutil/trace.go:171","msg":"trace[2023037506] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"319.106228ms","start":"2026-02-08T21:41:32.522927Z","end":"2026-02-08T21:41:32.842033Z","steps":["trace[2023037506] 'process raft request' (duration: 318.645198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:32.842105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:32.522918Z","time spent":"319.146749ms","remote":"127.0.0.1:37710","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-08T21:41:33.249195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.456883ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:41:33.249462Z","caller":"traceutil/trace.go:171","msg":"trace[578414177] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"404.111215ms","start":"2026-02-08T21:41:32.845337Z","end":"2026-02-08T21:41:33.249449Z","steps":["trace[578414177] 'process raft request' (duration: 151.241093ms)","trace[578414177] 'compare' (duration: 252.349911ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:41:33.249512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:32.845323Z","time spent":"404.169186ms","remote":"127.0.0.1:37710","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-08T21:41:34.431263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.496069ms","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-08T21:41:34.431392Z","caller":"traceutil/trace.go:171","msg":"trace[1393396809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:485; }","duration":"113.662632ms","start":"2026-02-08T21:41:34.317705Z","end":"2026-02-08T21:41:34.431368Z","steps":["trace[1393396809] 'range keys from in-memory index tree' (duration: 113.286714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:36.707481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.958055ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:41:36.70765Z","caller":"traceutil/trace.go:171","msg":"trace[1680390161] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"170.225378ms","start":"2026-02-08T21:41:36.53741Z","end":"2026-02-08T21:41:36.707635Z","steps":["trace[1680390161] 'process raft request' (duration: 170.168976ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:41:36.708002Z","caller":"traceutil/trace.go:171","msg":"trace[368999682] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"265.773764ms","start":"2026-02-08T21:41:36.442215Z","end":"2026-02-08T21:41:36.707988Z","steps":["trace[368999682] 'process raft request' (duration: 77.193566ms)","trace[368999682] 'compare' (duration: 187.140687ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:41:38.190162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.068751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-08T21:41:38.190277Z","caller":"traceutil/trace.go:171","msg":"trace[1882163120] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:496; }","duration":"131.211615ms","start":"2026-02-08T21:41:38.059036Z","end":"2026-02-08T21:41:38.190248Z","steps":["trace[1882163120] 'range keys from in-memory index tree' (duration: 130.98179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:38.191117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.805917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-w59f5\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-08T21:41:38.191154Z","caller":"traceutil/trace.go:171","msg":"trace[702397305] range","detail":"{range_begin:/registry/pods/kube-system/cilium-w59f5; range_end:; response_count:1; response_revision:496; }","duration":"272.877669ms","start":"2026-02-08T21:41:37.918265Z","end":"2026-02-08T21:41:38.191143Z","steps":["trace[702397305] 'range keys from in-memory index tree' (duration: 272.646704ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:41:38.767514Z","caller":"traceutil/trace.go:171","msg":"trace[477065509] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"402.868467ms","start":"2026-02-08T21:41:38.364628Z","end":"2026-02-08T21:41:38.767497Z","steps":["trace[477065509] 'process raft request' (duration: 402.319754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:38.767852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:38.364611Z","time spent":"403.168764ms","remote":"127.0.0.1:37710","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-08T21:41:40.577363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.749935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:41:40.577439Z","caller":"traceutil/trace.go:171","msg":"trace[1201303599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"418.920179ms","start":"2026-02-08T21:41:40.158501Z","end":"2026-02-08T21:41:40.577421Z","steps":["trace[1201303599] 'range keys from in-memory index tree' (duration: 418.620752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:40.577483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:40.158482Z","time spent":"418.99341ms","remote":"127.0.0.1:37468","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-08T21:41:43.279908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.322899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:41:43.280016Z","caller":"traceutil/trace.go:171","msg":"trace[918924050] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:563; }","duration":"121.466722ms","start":"2026-02-08T21:41:43.158531Z","end":"2026-02-08T21:41:43.279998Z","steps":["trace[918924050] 'range keys from in-memory index tree' (duration: 121.267688ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:41:52.468515Z","caller":"traceutil/trace.go:171","msg":"trace[565973730] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"265.512804ms","start":"2026-02-08T21:41:52.202988Z","end":"2026-02-08T21:41:52.468501Z","steps":["trace[565973730] 'process raft request' (duration: 265.431842ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:41:57.344921Z","caller":"traceutil/trace.go:171","msg":"trace[987289005] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"474.279071ms","start":"2026-02-08T21:41:56.870625Z","end":"2026-02-08T21:41:57.344904Z","steps":["trace[987289005] 'process raft request' (duration: 474.188469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:57.345051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:56.870612Z","time spent":"474.368232ms","remote":"127.0.0.1:37710","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-08T21:41:57.823554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.273443ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:41:57.823659Z","caller":"traceutil/trace.go:171","msg":"trace[1554195296] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:705; }","duration":"836.613499ms","start":"2026-02-08T21:41:56.987034Z","end":"2026-02-08T21:41:57.823647Z","steps":["trace[1554195296] 'read index received' (duration: 358.269129ms)","trace[1554195296] 'applied index is now lower than readState.Index' (duration: 478.34375ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:41:57.823806Z","caller":"traceutil/trace.go:171","msg":"trace[71991515] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"952.562479ms","start":"2026-02-08T21:41:56.871234Z","end":"2026-02-08T21:41:57.823797Z","steps":["trace[71991515] 'process raft request' (duration: 750.975649ms)","trace[71991515] 'compare' (duration: 201.160641ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:41:57.823845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:56.871228Z","time spent":"952.596859ms","remote":"127.0.0.1:37710","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-08T21:41:57.823969Z","caller":"traceutil/trace.go:171","msg":"trace[134251280] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"490.105846ms","start":"2026-02-08T21:41:57.333858Z","end":"2026-02-08T21:41:57.823963Z","steps":["trace[134251280] 'process raft request' (duration: 489.756469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:57.823999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:57.333838Z","time spent":"490.144927ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:41:57.824123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.089709ms","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-08T21:41:57.824141Z","caller":"traceutil/trace.go:171","msg":"trace[1467469307] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:686; }","duration":"837.15076ms","start":"2026-02-08T21:41:56.986984Z","end":"2026-02-08T21:41:57.824134Z","steps":["trace[1467469307] 'agreement among raft nodes before linearized reading' (duration: 837.078088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:57.824159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:56.986959Z","time spent":"837.194961ms","remote":"127.0.0.1:37710","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-08T21:41:57.824263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.343077ms","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-08T21:41:57.824279Z","caller":"traceutil/trace.go:171","msg":"trace[484231373] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:686; }","duration":"822.359447ms","start":"2026-02-08T21:41:57.001915Z","end":"2026-02-08T21:41:57.824274Z","steps":["trace[484231373] 'agreement among raft nodes before linearized reading' (duration: 822.315306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:57.824294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:57.001893Z","time spent":"822.397617ms","remote":"127.0.0.1:37710","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-08T21:41:57.824462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.579341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-08T21:41:57.824479Z","caller":"traceutil/trace.go:171","msg":"trace[1506906186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:686; }","duration":"471.612792ms","start":"2026-02-08T21:41:57.352862Z","end":"2026-02-08T21:41:57.824474Z","steps":["trace[1506906186] 'agreement among raft nodes before linearized reading' (duration: 471.563331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:57.824494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:57.352844Z","time spent":"471.647313ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-08T21:41:58.425985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.262507ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:41:58.42613Z","caller":"traceutil/trace.go:171","msg":"trace[1639915469] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:707; }","duration":"263.697915ms","start":"2026-02-08T21:41:58.162419Z","end":"2026-02-08T21:41:58.426117Z","steps":["trace[1639915469] 'read index received' (duration: 91.277851ms)","trace[1639915469] 'applied index is now lower than readState.Index' (duration: 172.419464ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:41:58.426201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.779017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:41:58.426228Z","caller":"traceutil/trace.go:171","msg":"trace[849504231] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:689; }","duration":"263.826838ms","start":"2026-02-08T21:41:58.162393Z","end":"2026-02-08T21:41:58.42622Z","steps":["trace[849504231] 'agreement among raft nodes before linearized reading' (duration: 263.765097ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:41:58.426456Z","caller":"traceutil/trace.go:171","msg":"trace[1382823437] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"523.75855ms","start":"2026-02-08T21:41:57.902688Z","end":"2026-02-08T21:41:58.426446Z","steps":["trace[1382823437] 'process raft request' (duration: 67.977763ms)","trace[1382823437] 'compare' (duration: 455.151203ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:41:58.426517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:57.902675Z","time spent":"523.819952ms","remote":"127.0.0.1:37710","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-08T21:41:58.426619Z","caller":"traceutil/trace.go:171","msg":"trace[1570151969] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"521.672755ms","start":"2026-02-08T21:41:57.90494Z","end":"2026-02-08T21:41:58.426613Z","steps":["trace[1570151969] 'process raft request' (duration: 521.114063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:58.426656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:57.904933Z","time spent":"521.706405ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:41:58.426738Z","caller":"traceutil/trace.go:171","msg":"trace[2050915153] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"521.578433ms","start":"2026-02-08T21:41:57.905155Z","end":"2026-02-08T21:41:58.426733Z","steps":["trace[2050915153] 'process raft request' (duration: 520.941249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:58.426774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:57.905147Z","time spent":"521.609844ms","remote":"127.0.0.1:37710","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-08T21:42:00.671261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.092155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-08T21:42:00.671315Z","caller":"traceutil/trace.go:171","msg":"trace[24483503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:690; }","duration":"769.187127ms","start":"2026-02-08T21:41:59.902115Z","end":"2026-02-08T21:42:00.671302Z","steps":["trace[24483503] 'range keys from in-memory index tree' (duration: 768.959411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:00.671339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:59.902096Z","time spent":"769.237969ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T21:42:00.671574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.058516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:00.671593Z","caller":"traceutil/trace.go:171","msg":"trace[1270438148] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:690; }","duration":"512.111696ms","start":"2026-02-08T21:42:00.159475Z","end":"2026-02-08T21:42:00.671587Z","steps":["trace[1270438148] 'range keys from in-memory index tree' (duration: 511.952083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:00.671628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:00.159459Z","time spent":"512.165388ms","remote":"127.0.0.1:37468","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-08T21:42:00.671881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.713841ms","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-08T21:42:00.671901Z","caller":"traceutil/trace.go:171","msg":"trace[865854659] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:690; }","duration":"239.746173ms","start":"2026-02-08T21:42:00.432148Z","end":"2026-02-08T21:42:00.671894Z","steps":["trace[865854659] 'range keys from in-memory index tree' (duration: 239.641091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:00.672879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.773115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-08T21:42:00.672902Z","caller":"traceutil/trace.go:171","msg":"trace[232323318] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:690; }","duration":"240.834046ms","start":"2026-02-08T21:42:00.432061Z","end":"2026-02-08T21:42:00.672895Z","steps":["trace[232323318] 'range keys from in-memory index tree' (duration: 240.618981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:00.673028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.18299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-08T21:42:00.673047Z","caller":"traceutil/trace.go:171","msg":"trace[1790928620] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:690; }","duration":"239.23312ms","start":"2026-02-08T21:42:00.433808Z","end":"2026-02-08T21:42:00.673042Z","steps":["trace[1790928620] 'range keys from in-memory index tree' (duration: 239.125838ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:01.076354Z","caller":"traceutil/trace.go:171","msg":"trace[2053015267] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"398.762612ms","start":"2026-02-08T21:42:00.677572Z","end":"2026-02-08T21:42:01.076334Z","steps":["trace[2053015267] 'process raft request' (duration: 398.626349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:01.076602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:00.677563Z","time spent":"398.914095ms","remote":"127.0.0.1:37710","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-08T21:42:01.646854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.151892ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:42:01.646937Z","caller":"traceutil/trace.go:171","msg":"trace[1693344135] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"964.812277ms","start":"2026-02-08T21:42:00.682113Z","end":"2026-02-08T21:42:01.646925Z","steps":["trace[1693344135] 'process raft request' (duration: 571.544784ms)","trace[1693344135] 'compare' (duration: 393.054539ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:01.646968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:00.682107Z","time spent":"964.845718ms","remote":"127.0.0.1:37710","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-08T21:42:01.647001Z","caller":"traceutil/trace.go:171","msg":"trace[374963054] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:713; }","duration":"286.984484ms","start":"2026-02-08T21:42:01.360008Z","end":"2026-02-08T21:42:01.646992Z","steps":["trace[374963054] 'read index received' (duration: 23.811µs)","trace[374963054] 'applied index is now lower than readState.Index' (duration: 286.960233ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:01.647075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.065315ms","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-08T21:42:01.647088Z","caller":"traceutil/trace.go:171","msg":"trace[688048223] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:694; }","duration":"287.105177ms","start":"2026-02-08T21:42:01.359978Z","end":"2026-02-08T21:42:01.647083Z","steps":["trace[688048223] 'agreement among raft nodes before linearized reading' (duration: 287.039635ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:01.647111Z","caller":"traceutil/trace.go:171","msg":"trace[1337492557] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"956.580607ms","start":"2026-02-08T21:42:00.690526Z","end":"2026-02-08T21:42:01.647107Z","steps":["trace[1337492557] 'process raft request' (duration: 956.380713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:01.647137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:00.690509Z","time spent":"956.615119ms","remote":"127.0.0.1:37710","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-08T21:42:01.647171Z","caller":"traceutil/trace.go:171","msg":"trace[450818869] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"956.581757ms","start":"2026-02-08T21:42:00.690584Z","end":"2026-02-08T21:42:01.647166Z","steps":["trace[450818869] 'process raft request' (duration: 956.380583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:01.647203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:00.690581Z","time spent":"956.606868ms","remote":"127.0.0.1:37710","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-08T21:42:17.426303Z","caller":"traceutil/trace.go:171","msg":"trace[1241062520] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"297.741619ms","start":"2026-02-08T21:42:17.128543Z","end":"2026-02-08T21:42:17.426285Z","steps":["trace[1241062520] 'process raft request' (duration: 297.610145ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:18.738851Z","caller":"traceutil/trace.go:171","msg":"trace[116445411] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"334.236654ms","start":"2026-02-08T21:42:18.404598Z","end":"2026-02-08T21:42:18.738835Z","steps":["trace[116445411] 'process raft request' (duration: 334.109501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:18.738952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:18.404581Z","time spent":"334.318976ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:19.296586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.638782ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-08T21:42:19.296644Z","caller":"traceutil/trace.go:171","msg":"trace[1025713365] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:772; }","duration":"136.361706ms","start":"2026-02-08T21:42:19.160272Z","end":"2026-02-08T21:42:19.296633Z","steps":["trace[1025713365] 'read index received' (duration: 38.001µs)","trace[1025713365] 'applied index is now lower than readState.Index' (duration: 136.322845ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:19.296687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.411297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:19.29671Z","caller":"traceutil/trace.go:171","msg":"trace[1557433244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:747; }","duration":"136.463039ms","start":"2026-02-08T21:42:19.160232Z","end":"2026-02-08T21:42:19.296695Z","steps":["trace[1557433244] 'agreement among raft nodes before linearized reading' (duration: 136.427538ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:33.754094Z","caller":"traceutil/trace.go:171","msg":"trace[1282468596] linearizableReadLoop","detail":"{readStateIndex:871; appliedIndex:870; }","duration":"110.702326ms","start":"2026-02-08T21:42:33.64337Z","end":"2026-02-08T21:42:33.754072Z","steps":["trace[1282468596] 'read index received' (duration: 110.492232ms)","trace[1282468596] 'applied index is now lower than readState.Index' (duration: 209.394µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:33.754348Z","caller":"traceutil/trace.go:171","msg":"trace[704409492] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"138.017622ms","start":"2026-02-08T21:42:33.616323Z","end":"2026-02-08T21:42:33.754341Z","steps":["trace[704409492] 'process raft request' (duration: 137.639434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:33.754505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.123895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:33.754527Z","caller":"traceutil/trace.go:171","msg":"trace[1061698631] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:841; }","duration":"111.192137ms","start":"2026-02-08T21:42:33.643329Z","end":"2026-02-08T21:42:33.754521Z","steps":["trace[1061698631] 'agreement among raft nodes before linearized reading' (duration: 111.140326ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:36.231836Z","caller":"traceutil/trace.go:171","msg":"trace[167884896] linearizableReadLoop","detail":"{readStateIndex:879; appliedIndex:878; }","duration":"259.531373ms","start":"2026-02-08T21:42:35.972289Z","end":"2026-02-08T21:42:36.231821Z","steps":["trace[167884896] 'read index received' (duration: 259.40369ms)","trace[167884896] 'applied index is now lower than readState.Index' (duration: 127.073µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:36.232075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.776579ms","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-08T21:42:36.232122Z","caller":"traceutil/trace.go:171","msg":"trace[1362582244] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:848; }","duration":"259.852981ms","start":"2026-02-08T21:42:35.972256Z","end":"2026-02-08T21:42:36.232109Z","steps":["trace[1362582244] 'agreement among raft nodes before linearized reading' (duration: 259.710128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:36.232133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.653243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-08T21:42:36.232152Z","caller":"traceutil/trace.go:171","msg":"trace[1476564723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:848; }","duration":"246.678643ms","start":"2026-02-08T21:42:35.985466Z","end":"2026-02-08T21:42:36.232144Z","steps":["trace[1476564723] 'agreement among raft nodes before linearized reading' (duration: 246.642552ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:36.232234Z","caller":"traceutil/trace.go:171","msg":"trace[580893862] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"470.911027ms","start":"2026-02-08T21:42:35.761316Z","end":"2026-02-08T21:42:36.232227Z","steps":["trace[580893862] 'process raft request' (duration: 470.418925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:36.232286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:35.761307Z","time spent":"470.942248ms","remote":"127.0.0.1:37710","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-08T21:42:36.232289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.893426ms","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-08T21:42:36.232306Z","caller":"traceutil/trace.go:171","msg":"trace[1070156664] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:848; }","duration":"250.909766ms","start":"2026-02-08T21:42:35.981392Z","end":"2026-02-08T21:42:36.232301Z","steps":["trace[1070156664] 'agreement among raft nodes before linearized reading' (duration: 250.866606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:36.232091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.868596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-08T21:42:36.232354Z","caller":"traceutil/trace.go:171","msg":"trace[1870263375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:848; }","duration":"251.168431ms","start":"2026-02-08T21:42:35.981181Z","end":"2026-02-08T21:42:36.232349Z","steps":["trace[1870263375] 'agreement among raft nodes before linearized reading' (duration: 250.830894ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:37.038987Z","caller":"traceutil/trace.go:171","msg":"trace[656139295] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"801.38907ms","start":"2026-02-08T21:42:36.237584Z","end":"2026-02-08T21:42:37.038973Z","steps":["trace[656139295] 'process raft request' (duration: 801.306918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:37.039077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:36.237569Z","time spent":"801.461792ms","remote":"127.0.0.1:37710","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-08T21:42:37.162751Z","caller":"traceutil/trace.go:171","msg":"trace[645325428] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"924.455446ms","start":"2026-02-08T21:42:36.238281Z","end":"2026-02-08T21:42:37.162736Z","steps":["trace[645325428] 'process raft request' (duration: 919.948247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:37.162846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:36.238255Z","time spent":"924.538428ms","remote":"127.0.0.1:37710","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-08T21:42:37.162837Z","caller":"traceutil/trace.go:171","msg":"trace[2061974084] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"922.824301ms","start":"2026-02-08T21:42:36.239993Z","end":"2026-02-08T21:42:37.162817Z","steps":["trace[2061974084] 'process raft request' (duration: 922.513415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:37.162935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:36.239984Z","time spent":"922.894343ms","remote":"127.0.0.1:37710","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-08T21:42:37.163046Z","caller":"traceutil/trace.go:171","msg":"trace[1282803289] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"923.067806ms","start":"2026-02-08T21:42:36.239972Z","end":"2026-02-08T21:42:37.16304Z","steps":["trace[1282803289] 'process raft request' (duration: 922.506804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:37.163072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:36.239957Z","time spent":"923.099416ms","remote":"127.0.0.1:37710","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-08T21:42:39.280066Z","caller":"traceutil/trace.go:171","msg":"trace[66183227] linearizableReadLoop","detail":"{readStateIndex:888; appliedIndex:887; }","duration":"112.072606ms","start":"2026-02-08T21:42:39.167977Z","end":"2026-02-08T21:42:39.28005Z","steps":["trace[66183227] 'read index received' (duration: 111.927073ms)","trace[66183227] 'applied index is now lower than readState.Index' (duration: 144.643µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:39.280154Z","caller":"traceutil/trace.go:171","msg":"trace[1527340782] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"112.747032ms","start":"2026-02-08T21:42:39.167401Z","end":"2026-02-08T21:42:39.280148Z","steps":["trace[1527340782] 'process raft request' (duration: 112.516767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:39.280294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.307951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-08T21:42:39.28031Z","caller":"traceutil/trace.go:171","msg":"trace[224900098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:856; }","duration":"112.343182ms","start":"2026-02-08T21:42:39.167962Z","end":"2026-02-08T21:42:39.280305Z","steps":["trace[224900098] 'agreement among raft nodes before linearized reading' (duration: 112.265601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:39.280373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.836151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-08T21:42:39.280384Z","caller":"traceutil/trace.go:171","msg":"trace[588749072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:856; }","duration":"111.871712ms","start":"2026-02-08T21:42:39.168509Z","end":"2026-02-08T21:42:39.28038Z","steps":["trace[588749072] 'agreement among raft nodes before linearized reading' (duration: 111.836671ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:40.411644Z","caller":"traceutil/trace.go:171","msg":"trace[194464043] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"163.602241ms","start":"2026-02-08T21:42:40.248026Z","end":"2026-02-08T21:42:40.411628Z","steps":["trace[194464043] 'process raft request' (duration: 163.472657ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:47.20441Z","caller":"traceutil/trace.go:171","msg":"trace[624277982] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"128.136556ms","start":"2026-02-08T21:42:47.076259Z","end":"2026-02-08T21:42:47.204396Z","steps":["trace[624277982] 'process raft request' (duration: 127.86356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:47.441848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.159842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-08T21:42:47.441949Z","caller":"traceutil/trace.go:171","msg":"trace[1055265037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:877; }","duration":"103.308414ms","start":"2026-02-08T21:42:47.33862Z","end":"2026-02-08T21:42:47.441928Z","steps":["trace[1055265037] 'range keys from in-memory index tree' (duration: 102.971207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:47.442151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.748415ms","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-08T21:42:47.442194Z","caller":"traceutil/trace.go:171","msg":"trace[1015675581] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:877; }","duration":"120.821957ms","start":"2026-02-08T21:42:47.321362Z","end":"2026-02-08T21:42:47.442184Z","steps":["trace[1015675581] 'range keys from in-memory index tree' (duration: 120.684903ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:47.69323Z","caller":"traceutil/trace.go:171","msg":"trace[2133490427] linearizableReadLoop","detail":"{readStateIndex:913; appliedIndex:911; }","duration":"103.456968ms","start":"2026-02-08T21:42:47.589759Z","end":"2026-02-08T21:42:47.693216Z","steps":["trace[2133490427] 'read index received' (duration: 68.198828ms)","trace[2133490427] 'applied index is now lower than readState.Index' (duration: 35.25746ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:47.693326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.557381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T21:42:47.693345Z","caller":"traceutil/trace.go:171","msg":"trace[778655725] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:880; }","duration":"103.614082ms","start":"2026-02-08T21:42:47.589726Z","end":"2026-02-08T21:42:47.69334Z","steps":["trace[778655725] 'agreement among raft nodes before linearized reading' (duration: 103.5402ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:47.693503Z","caller":"traceutil/trace.go:171","msg":"trace[1186693995] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"245.936728ms","start":"2026-02-08T21:42:47.44756Z","end":"2026-02-08T21:42:47.693497Z","steps":["trace[1186693995] 'process raft request' (duration: 210.442293ms)","trace[1186693995] 'compare' (duration: 34.993454ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:47.693578Z","caller":"traceutil/trace.go:171","msg":"trace[396786136] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"243.372802ms","start":"2026-02-08T21:42:47.450201Z","end":"2026-02-08T21:42:47.693574Z","steps":["trace[396786136] 'process raft request' (duration: 242.990573ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:49.416524Z","caller":"traceutil/trace.go:171","msg":"trace[837742424] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:914; }","duration":"261.059058ms","start":"2026-02-08T21:42:49.155454Z","end":"2026-02-08T21:42:49.416513Z","steps":["trace[837742424] 'read index received' (duration: 260.314412ms)","trace[837742424] 'applied index is now lower than readState.Index' (duration: 744.036µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:49.416624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.15982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:49.41664Z","caller":"traceutil/trace.go:171","msg":"trace[2110000014] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:882; }","duration":"261.21088ms","start":"2026-02-08T21:42:49.155423Z","end":"2026-02-08T21:42:49.416634Z","steps":["trace[2110000014] 'agreement among raft nodes before linearized reading' (duration: 261.159669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:49.416855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.93735ms","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-08T21:42:49.416868Z","caller":"traceutil/trace.go:171","msg":"trace[1060101866] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:882; }","duration":"208.97196ms","start":"2026-02-08T21:42:49.207892Z","end":"2026-02-08T21:42:49.416864Z","steps":["trace[1060101866] 'agreement among raft nodes before linearized reading' (duration: 208.914949ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:49.417124Z","caller":"traceutil/trace.go:171","msg":"trace[1248653054] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"190.932367ms","start":"2026-02-08T21:42:49.225565Z","end":"2026-02-08T21:42:49.416498Z","steps":["trace[1248653054] 'process raft request' (duration: 190.851906ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:51.554808Z","caller":"traceutil/trace.go:171","msg":"trace[307218139] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"129.522765ms","start":"2026-02-08T21:42:51.425273Z","end":"2026-02-08T21:42:51.554796Z","steps":["trace[307218139] 'process raft request' (duration: 129.28493ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:53.831676Z","caller":"traceutil/trace.go:171","msg":"trace[1655187953] linearizableReadLoop","detail":"{readStateIndex:937; appliedIndex:936; }","duration":"118.970293ms","start":"2026-02-08T21:42:53.712681Z","end":"2026-02-08T21:42:53.831651Z","steps":["trace[1655187953] 'read index received' (duration: 110.15175ms)","trace[1655187953] 'applied index is now lower than readState.Index' (duration: 8.817123ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:53.831877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.176928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-08T21:42:53.831909Z","caller":"traceutil/trace.go:171","msg":"trace[1319088569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:902; }","duration":"119.225888ms","start":"2026-02-08T21:42:53.712673Z","end":"2026-02-08T21:42:53.831899Z","steps":["trace[1319088569] 'agreement among raft nodes before linearized reading' (duration: 119.083235ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:53.833892Z","caller":"traceutil/trace.go:171","msg":"trace[1524584346] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"119.673429ms","start":"2026-02-08T21:42:53.714138Z","end":"2026-02-08T21:42:53.833812Z","steps":["trace[1524584346] 'process raft request' (duration: 119.128027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:08.153527Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360148801434004,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-08T21:43:08.369791Z","caller":"traceutil/trace.go:171","msg":"trace[1718394482] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"747.323788ms","start":"2026-02-08T21:43:07.622435Z","end":"2026-02-08T21:43:08.369759Z","steps":["trace[1718394482] 'process raft request' (duration: 747.219046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:08.370077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:07.622423Z","time spent":"747.590324ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":512,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:09.43211Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.0592373s","expected-duration":"1s"} {"level":"info","ts":"2026-02-08T21:43:09.589237Z","caller":"traceutil/trace.go:171","msg":"trace[278024165] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1034; }","duration":"1.936096432s","start":"2026-02-08T21:43:07.653123Z","end":"2026-02-08T21:43:09.589219Z","steps":["trace[278024165] 'read index received' (duration: 717.340408ms)","trace[278024165] 'applied index is now lower than readState.Index' (duration: 1.218755334s)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:09.589615Z","caller":"traceutil/trace.go:171","msg":"trace[572673925] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"1.689141798s","start":"2026-02-08T21:43:07.900463Z","end":"2026-02-08T21:43:09.589605Z","steps":["trace[572673925] 'process raft request' (duration: 1.688644907s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:09.589807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:07.900416Z","time spent":"1.689229759s","remote":"127.0.0.1:37630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5940,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:43:09.590181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.937052213s","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-08T21:43:09.590461Z","caller":"traceutil/trace.go:171","msg":"trace[1263156427] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:998; }","duration":"1.937090954s","start":"2026-02-08T21:43:07.653118Z","end":"2026-02-08T21:43:09.590209Z","steps":["trace[1263156427] 'agreement among raft nodes before linearized reading' (duration: 1.936979381s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:09.590583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:07.653084Z","time spent":"1.937412571s","remote":"127.0.0.1:37710","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":"info","ts":"2026-02-08T21:43:09.759556Z","caller":"traceutil/trace.go:171","msg":"trace[1853724426] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1035; }","duration":"170.262702ms","start":"2026-02-08T21:43:09.589275Z","end":"2026-02-08T21:43:09.759538Z","steps":["trace[1853724426] 'read index received' (duration: 163.641825ms)","trace[1853724426] 'applied index is now lower than readState.Index' (duration: 6.619306ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:09.759576Z","caller":"traceutil/trace.go:171","msg":"trace[1027141360] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"162.116782ms","start":"2026-02-08T21:43:09.597444Z","end":"2026-02-08T21:43:09.75956Z","steps":["trace[1027141360] 'process raft request' (duration: 161.995219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:09.759717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.925295715s","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-08T21:43:09.759741Z","caller":"traceutil/trace.go:171","msg":"trace[1782272445] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:999; }","duration":"1.925364457s","start":"2026-02-08T21:43:07.834369Z","end":"2026-02-08T21:43:09.759734Z","steps":["trace[1782272445] 'agreement among raft nodes before linearized reading' (duration: 1.925234974s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:09.759768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:07.834355Z","time spent":"1.925404147s","remote":"127.0.0.1:37710","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-08T21:43:09.759886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.597325281s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:09.759924Z","caller":"traceutil/trace.go:171","msg":"trace[871088954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:999; }","duration":"1.597384052s","start":"2026-02-08T21:43:08.162529Z","end":"2026-02-08T21:43:09.759913Z","steps":["trace[871088954] 'agreement among raft nodes before linearized reading' (duration: 1.59733064s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:09.759955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:08.162515Z","time spent":"1.597432353s","remote":"127.0.0.1:37468","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-08T21:43:09.760172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.009966447s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-08T21:43:09.760204Z","caller":"traceutil/trace.go:171","msg":"trace[1117839245] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:999; }","duration":"1.010026009s","start":"2026-02-08T21:43:08.750168Z","end":"2026-02-08T21:43:09.760194Z","steps":["trace[1117839245] 'agreement among raft nodes before linearized reading' (duration: 1.009930407s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:09.76023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:08.750155Z","time spent":"1.01006972s","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-08T21:43:09.760341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086664773s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-08T21:43:09.760365Z","caller":"traceutil/trace.go:171","msg":"trace[869173065] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:999; }","duration":"1.086710773s","start":"2026-02-08T21:43:08.673646Z","end":"2026-02-08T21:43:09.760357Z","steps":["trace[869173065] 'agreement among raft nodes before linearized reading' (duration: 1.086643242s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:09.76039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:08.67363Z","time spent":"1.086753415s","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-08T21:43:09.760488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.159224217s","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-08T21:43:09.760511Z","caller":"traceutil/trace.go:171","msg":"trace[1654186032] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:999; }","duration":"1.159246067s","start":"2026-02-08T21:43:08.601258Z","end":"2026-02-08T21:43:09.760504Z","steps":["trace[1654186032] 'agreement among raft nodes before linearized reading' (duration: 1.159181726s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:09.760535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:08.601218Z","time spent":"1.159311428s","remote":"127.0.0.1:37710","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-08T21:43:09.760684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.70520581s","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-08T21:43:09.760707Z","caller":"traceutil/trace.go:171","msg":"trace[1885992513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:999; }","duration":"1.705287083s","start":"2026-02-08T21:43:08.055413Z","end":"2026-02-08T21:43:09.7607Z","steps":["trace[1885992513] 'agreement among raft nodes before linearized reading' (duration: 1.705216401s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:09.760727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:08.055388Z","time spent":"1.705335554s","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T21:43:09.760777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.710789403s","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-08T21:43:09.760812Z","caller":"traceutil/trace.go:171","msg":"trace[1057515219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:999; }","duration":"1.710845024s","start":"2026-02-08T21:43:08.049958Z","end":"2026-02-08T21:43:09.760803Z","steps":["trace[1057515219] 'agreement among raft nodes before linearized reading' (duration: 1.710764502s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:09.760839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:08.049947Z","time spent":"1.710885215s","remote":"127.0.0.1:37710","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-08T21:43:09.760952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.768101633s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:09.760973Z","caller":"traceutil/trace.go:171","msg":"trace[1296380093] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:999; }","duration":"1.768146103s","start":"2026-02-08T21:43:07.99282Z","end":"2026-02-08T21:43:09.760966Z","steps":["trace[1296380093] 'agreement among raft nodes before linearized reading' (duration: 1.768112533s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:09.760992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:07.992799Z","time spent":"1.768186885s","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-02-08T21:43:33.625504Z","caller":"traceutil/trace.go:171","msg":"trace[2000233482] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"212.639301ms","start":"2026-02-08T21:43:33.412639Z","end":"2026-02-08T21:43:33.625278Z","steps":["trace[2000233482] 'process raft request' (duration: 212.535299ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:46:15.082243Z","caller":"traceutil/trace.go:171","msg":"trace[8940541] linearizableReadLoop","detail":"{readStateIndex:1856; appliedIndex:1855; }","duration":"183.393472ms","start":"2026-02-08T21:46:14.898831Z","end":"2026-02-08T21:46:15.082225Z","steps":["trace[8940541] 'read index received' (duration: 183.234008ms)","trace[8940541] 'applied index is now lower than readState.Index' (duration: 158.564µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:46:15.082506Z","caller":"traceutil/trace.go:171","msg":"trace[1133180049] transaction","detail":"{read_only:false; response_revision:1780; number_of_response:1; }","duration":"199.573558ms","start":"2026-02-08T21:46:14.88292Z","end":"2026-02-08T21:46:15.082494Z","steps":["trace[1133180049] 'process raft request' (duration: 199.197899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:46:15.082742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.904172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-08T21:46:15.082773Z","caller":"traceutil/trace.go:171","msg":"trace[78938164] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1780; }","duration":"183.983104ms","start":"2026-02-08T21:46:14.898781Z","end":"2026-02-08T21:46:15.082765Z","steps":["trace[78938164] 'agreement among raft nodes before linearized reading' (duration: 183.871962ms)"],"step_count":1}