{"level":"warn","ts":"2026-02-13T00:43:13.145074Z","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-13T00:43:13.145162Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.200: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.200:2380","--initial-cluster=instance=https://162.253.55.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.200: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-13T00:43:13.145875Z","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-13T00:43:13.14589Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-02-13T00:43:13.146014Z","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-13T00:43:13.147258Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"]} {"level":"info","ts":"2026-02-13T00:43:13.147696Z","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.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.200: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-13T00:43:13.157154Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.001222ms"} {"level":"info","ts":"2026-02-13T00:43:13.170611Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e10bb51ed7578852","cluster-id":"aa14459abd2161c3"} {"level":"info","ts":"2026-02-13T00:43:13.170741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=()"} {"level":"info","ts":"2026-02-13T00:43:13.170781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 0"} {"level":"info","ts":"2026-02-13T00:43:13.17086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e10bb51ed7578852 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-13T00:43:13.170904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 1"} {"level":"info","ts":"2026-02-13T00:43:13.171029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"warn","ts":"2026-02-13T00:43:13.179625Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-13T00:43:13.184497Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-13T00:43:13.190551Z","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-13T00:43:13.197565Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e10bb51ed7578852","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-13T00:43:13.197884Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e10bb51ed7578852","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-13T00:43:13.198063Z","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-13T00:43:13.198087Z","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-13T00:43:13.198096Z","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-13T00:43:13.198664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"info","ts":"2026-02-13T00:43:13.198797Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","added-peer-id":"e10bb51ed7578852","added-peer-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-02-13T00:43:13.210008Z","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-13T00:43:13.21016Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e10bb51ed7578852","initial-advertise-peer-urls":["https://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-13T00:43:13.210184Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-13T00:43:13.210235Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-02-13T00:43:13.210244Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-02-13T00:43:13.972045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 is starting a new election at term 1"} {"level":"info","ts":"2026-02-13T00:43:13.972102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-13T00:43:13.972126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgPreVoteResp from e10bb51ed7578852 at term 1"} {"level":"info","ts":"2026-02-13T00:43:13.972146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became candidate at term 2"} {"level":"info","ts":"2026-02-13T00:43:13.972155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgVoteResp from e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-02-13T00:43:13.972164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became leader at term 2"} {"level":"info","ts":"2026-02-13T00:43:13.972174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e10bb51ed7578852 elected leader e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-02-13T00:43:13.97871Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e10bb51ed7578852","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.200:2379]}","request-path":"/0/members/e10bb51ed7578852/attributes","cluster-id":"aa14459abd2161c3","publish-timeout":"7s"} {"level":"info","ts":"2026-02-13T00:43:13.978834Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T00:43:13.979036Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-13T00:43:13.979343Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-13T00:43:13.980067Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-13T00:43:13.980085Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-13T00:43:13.980128Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T00:43:13.980167Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T00:43:13.980183Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T00:43:13.981557Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.200:2379"} {"level":"info","ts":"2026-02-13T00:43:14.003727Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-13T00:43:27.236402Z","caller":"traceutil/trace.go:171","msg":"trace[848055232] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"127.174138ms","start":"2026-02-13T00:43:27.109207Z","end":"2026-02-13T00:43:27.236381Z","steps":["trace[848055232] 'process raft request' (duration: 124.568557ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:43:33.362715Z","caller":"traceutil/trace.go:171","msg":"trace[1502452547] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"267.380361ms","start":"2026-02-13T00:43:33.095318Z","end":"2026-02-13T00:43:33.362699Z","steps":["trace[1502452547] 'process raft request' (duration: 267.292429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:43:43.886264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.658353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T00:43:43.886346Z","caller":"traceutil/trace.go:171","msg":"trace[580757937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:470; }","duration":"134.797547ms","start":"2026-02-13T00:43:43.751535Z","end":"2026-02-13T00:43:43.886332Z","steps":["trace[580757937] 'range keys from in-memory index tree' (duration: 134.602982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:43:43.886635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.402816ms","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-13T00:43:43.886657Z","caller":"traceutil/trace.go:171","msg":"trace[2040351594] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:470; }","duration":"189.447276ms","start":"2026-02-13T00:43:43.697202Z","end":"2026-02-13T00:43:43.886649Z","steps":["trace[2040351594] 'range keys from in-memory index tree' (duration: 189.331494ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:43:44.064219Z","caller":"traceutil/trace.go:171","msg":"trace[598413385] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"171.420214ms","start":"2026-02-13T00:43:43.892778Z","end":"2026-02-13T00:43:44.064198Z","steps":["trace[598413385] 'process raft request' (duration: 171.318382ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:43:44.069381Z","caller":"traceutil/trace.go:171","msg":"trace[82284207] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"176.38688ms","start":"2026-02-13T00:43:43.892978Z","end":"2026-02-13T00:43:44.069365Z","steps":["trace[82284207] 'process raft request' (duration: 176.34793ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:43:44.069531Z","caller":"traceutil/trace.go:171","msg":"trace[304529113] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"176.569885ms","start":"2026-02-13T00:43:43.892949Z","end":"2026-02-13T00:43:44.069519Z","steps":["trace[304529113] 'process raft request' (duration: 176.286158ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:43:46.202968Z","caller":"traceutil/trace.go:171","msg":"trace[256070502] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:489; }","duration":"131.412917ms","start":"2026-02-13T00:43:46.07154Z","end":"2026-02-13T00:43:46.202953Z","steps":["trace[256070502] 'read index received' (duration: 131.287954ms)","trace[256070502] 'applied index is now lower than readState.Index' (duration: 124.473µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T00:43:46.203045Z","caller":"traceutil/trace.go:171","msg":"trace[1012116910] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"133.145658ms","start":"2026-02-13T00:43:46.069886Z","end":"2026-02-13T00:43:46.203032Z","steps":["trace[1012116910] 'process raft request' (duration: 132.977414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:43:46.20317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.615772ms","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-13T00:43:46.203189Z","caller":"traceutil/trace.go:171","msg":"trace[828698354] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:475; }","duration":"131.653383ms","start":"2026-02-13T00:43:46.071528Z","end":"2026-02-13T00:43:46.203181Z","steps":["trace[828698354] 'agreement among raft nodes before linearized reading' (duration: 131.610992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:43:46.203247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.902965ms","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-13T00:43:46.20326Z","caller":"traceutil/trace.go:171","msg":"trace[2068148264] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:475; }","duration":"130.923246ms","start":"2026-02-13T00:43:46.072331Z","end":"2026-02-13T00:43:46.203254Z","steps":["trace[2068148264] 'agreement among raft nodes before linearized reading' (duration: 130.903266ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:43:46.327889Z","caller":"traceutil/trace.go:171","msg":"trace[695271503] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"120.820909ms","start":"2026-02-13T00:43:46.207052Z","end":"2026-02-13T00:43:46.327873Z","steps":["trace[695271503] 'process raft request' (duration: 96.729165ms)","trace[695271503] 'compare' (duration: 23.940331ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T00:43:47.82281Z","caller":"traceutil/trace.go:171","msg":"trace[92466031] linearizableReadLoop","detail":"{readStateIndex:495; appliedIndex:494; }","duration":"348.085281ms","start":"2026-02-13T00:43:47.474703Z","end":"2026-02-13T00:43:47.822788Z","steps":["trace[92466031] 'read index received' (duration: 347.868246ms)","trace[92466031] 'applied index is now lower than readState.Index' (duration: 215.965µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T00:43:47.822926Z","caller":"traceutil/trace.go:171","msg":"trace[626741446] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"673.072521ms","start":"2026-02-13T00:43:47.149845Z","end":"2026-02-13T00:43:47.822918Z","steps":["trace[626741446] 'process raft request' (duration: 672.742443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:43:47.823462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:43:47.149823Z","time spent":"673.128272ms","remote":"127.0.0.1:55538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T00:43:47.823619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.907381ms","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-13T00:43:47.824238Z","caller":"traceutil/trace.go:171","msg":"trace[1210614118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:479; }","duration":"349.578127ms","start":"2026-02-13T00:43:47.474645Z","end":"2026-02-13T00:43:47.824223Z","steps":["trace[1210614118] 'agreement among raft nodes before linearized reading' (duration: 348.820519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:43:47.824264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:43:47.474503Z","time spent":"349.75443ms","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-13T00:43:48.388093Z","caller":"traceutil/trace.go:171","msg":"trace[1831090389] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"114.161073ms","start":"2026-02-13T00:43:48.273898Z","end":"2026-02-13T00:43:48.388059Z","steps":["trace[1831090389] 'process raft request' (duration: 80.670769ms)","trace[1831090389] 'compare' (duration: 32.941942ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T00:43:52.781259Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085623780415304,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T00:43:52.79981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.856459ms","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-13T00:43:52.799876Z","caller":"traceutil/trace.go:171","msg":"trace[1101461981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:491; }","duration":"916.959772ms","start":"2026-02-13T00:43:51.882898Z","end":"2026-02-13T00:43:52.799858Z","steps":["trace[1101461981] 'range keys from in-memory index tree' (duration: 916.700935ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:43:52.799919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:43:51.88288Z","time spent":"917.030593ms","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-13T00:43:53.670867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.356618ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-13T00:43:53.671047Z","caller":"traceutil/trace.go:171","msg":"trace[1234220272] linearizableReadLoop","detail":"{readStateIndex:508; appliedIndex:507; }","duration":"1.390209364s","start":"2026-02-13T00:43:52.280821Z","end":"2026-02-13T00:43:53.671031Z","steps":["trace[1234220272] 'read index received' (duration: 534.509347ms)","trace[1234220272] 'applied index is now lower than readState.Index' (duration: 855.699167ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T00:43:53.671236Z","caller":"traceutil/trace.go:171","msg":"trace[2104147943] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"853.782551ms","start":"2026-02-13T00:43:52.817443Z","end":"2026-02-13T00:43:53.671226Z","steps":["trace[2104147943] 'process raft request' (duration: 853.508925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:43:53.671919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:43:52.81743Z","time spent":"853.834433ms","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T00:43:53.674684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.195703849s","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-13T00:43:53.674739Z","caller":"traceutil/trace.go:171","msg":"trace[2050863015] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:492; }","duration":"1.195798691s","start":"2026-02-13T00:43:52.478927Z","end":"2026-02-13T00:43:53.674726Z","steps":["trace[2050863015] 'agreement among raft nodes before linearized reading' (duration: 1.19324054s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:43:53.674777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:43:52.478911Z","time spent":"1.195860653s","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-13T00:43:53.674877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.418823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T00:43:53.674893Z","caller":"traceutil/trace.go:171","msg":"trace[496988577] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:492; }","duration":"923.455713ms","start":"2026-02-13T00:43:52.751432Z","end":"2026-02-13T00:43:53.674887Z","steps":["trace[496988577] 'agreement among raft nodes before linearized reading' (duration: 920.700679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:43:53.674908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:43:52.751419Z","time spent":"923.485635ms","remote":"127.0.0.1:55506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T00:43:53.674946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.276979222s","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-13T00:43:53.674988Z","caller":"traceutil/trace.go:171","msg":"trace[559647162] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:492; }","duration":"1.277045173s","start":"2026-02-13T00:43:52.397931Z","end":"2026-02-13T00:43:53.674976Z","steps":["trace[559647162] 'agreement among raft nodes before linearized reading' (duration: 1.273271645s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:43:53.67502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:43:52.397918Z","time spent":"1.277095174s","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-13T00:43:53.675027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.7093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gwlqg\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-13T00:43:53.675048Z","caller":"traceutil/trace.go:171","msg":"trace[1692075550] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gwlqg; range_end:; response_count:1; response_revision:492; }","duration":"938.756712ms","start":"2026-02-13T00:43:52.736285Z","end":"2026-02-13T00:43:53.675042Z","steps":["trace[1692075550] 'agreement among raft nodes before linearized reading' (duration: 935.860414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:43:53.675065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:43:52.73627Z","time spent":"938.790042ms","remote":"127.0.0.1:55688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16680,"request content":"key:\"/registry/pods/kube-system/cilium-gwlqg\" "} {"level":"warn","ts":"2026-02-13T00:43:53.675302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.394479094s","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-13T00:43:53.675328Z","caller":"traceutil/trace.go:171","msg":"trace[1391565604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:492; }","duration":"1.394503824s","start":"2026-02-13T00:43:52.280816Z","end":"2026-02-13T00:43:53.67532Z","steps":["trace[1391565604] 'agreement among raft nodes before linearized reading' (duration: 1.390330596s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:43:53.675353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:43:52.280763Z","time spent":"1.394582286s","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-13T00:44:03.91556Z","caller":"traceutil/trace.go:171","msg":"trace[966188501] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"368.57001ms","start":"2026-02-13T00:44:03.546976Z","end":"2026-02-13T00:44:03.915546Z","steps":["trace[966188501] 'process raft request' (duration: 368.432627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:03.915659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:03.546959Z","time spent":"368.655673ms","remote":"127.0.0.1:55688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18235,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T00:44:03.916057Z","caller":"traceutil/trace.go:171","msg":"trace[1072313960] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:627; }","duration":"153.164216ms","start":"2026-02-13T00:44:03.762887Z","end":"2026-02-13T00:44:03.916051Z","steps":["trace[1072313960] 'read index received' (duration: 153.162056ms)","trace[1072313960] 'applied index is now lower than readState.Index' (duration: 1.79µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T00:44:03.916098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.209327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T00:44:03.916123Z","caller":"traceutil/trace.go:171","msg":"trace[1656814751] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"153.259719ms","start":"2026-02-13T00:44:03.762859Z","end":"2026-02-13T00:44:03.916119Z","steps":["trace[1656814751] 'agreement among raft nodes before linearized reading' (duration: 153.220948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:04.391171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.46496ms","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-13T00:44:04.391263Z","caller":"traceutil/trace.go:171","msg":"trace[363021561] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:607; }","duration":"316.566713ms","start":"2026-02-13T00:44:04.074686Z","end":"2026-02-13T00:44:04.391253Z","steps":["trace[363021561] 'range keys from in-memory index tree' (duration: 316.45405ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:44:04.391415Z","caller":"traceutil/trace.go:171","msg":"trace[157612689] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"475.33998ms","start":"2026-02-13T00:44:03.91607Z","end":"2026-02-13T00:44:04.39141Z","steps":["trace[157612689] 'read index received' (duration: 428.838591ms)","trace[157612689] 'applied index is now lower than readState.Index' (duration: 46.501129ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T00:44:04.391817Z","caller":"traceutil/trace.go:171","msg":"trace[1402160910] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"567.509879ms","start":"2026-02-13T00:44:03.824296Z","end":"2026-02-13T00:44:04.391806Z","steps":["trace[1402160910] 'process raft request' (duration: 520.669962ms)","trace[1402160910] 'compare' (duration: 45.955876ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T00:44:04.392208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:03.824279Z","time spent":"567.901828ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1238,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-13T00:44:04.392305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.940254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T00:44:04.39232Z","caller":"traceutil/trace.go:171","msg":"trace[1828859020] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"469.975846ms","start":"2026-02-13T00:44:03.922339Z","end":"2026-02-13T00:44:04.392314Z","steps":["trace[1828859020] 'agreement among raft nodes before linearized reading' (duration: 469.953525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:04.392334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:03.922328Z","time spent":"470.003516ms","remote":"127.0.0.1:55508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T00:44:04.392474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.669057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gwlqg\" ","response":"range_response_count:1 size:18250"} {"level":"info","ts":"2026-02-13T00:44:04.392487Z","caller":"traceutil/trace.go:171","msg":"trace[812490711] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gwlqg; range_end:; response_count:1; response_revision:608; }","duration":"463.698668ms","start":"2026-02-13T00:44:03.928785Z","end":"2026-02-13T00:44:04.392483Z","steps":["trace[812490711] 'agreement among raft nodes before linearized reading' (duration: 463.653357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:04.392499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:03.928775Z","time spent":"463.721939ms","remote":"127.0.0.1:55688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18274,"request content":"key:\"/registry/pods/kube-system/cilium-gwlqg\" "} {"level":"warn","ts":"2026-02-13T00:44:04.392018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.082327ms","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-13T00:44:04.3989Z","caller":"traceutil/trace.go:171","msg":"trace[1488656148] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:608; }","duration":"549.975498ms","start":"2026-02-13T00:44:03.848908Z","end":"2026-02-13T00:44:04.398884Z","steps":["trace[1488656148] 'agreement among raft nodes before linearized reading' (duration: 543.011065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:04.398997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:03.848895Z","time spent":"550.07745ms","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-13T00:44:04.392054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.142039ms","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-13T00:44:04.399179Z","caller":"traceutil/trace.go:171","msg":"trace[1749589330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:608; }","duration":"527.279797ms","start":"2026-02-13T00:44:03.871892Z","end":"2026-02-13T00:44:04.399172Z","steps":["trace[1749589330] 'agreement among raft nodes before linearized reading' (duration: 520.1358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:04.399256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:03.87188Z","time spent":"527.368159ms","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-13T00:44:04.392088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.365184ms","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-13T00:44:04.402125Z","caller":"traceutil/trace.go:171","msg":"trace[373548126] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:608; }","duration":"568.41321ms","start":"2026-02-13T00:44:03.833704Z","end":"2026-02-13T00:44:04.402117Z","steps":["trace[373548126] 'agreement among raft nodes before linearized reading' (duration: 558.361775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:04.402184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:03.833692Z","time spent":"568.483281ms","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-13T00:44:04.39212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.508129ms","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-13T00:44:04.402361Z","caller":"traceutil/trace.go:171","msg":"trace[1884280340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:608; }","duration":"615.762458ms","start":"2026-02-13T00:44:03.786593Z","end":"2026-02-13T00:44:04.402356Z","steps":["trace[1884280340] 'agreement among raft nodes before linearized reading' (duration: 605.506679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:04.402395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:03.786579Z","time spent":"615.80979ms","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-13T00:44:05.067619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.210629ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T00:44:05.0677Z","caller":"traceutil/trace.go:171","msg":"trace[814448998] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"660.197529ms","start":"2026-02-13T00:44:04.407492Z","end":"2026-02-13T00:44:05.06769Z","steps":["trace[814448998] 'process raft request' (duration: 380.866358ms)","trace[814448998] 'compare' (duration: 279.126937ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T00:44:05.06778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:04.40743Z","time spent":"660.334072ms","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T00:44:05.640753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.151556ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T00:44:05.641807Z","caller":"traceutil/trace.go:171","msg":"trace[1422230910] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"1.232758787s","start":"2026-02-13T00:44:04.409036Z","end":"2026-02-13T00:44:05.641795Z","steps":["trace[1422230910] 'process raft request' (duration: 964.516546ms)","trace[1422230910] 'compare' (duration: 267.056103ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T00:44:05.641973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:04.40903Z","time spent":"1.23292165s","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T00:44:05.642288Z","caller":"traceutil/trace.go:171","msg":"trace[1649983379] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"1.233196587s","start":"2026-02-13T00:44:04.409086Z","end":"2026-02-13T00:44:05.642282Z","steps":["trace[1649983379] 'process raft request' (duration: 1.232672505s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:05.642378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:04.409082Z","time spent":"1.233276619s","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T00:44:05.645043Z","caller":"traceutil/trace.go:171","msg":"trace[1289998355] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"1.234260252s","start":"2026-02-13T00:44:04.410774Z","end":"2026-02-13T00:44:05.645035Z","steps":["trace[1289998355] 'process raft request' (duration: 1.231451076s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:05.645143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:04.410755Z","time spent":"1.234362915s","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T00:44:05.645441Z","caller":"traceutil/trace.go:171","msg":"trace[6093431] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"1.226793177s","start":"2026-02-13T00:44:04.418642Z","end":"2026-02-13T00:44:05.645436Z","steps":["trace[6093431] 'process raft request' (duration: 1.226696635s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:05.645658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:04.418639Z","time spent":"1.226960331s","remote":"127.0.0.1:41474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2262,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-13T00:44:05.646386Z","caller":"traceutil/trace.go:171","msg":"trace[1008993228] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:628; }","duration":"1.235545862s","start":"2026-02-13T00:44:04.410834Z","end":"2026-02-13T00:44:05.64638Z","steps":["trace[1008993228] 'read index received' (duration: 377.49087ms)","trace[1008993228] 'applied index is now lower than readState.Index' (duration: 858.054202ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T00:44:05.646581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.235739897s","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-13T00:44:05.646626Z","caller":"traceutil/trace.go:171","msg":"trace[342546871] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:616; }","duration":"1.235802348s","start":"2026-02-13T00:44:04.410818Z","end":"2026-02-13T00:44:05.64662Z","steps":["trace[342546871] 'agreement among raft nodes before linearized reading' (duration: 1.235645164s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:05.64666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:04.410813Z","time spent":"1.235842169s","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-13T00:44:05.647091Z","caller":"traceutil/trace.go:171","msg":"trace[845129121] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"1.228418395s","start":"2026-02-13T00:44:04.418666Z","end":"2026-02-13T00:44:05.647084Z","steps":["trace[845129121] 'process raft request' (duration: 1.226758556s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:05.647225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:04.418663Z","time spent":"1.228546239s","remote":"127.0.0.1:41474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2262,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-13T00:44:05.647496Z","caller":"traceutil/trace.go:171","msg":"trace[768171577] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"1.22864482s","start":"2026-02-13T00:44:04.418844Z","end":"2026-02-13T00:44:05.647489Z","steps":["trace[768171577] 'process raft request' (duration: 1.227490613s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:05.647557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:04.418839Z","time spent":"1.228699541s","remote":"127.0.0.1:55688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18047,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T00:44:05.651595Z","caller":"traceutil/trace.go:171","msg":"trace[920372278] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"1.232978052s","start":"2026-02-13T00:44:04.418587Z","end":"2026-02-13T00:44:05.651565Z","steps":["trace[920372278] 'process raft request' (duration: 1.223661023s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:05.651695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:04.41858Z","time spent":"1.233095784s","remote":"127.0.0.1:41474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2262,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-13T00:44:05.869503Z","caller":"traceutil/trace.go:171","msg":"trace[1850616461] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"206.67027ms","start":"2026-02-13T00:44:05.662817Z","end":"2026-02-13T00:44:05.869487Z","steps":["trace[1850616461] 'process raft request' (duration: 206.562897ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:44:05.877629Z","caller":"traceutil/trace.go:171","msg":"trace[809691022] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:637; }","duration":"124.399863ms","start":"2026-02-13T00:44:05.753216Z","end":"2026-02-13T00:44:05.877616Z","steps":["trace[809691022] 'read index received' (duration: 116.654271ms)","trace[809691022] 'applied index is now lower than readState.Index' (duration: 7.744822ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T00:44:05.877714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.483405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T00:44:05.877735Z","caller":"traceutil/trace.go:171","msg":"trace[1129074998] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"124.548417ms","start":"2026-02-13T00:44:05.753181Z","end":"2026-02-13T00:44:05.877729Z","steps":["trace[1129074998] 'agreement among raft nodes before linearized reading' (duration: 124.480715ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:44:05.878151Z","caller":"traceutil/trace.go:171","msg":"trace[323987882] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"214.041212ms","start":"2026-02-13T00:44:05.664101Z","end":"2026-02-13T00:44:05.878142Z","steps":["trace[323987882] 'process raft request' (duration: 213.428398ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:44:05.878726Z","caller":"traceutil/trace.go:171","msg":"trace[1505555964] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"214.583785ms","start":"2026-02-13T00:44:05.664131Z","end":"2026-02-13T00:44:05.878715Z","steps":["trace[1505555964] 'process raft request' (duration: 213.456498ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:44:07.781513Z","caller":"traceutil/trace.go:171","msg":"trace[767091443] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"131.292454ms","start":"2026-02-13T00:44:07.650207Z","end":"2026-02-13T00:44:07.781499Z","steps":["trace[767091443] 'read index received' (duration: 131.084909ms)","trace[767091443] 'applied index is now lower than readState.Index' (duration: 206.875µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T00:44:07.781726Z","caller":"traceutil/trace.go:171","msg":"trace[1186388876] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"132.702468ms","start":"2026-02-13T00:44:07.649001Z","end":"2026-02-13T00:44:07.781703Z","steps":["trace[1186388876] 'process raft request' (duration: 132.34119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:07.781921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.713185ms","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-13T00:44:07.781959Z","caller":"traceutil/trace.go:171","msg":"trace[1997450620] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:660; }","duration":"131.743975ms","start":"2026-02-13T00:44:07.650193Z","end":"2026-02-13T00:44:07.781937Z","steps":["trace[1997450620] 'agreement among raft nodes before linearized reading' (duration: 131.649733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:07.782093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.134381ms","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-13T00:44:07.782129Z","caller":"traceutil/trace.go:171","msg":"trace[276799760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:660; }","duration":"128.165611ms","start":"2026-02-13T00:44:07.65394Z","end":"2026-02-13T00:44:07.782106Z","steps":["trace[276799760] 'agreement among raft nodes before linearized reading' (duration: 128.11309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:08.137669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.163739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T00:44:08.137987Z","caller":"traceutil/trace.go:171","msg":"trace[2131990688] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"349.639837ms","start":"2026-02-13T00:44:07.788327Z","end":"2026-02-13T00:44:08.137966Z","steps":["trace[2131990688] 'process raft request' (duration: 84.008527ms)","trace[2131990688] 'compare' (duration: 264.991336ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T00:44:08.138053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:07.788295Z","time spent":"349.71994ms","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T00:44:08.13794Z","caller":"traceutil/trace.go:171","msg":"trace[1805449762] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:682; }","duration":"253.846215ms","start":"2026-02-13T00:44:07.884083Z","end":"2026-02-13T00:44:08.137929Z","steps":["trace[1805449762] 'read index received' (duration: 22.711µs)","trace[1805449762] 'applied index is now lower than readState.Index' (duration: 253.822694ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T00:44:08.139442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.35382ms","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-13T00:44:08.139467Z","caller":"traceutil/trace.go:171","msg":"trace[1021243429] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:662; }","duration":"255.423071ms","start":"2026-02-13T00:44:07.884035Z","end":"2026-02-13T00:44:08.139459Z","steps":["trace[1021243429] 'agreement among raft nodes before linearized reading' (duration: 254.900929ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:44:14.378503Z","caller":"traceutil/trace.go:171","msg":"trace[589739603] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"132.113674ms","start":"2026-02-13T00:44:14.246368Z","end":"2026-02-13T00:44:14.378482Z","steps":["trace[589739603] 'process raft request' (duration: 122.796126ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:44:15.559668Z","caller":"traceutil/trace.go:171","msg":"trace[1330379627] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"441.524888ms","start":"2026-02-13T00:44:15.118125Z","end":"2026-02-13T00:44:15.55965Z","steps":["trace[1330379627] 'process raft request' (duration: 441.444747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:15.559767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:15.1181Z","time spent":"441.611851ms","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T00:44:15.944665Z","caller":"traceutil/trace.go:171","msg":"trace[89024834] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"112.32538ms","start":"2026-02-13T00:44:15.832325Z","end":"2026-02-13T00:44:15.94465Z","steps":["trace[89024834] 'process raft request' (duration: 71.847413ms)","trace[89024834] 'compare' (duration: 40.15045ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T00:44:31.927879Z","caller":"traceutil/trace.go:171","msg":"trace[641233434] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:758; }","duration":"176.673231ms","start":"2026-02-13T00:44:31.751184Z","end":"2026-02-13T00:44:31.927857Z","steps":["trace[641233434] 'read index received' (duration: 176.321743ms)","trace[641233434] 'applied index is now lower than readState.Index' (duration: 350.778µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T00:44:31.928849Z","caller":"traceutil/trace.go:171","msg":"trace[239472536] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"321.602536ms","start":"2026-02-13T00:44:31.607229Z","end":"2026-02-13T00:44:31.928832Z","steps":["trace[239472536] 'process raft request' (duration: 320.386218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:31.929145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:31.607214Z","time spent":"321.839473ms","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T00:44:31.92993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.73866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T00:44:31.930137Z","caller":"traceutil/trace.go:171","msg":"trace[1560295207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:734; }","duration":"178.948805ms","start":"2026-02-13T00:44:31.751145Z","end":"2026-02-13T00:44:31.930094Z","steps":["trace[1560295207] 'agreement among raft nodes before linearized reading' (duration: 178.75037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:32.82517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.382572ms","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-13T00:44:32.825256Z","caller":"traceutil/trace.go:171","msg":"trace[1815351596] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:737; }","duration":"281.537245ms","start":"2026-02-13T00:44:32.543702Z","end":"2026-02-13T00:44:32.825239Z","steps":["trace[1815351596] 'range keys from in-memory index tree' (duration: 281.195857ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:44:33.47469Z","caller":"traceutil/trace.go:171","msg":"trace[1492863122] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"644.74654ms","start":"2026-02-13T00:44:32.829917Z","end":"2026-02-13T00:44:33.474664Z","steps":["trace[1492863122] 'process raft request' (duration: 644.572646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:33.474846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:32.829897Z","time spent":"644.871353ms","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T00:44:34.46797Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085623780415833,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-13T00:44:34.482039Z","caller":"traceutil/trace.go:171","msg":"trace[1574428224] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:764; }","duration":"514.897599ms","start":"2026-02-13T00:44:33.967123Z","end":"2026-02-13T00:44:34.48202Z","steps":["trace[1574428224] 'read index received' (duration: 514.769806ms)","trace[1574428224] 'applied index is now lower than readState.Index' (duration: 127.143µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T00:44:34.482185Z","caller":"traceutil/trace.go:171","msg":"trace[1159556343] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"541.965674ms","start":"2026-02-13T00:44:33.940208Z","end":"2026-02-13T00:44:34.482174Z","steps":["trace[1159556343] 'process raft request' (duration: 541.725259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:34.482274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:33.940197Z","time spent":"542.015005ms","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T00:44:34.482604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.477162ms","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-13T00:44:34.482651Z","caller":"traceutil/trace.go:171","msg":"trace[1766819396] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:739; }","duration":"515.523983ms","start":"2026-02-13T00:44:33.967116Z","end":"2026-02-13T00:44:34.48264Z","steps":["trace[1766819396] 'agreement among raft nodes before linearized reading' (duration: 515.37188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:34.482688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:33.967069Z","time spent":"515.612965ms","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-13T00:44:34.483287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.431028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-13T00:44:34.483354Z","caller":"traceutil/trace.go:171","msg":"trace[372056460] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:739; }","duration":"414.535531ms","start":"2026-02-13T00:44:34.068801Z","end":"2026-02-13T00:44:34.483336Z","steps":["trace[372056460] 'agreement among raft nodes before linearized reading' (duration: 414.285375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:34.483398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:34.068784Z","time spent":"414.605672ms","remote":"127.0.0.1:55780","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-13T00:44:34.483717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.023632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T00:44:34.48375Z","caller":"traceutil/trace.go:171","msg":"trace[1422593111] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:739; }","duration":"152.132335ms","start":"2026-02-13T00:44:34.331607Z","end":"2026-02-13T00:44:34.48374Z","steps":["trace[1422593111] 'agreement among raft nodes before linearized reading' (duration: 151.993412ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:44:34.693538Z","caller":"traceutil/trace.go:171","msg":"trace[456254176] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"203.654035ms","start":"2026-02-13T00:44:34.489857Z","end":"2026-02-13T00:44:34.693511Z","steps":["trace[456254176] 'process raft request' (duration: 168.780756ms)","trace[456254176] 'compare' (duration: 34.786557ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T00:44:34.730059Z","caller":"traceutil/trace.go:171","msg":"trace[1860006453] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"238.599506ms","start":"2026-02-13T00:44:34.491442Z","end":"2026-02-13T00:44:34.730041Z","steps":["trace[1860006453] 'process raft request' (duration: 238.545355ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:44:34.730382Z","caller":"traceutil/trace.go:171","msg":"trace[802592831] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"240.01726ms","start":"2026-02-13T00:44:34.490338Z","end":"2026-02-13T00:44:34.730356Z","steps":["trace[802592831] 'process raft request' (duration: 239.490187ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:44:47.450919Z","caller":"traceutil/trace.go:171","msg":"trace[438761804] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"255.824471ms","start":"2026-02-13T00:44:47.195068Z","end":"2026-02-13T00:44:47.450892Z","steps":["trace[438761804] 'process raft request' (duration: 207.521546ms)","trace[438761804] 'compare' (duration: 48.062049ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T00:44:48.341507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.340694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T00:44:48.341655Z","caller":"traceutil/trace.go:171","msg":"trace[1645348962] linearizableReadLoop","detail":"{readStateIndex:861; appliedIndex:859; }","duration":"592.731878ms","start":"2026-02-13T00:44:47.74891Z","end":"2026-02-13T00:44:48.341642Z","steps":["trace[1645348962] 'read index received' (duration: 109.813701ms)","trace[1645348962] 'applied index is now lower than readState.Index' (duration: 482.917487ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T00:44:48.341714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.80187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T00:44:48.34173Z","caller":"traceutil/trace.go:171","msg":"trace[618651449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:830; }","duration":"592.842ms","start":"2026-02-13T00:44:47.748882Z","end":"2026-02-13T00:44:48.341724Z","steps":["trace[618651449] 'agreement among raft nodes before linearized reading' (duration: 592.792799ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:48.341751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:47.748869Z","time spent":"592.877401ms","remote":"127.0.0.1:55506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-13T00:44:48.341879Z","caller":"traceutil/trace.go:171","msg":"trace[622260771] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"808.647811ms","start":"2026-02-13T00:44:47.533224Z","end":"2026-02-13T00:44:48.341872Z","steps":["trace[622260771] 'process raft request' (duration: 325.489158ms)","trace[622260771] 'compare' (duration: 482.18585ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T00:44:48.341915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:47.533215Z","time spent":"808.679602ms","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T00:44:48.342072Z","caller":"traceutil/trace.go:171","msg":"trace[787727508] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"716.950386ms","start":"2026-02-13T00:44:47.625115Z","end":"2026-02-13T00:44:48.342065Z","steps":["trace[787727508] 'process raft request' (duration: 716.463505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:48.342147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:47.625098Z","time spent":"716.989388ms","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T00:44:53.463994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.620054ms","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-13T00:44:53.464053Z","caller":"traceutil/trace.go:171","msg":"trace[511611863] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:842; }","duration":"612.704736ms","start":"2026-02-13T00:44:52.851333Z","end":"2026-02-13T00:44:53.464038Z","steps":["trace[511611863] 'range keys from in-memory index tree' (duration: 612.497311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:53.464092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:52.85132Z","time spent":"612.766087ms","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-13T00:44:53.464313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.123822ms","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-13T00:44:53.464337Z","caller":"traceutil/trace.go:171","msg":"trace[639886328] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:842; }","duration":"512.154953ms","start":"2026-02-13T00:44:52.952176Z","end":"2026-02-13T00:44:53.464331Z","steps":["trace[639886328] 'range keys from in-memory index tree' (duration: 512.074171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:53.464358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:52.952173Z","time spent":"512.180203ms","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-13T00:44:53.464484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.350337ms","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-13T00:44:53.464506Z","caller":"traceutil/trace.go:171","msg":"trace[1400034241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:842; }","duration":"512.393879ms","start":"2026-02-13T00:44:52.952107Z","end":"2026-02-13T00:44:53.464501Z","steps":["trace[1400034241] 'range keys from in-memory index tree' (duration: 512.230625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:53.464527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:52.952094Z","time spent":"512.429309ms","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-13T00:44:53.640927Z","caller":"traceutil/trace.go:171","msg":"trace[1020491001] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"171.188522ms","start":"2026-02-13T00:44:53.469721Z","end":"2026-02-13T00:44:53.64091Z","steps":["trace[1020491001] 'process raft request' (duration: 171.08399ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:44:53.779607Z","caller":"traceutil/trace.go:171","msg":"trace[1768473488] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"309.092222ms","start":"2026-02-13T00:44:53.470496Z","end":"2026-02-13T00:44:53.779588Z","steps":["trace[1768473488] 'process raft request' (duration: 308.58967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:53.779745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:53.470489Z","time spent":"309.174664ms","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T00:44:53.779865Z","caller":"traceutil/trace.go:171","msg":"trace[861894334] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"308.520519ms","start":"2026-02-13T00:44:53.471316Z","end":"2026-02-13T00:44:53.779836Z","steps":["trace[861894334] 'process raft request' (duration: 307.909225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:44:53.780055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:44:53.471309Z","time spent":"308.620481ms","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T00:44:59.904985Z","caller":"traceutil/trace.go:171","msg":"trace[1412027130] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"109.000021ms","start":"2026-02-13T00:44:59.79597Z","end":"2026-02-13T00:44:59.90497Z","steps":["trace[1412027130] 'process raft request' (duration: 108.665303ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:45:00.011576Z","caller":"traceutil/trace.go:171","msg":"trace[2146095253] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"103.510322ms","start":"2026-02-13T00:44:59.908048Z","end":"2026-02-13T00:45:00.011558Z","steps":["trace[2146095253] 'process raft request' (duration: 100.939431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:45:02.108743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.364228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-13T00:45:02.108766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.239785ms","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-13T00:45:02.108822Z","caller":"traceutil/trace.go:171","msg":"trace[738683913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:866; }","duration":"360.472389ms","start":"2026-02-13T00:45:01.748333Z","end":"2026-02-13T00:45:02.108805Z","steps":["trace[738683913] 'range keys from in-memory index tree' (duration: 360.312256ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:45:02.108832Z","caller":"traceutil/trace.go:171","msg":"trace[1617100762] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:866; }","duration":"200.338598ms","start":"2026-02-13T00:45:01.908479Z","end":"2026-02-13T00:45:02.108818Z","steps":["trace[1617100762] 'range keys from in-memory index tree' (duration: 200.103032ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:45:02.10886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:45:01.748318Z","time spent":"360.533202ms","remote":"127.0.0.1:55506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-13T00:45:02.295406Z","caller":"traceutil/trace.go:171","msg":"trace[643638958] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"181.198348ms","start":"2026-02-13T00:45:02.114192Z","end":"2026-02-13T00:45:02.29539Z","steps":["trace[643638958] 'process raft request' (duration: 181.097145ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:45:02.295709Z","caller":"traceutil/trace.go:171","msg":"trace[2076342682] linearizableReadLoop","detail":"{readStateIndex:900; appliedIndex:900; }","duration":"183.748128ms","start":"2026-02-13T00:45:02.111953Z","end":"2026-02-13T00:45:02.295701Z","steps":["trace[2076342682] 'read index received' (duration: 183.745338ms)","trace[2076342682] 'applied index is now lower than readState.Index' (duration: 2.2µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T00:45:02.295757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.790679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T00:45:02.295774Z","caller":"traceutil/trace.go:171","msg":"trace[1103040784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:867; }","duration":"183.840809ms","start":"2026-02-13T00:45:02.111928Z","end":"2026-02-13T00:45:02.295769Z","steps":["trace[1103040784] 'agreement among raft nodes before linearized reading' (duration: 183.798898ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:45:02.35279Z","caller":"traceutil/trace.go:171","msg":"trace[1847772492] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"237.355267ms","start":"2026-02-13T00:45:02.11542Z","end":"2026-02-13T00:45:02.352775Z","steps":["trace[1847772492] 'process raft request' (duration: 233.604619ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:45:05.844579Z","caller":"traceutil/trace.go:171","msg":"trace[237452401] linearizableReadLoop","detail":"{readStateIndex:916; appliedIndex:915; }","duration":"126.662246ms","start":"2026-02-13T00:45:05.717896Z","end":"2026-02-13T00:45:05.844558Z","steps":["trace[237452401] 'read index received' (duration: 126.477431ms)","trace[237452401] 'applied index is now lower than readState.Index' (duration: 183.755µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T00:45:05.844652Z","caller":"traceutil/trace.go:171","msg":"trace[812704705] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"289.608625ms","start":"2026-02-13T00:45:05.555018Z","end":"2026-02-13T00:45:05.844627Z","steps":["trace[812704705] 'process raft request' (duration: 289.4048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:45:05.844822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.928162ms","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-13T00:45:05.844854Z","caller":"traceutil/trace.go:171","msg":"trace[2078607683] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:882; }","duration":"127.010244ms","start":"2026-02-13T00:45:05.717834Z","end":"2026-02-13T00:45:05.844845Z","steps":["trace[2078607683] 'agreement among raft nodes before linearized reading' (duration: 126.8258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:45:06.166787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.427168ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-13T00:45:06.16688Z","caller":"traceutil/trace.go:171","msg":"trace[395032918] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"316.768313ms","start":"2026-02-13T00:45:05.850093Z","end":"2026-02-13T00:45:06.166861Z","steps":["trace[395032918] 'process raft request' (duration: 102.185371ms)","trace[395032918] 'compare' (duration: 214.331186ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T00:45:06.166952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:45:05.850081Z","time spent":"316.848065ms","remote":"127.0.0.1:55576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":823,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-13T00:45:06.172929Z","caller":"traceutil/trace.go:171","msg":"trace[2094710798] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:916; }","duration":"320.265025ms","start":"2026-02-13T00:45:05.852649Z","end":"2026-02-13T00:45:06.172914Z","steps":["trace[2094710798] 'read index received' (duration: 99.58445ms)","trace[2094710798] 'applied index is now lower than readState.Index' (duration: 220.679765ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T00:45:06.173066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.400428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T00:45:06.173096Z","caller":"traceutil/trace.go:171","msg":"trace[1016639921] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:884; }","duration":"320.46123ms","start":"2026-02-13T00:45:05.852626Z","end":"2026-02-13T00:45:06.173088Z","steps":["trace[1016639921] 'agreement among raft nodes before linearized reading' (duration: 320.343107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:45:06.173129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:45:05.852616Z","time spent":"320.502091ms","remote":"127.0.0.1:56074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":94,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-02-13T00:45:06.173536Z","caller":"traceutil/trace.go:171","msg":"trace[1805307467] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"322.744183ms","start":"2026-02-13T00:45:05.850779Z","end":"2026-02-13T00:45:06.173523Z","steps":["trace[1805307467] 'process raft request' (duration: 322.062617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:45:06.173606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:45:05.850768Z","time spent":"322.798435ms","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T00:45:06.702414Z","caller":"traceutil/trace.go:171","msg":"trace[341603979] linearizableReadLoop","detail":"{readStateIndex:920; appliedIndex:919; }","duration":"234.886579ms","start":"2026-02-13T00:45:06.467509Z","end":"2026-02-13T00:45:06.702396Z","steps":["trace[341603979] 'read index received' (duration: 138.540475ms)","trace[341603979] 'applied index is now lower than readState.Index' (duration: 96.345534ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T00:45:06.702552Z","caller":"traceutil/trace.go:171","msg":"trace[909658989] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"324.118425ms","start":"2026-02-13T00:45:06.378415Z","end":"2026-02-13T00:45:06.702533Z","steps":["trace[909658989] 'process raft request' (duration: 227.681099ms)","trace[909658989] 'compare' (duration: 96.230762ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T00:45:06.702568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.039873ms","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-13T00:45:06.702597Z","caller":"traceutil/trace.go:171","msg":"trace[1191090986] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:886; }","duration":"235.121925ms","start":"2026-02-13T00:45:06.467468Z","end":"2026-02-13T00:45:06.70259Z","steps":["trace[1191090986] 'agreement among raft nodes before linearized reading' (duration: 234.990442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:45:06.702638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:45:06.378401Z","time spent":"324.189658ms","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T00:45:07.160494Z","caller":"traceutil/trace.go:171","msg":"trace[477715249] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:921; }","duration":"145.453057ms","start":"2026-02-13T00:45:07.015019Z","end":"2026-02-13T00:45:07.160472Z","steps":["trace[477715249] 'read index received' (duration: 145.15336ms)","trace[477715249] 'applied index is now lower than readState.Index' (duration: 298.837µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T00:45:07.160618Z","caller":"traceutil/trace.go:171","msg":"trace[1533843340] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"366.166023ms","start":"2026-02-13T00:45:06.794443Z","end":"2026-02-13T00:45:07.160609Z","steps":["trace[1533843340] 'process raft request' (duration: 365.822435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:45:07.160726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:45:06.794431Z","time spent":"366.213214ms","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T00:45:07.161056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.00581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T00:45:07.161124Z","caller":"traceutil/trace.go:171","msg":"trace[128640797] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:888; }","duration":"146.113423ms","start":"2026-02-13T00:45:07.014992Z","end":"2026-02-13T00:45:07.161105Z","steps":["trace[128640797] 'agreement among raft nodes before linearized reading' (duration: 145.99154ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:45:09.022993Z","caller":"traceutil/trace.go:171","msg":"trace[433342633] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"242.116229ms","start":"2026-02-13T00:45:08.780854Z","end":"2026-02-13T00:45:09.02297Z","steps":["trace[433342633] 'process raft request' (duration: 241.998227ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:45:09.035001Z","caller":"traceutil/trace.go:171","msg":"trace[1909972710] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"231.885339ms","start":"2026-02-13T00:45:08.803102Z","end":"2026-02-13T00:45:09.034988Z","steps":["trace[1909972710] 'process raft request' (duration: 231.51429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:45:11.406755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.278832ms","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-13T00:45:11.406867Z","caller":"traceutil/trace.go:171","msg":"trace[137100016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:919; }","duration":"196.378274ms","start":"2026-02-13T00:45:11.210451Z","end":"2026-02-13T00:45:11.406829Z","steps":["trace[137100016] 'range keys from in-memory index tree' (duration: 196.052247ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:45:18.909611Z","caller":"traceutil/trace.go:171","msg":"trace[1695101793] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"101.602597ms","start":"2026-02-13T00:45:18.807985Z","end":"2026-02-13T00:45:18.909587Z","steps":["trace[1695101793] 'process raft request' (duration: 101.182418ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:45:19.034959Z","caller":"traceutil/trace.go:171","msg":"trace[1950420721] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"121.419383ms","start":"2026-02-13T00:45:18.913523Z","end":"2026-02-13T00:45:19.034942Z","steps":["trace[1950420721] 'process raft request' (duration: 120.534402ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:47:17.942983Z","caller":"traceutil/trace.go:171","msg":"trace[409064065] linearizableReadLoop","detail":"{readStateIndex:1521; appliedIndex:1520; }","duration":"267.402107ms","start":"2026-02-13T00:47:17.675558Z","end":"2026-02-13T00:47:17.94296Z","steps":["trace[409064065] 'read index received' (duration: 238.917521ms)","trace[409064065] 'applied index is now lower than readState.Index' (duration: 28.483766ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T00:47:17.943092Z","caller":"traceutil/trace.go:171","msg":"trace[1609784472] transaction","detail":"{read_only:false; response_revision:1460; number_of_response:1; }","duration":"272.968081ms","start":"2026-02-13T00:47:17.670097Z","end":"2026-02-13T00:47:17.943065Z","steps":["trace[1609784472] 'process raft request' (duration: 244.521176ms)","trace[1609784472] 'compare' (duration: 28.21709ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T00:47:17.943806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.228357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T00:47:17.943849Z","caller":"traceutil/trace.go:171","msg":"trace[682335394] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1460; }","duration":"268.299569ms","start":"2026-02-13T00:47:17.675535Z","end":"2026-02-13T00:47:17.943834Z","steps":["trace[682335394] 'agreement among raft nodes before linearized reading' (duration: 268.213857ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:47:21.199501Z","caller":"traceutil/trace.go:171","msg":"trace[116054402] transaction","detail":"{read_only:false; response_revision:1473; number_of_response:1; }","duration":"105.429837ms","start":"2026-02-13T00:47:21.094049Z","end":"2026-02-13T00:47:21.199479Z","steps":["trace[116054402] 'process raft request' (duration: 56.344976ms)","trace[116054402] 'compare' (duration: 48.996819ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T00:47:22.207359Z","caller":"traceutil/trace.go:171","msg":"trace[1687488397] transaction","detail":"{read_only:false; response_revision:1478; number_of_response:1; }","duration":"110.234593ms","start":"2026-02-13T00:47:22.097101Z","end":"2026-02-13T00:47:22.207335Z","steps":["trace[1687488397] 'process raft request' (duration: 110.10878ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:48:26.218954Z","caller":"traceutil/trace.go:171","msg":"trace[205624611] transaction","detail":"{read_only:false; response_revision:1763; number_of_response:1; }","duration":"114.599663ms","start":"2026-02-13T00:48:26.10433Z","end":"2026-02-13T00:48:26.218929Z","steps":["trace[205624611] 'process raft request' (duration: 114.4664ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:48:27.653127Z","caller":"traceutil/trace.go:171","msg":"trace[1128411522] transaction","detail":"{read_only:false; response_revision:1771; number_of_response:1; }","duration":"154.838649ms","start":"2026-02-13T00:48:27.49827Z","end":"2026-02-13T00:48:27.653108Z","steps":["trace[1128411522] 'process raft request' (duration: 154.743167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:48:36.088588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.813286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-13T00:48:36.088657Z","caller":"traceutil/trace.go:171","msg":"trace[1494636591] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1808; }","duration":"399.944829ms","start":"2026-02-13T00:48:35.688696Z","end":"2026-02-13T00:48:36.088641Z","steps":["trace[1494636591] 'range keys from in-memory index tree' (duration: 399.682693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:48:36.088693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:48:35.688678Z","time spent":"400.004691ms","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-13T00:48:36.088702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.964606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T00:48:36.088775Z","caller":"traceutil/trace.go:171","msg":"trace[731771754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1808; }","duration":"336.082409ms","start":"2026-02-13T00:48:35.752674Z","end":"2026-02-13T00:48:36.088757Z","steps":["trace[731771754] 'range keys from in-memory index tree' (duration: 335.905664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:48:36.088818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:48:35.752661Z","time spent":"336.14883ms","remote":"127.0.0.1:55506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}