{"level":"warn","ts":"2026-02-18T05:19:08.536871Z","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-18T05:19:08.536962Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169: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-18T05:19:08.537019Z","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-18T05:19:08.537026Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-02-18T05:19:08.537042Z","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-18T05:19:08.537744Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-02-18T05:19:08.537841Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169: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-18T05:19:08.54164Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.542606ms"} {"level":"info","ts":"2026-02-18T05:19:08.555619Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-02-18T05:19:08.555691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T05:19:08.555712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-02-18T05:19:08.55572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T05:19:08.555736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-02-18T05:19:08.555766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-02-18T05:19:08.561123Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T05:19:08.564822Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T05:19:08.566797Z","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-18T05:19:08.568589Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T05:19:08.57644Z","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-18T05:19:08.576679Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T05:19:08.576698Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T05:19:08.576772Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-02-18T05:19:08.576781Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-02-18T05:19:08.577342Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T05:19:08.57744Z","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-18T05:19:08.57747Z","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-18T05:19:08.577476Z","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-18T05:19:08.578762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-02-18T05:19:08.578835Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-02-18T05:19:09.156302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T05:19:09.156353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T05:19:09.156376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-02-18T05:19:09.156388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-02-18T05:19:09.156442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-02-18T05:19:09.156453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-02-18T05:19:09.156462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-02-18T05:19:09.194948Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T05:19:09.196165Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T05:19:09.196376Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T05:19:09.196551Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T05:19:09.196644Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T05:19:09.196797Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T05:19:09.203354Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-02-18T05:19:09.203933Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T05:19:09.20497Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T05:19:09.205Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T05:19:09.217444Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-18T05:19:33.801482Z","caller":"traceutil/trace.go:171","msg":"trace[742532589] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"414.087249ms","start":"2026-02-18T05:19:33.387379Z","end":"2026-02-18T05:19:33.801466Z","steps":["trace[742532589] 'process raft request' (duration: 413.983776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:33.802881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:33.38736Z","time spent":"414.391277ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:19:34.051179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.68402ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:19:34.051257Z","caller":"traceutil/trace.go:171","msg":"trace[1515088072] linearizableReadLoop","detail":"{readStateIndex:484; appliedIndex:483; }","duration":"268.720001ms","start":"2026-02-18T05:19:33.782528Z","end":"2026-02-18T05:19:34.051248Z","steps":["trace[1515088072] 'read index received' (duration: 22.787655ms)","trace[1515088072] 'applied index is now lower than readState.Index' (duration: 245.931746ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:19:34.051295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.767152ms","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-18T05:19:34.051309Z","caller":"traceutil/trace.go:171","msg":"trace[1012523552] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:471; }","duration":"268.813373ms","start":"2026-02-18T05:19:33.782488Z","end":"2026-02-18T05:19:34.051302Z","steps":["trace[1012523552] 'agreement among raft nodes before linearized reading' (duration: 268.782243ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:34.05138Z","caller":"traceutil/trace.go:171","msg":"trace[201832157] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"663.796927ms","start":"2026-02-18T05:19:33.387578Z","end":"2026-02-18T05:19:34.051375Z","steps":["trace[201832157] 'process raft request' (duration: 498.793989ms)","trace[201832157] 'compare' (duration: 164.607038ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:19:34.051403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:33.387567Z","time spent":"663.822578ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:19:38.308373Z","caller":"traceutil/trace.go:171","msg":"trace[798019398] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"216.693725ms","start":"2026-02-18T05:19:38.091663Z","end":"2026-02-18T05:19:38.308356Z","steps":["trace[798019398] 'process raft request' (duration: 216.577112ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:38.312294Z","caller":"traceutil/trace.go:171","msg":"trace[565293848] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"219.82044ms","start":"2026-02-18T05:19:38.092463Z","end":"2026-02-18T05:19:38.312283Z","steps":["trace[565293848] 'process raft request' (duration: 219.710957ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:38.62434Z","caller":"traceutil/trace.go:171","msg":"trace[934388444] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"209.093979ms","start":"2026-02-18T05:19:38.415223Z","end":"2026-02-18T05:19:38.624316Z","steps":["trace[934388444] 'process raft request' (duration: 208.826662ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:40.252673Z","caller":"traceutil/trace.go:171","msg":"trace[1229677536] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"408.857639ms","start":"2026-02-18T05:19:39.843796Z","end":"2026-02-18T05:19:40.252654Z","steps":["trace[1229677536] 'process raft request' (duration: 408.747786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:40.25279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:39.843787Z","time spent":"408.942451ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:19:40.992141Z","caller":"traceutil/trace.go:171","msg":"trace[1804536925] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"194.248209ms","start":"2026-02-18T05:19:40.797871Z","end":"2026-02-18T05:19:40.992119Z","steps":["trace[1804536925] 'process raft request' (duration: 194.035083ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:46.211303Z","caller":"traceutil/trace.go:171","msg":"trace[275944599] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"476.772713ms","start":"2026-02-18T05:19:45.734502Z","end":"2026-02-18T05:19:46.211275Z","steps":["trace[275944599] 'process raft request' (duration: 476.549947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:46.211519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:45.734487Z","time spent":"476.940837ms","remote":"127.0.0.1:53226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:19:47.180987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.39688ms","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-18T05:19:47.181056Z","caller":"traceutil/trace.go:171","msg":"trace[951298526] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:537; }","duration":"843.521974ms","start":"2026-02-18T05:19:46.337514Z","end":"2026-02-18T05:19:47.181036Z","steps":["trace[951298526] 'range keys from in-memory index tree' (duration: 843.295168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:47.181094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:46.337492Z","time spent":"843.594446ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T05:19:47.181247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.525214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T05:19:47.181275Z","caller":"traceutil/trace.go:171","msg":"trace[1282218469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:537; }","duration":"843.554285ms","start":"2026-02-18T05:19:46.337715Z","end":"2026-02-18T05:19:47.181269Z","steps":["trace[1282218469] 'range keys from in-memory index tree' (duration: 843.414672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:47.181297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:46.337657Z","time spent":"843.635857ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T05:19:47.181446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.16056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T05:19:47.181471Z","caller":"traceutil/trace.go:171","msg":"trace[1280202993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:537; }","duration":"841.205051ms","start":"2026-02-18T05:19:46.34026Z","end":"2026-02-18T05:19:47.181465Z","steps":["trace[1280202993] 'range keys from in-memory index tree' (duration: 841.051777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:47.181496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:46.34025Z","time spent":"841.238993ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T05:19:47.181725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.832997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:19:47.181752Z","caller":"traceutil/trace.go:171","msg":"trace[1545527628] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:537; }","duration":"603.886598ms","start":"2026-02-18T05:19:46.577858Z","end":"2026-02-18T05:19:47.181745Z","steps":["trace[1545527628] 'range keys from in-memory index tree' (duration: 603.776266ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:47.181773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:46.577843Z","time spent":"603.92494ms","remote":"127.0.0.1:53056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:19:47.182213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.794376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-18T05:19:47.182242Z","caller":"traceutil/trace.go:171","msg":"trace[1683451590] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:537; }","duration":"170.857077ms","start":"2026-02-18T05:19:47.011378Z","end":"2026-02-18T05:19:47.182235Z","steps":["trace[1683451590] 'range keys from in-memory index tree' (duration: 170.704883ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:49.178265Z","caller":"traceutil/trace.go:171","msg":"trace[1897889828] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:566; }","duration":"337.756137ms","start":"2026-02-18T05:19:48.840493Z","end":"2026-02-18T05:19:49.178249Z","steps":["trace[1897889828] 'read index received' (duration: 337.619774ms)","trace[1897889828] 'applied index is now lower than readState.Index' (duration: 135.703µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:19:49.178342Z","caller":"traceutil/trace.go:171","msg":"trace[352968324] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"338.046445ms","start":"2026-02-18T05:19:48.840289Z","end":"2026-02-18T05:19:49.178336Z","steps":["trace[352968324] 'process raft request' (duration: 337.86884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:49.178435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:48.840273Z","time spent":"338.115657ms","remote":"127.0.0.1:53114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":846,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T05:19:49.178686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.180039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-m8979\" ","response":"range_response_count:1 size:4639"} {"level":"info","ts":"2026-02-18T05:19:49.17872Z","caller":"traceutil/trace.go:171","msg":"trace[1494843210] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-m8979; range_end:; response_count:1; response_revision:551; }","duration":"338.23857ms","start":"2026-02-18T05:19:48.840475Z","end":"2026-02-18T05:19:49.178714Z","steps":["trace[1494843210] 'agreement among raft nodes before linearized reading' (duration: 338.149018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:49.178739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:48.840464Z","time spent":"338.26963ms","remote":"127.0.0.1:53226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4662,"request content":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-m8979\" "} {"level":"warn","ts":"2026-02-18T05:19:49.178871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.771732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-18T05:19:49.178892Z","caller":"traceutil/trace.go:171","msg":"trace[1092768653] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:551; }","duration":"285.828994ms","start":"2026-02-18T05:19:48.893058Z","end":"2026-02-18T05:19:49.178887Z","steps":["trace[1092768653] 'agreement among raft nodes before linearized reading' (duration: 285.791113ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:01.538191Z","caller":"traceutil/trace.go:171","msg":"trace[1333619494] linearizableReadLoop","detail":"{readStateIndex:726; appliedIndex:725; }","duration":"209.192783ms","start":"2026-02-18T05:20:01.328976Z","end":"2026-02-18T05:20:01.538168Z","steps":["trace[1333619494] 'read index received' (duration: 209.006938ms)","trace[1333619494] 'applied index is now lower than readState.Index' (duration: 185.025µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:20:01.538457Z","caller":"traceutil/trace.go:171","msg":"trace[453484143] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"215.902494ms","start":"2026-02-18T05:20:01.322543Z","end":"2026-02-18T05:20:01.538445Z","steps":["trace[453484143] 'process raft request' (duration: 215.501313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:01.538697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.705187ms","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-18T05:20:01.538744Z","caller":"traceutil/trace.go:171","msg":"trace[117414274] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:707; }","duration":"209.781989ms","start":"2026-02-18T05:20:01.328938Z","end":"2026-02-18T05:20:01.53872Z","steps":["trace[117414274] 'agreement among raft nodes before linearized reading' (duration: 209.663996ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:01.538931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.803684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T05:20:01.538989Z","caller":"traceutil/trace.go:171","msg":"trace[579451683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:707; }","duration":"194.877836ms","start":"2026-02-18T05:20:01.344103Z","end":"2026-02-18T05:20:01.538981Z","steps":["trace[579451683] 'agreement among raft nodes before linearized reading' (duration: 194.772994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:01.539322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.169574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T05:20:01.539358Z","caller":"traceutil/trace.go:171","msg":"trace[478789536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:707; }","duration":"195.214945ms","start":"2026-02-18T05:20:01.344135Z","end":"2026-02-18T05:20:01.53935Z","steps":["trace[478789536] 'agreement among raft nodes before linearized reading' (duration: 195.137543ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:01.698021Z","caller":"traceutil/trace.go:171","msg":"trace[29283750] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"154.895885ms","start":"2026-02-18T05:20:01.543105Z","end":"2026-02-18T05:20:01.698001Z","steps":["trace[29283750] 'process raft request' (duration: 98.384108ms)","trace[29283750] 'compare' (duration: 56.314022ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:20:01.850002Z","caller":"traceutil/trace.go:171","msg":"trace[1495967776] linearizableReadLoop","detail":"{readStateIndex:730; appliedIndex:726; }","duration":"275.33784ms","start":"2026-02-18T05:20:01.574645Z","end":"2026-02-18T05:20:01.849982Z","steps":["trace[1495967776] 'read index received' (duration: 66.851707ms)","trace[1495967776] 'applied index is now lower than readState.Index' (duration: 208.485273ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:01.850138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.474614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:01.850169Z","caller":"traceutil/trace.go:171","msg":"trace[858397053] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:711; }","duration":"275.549306ms","start":"2026-02-18T05:20:01.57461Z","end":"2026-02-18T05:20:01.85016Z","steps":["trace[858397053] 'agreement among raft nodes before linearized reading' (duration: 275.473334ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:01.85049Z","caller":"traceutil/trace.go:171","msg":"trace[932850014] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"304.57798ms","start":"2026-02-18T05:20:01.545897Z","end":"2026-02-18T05:20:01.850475Z","steps":["trace[932850014] 'process raft request' (duration: 255.236397ms)","trace[932850014] 'compare' (duration: 48.646395ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:01.85057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:01.545882Z","time spent":"304.637112ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:01.850719Z","caller":"traceutil/trace.go:171","msg":"trace[1280008741] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"304.688103ms","start":"2026-02-18T05:20:01.546017Z","end":"2026-02-18T05:20:01.850706Z","steps":["trace[1280008741] 'process raft request' (duration: 303.873901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:01.850776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:01.54601Z","time spent":"304.735395ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:01.850902Z","caller":"traceutil/trace.go:171","msg":"trace[445061700] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"304.731475ms","start":"2026-02-18T05:20:01.546162Z","end":"2026-02-18T05:20:01.850893Z","steps":["trace[445061700] 'process raft request' (duration: 303.783779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:01.85095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:01.546154Z","time spent":"304.768145ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:20:02.613471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.321079ms","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-18T05:20:02.613556Z","caller":"traceutil/trace.go:171","msg":"trace[892753617] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:711; }","duration":"238.412772ms","start":"2026-02-18T05:20:02.375131Z","end":"2026-02-18T05:20:02.613544Z","steps":["trace[892753617] 'range keys from in-memory index tree' (duration: 238.311039ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:02.614023Z","caller":"traceutil/trace.go:171","msg":"trace[1512701047] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"679.823239ms","start":"2026-02-18T05:20:01.93419Z","end":"2026-02-18T05:20:02.614014Z","steps":["trace[1512701047] 'process raft request' (duration: 674.366042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:02.614072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:01.934162Z","time spent":"679.878831ms","remote":"127.0.0.1:53114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T05:20:02.614207Z","caller":"traceutil/trace.go:171","msg":"trace[714667561] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"279.928223ms","start":"2026-02-18T05:20:02.334272Z","end":"2026-02-18T05:20:02.614201Z","steps":["trace[714667561] 'process raft request' (duration: 279.576584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:03.149425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:02.70843Z","time spent":"440.992056ms","remote":"127.0.0.1:53066","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-18T05:20:03.653025Z","caller":"traceutil/trace.go:171","msg":"trace[1043943767] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"107.231568ms","start":"2026-02-18T05:20:03.545781Z","end":"2026-02-18T05:20:03.653013Z","steps":["trace[1043943767] 'process raft request' (duration: 106.979841ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:08.049236Z","caller":"traceutil/trace.go:171","msg":"trace[2017453652] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:746; }","duration":"283.376758ms","start":"2026-02-18T05:20:07.76584Z","end":"2026-02-18T05:20:08.049217Z","steps":["trace[2017453652] 'read index received' (duration: 283.233874ms)","trace[2017453652] 'applied index is now lower than readState.Index' (duration: 142.204µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:20:08.049509Z","caller":"traceutil/trace.go:171","msg":"trace[1652858906] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"363.777495ms","start":"2026-02-18T05:20:07.685722Z","end":"2026-02-18T05:20:08.049499Z","steps":["trace[1652858906] 'process raft request' (duration: 363.402015ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:08.049603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:07.685702Z","time spent":"363.835087ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:20:08.0498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.957783ms","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-18T05:20:08.049836Z","caller":"traceutil/trace.go:171","msg":"trace[450517947] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:727; }","duration":"284.013635ms","start":"2026-02-18T05:20:07.765816Z","end":"2026-02-18T05:20:08.049829Z","steps":["trace[450517947] 'agreement among raft nodes before linearized reading' (duration: 283.919732ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:20.360679Z","caller":"traceutil/trace.go:171","msg":"trace[675020940] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:788; }","duration":"244.36489ms","start":"2026-02-18T05:20:20.116298Z","end":"2026-02-18T05:20:20.360663Z","steps":["trace[675020940] 'read index received' (duration: 244.238647ms)","trace[675020940] 'applied index is now lower than readState.Index' (duration: 125.693µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:20:20.360837Z","caller":"traceutil/trace.go:171","msg":"trace[1639171463] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"256.180119ms","start":"2026-02-18T05:20:20.10465Z","end":"2026-02-18T05:20:20.36083Z","steps":["trace[1639171463] 'process raft request' (duration: 255.923852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:20.361015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.701209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-18T05:20:20.361039Z","caller":"traceutil/trace.go:171","msg":"trace[963643067] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:766; }","duration":"244.762801ms","start":"2026-02-18T05:20:20.11627Z","end":"2026-02-18T05:20:20.361032Z","steps":["trace[963643067] 'agreement among raft nodes before linearized reading' (duration: 244.672869ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:20.361148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.19443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-18T05:20:20.361165Z","caller":"traceutil/trace.go:171","msg":"trace[1626560075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:766; }","duration":"238.22319ms","start":"2026-02-18T05:20:20.122936Z","end":"2026-02-18T05:20:20.361159Z","steps":["trace[1626560075] 'agreement among raft nodes before linearized reading' (duration: 238.175649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:20.361299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.248122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-18T05:20:20.361317Z","caller":"traceutil/trace.go:171","msg":"trace[1946357338] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:766; }","duration":"225.275573ms","start":"2026-02-18T05:20:20.136037Z","end":"2026-02-18T05:20:20.361312Z","steps":["trace[1946357338] 'agreement among raft nodes before linearized reading' (duration: 225.225732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:20.36146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.892986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-18T05:20:20.361477Z","caller":"traceutil/trace.go:171","msg":"trace[595175309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:766; }","duration":"236.918877ms","start":"2026-02-18T05:20:20.124553Z","end":"2026-02-18T05:20:20.361472Z","steps":["trace[595175309] 'agreement among raft nodes before linearized reading' (duration: 236.873555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:24.882691Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826754807796107,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T05:20:24.922232Z","caller":"traceutil/trace.go:171","msg":"trace[365981935] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:801; }","duration":"539.691891ms","start":"2026-02-18T05:20:24.382523Z","end":"2026-02-18T05:20:24.922215Z","steps":["trace[365981935] 'read index received' (duration: 539.550257ms)","trace[365981935] 'applied index is now lower than readState.Index' (duration: 141.004µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:20:24.922463Z","caller":"traceutil/trace.go:171","msg":"trace[79388949] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"546.466457ms","start":"2026-02-18T05:20:24.375987Z","end":"2026-02-18T05:20:24.922453Z","steps":["trace[79388949] 'process raft request' (duration: 546.131038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:24.922541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:24.375974Z","time spent":"546.514788ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:20:24.922739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.211884ms","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-18T05:20:24.922762Z","caller":"traceutil/trace.go:171","msg":"trace[769409652] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:778; }","duration":"540.249745ms","start":"2026-02-18T05:20:24.382505Z","end":"2026-02-18T05:20:24.922755Z","steps":["trace[769409652] 'agreement among raft nodes before linearized reading' (duration: 540.148092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:24.922782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:24.382498Z","time spent":"540.278485ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T05:20:24.922899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.304347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-18T05:20:24.922917Z","caller":"traceutil/trace.go:171","msg":"trace[806923242] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:778; }","duration":"532.334349ms","start":"2026-02-18T05:20:24.390577Z","end":"2026-02-18T05:20:24.922912Z","steps":["trace[806923242] 'agreement among raft nodes before linearized reading' (duration: 532.283157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:24.922935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:24.390569Z","time spent":"532.361529ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T05:20:24.92305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.338831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:24.923071Z","caller":"traceutil/trace.go:171","msg":"trace[1325625137] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:778; }","duration":"348.381683ms","start":"2026-02-18T05:20:24.574683Z","end":"2026-02-18T05:20:24.923065Z","steps":["trace[1325625137] 'agreement among raft nodes before linearized reading' (duration: 348.351532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:24.923102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:24.574669Z","time spent":"348.429294ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:20:24.923237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.119735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:24.923259Z","caller":"traceutil/trace.go:171","msg":"trace[21063591] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:778; }","duration":"412.168696ms","start":"2026-02-18T05:20:24.511084Z","end":"2026-02-18T05:20:24.923253Z","steps":["trace[21063591] 'agreement among raft nodes before linearized reading' (duration: 412.133345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:24.923277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:24.511068Z","time spent":"412.204107ms","remote":"127.0.0.1:53346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-18T05:20:24.923391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.103962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T05:20:24.923409Z","caller":"traceutil/trace.go:171","msg":"trace[10925283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:778; }","duration":"527.133753ms","start":"2026-02-18T05:20:24.396269Z","end":"2026-02-18T05:20:24.923403Z","steps":["trace[10925283] 'agreement among raft nodes before linearized reading' (duration: 527.085021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:24.923426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:24.39626Z","time spent":"527.161703ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T05:20:24.923527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.494505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T05:20:24.923552Z","caller":"traceutil/trace.go:171","msg":"trace[1969954094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:778; }","duration":"529.528625ms","start":"2026-02-18T05:20:24.394014Z","end":"2026-02-18T05:20:24.923542Z","steps":["trace[1969954094] 'agreement among raft nodes before linearized reading' (duration: 529.475054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:24.923581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:24.394007Z","time spent":"529.567346ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T05:20:25.110977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.892933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:25.111031Z","caller":"traceutil/trace.go:171","msg":"trace[837134107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:778; }","duration":"183.980026ms","start":"2026-02-18T05:20:24.927038Z","end":"2026-02-18T05:20:25.111018Z","steps":["trace[837134107] 'range keys from in-memory index tree' (duration: 183.832302ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:25.11141Z","caller":"traceutil/trace.go:171","msg":"trace[802803894] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"182.553179ms","start":"2026-02-18T05:20:24.928848Z","end":"2026-02-18T05:20:25.111401Z","steps":["trace[802803894] 'process raft request' (duration: 174.021297ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:25.121997Z","caller":"traceutil/trace.go:171","msg":"trace[1180751519] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"188.055052ms","start":"2026-02-18T05:20:24.933925Z","end":"2026-02-18T05:20:25.12198Z","steps":["trace[1180751519] 'process raft request' (duration: 188.017501ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:25.122322Z","caller":"traceutil/trace.go:171","msg":"trace[745259450] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"189.745876ms","start":"2026-02-18T05:20:24.932565Z","end":"2026-02-18T05:20:25.122311Z","steps":["trace[745259450] 'process raft request' (duration: 189.277984ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:25.122446Z","caller":"traceutil/trace.go:171","msg":"trace[1546894074] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"189.850288ms","start":"2026-02-18T05:20:24.932587Z","end":"2026-02-18T05:20:25.122437Z","steps":["trace[1546894074] 'process raft request' (duration: 189.324355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:28.821295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.726333ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T05:20:29.145673Z","caller":"traceutil/trace.go:171","msg":"trace[1968055291] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"165.245558ms","start":"2026-02-18T05:20:28.980413Z","end":"2026-02-18T05:20:29.145658Z","steps":["trace[1968055291] 'process raft request' (duration: 164.988822ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:45.513312Z","caller":"traceutil/trace.go:171","msg":"trace[4353138] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:925; }","duration":"113.305154ms","start":"2026-02-18T05:20:45.399993Z","end":"2026-02-18T05:20:45.513298Z","steps":["trace[4353138] 'read index received' (duration: 113.17829ms)","trace[4353138] 'applied index is now lower than readState.Index' (duration: 126.364µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:20:45.513447Z","caller":"traceutil/trace.go:171","msg":"trace[394155423] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"149.404755ms","start":"2026-02-18T05:20:45.364037Z","end":"2026-02-18T05:20:45.513442Z","steps":["trace[394155423] 'process raft request' (duration: 149.170569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:45.513651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.657163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T05:20:45.513673Z","caller":"traceutil/trace.go:171","msg":"trace[1464730877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:896; }","duration":"113.724204ms","start":"2026-02-18T05:20:45.399943Z","end":"2026-02-18T05:20:45.513667Z","steps":["trace[1464730877] 'agreement among raft nodes before linearized reading' (duration: 113.594391ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:45.513765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.023573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-18T05:20:45.513783Z","caller":"traceutil/trace.go:171","msg":"trace[452143926] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:896; }","duration":"109.059913ms","start":"2026-02-18T05:20:45.404718Z","end":"2026-02-18T05:20:45.513778Z","steps":["trace[452143926] 'agreement among raft nodes before linearized reading' (duration: 109.016302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:45.513901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.104414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T05:20:45.513919Z","caller":"traceutil/trace.go:171","msg":"trace[1362162995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:896; }","duration":"109.134246ms","start":"2026-02-18T05:20:45.404781Z","end":"2026-02-18T05:20:45.513915Z","steps":["trace[1362162995] 'agreement among raft nodes before linearized reading' (duration: 109.091995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:45.709343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.982183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:20:45.709515Z","caller":"traceutil/trace.go:171","msg":"trace[92840557] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"192.088467ms","start":"2026-02-18T05:20:45.5174Z","end":"2026-02-18T05:20:45.709488Z","steps":["trace[92840557] 'process raft request' (duration: 85.629202ms)","trace[92840557] 'compare' (duration: 105.86788ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:20:45.710594Z","caller":"traceutil/trace.go:171","msg":"trace[883913960] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"192.18331ms","start":"2026-02-18T05:20:45.518403Z","end":"2026-02-18T05:20:45.710587Z","steps":["trace[883913960] 'process raft request' (duration: 191.04571ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:45.711994Z","caller":"traceutil/trace.go:171","msg":"trace[247426367] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"191.296727ms","start":"2026-02-18T05:20:45.52069Z","end":"2026-02-18T05:20:45.711986Z","steps":["trace[247426367] 'process raft request' (duration: 189.553941ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:47.764849Z","caller":"traceutil/trace.go:171","msg":"trace[661035335] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:931; }","duration":"247.938565ms","start":"2026-02-18T05:20:47.516898Z","end":"2026-02-18T05:20:47.764836Z","steps":["trace[661035335] 'read index received' (duration: 247.813801ms)","trace[661035335] 'applied index is now lower than readState.Index' (duration: 124.224µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:20:47.765014Z","caller":"traceutil/trace.go:171","msg":"trace[1473963429] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"550.971044ms","start":"2026-02-18T05:20:47.214038Z","end":"2026-02-18T05:20:47.765009Z","steps":["trace[1473963429] 'process raft request' (duration: 550.721248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:47.765078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:47.214028Z","time spent":"551.000196ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:20:47.765203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.305174ms","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-18T05:20:47.765219Z","caller":"traceutil/trace.go:171","msg":"trace[1627306433] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:902; }","duration":"248.319684ms","start":"2026-02-18T05:20:47.516894Z","end":"2026-02-18T05:20:47.765213Z","steps":["trace[1627306433] 'agreement among raft nodes before linearized reading' (duration: 248.264033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:47.765284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.39999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:47.765295Z","caller":"traceutil/trace.go:171","msg":"trace[712348432] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:902; }","duration":"191.43285ms","start":"2026-02-18T05:20:47.573859Z","end":"2026-02-18T05:20:47.765292Z","steps":["trace[712348432] 'agreement among raft nodes before linearized reading' (duration: 191.41549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:48.346279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.155507ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:20:48.34684Z","caller":"traceutil/trace.go:171","msg":"trace[1935852836] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"574.049616ms","start":"2026-02-18T05:20:47.772759Z","end":"2026-02-18T05:20:48.346809Z","steps":["trace[1935852836] 'process raft request' (duration: 85.311934ms)","trace[1935852836] 'compare' (duration: 488.018373ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:48.346942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:47.772748Z","time spent":"574.142558ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:48.347318Z","caller":"traceutil/trace.go:171","msg":"trace[5699256] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"570.400101ms","start":"2026-02-18T05:20:47.776912Z","end":"2026-02-18T05:20:48.347312Z","steps":["trace[5699256] 'process raft request' (duration: 569.422856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:48.347355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:47.776905Z","time spent":"570.432412ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:48.347464Z","caller":"traceutil/trace.go:171","msg":"trace[2055426649] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"568.706297ms","start":"2026-02-18T05:20:47.778753Z","end":"2026-02-18T05:20:48.347459Z","steps":["trace[2055426649] 'process raft request' (duration: 567.629179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:48.347506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:47.778747Z","time spent":"568.743888ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:56.685024Z","caller":"traceutil/trace.go:171","msg":"trace[159252004] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"302.182659ms","start":"2026-02-18T05:20:56.382818Z","end":"2026-02-18T05:20:56.685Z","steps":["trace[159252004] 'process raft request' (duration: 302.064805ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:56.685036Z","caller":"traceutil/trace.go:171","msg":"trace[358191853] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:958; }","duration":"301.351877ms","start":"2026-02-18T05:20:56.383658Z","end":"2026-02-18T05:20:56.68501Z","steps":["trace[358191853] 'read index received' (duration: 301.342777ms)","trace[358191853] 'applied index is now lower than readState.Index' (duration: 7.27µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:56.685162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:56.382809Z","time spent":"302.282891ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:20:56.685414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.697076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T05:20:56.685458Z","caller":"traceutil/trace.go:171","msg":"trace[433361543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:926; }","duration":"301.795878ms","start":"2026-02-18T05:20:56.383648Z","end":"2026-02-18T05:20:56.685444Z","steps":["trace[433361543] 'agreement among raft nodes before linearized reading' (duration: 301.48729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:56.685495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:56.383643Z","time spent":"301.843289ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T05:20:57.18281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.381216ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:20:57.183013Z","caller":"traceutil/trace.go:171","msg":"trace[1285622326] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"489.557903ms","start":"2026-02-18T05:20:56.693436Z","end":"2026-02-18T05:20:57.182994Z","steps":["trace[1285622326] 'process raft request' (duration: 489.491722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:57.183078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:56.693418Z","time spent":"489.627455ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:57.183208Z","caller":"traceutil/trace.go:171","msg":"trace[2077002229] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"798.321603ms","start":"2026-02-18T05:20:56.384855Z","end":"2026-02-18T05:20:57.183176Z","steps":["trace[2077002229] 'process raft request' (duration: 450.471825ms)","trace[2077002229] 'compare' (duration: 347.280763ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:20:57.183288Z","caller":"traceutil/trace.go:171","msg":"trace[772167343] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:958; }","duration":"498.161657ms","start":"2026-02-18T05:20:56.685115Z","end":"2026-02-18T05:20:57.183277Z","steps":["trace[772167343] 'read index received' (duration: 150.218876ms)","trace[772167343] 'applied index is now lower than readState.Index' (duration: 347.941681ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:57.18336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.350756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-18T05:20:57.183359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:56.384848Z","time spent":"798.438186ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:57.183396Z","caller":"traceutil/trace.go:171","msg":"trace[783782552] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:928; }","duration":"609.413418ms","start":"2026-02-18T05:20:56.573972Z","end":"2026-02-18T05:20:57.183386Z","steps":["trace[783782552] 'agreement among raft nodes before linearized reading' (duration: 609.342536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:57.183425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:56.573956Z","time spent":"609.462999ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T05:20:57.205537Z","caller":"traceutil/trace.go:171","msg":"trace[1923118329] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"140.487822ms","start":"2026-02-18T05:20:57.065028Z","end":"2026-02-18T05:20:57.205516Z","steps":["trace[1923118329] 'process raft request' (duration: 140.164833ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:58.008431Z","caller":"traceutil/trace.go:171","msg":"trace[230966598] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"177.863077ms","start":"2026-02-18T05:20:57.830508Z","end":"2026-02-18T05:20:58.008372Z","steps":["trace[230966598] 'process raft request' (duration: 79.036651ms)","trace[230966598] 'compare' (duration: 98.56988ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:21:00.876373Z","caller":"traceutil/trace.go:171","msg":"trace[233956105] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"176.780938ms","start":"2026-02-18T05:21:00.699577Z","end":"2026-02-18T05:21:00.876357Z","steps":["trace[233956105] 'process raft request' (duration: 176.673326ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:02.118197Z","caller":"traceutil/trace.go:171","msg":"trace[1464275850] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:972; }","duration":"100.994943ms","start":"2026-02-18T05:21:02.017188Z","end":"2026-02-18T05:21:02.118183Z","steps":["trace[1464275850] 'read index received' (duration: 100.85585ms)","trace[1464275850] 'applied index is now lower than readState.Index' (duration: 138.503µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:02.118336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.136677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-18T05:21:02.118357Z","caller":"traceutil/trace.go:171","msg":"trace[1907186241] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:940; }","duration":"101.200808ms","start":"2026-02-18T05:21:02.017149Z","end":"2026-02-18T05:21:02.11835Z","steps":["trace[1907186241] 'agreement among raft nodes before linearized reading' (duration: 101.091946ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:02.118514Z","caller":"traceutil/trace.go:171","msg":"trace[186273406] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"280.462943ms","start":"2026-02-18T05:21:01.838046Z","end":"2026-02-18T05:21:02.118509Z","steps":["trace[186273406] 'process raft request' (duration: 280.035151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:03.226308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.712305ms","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-18T05:21:03.226378Z","caller":"traceutil/trace.go:171","msg":"trace[2121287636] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:940; }","duration":"851.787557ms","start":"2026-02-18T05:21:02.374582Z","end":"2026-02-18T05:21:03.226369Z","steps":["trace[2121287636] 'range keys from in-memory index tree' (duration: 851.703444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:03.226794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.264858ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:21:03.226831Z","caller":"traceutil/trace.go:171","msg":"trace[1811676633] linearizableReadLoop","detail":"{readStateIndex:974; appliedIndex:973; }","duration":"648.112526ms","start":"2026-02-18T05:21:02.578713Z","end":"2026-02-18T05:21:03.226825Z","steps":["trace[1811676633] 'read index received' (duration: 296.722695ms)","trace[1811676633] 'applied index is now lower than readState.Index' (duration: 351.389261ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:21:03.226947Z","caller":"traceutil/trace.go:171","msg":"trace[849874867] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"1.106182259s","start":"2026-02-18T05:21:02.120759Z","end":"2026-02-18T05:21:03.226942Z","steps":["trace[849874867] 'process raft request' (duration: 754.728947ms)","trace[849874867] 'compare' (duration: 351.085803ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:03.226975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:02.120748Z","time spent":"1.10621226s","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:21:03.227042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.330453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:21:03.227054Z","caller":"traceutil/trace.go:171","msg":"trace[978896001] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:941; }","duration":"648.369933ms","start":"2026-02-18T05:21:02.57868Z","end":"2026-02-18T05:21:03.22705Z","steps":["trace[978896001] 'agreement among raft nodes before linearized reading' (duration: 648.349853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:03.227066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:02.578666Z","time spent":"648.397905ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:21:03.227164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.608743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T05:21:03.227177Z","caller":"traceutil/trace.go:171","msg":"trace[963186457] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:941; }","duration":"515.655504ms","start":"2026-02-18T05:21:02.711518Z","end":"2026-02-18T05:21:03.227174Z","steps":["trace[963186457] 'agreement among raft nodes before linearized reading' (duration: 515.608732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:03.227189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:02.711499Z","time spent":"515.686975ms","remote":"127.0.0.1:53066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.169\" "} {"level":"warn","ts":"2026-02-18T05:21:03.227455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.747076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T05:21:03.227469Z","caller":"traceutil/trace.go:171","msg":"trace[1007589458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:941; }","duration":"347.796097ms","start":"2026-02-18T05:21:02.879669Z","end":"2026-02-18T05:21:03.227465Z","steps":["trace[1007589458] 'agreement among raft nodes before linearized reading' (duration: 347.752736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:03.227481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:02.879655Z","time spent":"347.822608ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T05:21:03.56567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.816378ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:21:03.565858Z","caller":"traceutil/trace.go:171","msg":"trace[453612411] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:975; }","duration":"332.080657ms","start":"2026-02-18T05:21:03.233762Z","end":"2026-02-18T05:21:03.565843Z","steps":["trace[453612411] 'read index received' (duration: 31.508771ms)","trace[453612411] 'applied index is now lower than readState.Index' (duration: 300.571006ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:03.566002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.232922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-18T05:21:03.566029Z","caller":"traceutil/trace.go:171","msg":"trace[196185828] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:943; }","duration":"332.275652ms","start":"2026-02-18T05:21:03.233744Z","end":"2026-02-18T05:21:03.56602Z","steps":["trace[196185828] 'agreement among raft nodes before linearized reading' (duration: 332.144349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:03.56606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:03.233737Z","time spent":"332.314983ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-18T05:21:03.56616Z","caller":"traceutil/trace.go:171","msg":"trace[756746368] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"333.218327ms","start":"2026-02-18T05:21:03.232922Z","end":"2026-02-18T05:21:03.56614Z","steps":["trace[756746368] 'process raft request' (duration: 142.823403ms)","trace[756746368] 'compare' (duration: 189.684355ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:21:03.566248Z","caller":"traceutil/trace.go:171","msg":"trace[1151033152] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"333.267518ms","start":"2026-02-18T05:21:03.232968Z","end":"2026-02-18T05:21:03.566236Z","steps":["trace[1151033152] 'process raft request' (duration: 332.802866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:03.566306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:03.232965Z","time spent":"333.307989ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:21:03.566245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:03.232909Z","time spent":"333.294009ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:03.619645Z","caller":"traceutil/trace.go:171","msg":"trace[1938833474] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"353.711752ms","start":"2026-02-18T05:21:03.265899Z","end":"2026-02-18T05:21:03.619611Z","steps":["trace[1938833474] 'process raft request' (duration: 353.599729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:03.62005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:03.265886Z","time spent":"354.093251ms","remote":"127.0.0.1:53066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:21:04.279086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.766097ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:21:04.27927Z","caller":"traceutil/trace.go:171","msg":"trace[1710142373] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:978; }","duration":"684.798793ms","start":"2026-02-18T05:21:03.594452Z","end":"2026-02-18T05:21:04.279251Z","steps":["trace[1710142373] 'read index received' (duration: 25.401202ms)","trace[1710142373] 'applied index is now lower than readState.Index' (duration: 659.396251ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:21:04.279371Z","caller":"traceutil/trace.go:171","msg":"trace[5073975] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"703.993054ms","start":"2026-02-18T05:21:03.575362Z","end":"2026-02-18T05:21:04.279355Z","steps":["trace[5073975] 'process raft request' (duration: 210.867868ms)","trace[5073975] 'compare' (duration: 492.586372ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:21:04.279452Z","caller":"traceutil/trace.go:171","msg":"trace[491652272] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"604.90123ms","start":"2026-02-18T05:21:03.674532Z","end":"2026-02-18T05:21:04.279433Z","steps":["trace[491652272] 'process raft request' (duration: 604.659524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:04.279567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.103931ms","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":"warn","ts":"2026-02-18T05:21:04.279594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:03.674516Z","time spent":"604.976063ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:04.279616Z","caller":"traceutil/trace.go:171","msg":"trace[1699236385] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:946; }","duration":"685.153873ms","start":"2026-02-18T05:21:03.594448Z","end":"2026-02-18T05:21:04.279602Z","steps":["trace[1699236385] 'agreement among raft nodes before linearized reading' (duration: 685.03601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:04.279661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:03.594408Z","time spent":"685.238635ms","remote":"127.0.0.1:53274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-18T05:21:04.279454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:03.575344Z","time spent":"704.062376ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:21:04.279889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.769889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-18T05:21:04.279931Z","caller":"traceutil/trace.go:171","msg":"trace[534884813] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:946; }","duration":"657.841581ms","start":"2026-02-18T05:21:03.622077Z","end":"2026-02-18T05:21:04.279919Z","steps":["trace[534884813] 'agreement among raft nodes before linearized reading' (duration: 657.762859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:04.27997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:03.622063Z","time spent":"657.895542ms","remote":"127.0.0.1:53206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-18T05:21:04.280317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.871482ms","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-18T05:21:04.280364Z","caller":"traceutil/trace.go:171","msg":"trace[1003506103] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:946; }","duration":"158.949674ms","start":"2026-02-18T05:21:04.121401Z","end":"2026-02-18T05:21:04.280351Z","steps":["trace[1003506103] 'agreement among raft nodes before linearized reading' (duration: 158.80436ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:04.499298Z","caller":"traceutil/trace.go:171","msg":"trace[582390646] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:980; }","duration":"210.794285ms","start":"2026-02-18T05:21:04.288482Z","end":"2026-02-18T05:21:04.499276Z","steps":["trace[582390646] 'read index received' (duration: 116.146877ms)","trace[582390646] 'applied index is now lower than readState.Index' (duration: 94.646518ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:04.499439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.932318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-18T05:21:04.499478Z","caller":"traceutil/trace.go:171","msg":"trace[903563539] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:947; }","duration":"211.00206ms","start":"2026-02-18T05:21:04.288465Z","end":"2026-02-18T05:21:04.499467Z","steps":["trace[903563539] 'agreement among raft nodes before linearized reading' (duration: 210.888457ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:04.499814Z","caller":"traceutil/trace.go:171","msg":"trace[346680929] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"211.516663ms","start":"2026-02-18T05:21:04.288282Z","end":"2026-02-18T05:21:04.499799Z","steps":["trace[346680929] 'process raft request' (duration: 116.410984ms)","trace[346680929] 'compare' (duration: 94.312579ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:21:05.477538Z","caller":"traceutil/trace.go:171","msg":"trace[82262495] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"244.870464ms","start":"2026-02-18T05:21:05.232647Z","end":"2026-02-18T05:21:05.477517Z","steps":["trace[82262495] 'process raft request' (duration: 244.74233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:05.723253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.407621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-18T05:21:05.723299Z","caller":"traceutil/trace.go:171","msg":"trace[1106261417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:948; }","duration":"150.471153ms","start":"2026-02-18T05:21:05.572818Z","end":"2026-02-18T05:21:05.723289Z","steps":["trace[1106261417] 'range keys from in-memory index tree' (duration: 150.319389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:05.723518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.72761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T05:21:05.723564Z","caller":"traceutil/trace.go:171","msg":"trace[1442156697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:948; }","duration":"150.843443ms","start":"2026-02-18T05:21:05.572708Z","end":"2026-02-18T05:21:05.723552Z","steps":["trace[1442156697] 'range keys from in-memory index tree' (duration: 150.554455ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:06.236263Z","caller":"traceutil/trace.go:171","msg":"trace[1702141037] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"510.327995ms","start":"2026-02-18T05:21:05.725906Z","end":"2026-02-18T05:21:06.236234Z","steps":["trace[1702141037] 'process raft request' (duration: 510.14713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:06.236507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:05.725893Z","time spent":"510.464338ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:06.247341Z","caller":"traceutil/trace.go:171","msg":"trace[1180300907] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"507.403898ms","start":"2026-02-18T05:21:05.739909Z","end":"2026-02-18T05:21:06.247313Z","steps":["trace[1180300907] 'process raft request' (duration: 500.933789ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:06.247503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:05.739894Z","time spent":"507.511411ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:21:06.249392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.989125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:21:06.249455Z","caller":"traceutil/trace.go:171","msg":"trace[1903727216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:950; }","duration":"393.092138ms","start":"2026-02-18T05:21:05.856342Z","end":"2026-02-18T05:21:06.249434Z","steps":["trace[1903727216] 'agreement among raft nodes before linearized reading' (duration: 392.955445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:06.249507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:05.856292Z","time spent":"393.208161ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T05:21:06.249752Z","caller":"traceutil/trace.go:171","msg":"trace[1782422771] linearizableReadLoop","detail":"{readStateIndex:984; appliedIndex:983; }","duration":"384.552406ms","start":"2026-02-18T05:21:05.856348Z","end":"2026-02-18T05:21:06.2409Z","steps":["trace[1782422771] 'read index received' (duration: 380.638554ms)","trace[1782422771] 'applied index is now lower than readState.Index' (duration: 3.913362ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:21:06.68928Z","caller":"traceutil/trace.go:171","msg":"trace[581961890] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"399.858595ms","start":"2026-02-18T05:21:06.289402Z","end":"2026-02-18T05:21:06.68926Z","steps":["trace[581961890] 'process raft request' (duration: 323.189296ms)","trace[581961890] 'compare' (duration: 76.181086ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:06.689384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:06.289389Z","time spent":"399.940827ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:06.689488Z","caller":"traceutil/trace.go:171","msg":"trace[670777501] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:984; }","duration":"183.952086ms","start":"2026-02-18T05:21:06.505528Z","end":"2026-02-18T05:21:06.68948Z","steps":["trace[670777501] 'read index received' (duration: 107.070022ms)","trace[670777501] 'applied index is now lower than readState.Index' (duration: 76.881464ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:06.689662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.13447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T05:21:06.689691Z","caller":"traceutil/trace.go:171","msg":"trace[244305534] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:952; }","duration":"184.188272ms","start":"2026-02-18T05:21:06.505496Z","end":"2026-02-18T05:21:06.689684Z","steps":["trace[244305534] 'agreement among raft nodes before linearized reading' (duration: 184.09718ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:09.693397Z","caller":"traceutil/trace.go:171","msg":"trace[900337609] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"201.343216ms","start":"2026-02-18T05:21:09.492036Z","end":"2026-02-18T05:21:09.693379Z","steps":["trace[900337609] 'process raft request' (duration: 201.240143ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:11.859718Z","caller":"traceutil/trace.go:171","msg":"trace[1411908125] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"160.429934ms","start":"2026-02-18T05:21:11.699274Z","end":"2026-02-18T05:21:11.859704Z","steps":["trace[1411908125] 'process raft request' (duration: 160.351152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:12.586692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.882252ms","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-18T05:21:12.586796Z","caller":"traceutil/trace.go:171","msg":"trace[1863200971] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:977; }","duration":"211.995605ms","start":"2026-02-18T05:21:12.374789Z","end":"2026-02-18T05:21:12.586784Z","steps":["trace[1863200971] 'range keys from in-memory index tree' (duration: 211.871732ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:12.630363Z","caller":"traceutil/trace.go:171","msg":"trace[232596381] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1012; }","duration":"196.463208ms","start":"2026-02-18T05:21:12.433882Z","end":"2026-02-18T05:21:12.630345Z","steps":["trace[232596381] 'read index received' (duration: 196.340425ms)","trace[232596381] 'applied index is now lower than readState.Index' (duration: 121.673µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:21:12.630379Z","caller":"traceutil/trace.go:171","msg":"trace[165622106] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"453.615085ms","start":"2026-02-18T05:21:12.176741Z","end":"2026-02-18T05:21:12.630356Z","steps":["trace[165622106] 'process raft request' (duration: 453.496282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:12.630492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:12.176725Z","time spent":"453.714978ms","remote":"127.0.0.1:53114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":782,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T05:21:12.630561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.661363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T05:21:12.630585Z","caller":"traceutil/trace.go:171","msg":"trace[463747962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:978; }","duration":"196.734095ms","start":"2026-02-18T05:21:12.433844Z","end":"2026-02-18T05:21:12.630578Z","steps":["trace[463747962] 'agreement among raft nodes before linearized reading' (duration: 196.599272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:12.630814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.925262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T05:21:12.630833Z","caller":"traceutil/trace.go:171","msg":"trace[389752536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:978; }","duration":"177.974463ms","start":"2026-02-18T05:21:12.452853Z","end":"2026-02-18T05:21:12.630828Z","steps":["trace[389752536] 'agreement among raft nodes before linearized reading' (duration: 177.920081ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:12.759049Z","caller":"traceutil/trace.go:171","msg":"trace[2081195037] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"122.776017ms","start":"2026-02-18T05:21:12.636258Z","end":"2026-02-18T05:21:12.759034Z","steps":["trace[2081195037] 'process raft request' (duration: 122.676294ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:12.829991Z","caller":"traceutil/trace.go:171","msg":"trace[1714645581] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1015; }","duration":"117.826298ms","start":"2026-02-18T05:21:12.712146Z","end":"2026-02-18T05:21:12.829973Z","steps":["trace[1714645581] 'read index received' (duration: 47.224378ms)","trace[1714645581] 'applied index is now lower than readState.Index' (duration: 70.60103ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:21:12.830036Z","caller":"traceutil/trace.go:171","msg":"trace[454254905] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"151.013537ms","start":"2026-02-18T05:21:12.678975Z","end":"2026-02-18T05:21:12.829988Z","steps":["trace[454254905] 'process raft request' (duration: 150.904254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:12.830185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.027983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T05:21:12.830209Z","caller":"traceutil/trace.go:171","msg":"trace[688574745] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:981; }","duration":"118.168197ms","start":"2026-02-18T05:21:12.712034Z","end":"2026-02-18T05:21:12.830202Z","steps":["trace[688574745] 'agreement among raft nodes before linearized reading' (duration: 118.032223ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:33.890372Z","caller":"traceutil/trace.go:171","msg":"trace[49869244] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"105.430843ms","start":"2026-02-18T05:21:33.784916Z","end":"2026-02-18T05:21:33.890347Z","steps":["trace[49869244] 'process raft request' (duration: 105.295539ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:23:08.962258Z","caller":"traceutil/trace.go:171","msg":"trace[403741660] linearizableReadLoop","detail":"{readStateIndex:1592; appliedIndex:1591; }","duration":"389.412784ms","start":"2026-02-18T05:23:08.572811Z","end":"2026-02-18T05:23:08.962223Z","steps":["trace[403741660] 'read index received' (duration: 389.223039ms)","trace[403741660] 'applied index is now lower than readState.Index' (duration: 189.015µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:23:08.962526Z","caller":"traceutil/trace.go:171","msg":"trace[479264970] transaction","detail":"{read_only:false; response_revision:1533; number_of_response:1; }","duration":"514.31377ms","start":"2026-02-18T05:23:08.448201Z","end":"2026-02-18T05:23:08.962514Z","steps":["trace[479264970] 'process raft request' (duration: 513.900129ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:23:08.962618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:23:08.448185Z","time spent":"514.369712ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:23:08.962766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.952468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:23:08.962797Z","caller":"traceutil/trace.go:171","msg":"trace[1295745589] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1533; }","duration":"389.99735ms","start":"2026-02-18T05:23:08.572791Z","end":"2026-02-18T05:23:08.962789Z","steps":["trace[1295745589] 'agreement among raft nodes before linearized reading' (duration: 389.951999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:23:08.962823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:23:08.572783Z","time spent":"390.035461ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:23:09.846734Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826754807797986,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:23:10.347398Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826754807797986,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:23:10.672902Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.703131364s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T05:23:10.680671Z","caller":"traceutil/trace.go:171","msg":"trace[2008823106] linearizableReadLoop","detail":"{readStateIndex:1594; appliedIndex:1592; }","duration":"1.334363332s","start":"2026-02-18T05:23:09.346293Z","end":"2026-02-18T05:23:10.680657Z","steps":["trace[2008823106] 'read index received' (duration: 1.329019742s)","trace[2008823106] 'applied index is now lower than readState.Index' (duration: 5.34262ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:23:10.680845Z","caller":"traceutil/trace.go:171","msg":"trace[936514488] transaction","detail":"{read_only:false; response_revision:1534; number_of_response:1; }","duration":"1.711125214s","start":"2026-02-18T05:23:08.969697Z","end":"2026-02-18T05:23:10.680822Z","steps":["trace[936514488] 'process raft request' (duration: 1.705609119s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:23:10.680921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:23:08.969683Z","time spent":"1.711186436s","remote":"127.0.0.1:53144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":606,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:23:10.681092Z","caller":"traceutil/trace.go:171","msg":"trace[1107188430] transaction","detail":"{read_only:false; response_revision:1535; number_of_response:1; }","duration":"900.860959ms","start":"2026-02-18T05:23:09.780224Z","end":"2026-02-18T05:23:10.681085Z","steps":["trace[1107188430] 'process raft request' (duration: 900.396406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:23:10.681128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:23:09.780208Z","time spent":"900.89906ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:23:10.681262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.334968698s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T05:23:10.681297Z","caller":"traceutil/trace.go:171","msg":"trace[169424104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1535; }","duration":"1.33504001s","start":"2026-02-18T05:23:09.346252Z","end":"2026-02-18T05:23:10.681292Z","steps":["trace[169424104] 'agreement among raft nodes before linearized reading' (duration: 1.334946827s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:23:10.681317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:23:09.34623Z","time spent":"1.335083272s","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T05:23:10.681428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.310401311s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T05:23:10.681445Z","caller":"traceutil/trace.go:171","msg":"trace[500300904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1535; }","duration":"1.310436193s","start":"2026-02-18T05:23:09.371004Z","end":"2026-02-18T05:23:10.68144Z","steps":["trace[500300904] 'agreement among raft nodes before linearized reading' (duration: 1.310388672s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:23:10.681462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:23:09.370977Z","time spent":"1.310481334s","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T05:23:10.682424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.107462244s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:23:10.682629Z","caller":"traceutil/trace.go:171","msg":"trace[543306955] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1535; }","duration":"1.107530664s","start":"2026-02-18T05:23:09.574919Z","end":"2026-02-18T05:23:10.68245Z","steps":["trace[543306955] 'agreement among raft nodes before linearized reading' (duration: 1.107479673s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:23:10.682777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:23:09.574881Z","time spent":"1.107865224s","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:23:10.687468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.243273546s","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-18T05:23:10.687535Z","caller":"traceutil/trace.go:171","msg":"trace[1415199269] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1535; }","duration":"1.243354268s","start":"2026-02-18T05:23:09.444171Z","end":"2026-02-18T05:23:10.687525Z","steps":["trace[1415199269] 'agreement among raft nodes before linearized reading' (duration: 1.243198674s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:23:10.687591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:23:09.444165Z","time spent":"1.243390979s","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T05:23:10.68969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.238231694s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-18T05:23:10.689729Z","caller":"traceutil/trace.go:171","msg":"trace[615487179] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1535; }","duration":"1.238315386s","start":"2026-02-18T05:23:09.451404Z","end":"2026-02-18T05:23:10.68972Z","steps":["trace[615487179] 'agreement among raft nodes before linearized reading' (duration: 1.236340764s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:23:10.689783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:23:09.451395Z","time spent":"1.238375977s","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T05:23:10.692379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.247273961s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T05:23:10.692458Z","caller":"traceutil/trace.go:171","msg":"trace[1361228415] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1535; }","duration":"1.249355796s","start":"2026-02-18T05:23:09.443092Z","end":"2026-02-18T05:23:10.692447Z","steps":["trace[1361228415] 'agreement among raft nodes before linearized reading' (duration: 1.247254461s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:23:10.692518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:23:09.443078Z","time spent":"1.249401327s","remote":"127.0.0.1:53308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-02-18T05:23:37.076526Z","caller":"traceutil/trace.go:171","msg":"trace[386538704] transaction","detail":"{read_only:false; response_revision:1657; number_of_response:1; }","duration":"135.786775ms","start":"2026-02-18T05:23:36.940711Z","end":"2026-02-18T05:23:37.076498Z","steps":["trace[386538704] 'process raft request' (duration: 134.501351ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:23:46.263975Z","caller":"traceutil/trace.go:171","msg":"trace[1723234992] linearizableReadLoop","detail":"{readStateIndex:1760; appliedIndex:1759; }","duration":"428.532505ms","start":"2026-02-18T05:23:45.835424Z","end":"2026-02-18T05:23:46.263957Z","steps":["trace[1723234992] 'read index received' (duration: 428.3282ms)","trace[1723234992] 'applied index is now lower than readState.Index' (duration: 203.285µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:23:46.264084Z","caller":"traceutil/trace.go:171","msg":"trace[1021804673] transaction","detail":"{read_only:false; response_revision:1694; number_of_response:1; }","duration":"433.326732ms","start":"2026-02-18T05:23:45.830749Z","end":"2026-02-18T05:23:46.264076Z","steps":["trace[1021804673] 'process raft request' (duration: 433.065395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:23:46.26418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:23:45.830733Z","time spent":"433.371344ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:23:46.26449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.061959ms","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-18T05:23:46.264514Z","caller":"traceutil/trace.go:171","msg":"trace[951374139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1694; }","duration":"429.108701ms","start":"2026-02-18T05:23:45.835397Z","end":"2026-02-18T05:23:46.264505Z","steps":["trace[951374139] 'agreement among raft nodes before linearized reading' (duration: 428.961167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:23:46.264535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:23:45.835385Z","time spent":"429.143721ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T05:23:46.264669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.934378ms","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-18T05:23:46.264686Z","caller":"traceutil/trace.go:171","msg":"trace[1114556928] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1694; }","duration":"410.968649ms","start":"2026-02-18T05:23:45.853712Z","end":"2026-02-18T05:23:46.264681Z","steps":["trace[1114556928] 'agreement among raft nodes before linearized reading' (duration: 410.900978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:23:46.264702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:23:45.853705Z","time spent":"410.99388ms","remote":"127.0.0.1:53304","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-18T05:23:46.26483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.81833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:23:46.264851Z","caller":"traceutil/trace.go:171","msg":"trace[1918602197] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1694; }","duration":"365.886212ms","start":"2026-02-18T05:23:45.898958Z","end":"2026-02-18T05:23:46.264845Z","steps":["trace[1918602197] 'agreement among raft nodes before linearized reading' (duration: 365.851631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:23:46.26487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:23:45.898938Z","time spent":"365.925313ms","remote":"127.0.0.1:53198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-02-18T05:23:46.264996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.656622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-02-18T05:23:46.265012Z","caller":"traceutil/trace.go:171","msg":"trace[9677549] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1694; }","duration":"378.684722ms","start":"2026-02-18T05:23:45.886322Z","end":"2026-02-18T05:23:46.265007Z","steps":["trace[9677549] 'agreement among raft nodes before linearized reading' (duration: 378.633251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:23:46.265028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:23:45.886314Z","time spent":"378.710732ms","remote":"127.0.0.1:53144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":644,"request content":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-18T05:24:03.317013Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826754807798616,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T05:24:03.653816Z","caller":"traceutil/trace.go:171","msg":"trace[740529267] linearizableReadLoop","detail":"{readStateIndex:1842; appliedIndex:1841; }","duration":"837.279274ms","start":"2026-02-18T05:24:02.816494Z","end":"2026-02-18T05:24:03.653773Z","steps":["trace[740529267] 'read index received' (duration: 837.11315ms)","trace[740529267] 'applied index is now lower than readState.Index' (duration: 164.554µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:24:03.654061Z","caller":"traceutil/trace.go:171","msg":"trace[1416181668] transaction","detail":"{read_only:false; response_revision:1772; number_of_response:1; }","duration":"863.157181ms","start":"2026-02-18T05:24:02.790882Z","end":"2026-02-18T05:24:03.654039Z","steps":["trace[1416181668] 'process raft request' (duration: 862.631857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:24:03.654301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:24:02.790865Z","time spent":"863.261574ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:24:03.654966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.237914ms","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-18T05:24:03.655174Z","caller":"traceutil/trace.go:171","msg":"trace[1892649447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1772; }","duration":"837.506891ms","start":"2026-02-18T05:24:02.817634Z","end":"2026-02-18T05:24:03.655141Z","steps":["trace[1892649447] 'agreement among raft nodes before linearized reading' (duration: 837.003847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:24:03.655212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.717682ms","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-18T05:24:03.65533Z","caller":"traceutil/trace.go:171","msg":"trace[1256748373] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1772; }","duration":"838.825185ms","start":"2026-02-18T05:24:02.816484Z","end":"2026-02-18T05:24:03.655309Z","steps":["trace[1256748373] 'agreement among raft nodes before linearized reading' (duration: 838.64752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:24:03.655387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:24:02.816377Z","time spent":"838.99684ms","remote":"127.0.0.1:53304","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-18T05:24:03.655926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.306259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-18T05:24:03.655982Z","caller":"traceutil/trace.go:171","msg":"trace[1925987362] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1772; }","duration":"697.404672ms","start":"2026-02-18T05:24:02.958564Z","end":"2026-02-18T05:24:03.655968Z","steps":["trace[1925987362] 'agreement among raft nodes before linearized reading' (duration: 697.262618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:24:03.656024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:24:02.958546Z","time spent":"697.465143ms","remote":"127.0.0.1:53304","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-18T05:24:03.656808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.822134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-02-18T05:24:03.656857Z","caller":"traceutil/trace.go:171","msg":"trace[1395064729] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1772; }","duration":"746.908416ms","start":"2026-02-18T05:24:02.909935Z","end":"2026-02-18T05:24:03.656844Z","steps":["trace[1395064729] 'agreement among raft nodes before linearized reading' (duration: 746.398422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:24:03.656905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:24:02.909917Z","time spent":"746.979157ms","remote":"127.0.0.1:53144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":644,"request content":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-18T05:24:03.655091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.712467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-18T05:24:03.657571Z","caller":"traceutil/trace.go:171","msg":"trace[253205414] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1772; }","duration":"672.222873ms","start":"2026-02-18T05:24:02.985337Z","end":"2026-02-18T05:24:03.657559Z","steps":["trace[253205414] 'agreement among raft nodes before linearized reading' (duration: 669.595403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:24:03.657609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:24:02.985324Z","time spent":"672.273445ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T05:24:03.660147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:24:02.81761Z","time spent":"837.727367ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-18T05:24:34.047487Z","caller":"traceutil/trace.go:171","msg":"trace[692740558] transaction","detail":"{read_only:false; response_revision:1938; number_of_response:1; }","duration":"150.620845ms","start":"2026-02-18T05:24:33.89684Z","end":"2026-02-18T05:24:34.047461Z","steps":["trace[692740558] 'process raft request' (duration: 78.370449ms)","trace[692740558] 'compare' (duration: 72.038791ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:24:34.048022Z","caller":"traceutil/trace.go:171","msg":"trace[2069576862] transaction","detail":"{read_only:false; response_revision:1939; number_of_response:1; }","duration":"145.869719ms","start":"2026-02-18T05:24:33.902048Z","end":"2026-02-18T05:24:34.047918Z","steps":["trace[2069576862] 'process raft request' (duration: 145.354996ms)"],"step_count":1}