{"level":"warn","ts":"2026-02-18T14:29:00.502095Z","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-18T14:29:00.502186Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.212:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.212:2380","--initial-cluster=instance=https://162.253.55.212:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.212:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.212: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-18T14:29:00.502253Z","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-18T14:29:00.50226Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.212:2380"]} {"level":"info","ts":"2026-02-18T14:29:00.502279Z","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-18T14:29:00.502857Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"]} {"level":"info","ts":"2026-02-18T14:29:00.502919Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.212: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-18T14:29:00.508184Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.072427ms"} {"level":"info","ts":"2026-02-18T14:29:00.516842Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d078b1e39c21939c","cluster-id":"cdc9ba8de57e3589"} {"level":"info","ts":"2026-02-18T14:29:00.516927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T14:29:00.516971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 0"} {"level":"info","ts":"2026-02-18T14:29:00.516997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d078b1e39c21939c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T14:29:00.517012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 1"} {"level":"info","ts":"2026-02-18T14:29:00.517106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"} {"level":"warn","ts":"2026-02-18T14:29:00.526135Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T14:29:00.529472Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T14:29:00.530723Z","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-18T14:29:00.532629Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d078b1e39c21939c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T14:29:00.536445Z","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-18T14:29:00.536869Z","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-18T14:29:00.536932Z","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-18T14:29:00.537276Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d078b1e39c21939c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T14:29:00.539348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"} {"level":"info","ts":"2026-02-18T14:29:00.539476Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","added-peer-id":"d078b1e39c21939c","added-peer-peer-urls":["https://162.253.55.212:2380"]} {"level":"info","ts":"2026-02-18T14:29:00.539624Z","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-18T14:29:00.539735Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.212:2380"} {"level":"info","ts":"2026-02-18T14:29:00.539782Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.212:2380"} {"level":"info","ts":"2026-02-18T14:29:00.540848Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d078b1e39c21939c","initial-advertise-peer-urls":["https://162.253.55.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T14:29:00.543019Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T14:29:01.517548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T14:29:01.517595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T14:29:01.517621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgPreVoteResp from d078b1e39c21939c at term 1"} {"level":"info","ts":"2026-02-18T14:29:01.517645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became candidate at term 2"} {"level":"info","ts":"2026-02-18T14:29:01.517651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgVoteResp from d078b1e39c21939c at term 2"} {"level":"info","ts":"2026-02-18T14:29:01.517662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became leader at term 2"} {"level":"info","ts":"2026-02-18T14:29:01.517669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d078b1e39c21939c elected leader d078b1e39c21939c at term 2"} {"level":"info","ts":"2026-02-18T14:29:01.518852Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T14:29:01.520011Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T14:29:01.520082Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T14:29:01.520112Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T14:29:01.52014Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d078b1e39c21939c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.212:2379]}","request-path":"/0/members/d078b1e39c21939c/attributes","cluster-id":"cdc9ba8de57e3589","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T14:29:01.520155Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T14:29:01.520488Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T14:29:01.521995Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-18T14:29:01.523826Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.212:2379"} {"level":"info","ts":"2026-02-18T14:29:01.535801Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T14:29:01.535885Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-02-18T14:29:21.512123Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548429750331602,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T14:29:21.732082Z","caller":"traceutil/trace.go:171","msg":"trace[1189939560] linearizableReadLoop","detail":"{readStateIndex:468; appliedIndex:467; }","duration":"720.804565ms","start":"2026-02-18T14:29:21.011251Z","end":"2026-02-18T14:29:21.732055Z","steps":["trace[1189939560] 'read index received' (duration: 720.599781ms)","trace[1189939560] 'applied index is now lower than readState.Index' (duration: 203.844µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:29:21.73221Z","caller":"traceutil/trace.go:171","msg":"trace[971124877] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"723.402416ms","start":"2026-02-18T14:29:21.008798Z","end":"2026-02-18T14:29:21.732201Z","steps":["trace[971124877] 'process raft request' (duration: 723.119669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:29:21.732483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.810801ms","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-18T14:29:21.732597Z","caller":"traceutil/trace.go:171","msg":"trace[138666791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:455; }","duration":"710.978135ms","start":"2026-02-18T14:29:21.021602Z","end":"2026-02-18T14:29:21.73258Z","steps":["trace[138666791] 'agreement among raft nodes before linearized reading' (duration: 710.79624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:29:21.732657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:29:21.021592Z","time spent":"711.055248ms","remote":"127.0.0.1:53840","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-18T14:29:21.732773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:29:21.008782Z","time spent":"723.455068ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:29:21.732919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.667766ms","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-18T14:29:21.732975Z","caller":"traceutil/trace.go:171","msg":"trace[292022725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:455; }","duration":"721.733718ms","start":"2026-02-18T14:29:21.011208Z","end":"2026-02-18T14:29:21.732942Z","steps":["trace[292022725] 'agreement among raft nodes before linearized reading' (duration: 721.681477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:29:21.733005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:29:21.011202Z","time spent":"721.794429ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-18T14:29:22.015769Z","caller":"traceutil/trace.go:171","msg":"trace[1946685593] linearizableReadLoop","detail":"{readStateIndex:470; appliedIndex:468; }","duration":"224.578869ms","start":"2026-02-18T14:29:21.791169Z","end":"2026-02-18T14:29:22.015748Z","steps":["trace[1946685593] 'read index received' (duration: 222.694994ms)","trace[1946685593] 'applied index is now lower than readState.Index' (duration: 1.883065ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:29:22.015867Z","caller":"traceutil/trace.go:171","msg":"trace[2091721015] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"275.978141ms","start":"2026-02-18T14:29:21.73988Z","end":"2026-02-18T14:29:22.015858Z","steps":["trace[2091721015] 'process raft request' (duration: 273.862292ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:29:22.016118Z","caller":"traceutil/trace.go:171","msg":"trace[1039903610] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"276.052663ms","start":"2026-02-18T14:29:21.740055Z","end":"2026-02-18T14:29:22.016108Z","steps":["trace[1039903610] 'process raft request' (duration: 275.596143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:29:22.016264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.07688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:29:22.016293Z","caller":"traceutil/trace.go:171","msg":"trace[1048633596] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:457; }","duration":"225.139191ms","start":"2026-02-18T14:29:21.791145Z","end":"2026-02-18T14:29:22.016284Z","steps":["trace[1048633596] 'agreement among raft nodes before linearized reading' (duration: 225.08083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:29:42.293777Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548429750331798,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:29:42.51856Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.37923326s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T14:29:42.519092Z","caller":"traceutil/trace.go:171","msg":"trace[952143516] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:557; }","duration":"726.045238ms","start":"2026-02-18T14:29:41.793024Z","end":"2026-02-18T14:29:42.51907Z","steps":["trace[952143516] 'read index received' (duration: 725.759592ms)","trace[952143516] 'applied index is now lower than readState.Index' (duration: 284.796µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:29:42.519338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.290154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:29:42.519419Z","caller":"traceutil/trace.go:171","msg":"trace[104270772] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:541; }","duration":"726.414937ms","start":"2026-02-18T14:29:41.79298Z","end":"2026-02-18T14:29:42.519395Z","steps":["trace[104270772] 'agreement among raft nodes before linearized reading' (duration: 726.266223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:29:42.519471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:29:41.792958Z","time spent":"726.501258ms","remote":"127.0.0.1:53572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:29:42.519608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.28705ms","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-18T14:29:42.519651Z","caller":"traceutil/trace.go:171","msg":"trace[97071399] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:541; }","duration":"646.381632ms","start":"2026-02-18T14:29:41.873256Z","end":"2026-02-18T14:29:42.519638Z","steps":["trace[97071399] 'agreement among raft nodes before linearized reading' (duration: 646.29235ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:29:42.519723Z","caller":"traceutil/trace.go:171","msg":"trace[181592210] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.380477679s","start":"2026-02-18T14:29:41.139233Z","end":"2026-02-18T14:29:42.519711Z","steps":["trace[181592210] 'process raft request' (duration: 1.3796295s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:29:42.519684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:29:41.873236Z","time spent":"646.440703ms","remote":"127.0.0.1:53840","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-18T14:29:42.519807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:29:41.139218Z","time spent":"1.38053427s","remote":"127.0.0.1:53756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:29:42.520207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.089632ms","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-18T14:29:42.520242Z","caller":"traceutil/trace.go:171","msg":"trace[2087928019] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:541; }","duration":"352.191255ms","start":"2026-02-18T14:29:42.168039Z","end":"2026-02-18T14:29:42.52023Z","steps":["trace[2087928019] 'agreement among raft nodes before linearized reading' (duration: 352.119833ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:29:42.52027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:29:42.168025Z","time spent":"352.238646ms","remote":"127.0.0.1:53840","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-18T14:29:42.520484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.599551ms","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-18T14:29:42.520512Z","caller":"traceutil/trace.go:171","msg":"trace[1264069985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:541; }","duration":"401.662992ms","start":"2026-02-18T14:29:42.118841Z","end":"2026-02-18T14:29:42.520504Z","steps":["trace[1264069985] 'agreement among raft nodes before linearized reading' (duration: 401.615761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:29:42.52054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:29:42.118822Z","time spent":"401.712233ms","remote":"127.0.0.1:53840","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-18T14:29:42.520618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.495975ms","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-18T14:29:42.52064Z","caller":"traceutil/trace.go:171","msg":"trace[2128705031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:541; }","duration":"397.547566ms","start":"2026-02-18T14:29:42.123085Z","end":"2026-02-18T14:29:42.520633Z","steps":["trace[2128705031] 'agreement among raft nodes before linearized reading' (duration: 397.511356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:29:42.520665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:29:42.12306Z","time spent":"397.590777ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T14:29:44.877503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.840839ms","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-18T14:29:44.87758Z","caller":"traceutil/trace.go:171","msg":"trace[1123603725] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:557; }","duration":"322.960422ms","start":"2026-02-18T14:29:44.554599Z","end":"2026-02-18T14:29:44.877559Z","steps":["trace[1123603725] 'range keys from in-memory index tree' (duration: 322.673084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:29:44.877614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:29:44.554581Z","time spent":"323.024494ms","remote":"127.0.0.1:53840","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-18T14:29:44.877753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.61705ms","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-18T14:29:44.877781Z","caller":"traceutil/trace.go:171","msg":"trace[619414365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:557; }","duration":"321.681012ms","start":"2026-02-18T14:29:44.556091Z","end":"2026-02-18T14:29:44.877772Z","steps":["trace[619414365] 'range keys from in-memory index tree' (duration: 321.543518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:29:44.877824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:29:44.556081Z","time spent":"321.734223ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T14:29:44.877962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.529658ms","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-18T14:29:44.877989Z","caller":"traceutil/trace.go:171","msg":"trace[359664396] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:557; }","duration":"321.571348ms","start":"2026-02-18T14:29:44.55641Z","end":"2026-02-18T14:29:44.877981Z","steps":["trace[359664396] 'range keys from in-memory index tree' (duration: 321.386414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:29:44.878013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:29:44.556392Z","time spent":"321.61522ms","remote":"127.0.0.1:53840","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-18T14:29:44.87816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.002995ms","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-18T14:29:44.878186Z","caller":"traceutil/trace.go:171","msg":"trace[204272232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:557; }","duration":"321.045657ms","start":"2026-02-18T14:29:44.557133Z","end":"2026-02-18T14:29:44.878179Z","steps":["trace[204272232] 'range keys from in-memory index tree' (duration: 320.912443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:29:44.878222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:29:44.557124Z","time spent":"321.091518ms","remote":"127.0.0.1:53840","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-18T14:29:59.038843Z","caller":"traceutil/trace.go:171","msg":"trace[1436147190] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"763.62835ms","start":"2026-02-18T14:29:58.275204Z","end":"2026-02-18T14:29:59.038833Z","steps":["trace[1436147190] 'process raft request' (duration: 763.343693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:29:59.03903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:29:58.275183Z","time spent":"763.745482ms","remote":"127.0.0.1:53654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":723,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T14:29:59.038791Z","caller":"traceutil/trace.go:171","msg":"trace[250539866] linearizableReadLoop","detail":"{readStateIndex:740; appliedIndex:739; }","duration":"246.291566ms","start":"2026-02-18T14:29:58.792478Z","end":"2026-02-18T14:29:59.038769Z","steps":["trace[250539866] 'read index received' (duration: 245.998709ms)","trace[250539866] 'applied index is now lower than readState.Index' (duration: 292.027µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:29:59.039384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.89992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:29:59.039654Z","caller":"traceutil/trace.go:171","msg":"trace[1054671287] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:719; }","duration":"246.988212ms","start":"2026-02-18T14:29:58.792424Z","end":"2026-02-18T14:29:59.039413Z","steps":["trace[1054671287] 'agreement among raft nodes before linearized reading' (duration: 246.766097ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:30:00.837901Z","caller":"traceutil/trace.go:171","msg":"trace[1857181393] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"355.198596ms","start":"2026-02-18T14:30:00.482689Z","end":"2026-02-18T14:30:00.837888Z","steps":["trace[1857181393] 'process raft request' (duration: 354.942271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:00.837988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:00.482678Z","time spent":"355.267398ms","remote":"127.0.0.1:53756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18047,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:30:00.955032Z","caller":"traceutil/trace.go:171","msg":"trace[916516621] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"112.170216ms","start":"2026-02-18T14:30:00.842737Z","end":"2026-02-18T14:30:00.954907Z","steps":["trace[916516621] 'process raft request' (duration: 109.294949ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:30:00.95568Z","caller":"traceutil/trace.go:171","msg":"trace[512548296] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"105.730456ms","start":"2026-02-18T14:30:00.849942Z","end":"2026-02-18T14:30:00.955672Z","steps":["trace[512548296] 'process raft request' (duration: 105.700525ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:30:00.955922Z","caller":"traceutil/trace.go:171","msg":"trace[1569793761] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"106.640146ms","start":"2026-02-18T14:30:00.849265Z","end":"2026-02-18T14:30:00.955905Z","steps":["trace[1569793761] 'process raft request' (duration: 106.320709ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:30:03.368568Z","caller":"traceutil/trace.go:171","msg":"trace[980550687] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:756; }","duration":"209.894974ms","start":"2026-02-18T14:30:03.158649Z","end":"2026-02-18T14:30:03.368544Z","steps":["trace[980550687] 'read index received' (duration: 209.747451ms)","trace[980550687] 'applied index is now lower than readState.Index' (duration: 146.463µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:30:03.368689Z","caller":"traceutil/trace.go:171","msg":"trace[1798112483] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"280.601539ms","start":"2026-02-18T14:30:03.088075Z","end":"2026-02-18T14:30:03.368677Z","steps":["trace[1798112483] 'process raft request' (duration: 280.330773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:03.368914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.671442ms","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-18T14:30:03.368984Z","caller":"traceutil/trace.go:171","msg":"trace[1170184901] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:735; }","duration":"207.765974ms","start":"2026-02-18T14:30:03.161198Z","end":"2026-02-18T14:30:03.368964Z","steps":["trace[1170184901] 'agreement among raft nodes before linearized reading' (duration: 207.59006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:03.369134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.335274ms","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-18T14:30:03.369174Z","caller":"traceutil/trace.go:171","msg":"trace[1473271500] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:735; }","duration":"169.398955ms","start":"2026-02-18T14:30:03.199764Z","end":"2026-02-18T14:30:03.369163Z","steps":["trace[1473271500] 'agreement among raft nodes before linearized reading' (duration: 169.259122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:03.369242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.566468ms","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-18T14:30:03.369275Z","caller":"traceutil/trace.go:171","msg":"trace[2020560204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:735; }","duration":"198.608339ms","start":"2026-02-18T14:30:03.170655Z","end":"2026-02-18T14:30:03.369264Z","steps":["trace[2020560204] 'agreement among raft nodes before linearized reading' (duration: 198.507096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:03.36935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.710643ms","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-18T14:30:03.369379Z","caller":"traceutil/trace.go:171","msg":"trace[1397882319] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:735; }","duration":"210.769715ms","start":"2026-02-18T14:30:03.158601Z","end":"2026-02-18T14:30:03.36937Z","steps":["trace[1397882319] 'agreement among raft nodes before linearized reading' (duration: 210.692752ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:30:03.742503Z","caller":"traceutil/trace.go:171","msg":"trace[110798455] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"367.205597ms","start":"2026-02-18T14:30:03.375273Z","end":"2026-02-18T14:30:03.742478Z","steps":["trace[110798455] 'process raft request' (duration: 364.018243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:03.742629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:03.375257Z","time spent":"367.307549ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:30:04.290113Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548429750332160,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:30:04.778135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.405393ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:30:04.778343Z","caller":"traceutil/trace.go:171","msg":"trace[1909556890] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"1.400460646s","start":"2026-02-18T14:30:03.377867Z","end":"2026-02-18T14:30:04.778328Z","steps":["trace[1909556890] 'process raft request' (duration: 1.400422445s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:04.778408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:03.377859Z","time spent":"1.400517087s","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:30:04.778408Z","caller":"traceutil/trace.go:171","msg":"trace[1352353989] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"1.403007065s","start":"2026-02-18T14:30:03.375369Z","end":"2026-02-18T14:30:04.778377Z","steps":["trace[1352353989] 'process raft request' (duration: 1.178104931s)","trace[1352353989] 'compare' (duration: 224.260359ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:30:04.778566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:03.375363Z","time spent":"1.403110939s","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:30:04.778656Z","caller":"traceutil/trace.go:171","msg":"trace[686989365] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"1.401891461s","start":"2026-02-18T14:30:03.376751Z","end":"2026-02-18T14:30:04.778643Z","steps":["trace[686989365] 'process raft request' (duration: 1.401485571s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:04.778711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:03.376743Z","time spent":"1.401939772s","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:30:05.790646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000573454s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T14:30:05.790694Z","caller":"traceutil/trace.go:171","msg":"trace[1847270885] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000649275s","start":"2026-02-18T14:30:03.790035Z","end":"2026-02-18T14:30:05.790684Z","steps":["trace[1847270885] 'agreement among raft nodes before linearized reading' (duration: 2.000595064s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:05.790715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:03.790024Z","time spent":"2.000686827s","remote":"127.0.0.1:53572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/18 14:30:05 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:30:07.064012Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.033113967s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T14:30:07.374259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.009737ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:30:07.374356Z","caller":"traceutil/trace.go:171","msg":"trace[926221125] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"308.87537ms","start":"2026-02-18T14:30:07.065471Z","end":"2026-02-18T14:30:07.374346Z","steps":["trace[926221125] 'process raft request' (duration: 308.845979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:07.374386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:07.065368Z","time spent":"309.004512ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:30:07.374527Z","caller":"traceutil/trace.go:171","msg":"trace[1989497246] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"3.167773142s","start":"2026-02-18T14:30:04.206749Z","end":"2026-02-18T14:30:07.374522Z","steps":["trace[1989497246] 'process raft request' (duration: 2.857454628s)","trace[1989497246] 'compare' (duration: 309.785412ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:30:07.374551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:04.206717Z","time spent":"3.167821173s","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:30:07.37522Z","caller":"traceutil/trace.go:171","msg":"trace[1293792753] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:758; }","duration":"3.585152993s","start":"2026-02-18T14:30:03.790062Z","end":"2026-02-18T14:30:07.375215Z","steps":["trace[1293792753] 'read index received' (duration: 763.429934ms)","trace[1293792753] 'applied index is now lower than readState.Index' (duration: 2.821722509s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:30:07.3753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.521924244s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:30:07.375314Z","caller":"traceutil/trace.go:171","msg":"trace[221200880] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:741; }","duration":"3.521957054s","start":"2026-02-18T14:30:03.853352Z","end":"2026-02-18T14:30:07.375309Z","steps":["trace[221200880] 'agreement among raft nodes before linearized reading' (duration: 3.521930234s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:07.375327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:03.85334Z","time spent":"3.521983746s","remote":"127.0.0.1:53778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:30:07.375539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.037611ms","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-18T14:30:07.375552Z","caller":"traceutil/trace.go:171","msg":"trace[127712651] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:741; }","duration":"589.066832ms","start":"2026-02-18T14:30:06.786481Z","end":"2026-02-18T14:30:07.375548Z","steps":["trace[127712651] 'agreement among raft nodes before linearized reading' (duration: 589.01167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:07.375563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:06.78647Z","time spent":"589.089543ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T14:30:07.375627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.395607ms","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-18T14:30:07.375641Z","caller":"traceutil/trace.go:171","msg":"trace[1538594194] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:741; }","duration":"591.408497ms","start":"2026-02-18T14:30:06.784227Z","end":"2026-02-18T14:30:07.375636Z","steps":["trace[1538594194] 'agreement among raft nodes before linearized reading' (duration: 591.375406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:07.375651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:06.784197Z","time spent":"591.451218ms","remote":"127.0.0.1:53840","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-18T14:30:07.375714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.853828ms","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-18T14:30:07.375725Z","caller":"traceutil/trace.go:171","msg":"trace[903045947] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:741; }","duration":"591.885419ms","start":"2026-02-18T14:30:06.783836Z","end":"2026-02-18T14:30:07.375721Z","steps":["trace[903045947] 'agreement among raft nodes before linearized reading' (duration: 591.852088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:07.375736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:06.783822Z","time spent":"591.910759ms","remote":"127.0.0.1:53840","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-18T14:30:07.375784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.721061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:30:07.375796Z","caller":"traceutil/trace.go:171","msg":"trace[1731507938] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:741; }","duration":"612.766011ms","start":"2026-02-18T14:30:06.763027Z","end":"2026-02-18T14:30:07.375793Z","steps":["trace[1731507938] 'agreement among raft nodes before linearized reading' (duration: 612.745941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:07.375808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:06.763006Z","time spent":"612.798363ms","remote":"127.0.0.1:53812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:30:07.375854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.585251826s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:30:07.375865Z","caller":"traceutil/trace.go:171","msg":"trace[917381419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:741; }","duration":"1.585285546s","start":"2026-02-18T14:30:05.790575Z","end":"2026-02-18T14:30:07.375861Z","steps":["trace[917381419] 'agreement among raft nodes before linearized reading' (duration: 1.585269756s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:07.375876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:05.790563Z","time spent":"1.585310127s","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:30:07.375945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.630223379s","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-18T14:30:07.375955Z","caller":"traceutil/trace.go:171","msg":"trace[1072127179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:741; }","duration":"1.630259359s","start":"2026-02-18T14:30:05.745692Z","end":"2026-02-18T14:30:07.375952Z","steps":["trace[1072127179] 'agreement among raft nodes before linearized reading' (duration: 1.630226738s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:07.375966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:05.745678Z","time spent":"1.63028472s","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T14:30:07.376068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001917863s","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-18T14:30:07.37608Z","caller":"traceutil/trace.go:171","msg":"trace[322851358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:741; }","duration":"2.001964994s","start":"2026-02-18T14:30:05.374111Z","end":"2026-02-18T14:30:07.376076Z","steps":["trace[322851358] 'agreement among raft nodes before linearized reading' (duration: 2.001916253s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:07.376095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:05.374093Z","time spent":"2.001999595s","remote":"127.0.0.1:53840","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-18T14:30:07.376153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.136289796s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.212\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T14:30:07.376163Z","caller":"traceutil/trace.go:171","msg":"trace[1425640445] range","detail":"{range_begin:/registry/masterleases/162.253.55.212; range_end:; response_count:1; response_revision:741; }","duration":"3.136321846s","start":"2026-02-18T14:30:04.239838Z","end":"2026-02-18T14:30:07.376159Z","steps":["trace[1425640445] 'agreement among raft nodes before linearized reading' (duration: 3.136291185s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:07.376173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:04.239826Z","time spent":"3.136344836s","remote":"127.0.0.1:53600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.212\" "} {"level":"warn","ts":"2026-02-18T14:30:08.432312Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.019822432s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T14:30:08.432636Z","caller":"traceutil/trace.go:171","msg":"trace[1935620057] linearizableReadLoop","detail":"{readStateIndex:769; appliedIndex:764; }","duration":"1.039924117s","start":"2026-02-18T14:30:07.392698Z","end":"2026-02-18T14:30:08.432622Z","steps":["trace[1935620057] 'read index received' (duration: 13.610252ms)","trace[1935620057] 'applied index is now lower than readState.Index' (duration: 1.026313285s)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:30:08.432762Z","caller":"traceutil/trace.go:171","msg":"trace[240371738] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"1.041807122s","start":"2026-02-18T14:30:07.390932Z","end":"2026-02-18T14:30:08.432739Z","steps":["trace[240371738] 'process raft request' (duration: 1.041626068s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:08.432805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:07.391426Z","time spent":"1.041376753s","remote":"127.0.0.1:53654","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-18T14:30:08.432869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:07.390929Z","time spent":"1.041885394s","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:30:08.43292Z","caller":"traceutil/trace.go:171","msg":"trace[1427753342] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"1.039414255s","start":"2026-02-18T14:30:07.3935Z","end":"2026-02-18T14:30:08.432914Z","steps":["trace[1427753342] 'process raft request' (duration: 1.039102958s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:08.432978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:07.393495Z","time spent":"1.039439357s","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:30:08.433022Z","caller":"traceutil/trace.go:171","msg":"trace[295109046] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"1.047294442s","start":"2026-02-18T14:30:07.385719Z","end":"2026-02-18T14:30:08.433014Z","steps":["trace[295109046] 'process raft request' (duration: 1.046719538s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:08.433059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040359178s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-18T14:30:08.433059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:07.38571Z","time spent":"1.047327813s","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:30:08.433076Z","caller":"traceutil/trace.go:171","msg":"trace[192450837] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:746; }","duration":"1.040386018s","start":"2026-02-18T14:30:07.392685Z","end":"2026-02-18T14:30:08.433071Z","steps":["trace[192450837] 'agreement among raft nodes before linearized reading' (duration: 1.040357837s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:08.433091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:07.39268Z","time spent":"1.040409069s","remote":"127.0.0.1:53572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T14:30:08.433142Z","caller":"traceutil/trace.go:171","msg":"trace[551844413] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"1.045057008s","start":"2026-02-18T14:30:07.388079Z","end":"2026-02-18T14:30:08.433136Z","steps":["trace[551844413] 'process raft request' (duration: 1.044447514s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:08.433171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:07.388072Z","time spent":"1.04508364s","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:30:08.433264Z","caller":"traceutil/trace.go:171","msg":"trace[1190392495] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"1.042366545s","start":"2026-02-18T14:30:07.390889Z","end":"2026-02-18T14:30:08.433256Z","steps":["trace[1190392495] 'process raft request' (duration: 1.041655439s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:08.433294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:07.390883Z","time spent":"1.042393796s","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:30:08.933337Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548429750332180,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T14:30:09.084154Z","caller":"traceutil/trace.go:171","msg":"trace[133455663] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"1.20376046s","start":"2026-02-18T14:30:07.880378Z","end":"2026-02-18T14:30:09.084139Z","steps":["trace[133455663] 'process raft request' (duration: 1.203731799s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:09.084251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:07.880365Z","time spent":"1.203838522s","remote":"127.0.0.1:53748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4769,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:30:09.084417Z","caller":"traceutil/trace.go:171","msg":"trace[374022525] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"1.677532077s","start":"2026-02-18T14:30:07.406879Z","end":"2026-02-18T14:30:09.084411Z","steps":["trace[374022525] 'process raft request' (duration: 1.677131656s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:09.084445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:07.406866Z","time spent":"1.677562727s","remote":"127.0.0.1:53600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:30:09.441731Z","caller":"traceutil/trace.go:171","msg":"trace[2022645440] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"1.00621488s","start":"2026-02-18T14:30:08.435507Z","end":"2026-02-18T14:30:09.441722Z","steps":["trace[2022645440] 'process raft request' (duration: 994.467312ms)","trace[2022645440] 'compare' (duration: 11.39824ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:30:09.441798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:08.435501Z","time spent":"1.006260042s","remote":"127.0.0.1:53654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T14:30:09.441684Z","caller":"traceutil/trace.go:171","msg":"trace[1205860450] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:772; }","duration":"1.009015877s","start":"2026-02-18T14:30:08.432653Z","end":"2026-02-18T14:30:09.441669Z","steps":["trace[1205860450] 'read index received' (duration: 652.05001ms)","trace[1205860450] 'applied index is now lower than readState.Index' (duration: 356.965137ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:30:09.442087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006605009s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:30:09.442109Z","caller":"traceutil/trace.go:171","msg":"trace[1484861097] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:749; }","duration":"1.006643821s","start":"2026-02-18T14:30:08.435458Z","end":"2026-02-18T14:30:09.442102Z","steps":["trace[1484861097] 'agreement among raft nodes before linearized reading' (duration: 1.0065995s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:09.442137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:08.435435Z","time spent":"1.006698121s","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:30:09.442282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.38942ms","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-18T14:30:09.442298Z","caller":"traceutil/trace.go:171","msg":"trace[1255125063] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:749; }","duration":"355.42276ms","start":"2026-02-18T14:30:09.086871Z","end":"2026-02-18T14:30:09.442293Z","steps":["trace[1255125063] 'agreement among raft nodes before linearized reading' (duration: 355.347318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:09.442314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:09.086861Z","time spent":"355.449211ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-18T14:30:09.442473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.207391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:30:09.442501Z","caller":"traceutil/trace.go:171","msg":"trace[1559426927] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:749; }","duration":"665.259132ms","start":"2026-02-18T14:30:08.777234Z","end":"2026-02-18T14:30:09.442493Z","steps":["trace[1559426927] 'agreement among raft nodes before linearized reading' (duration: 665.17641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:09.442526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:08.777221Z","time spent":"665.297593ms","remote":"127.0.0.1:53654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":102,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:30:09.44297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.991507877s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:30:09.44306Z","caller":"traceutil/trace.go:171","msg":"trace[1280465973] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:749; }","duration":"1.991561378s","start":"2026-02-18T14:30:07.45143Z","end":"2026-02-18T14:30:09.442991Z","steps":["trace[1280465973] 'agreement among raft nodes before linearized reading' (duration: 1.990596745s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:09.443092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:07.451415Z","time spent":"1.99166696s","remote":"127.0.0.1:53876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-02-18T14:30:12.723046Z","caller":"traceutil/trace.go:171","msg":"trace[734084031] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:785; }","duration":"233.818335ms","start":"2026-02-18T14:30:12.489211Z","end":"2026-02-18T14:30:12.723029Z","steps":["trace[734084031] 'read index received' (duration: 138.838021ms)","trace[734084031] 'applied index is now lower than readState.Index' (duration: 94.979634ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:30:12.723067Z","caller":"traceutil/trace.go:171","msg":"trace[782481560] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"255.776554ms","start":"2026-02-18T14:30:12.467266Z","end":"2026-02-18T14:30:12.723042Z","steps":["trace[782481560] 'process raft request' (duration: 160.681528ms)","trace[782481560] 'compare' (duration: 94.747058ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:30:12.723193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.677227ms","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-18T14:30:12.723217Z","caller":"traceutil/trace.go:171","msg":"trace[1245398316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:762; }","duration":"256.740848ms","start":"2026-02-18T14:30:12.466469Z","end":"2026-02-18T14:30:12.72321Z","steps":["trace[1245398316] 'agreement among raft nodes before linearized reading' (duration: 256.625195ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:30:12.723315Z","caller":"traceutil/trace.go:171","msg":"trace[451042261] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"255.51244ms","start":"2026-02-18T14:30:12.467796Z","end":"2026-02-18T14:30:12.723309Z","steps":["trace[451042261] 'process raft request' (duration: 255.197871ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:30:12.745208Z","caller":"traceutil/trace.go:171","msg":"trace[1881563360] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"251.628476ms","start":"2026-02-18T14:30:12.493566Z","end":"2026-02-18T14:30:12.745195Z","steps":["trace[1881563360] 'process raft request' (duration: 251.543354ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:30:26.873034Z","caller":"traceutil/trace.go:171","msg":"trace[1637036292] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"285.340194ms","start":"2026-02-18T14:30:26.587668Z","end":"2026-02-18T14:30:26.873008Z","steps":["trace[1637036292] 'process raft request' (duration: 285.111958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:28.468842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.056087519s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:30:28.468953Z","caller":"traceutil/trace.go:171","msg":"trace[1848491302] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"1.539110244s","start":"2026-02-18T14:30:26.929823Z","end":"2026-02-18T14:30:28.468934Z","steps":["trace[1848491302] 'read index received' (duration: 482.776009ms)","trace[1848491302] 'applied index is now lower than readState.Index' (duration: 1.056333165s)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:30:28.46896Z","caller":"traceutil/trace.go:171","msg":"trace[1560808845] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"1.588769218s","start":"2026-02-18T14:30:26.880169Z","end":"2026-02-18T14:30:28.468938Z","steps":["trace[1560808845] 'process raft request' (duration: 532.485344ms)","trace[1560808845] 'compare' (duration: 1.055972137s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:30:28.469068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:26.880147Z","time spent":"1.58886171s","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:30:28.469109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.539281008s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T14:30:28.469142Z","caller":"traceutil/trace.go:171","msg":"trace[1335414685] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:800; }","duration":"1.539341059s","start":"2026-02-18T14:30:26.929789Z","end":"2026-02-18T14:30:28.46913Z","steps":["trace[1335414685] 'agreement among raft nodes before linearized reading' (duration: 1.539220186s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:28.46918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:26.929772Z","time spent":"1.539396391s","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T14:30:28.74169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.825752ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:30:28.741865Z","caller":"traceutil/trace.go:171","msg":"trace[1532430745] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:828; }","duration":"272.869179ms","start":"2026-02-18T14:30:28.468981Z","end":"2026-02-18T14:30:28.74185Z","steps":["trace[1532430745] 'read index received' (duration: 141.75829ms)","trace[1532430745] 'applied index is now lower than readState.Index' (duration: 131.110039ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:30:28.741998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.803013111s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T14:30:28.742023Z","caller":"traceutil/trace.go:171","msg":"trace[109914665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:803; }","duration":"1.803052603s","start":"2026-02-18T14:30:26.938962Z","end":"2026-02-18T14:30:28.742015Z","steps":["trace[109914665] 'agreement among raft nodes before linearized reading' (duration: 1.80293111s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:28.742053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:26.938938Z","time spent":"1.803108454s","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-18T14:30:28.742317Z","caller":"traceutil/trace.go:171","msg":"trace[1730906370] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"940.870456ms","start":"2026-02-18T14:30:27.801434Z","end":"2026-02-18T14:30:28.742304Z","steps":["trace[1730906370] 'process raft request' (duration: 809.360538ms)","trace[1730906370] 'compare' (duration: 130.73886ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:30:28.742375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:27.801379Z","time spent":"940.963919ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:30:28.742492Z","caller":"traceutil/trace.go:171","msg":"trace[1979952520] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"934.565288ms","start":"2026-02-18T14:30:27.807918Z","end":"2026-02-18T14:30:28.742483Z","steps":["trace[1979952520] 'process raft request' (duration: 933.859321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:28.742537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:27.807893Z","time spent":"934.619489ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:30:28.742642Z","caller":"traceutil/trace.go:171","msg":"trace[847928208] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"262.818551ms","start":"2026-02-18T14:30:28.479813Z","end":"2026-02-18T14:30:28.742632Z","steps":["trace[847928208] 'process raft request' (duration: 262.007102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:28.742781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.130454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:30:28.742808Z","caller":"traceutil/trace.go:171","msg":"trace[1927869766] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:803; }","duration":"691.206166ms","start":"2026-02-18T14:30:28.051593Z","end":"2026-02-18T14:30:28.742799Z","steps":["trace[1927869766] 'agreement among raft nodes before linearized reading' (duration: 691.136275ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:28.742859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:28.051575Z","time spent":"691.276647ms","remote":"127.0.0.1:53938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":51,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:30:28.74303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.800818449s","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-18T14:30:28.743072Z","caller":"traceutil/trace.go:171","msg":"trace[454787835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:803; }","duration":"1.80087103s","start":"2026-02-18T14:30:26.942191Z","end":"2026-02-18T14:30:28.743062Z","steps":["trace[454787835] 'agreement among raft nodes before linearized reading' (duration: 1.800776848s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:28.743097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:26.942184Z","time spent":"1.800906872s","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T14:30:41.098564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.903505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:30:41.098618Z","caller":"traceutil/trace.go:171","msg":"trace[23999882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:887; }","duration":"309.998337ms","start":"2026-02-18T14:30:40.788609Z","end":"2026-02-18T14:30:41.098608Z","steps":["trace[23999882] 'range keys from in-memory index tree' (duration: 309.859804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:41.09864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:40.788596Z","time spent":"310.038938ms","remote":"127.0.0.1:53572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:30:41.098815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.163781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T14:30:41.098831Z","caller":"traceutil/trace.go:171","msg":"trace[415088408] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:887; }","duration":"233.210023ms","start":"2026-02-18T14:30:40.865617Z","end":"2026-02-18T14:30:41.098827Z","steps":["trace[415088408] 'range keys from in-memory index tree' (duration: 233.045059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:41.098927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.640432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T14:30:41.09894Z","caller":"traceutil/trace.go:171","msg":"trace[1089059612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:887; }","duration":"208.674602ms","start":"2026-02-18T14:30:40.890262Z","end":"2026-02-18T14:30:41.098937Z","steps":["trace[1089059612] 'range keys from in-memory index tree' (duration: 208.55493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:41.099022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.017583ms","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-18T14:30:41.099039Z","caller":"traceutil/trace.go:171","msg":"trace[619063685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:887; }","duration":"207.043524ms","start":"2026-02-18T14:30:40.891992Z","end":"2026-02-18T14:30:41.099035Z","steps":["trace[619063685] 'range keys from in-memory index tree' (duration: 206.976592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:41.09912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.189672ms","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-18T14:30:41.099137Z","caller":"traceutil/trace.go:171","msg":"trace[517832970] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:887; }","duration":"112.232433ms","start":"2026-02-18T14:30:40.986899Z","end":"2026-02-18T14:30:41.099132Z","steps":["trace[517832970] 'range keys from in-memory index tree' (duration: 112.12907ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:30:42.85983Z","caller":"traceutil/trace.go:171","msg":"trace[1549668430] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"242.612903ms","start":"2026-02-18T14:30:42.617198Z","end":"2026-02-18T14:30:42.859811Z","steps":["trace[1549668430] 'process raft request' (duration: 242.212244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:44.260924Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548429750332548,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:30:44.370012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028265101s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:30:44.370076Z","caller":"traceutil/trace.go:171","msg":"trace[1322596607] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"1.159187725s","start":"2026-02-18T14:30:43.210878Z","end":"2026-02-18T14:30:44.370065Z","steps":["trace[1322596607] 'process raft request' (duration: 130.820961ms)","trace[1322596607] 'compare' (duration: 1.028078387s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:30:44.370105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:43.210854Z","time spent":"1.159229866s","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:30:44.371638Z","caller":"traceutil/trace.go:171","msg":"trace[844840308] linearizableReadLoop","detail":"{readStateIndex:928; appliedIndex:926; }","duration":"610.772615ms","start":"2026-02-18T14:30:43.760853Z","end":"2026-02-18T14:30:44.371626Z","steps":["trace[844840308] 'read index received' (duration: 610.653992ms)","trace[844840308] 'applied index is now lower than readState.Index' (duration: 118.093µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:30:44.371765Z","caller":"traceutil/trace.go:171","msg":"trace[1121083187] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"1.159580475s","start":"2026-02-18T14:30:43.212179Z","end":"2026-02-18T14:30:44.371759Z","steps":["trace[1121083187] 'process raft request' (duration: 1.15936458s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:44.371828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:43.21217Z","time spent":"1.159614936s","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:30:44.371916Z","caller":"traceutil/trace.go:171","msg":"trace[1516755687] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"1.158831887s","start":"2026-02-18T14:30:43.21308Z","end":"2026-02-18T14:30:44.371912Z","steps":["trace[1516755687] 'process raft request' (duration: 1.15852826s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:44.371942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:43.213065Z","time spent":"1.158861558s","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:30:44.37202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.169194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:30:44.372041Z","caller":"traceutil/trace.go:171","msg":"trace[718049609] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:896; }","duration":"611.207065ms","start":"2026-02-18T14:30:43.760825Z","end":"2026-02-18T14:30:44.372032Z","steps":["trace[718049609] 'agreement among raft nodes before linearized reading' (duration: 611.174925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:44.372054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:43.760812Z","time spent":"611.238786ms","remote":"127.0.0.1:53824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":31,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:30:44.372133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.919886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:30:44.372146Z","caller":"traceutil/trace.go:171","msg":"trace[813632562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:896; }","duration":"169.965326ms","start":"2026-02-18T14:30:44.202177Z","end":"2026-02-18T14:30:44.372142Z","steps":["trace[813632562] 'agreement among raft nodes before linearized reading' (duration: 169.946225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:44.372307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.529681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.212\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T14:30:44.372322Z","caller":"traceutil/trace.go:171","msg":"trace[632263487] range","detail":"{range_begin:/registry/masterleases/162.253.55.212; range_end:; response_count:1; response_revision:896; }","duration":"129.560312ms","start":"2026-02-18T14:30:44.242758Z","end":"2026-02-18T14:30:44.372318Z","steps":["trace[632263487] 'agreement among raft nodes before linearized reading' (duration: 129.502541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:44.60424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.462098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T14:30:44.604298Z","caller":"traceutil/trace.go:171","msg":"trace[149902739] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:897; }","duration":"134.537309ms","start":"2026-02-18T14:30:44.469747Z","end":"2026-02-18T14:30:44.604285Z","steps":["trace[149902739] 'range keys from in-memory index tree' (duration: 134.426837ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:30:46.503963Z","caller":"traceutil/trace.go:171","msg":"trace[1520279798] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"123.706984ms","start":"2026-02-18T14:30:46.380235Z","end":"2026-02-18T14:30:46.503942Z","steps":["trace[1520279798] 'process raft request' (duration: 123.573061ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:30:46.640462Z","caller":"traceutil/trace.go:171","msg":"trace[203440776] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"257.882065ms","start":"2026-02-18T14:30:46.382554Z","end":"2026-02-18T14:30:46.640436Z","steps":["trace[203440776] 'process raft request' (duration: 257.810283ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:30:46.640903Z","caller":"traceutil/trace.go:171","msg":"trace[395461290] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"259.821081ms","start":"2026-02-18T14:30:46.38107Z","end":"2026-02-18T14:30:46.640891Z","steps":["trace[395461290] 'process raft request' (duration: 200.529219ms)","trace[395461290] 'compare' (duration: 58.658287ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:30:48.933183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.738044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T14:30:48.933231Z","caller":"traceutil/trace.go:171","msg":"trace[281335042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:905; }","duration":"285.822295ms","start":"2026-02-18T14:30:48.647398Z","end":"2026-02-18T14:30:48.933221Z","steps":["trace[281335042] 'range keys from in-memory index tree' (duration: 285.644071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:48.933422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.626661ms","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-18T14:30:48.933441Z","caller":"traceutil/trace.go:171","msg":"trace[445692762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:905; }","duration":"285.653461ms","start":"2026-02-18T14:30:48.647783Z","end":"2026-02-18T14:30:48.933436Z","steps":["trace[445692762] 'range keys from in-memory index tree' (duration: 285.57507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:48.933687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.729111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:30:48.933701Z","caller":"traceutil/trace.go:171","msg":"trace[892700269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:905; }","duration":"144.767372ms","start":"2026-02-18T14:30:48.78893Z","end":"2026-02-18T14:30:48.933697Z","steps":["trace[892700269] 'range keys from in-memory index tree' (duration: 144.68572ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:30:55.199874Z","caller":"traceutil/trace.go:171","msg":"trace[1378803769] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:958; }","duration":"165.213434ms","start":"2026-02-18T14:30:55.034644Z","end":"2026-02-18T14:30:55.199857Z","steps":["trace[1378803769] 'read index received' (duration: 165.075661ms)","trace[1378803769] 'applied index is now lower than readState.Index' (duration: 136.963µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:30:55.199966Z","caller":"traceutil/trace.go:171","msg":"trace[1842110050] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"185.438553ms","start":"2026-02-18T14:30:55.014522Z","end":"2026-02-18T14:30:55.199961Z","steps":["trace[1842110050] 'process raft request' (duration: 185.212347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:55.200334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.671345ms","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-18T14:30:55.200355Z","caller":"traceutil/trace.go:171","msg":"trace[692449250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:924; }","duration":"165.739797ms","start":"2026-02-18T14:30:55.034609Z","end":"2026-02-18T14:30:55.200349Z","steps":["trace[692449250] 'agreement among raft nodes before linearized reading' (duration: 165.604523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:30:55.200323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.095584ms","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-18T14:30:55.200425Z","caller":"traceutil/trace.go:171","msg":"trace[1748291760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:924; }","duration":"163.224108ms","start":"2026-02-18T14:30:55.037176Z","end":"2026-02-18T14:30:55.2004Z","steps":["trace[1748291760] 'agreement among raft nodes before linearized reading' (duration: 162.958201ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:30:55.433889Z","caller":"traceutil/trace.go:171","msg":"trace[1909842473] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:960; }","duration":"198.726966ms","start":"2026-02-18T14:30:55.235147Z","end":"2026-02-18T14:30:55.433874Z","steps":["trace[1909842473] 'read index received' (duration: 22.224655ms)","trace[1909842473] 'applied index is now lower than readState.Index' (duration: 176.501691ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:30:55.434029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.8689ms","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-18T14:30:55.434048Z","caller":"traceutil/trace.go:171","msg":"trace[1011421190] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:926; }","duration":"198.907291ms","start":"2026-02-18T14:30:55.235134Z","end":"2026-02-18T14:30:55.434041Z","steps":["trace[1011421190] 'agreement among raft nodes before linearized reading' (duration: 198.800959ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:30:55.434275Z","caller":"traceutil/trace.go:171","msg":"trace[787167049] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"228.796107ms","start":"2026-02-18T14:30:55.205474Z","end":"2026-02-18T14:30:55.43427Z","steps":["trace[787167049] 'process raft request' (duration: 228.300326ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:30:56.723877Z","caller":"traceutil/trace.go:171","msg":"trace[1112469677] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"134.709514ms","start":"2026-02-18T14:30:56.589153Z","end":"2026-02-18T14:30:56.723862Z","steps":["trace[1112469677] 'process raft request' (duration: 134.599991ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:30:57.633272Z","caller":"traceutil/trace.go:171","msg":"trace[1140868476] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"121.190414ms","start":"2026-02-18T14:30:57.512036Z","end":"2026-02-18T14:30:57.633226Z","steps":["trace[1140868476] 'process raft request' (duration: 120.971039ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:30:57.880491Z","caller":"traceutil/trace.go:171","msg":"trace[1910010959] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"364.501244ms","start":"2026-02-18T14:30:57.515965Z","end":"2026-02-18T14:30:57.880466Z","steps":["trace[1910010959] 'process raft request' (duration: 317.632147ms)","trace[1910010959] 'compare' (duration: 46.354796ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:30:57.880595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:57.515949Z","time spent":"364.590476ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:30:59.549865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.886396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:30:59.549968Z","caller":"traceutil/trace.go:171","msg":"trace[483596491] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:936; }","duration":"140.07595ms","start":"2026-02-18T14:30:59.40987Z","end":"2026-02-18T14:30:59.549946Z","steps":["trace[483596491] 'count revisions from in-memory index tree' (duration: 139.783813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:31:00.294618Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548429750332667,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T14:31:00.359415Z","caller":"traceutil/trace.go:171","msg":"trace[342563634] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"677.798129ms","start":"2026-02-18T14:30:59.681592Z","end":"2026-02-18T14:31:00.35939Z","steps":["trace[342563634] 'process raft request' (duration: 677.007051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:31:00.359591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:59.681574Z","time spent":"677.906271ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:31:00.856628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.347923ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-18T14:31:00.856712Z","caller":"traceutil/trace.go:171","msg":"trace[1186318347] linearizableReadLoop","detail":"{readStateIndex:974; appliedIndex:973; }","duration":"1.062244916s","start":"2026-02-18T14:30:59.794454Z","end":"2026-02-18T14:31:00.856699Z","steps":["trace[1186318347] 'read index received' (duration: 565.561937ms)","trace[1186318347] 'applied index is now lower than readState.Index' (duration: 496.681569ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:31:00.857016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.285067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T14:31:00.857037Z","caller":"traceutil/trace.go:171","msg":"trace[743469053] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:938; }","duration":"121.390339ms","start":"2026-02-18T14:31:00.735641Z","end":"2026-02-18T14:31:00.857031Z","steps":["trace[743469053] 'agreement among raft nodes before linearized reading' (duration: 121.279157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:31:00.857071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.106548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T14:31:00.857149Z","caller":"traceutil/trace.go:171","msg":"trace[618826600] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:938; }","duration":"970.243771ms","start":"2026-02-18T14:30:59.886887Z","end":"2026-02-18T14:31:00.857131Z","steps":["trace[618826600] 'agreement among raft nodes before linearized reading' (duration: 969.986695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:31:00.857159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.062712916s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:31:00.857183Z","caller":"traceutil/trace.go:171","msg":"trace[795172801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:938; }","duration":"1.062783978s","start":"2026-02-18T14:30:59.794394Z","end":"2026-02-18T14:31:00.857178Z","steps":["trace[795172801] 'agreement among raft nodes before linearized reading' (duration: 1.062748077s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:31:00.857199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:59.794368Z","time spent":"1.062825799s","remote":"127.0.0.1:53572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:31:00.857187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:30:59.886855Z","time spent":"970.324853ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T14:31:00.857378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.120684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:31:00.857422Z","caller":"traceutil/trace.go:171","msg":"trace[93897759] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:938; }","duration":"559.227807ms","start":"2026-02-18T14:31:00.29819Z","end":"2026-02-18T14:31:00.857417Z","steps":["trace[93897759] 'agreement among raft nodes before linearized reading' (duration: 559.170626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:31:00.857436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:31:00.298163Z","time spent":"559.269028ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:31:01.244977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.509079ms","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-18T14:31:01.245102Z","caller":"traceutil/trace.go:171","msg":"trace[1864605761] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:939; }","duration":"211.652522ms","start":"2026-02-18T14:31:01.033431Z","end":"2026-02-18T14:31:01.245084Z","steps":["trace[1864605761] 'range keys from in-memory index tree' (duration: 211.492458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:31:01.245187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.045838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:31:01.245308Z","caller":"traceutil/trace.go:171","msg":"trace[270689545] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:975; }","duration":"373.335833ms","start":"2026-02-18T14:31:00.871952Z","end":"2026-02-18T14:31:01.245287Z","steps":["trace[270689545] 'read index received' (duration: 4.181939ms)","trace[270689545] 'applied index is now lower than readState.Index' (duration: 369.149933ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:31:01.245434Z","caller":"traceutil/trace.go:171","msg":"trace[1131036604] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"374.658904ms","start":"2026-02-18T14:31:00.870722Z","end":"2026-02-18T14:31:01.245381Z","steps":["trace[1131036604] 'process raft request' (duration: 273.300599ms)","trace[1131036604] 'compare' (duration: 100.794362ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:31:01.245485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:31:00.870715Z","time spent":"374.742665ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:31:01.245588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.301624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:31:01.24563Z","caller":"traceutil/trace.go:171","msg":"trace[1232867070] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:940; }","duration":"291.393336ms","start":"2026-02-18T14:31:00.954222Z","end":"2026-02-18T14:31:01.245616Z","steps":["trace[1232867070] 'agreement among raft nodes before linearized reading' (duration: 291.325365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:31:01.245825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.329706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:31:01.245854Z","caller":"traceutil/trace.go:171","msg":"trace[665964955] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:940; }","duration":"269.380927ms","start":"2026-02-18T14:31:00.976464Z","end":"2026-02-18T14:31:01.245845Z","steps":["trace[665964955] 'agreement among raft nodes before linearized reading' (duration: 269.317535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:31:01.245997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.053631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:31:01.246015Z","caller":"traceutil/trace.go:171","msg":"trace[1671905364] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:940; }","duration":"374.104301ms","start":"2026-02-18T14:31:00.871905Z","end":"2026-02-18T14:31:01.246009Z","steps":["trace[1671905364] 'agreement among raft nodes before linearized reading' (duration: 374.070321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:31:01.246032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:31:00.871887Z","time spent":"374.141143ms","remote":"127.0.0.1:53572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:31:10.273248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.855149ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-18T14:31:10.273387Z","caller":"traceutil/trace.go:171","msg":"trace[1567654990] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1027; }","duration":"905.658698ms","start":"2026-02-18T14:31:09.367716Z","end":"2026-02-18T14:31:10.273375Z","steps":["trace[1567654990] 'read index received' (duration: 33.918482ms)","trace[1567654990] 'applied index is now lower than readState.Index' (duration: 871.739726ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:31:10.273822Z","caller":"traceutil/trace.go:171","msg":"trace[291658022] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"894.807901ms","start":"2026-02-18T14:31:09.378988Z","end":"2026-02-18T14:31:10.273796Z","steps":["trace[291658022] 'process raft request' (duration: 894.3331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:31:10.273973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:31:09.378976Z","time spent":"894.904183ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:31:10.302608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.860075ms","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-18T14:31:10.302655Z","caller":"traceutil/trace.go:171","msg":"trace[1078750603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:990; }","duration":"934.953617ms","start":"2026-02-18T14:31:09.367687Z","end":"2026-02-18T14:31:10.302641Z","steps":["trace[1078750603] 'agreement among raft nodes before linearized reading' (duration: 906.042686ms)","trace[1078750603] 'range keys from in-memory index tree' (duration: 28.762416ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:31:10.302681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:31:09.367679Z","time spent":"934.995719ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-18T14:31:10.303742Z","caller":"traceutil/trace.go:171","msg":"trace[1916346408] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"411.883516ms","start":"2026-02-18T14:31:09.891847Z","end":"2026-02-18T14:31:10.303731Z","steps":["trace[1916346408] 'process raft request' (duration: 398.674755ms)","trace[1916346408] 'compare' (duration: 11.91398ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:31:10.304055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:31:09.891824Z","time spent":"411.944666ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:31:10.309079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.531978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:31:10.309658Z","caller":"traceutil/trace.go:171","msg":"trace[240375008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:992; }","duration":"517.138123ms","start":"2026-02-18T14:31:09.792509Z","end":"2026-02-18T14:31:10.309648Z","steps":["trace[240375008] 'agreement among raft nodes before linearized reading' (duration: 511.817697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:31:10.309894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:31:09.792492Z","time spent":"517.391778ms","remote":"127.0.0.1:53572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T14:31:11.145246Z","caller":"traceutil/trace.go:171","msg":"trace[677661636] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"205.596708ms","start":"2026-02-18T14:31:10.939631Z","end":"2026-02-18T14:31:11.145228Z","steps":["trace[677661636] 'process raft request' (duration: 205.444965ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:31:12.420508Z","caller":"traceutil/trace.go:171","msg":"trace[1413416473] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"138.868858ms","start":"2026-02-18T14:31:12.281615Z","end":"2026-02-18T14:31:12.420484Z","steps":["trace[1413416473] 'process raft request' (duration: 138.526519ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:31:16.792402Z","caller":"traceutil/trace.go:171","msg":"trace[693952113] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1055; }","duration":"320.010381ms","start":"2026-02-18T14:31:16.472371Z","end":"2026-02-18T14:31:16.792382Z","steps":["trace[693952113] 'read index received' (duration: 319.882788ms)","trace[693952113] 'applied index is now lower than readState.Index' (duration: 126.523µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:31:16.792652Z","caller":"traceutil/trace.go:171","msg":"trace[1690473045] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"348.44293ms","start":"2026-02-18T14:31:16.444201Z","end":"2026-02-18T14:31:16.792644Z","steps":["trace[1690473045] 'process raft request' (duration: 348.04284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:31:16.792729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:31:16.444172Z","time spent":"348.500461ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:31:16.792916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.543483ms","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-18T14:31:16.792941Z","caller":"traceutil/trace.go:171","msg":"trace[1404297749] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1016; }","duration":"320.593784ms","start":"2026-02-18T14:31:16.472338Z","end":"2026-02-18T14:31:16.792932Z","steps":["trace[1404297749] 'agreement among raft nodes before linearized reading' (duration: 320.506232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:31:16.792973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:31:16.472325Z","time spent":"320.644265ms","remote":"127.0.0.1:53840","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-18T14:31:16.793114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.917452ms","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-18T14:31:16.793131Z","caller":"traceutil/trace.go:171","msg":"trace[2046902223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1016; }","duration":"221.988624ms","start":"2026-02-18T14:31:16.571137Z","end":"2026-02-18T14:31:16.793126Z","steps":["trace[2046902223] 'agreement among raft nodes before linearized reading' (duration: 221.934002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:31:17.070847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.582022ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:31:17.071Z","caller":"traceutil/trace.go:171","msg":"trace[1546446520] linearizableReadLoop","detail":"{readStateIndex:1058; appliedIndex:1056; }","duration":"216.808703ms","start":"2026-02-18T14:31:16.854175Z","end":"2026-02-18T14:31:17.070984Z","steps":["trace[1546446520] 'read index received' (duration: 74.955534ms)","trace[1546446520] 'applied index is now lower than readState.Index' (duration: 141.852149ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:31:17.071086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.904295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:31:17.071112Z","caller":"traceutil/trace.go:171","msg":"trace[335140341] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1018; }","duration":"216.965236ms","start":"2026-02-18T14:31:16.854138Z","end":"2026-02-18T14:31:17.071103Z","steps":["trace[335140341] 'agreement among raft nodes before linearized reading' (duration: 216.891515ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:31:17.071389Z","caller":"traceutil/trace.go:171","msg":"trace[959233520] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"272.120844ms","start":"2026-02-18T14:31:16.799253Z","end":"2026-02-18T14:31:17.071374Z","steps":["trace[959233520] 'process raft request' (duration: 129.927537ms)","trace[959233520] 'compare' (duration: 141.422868ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:31:17.071525Z","caller":"traceutil/trace.go:171","msg":"trace[233967075] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"271.789386ms","start":"2026-02-18T14:31:16.799727Z","end":"2026-02-18T14:31:17.071517Z","steps":["trace[233967075] 'process raft request' (duration: 271.211233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:31:19.99506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.103223ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:31:19.995224Z","caller":"traceutil/trace.go:171","msg":"trace[1417204521] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"912.911114ms","start":"2026-02-18T14:31:19.082291Z","end":"2026-02-18T14:31:19.995202Z","steps":["trace[1417204521] 'process raft request' (duration: 111.595956ms)","trace[1417204521] 'compare' (duration: 800.945219ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:31:19.995329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:31:19.082283Z","time spent":"912.988235ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:31:19.995534Z","caller":"traceutil/trace.go:171","msg":"trace[978589855] linearizableReadLoop","detail":"{readStateIndex:1071; appliedIndex:1070; }","duration":"770.613236ms","start":"2026-02-18T14:31:19.224912Z","end":"2026-02-18T14:31:19.995525Z","steps":["trace[978589855] 'read index received' (duration: 28.901µs)","trace[978589855] 'applied index is now lower than readState.Index' (duration: 770.583345ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:31:20.216761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.590456ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-02-18T14:31:20.2171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.17343ms","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-18T14:31:20.217151Z","caller":"traceutil/trace.go:171","msg":"trace[416992020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1031; }","duration":"992.253162ms","start":"2026-02-18T14:31:19.224875Z","end":"2026-02-18T14:31:20.217128Z","steps":["trace[416992020] 'agreement among raft nodes before linearized reading' (duration: 770.703928ms)","trace[416992020] 'range keys from in-memory index tree' (duration: 221.411841ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:31:20.217199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:31:19.22486Z","time spent":"992.329324ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-18T14:31:20.219972Z","caller":"traceutil/trace.go:171","msg":"trace[423153117] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1071; }","duration":"224.405971ms","start":"2026-02-18T14:31:19.99555Z","end":"2026-02-18T14:31:20.219956Z","steps":["trace[423153117] 'read index received' (duration: 40.601µs)","trace[423153117] 'applied index is now lower than readState.Index' (duration: 224.36442ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:31:20.220069Z","caller":"traceutil/trace.go:171","msg":"trace[770095335] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"892.660838ms","start":"2026-02-18T14:31:19.327395Z","end":"2026-02-18T14:31:20.220056Z","steps":["trace[770095335] 'process raft request' (duration: 889.477963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:31:20.220142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:31:19.327377Z","time spent":"892.715419ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:31:20.263187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.158685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T14:31:20.263237Z","caller":"traceutil/trace.go:171","msg":"trace[1771911664] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1032; }","duration":"872.306329ms","start":"2026-02-18T14:31:19.390918Z","end":"2026-02-18T14:31:20.263224Z","steps":["trace[1771911664] 'agreement among raft nodes before linearized reading' (duration: 829.113803ms)","trace[1771911664] 'range keys from in-memory index tree' (duration: 43.016802ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:31:20.263268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:31:19.390904Z","time spent":"872.357689ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T14:31:20.263428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.882492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:31:20.263451Z","caller":"traceutil/trace.go:171","msg":"trace[1120745690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1032; }","duration":"705.946224ms","start":"2026-02-18T14:31:19.557498Z","end":"2026-02-18T14:31:20.263445Z","steps":["trace[1120745690] 'agreement among raft nodes before linearized reading' (duration: 662.785988ms)","trace[1120745690] 'range keys from in-memory index tree' (duration: 43.130774ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:31:20.263468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:31:19.557488Z","time spent":"705.976784ms","remote":"127.0.0.1:53572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:31:20.263662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.282042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-18T14:31:20.263691Z","caller":"traceutil/trace.go:171","msg":"trace[899899389] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1032; }","duration":"774.333402ms","start":"2026-02-18T14:31:19.489349Z","end":"2026-02-18T14:31:20.263682Z","steps":["trace[899899389] 'agreement among raft nodes before linearized reading' (duration: 730.941412ms)","trace[899899389] 'range keys from in-memory index tree' (duration: 43.331099ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:31:20.26371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:31:19.489318Z","time spent":"774.387724ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":534,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-18T14:31:20.266251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.647947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:31:20.266969Z","caller":"traceutil/trace.go:171","msg":"trace[411143566] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1032; }","duration":"474.409474ms","start":"2026-02-18T14:31:19.792542Z","end":"2026-02-18T14:31:20.266952Z","steps":["trace[411143566] 'agreement among raft nodes before linearized reading' (duration: 427.733166ms)","trace[411143566] 'range keys from in-memory index tree' (duration: 42.842838ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:31:20.269725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:31:19.792527Z","time spent":"477.1827ms","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T14:31:25.316137Z","caller":"traceutil/trace.go:171","msg":"trace[238470020] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"141.974062ms","start":"2026-02-18T14:31:25.174143Z","end":"2026-02-18T14:31:25.316117Z","steps":["trace[238470020] 'process raft request' (duration: 141.628864ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:31:41.219943Z","caller":"traceutil/trace.go:171","msg":"trace[498625115] linearizableReadLoop","detail":"{readStateIndex:1179; appliedIndex:1178; }","duration":"269.624725ms","start":"2026-02-18T14:31:40.950295Z","end":"2026-02-18T14:31:41.21992Z","steps":["trace[498625115] 'read index received' (duration: 269.530543ms)","trace[498625115] 'applied index is now lower than readState.Index' (duration: 93.182µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:31:41.220118Z","caller":"traceutil/trace.go:171","msg":"trace[2032888773] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"360.147416ms","start":"2026-02-18T14:31:40.859956Z","end":"2026-02-18T14:31:41.220103Z","steps":["trace[2032888773] 'process raft request' (duration: 359.825169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:31:41.220246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:31:40.859943Z","time spent":"360.232528ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:31:41.22058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.27543ms","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-18T14:31:41.220652Z","caller":"traceutil/trace.go:171","msg":"trace[707577702] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1134; }","duration":"270.382002ms","start":"2026-02-18T14:31:40.950252Z","end":"2026-02-18T14:31:41.220634Z","steps":["trace[707577702] 'agreement among raft nodes before linearized reading' (duration: 270.179518ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:31:41.59009Z","caller":"traceutil/trace.go:171","msg":"trace[230509620] linearizableReadLoop","detail":"{readStateIndex:1180; appliedIndex:1179; }","duration":"369.68074ms","start":"2026-02-18T14:31:41.220076Z","end":"2026-02-18T14:31:41.589756Z","steps":["trace[230509620] 'read index received' (duration: 369.544957ms)","trace[230509620] 'applied index is now lower than readState.Index' (duration: 134.863µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:31:41.590419Z","caller":"traceutil/trace.go:171","msg":"trace[1505785457] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"428.235518ms","start":"2026-02-18T14:31:41.161942Z","end":"2026-02-18T14:31:41.590177Z","steps":["trace[1505785457] 'process raft request' (duration: 427.563253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:31:41.5907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:31:41.161921Z","time spent":"428.536975ms","remote":"127.0.0.1:53748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5941,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:31:41.591014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.772808ms","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-18T14:31:41.591055Z","caller":"traceutil/trace.go:171","msg":"trace[107469641] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1135; }","duration":"388.84331ms","start":"2026-02-18T14:31:41.202202Z","end":"2026-02-18T14:31:41.591045Z","steps":["trace[107469641] 'agreement among raft nodes before linearized reading' (duration: 388.720627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:31:41.591214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:31:41.202119Z","time spent":"389.081467ms","remote":"127.0.0.1:53840","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-18T14:31:41.854432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.042879ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:31:41.85512Z","caller":"traceutil/trace.go:171","msg":"trace[1841526494] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"630.16651ms","start":"2026-02-18T14:31:41.224933Z","end":"2026-02-18T14:31:41.8551Z","steps":["trace[1841526494] 'process raft request' (duration: 485.386693ms)","trace[1841526494] 'compare' (duration: 143.843454ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:31:41.856264Z","caller":"traceutil/trace.go:171","msg":"trace[538660935] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"261.550645ms","start":"2026-02-18T14:31:41.5947Z","end":"2026-02-18T14:31:41.85625Z","steps":["trace[538660935] 'process raft request' (duration: 259.821865ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:31:41.856629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:31:41.224917Z","time spent":"630.248092ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:31:41.856837Z","caller":"traceutil/trace.go:171","msg":"trace[601066377] linearizableReadLoop","detail":"{readStateIndex:1181; appliedIndex:1180; }","duration":"266.624595ms","start":"2026-02-18T14:31:41.590147Z","end":"2026-02-18T14:31:41.856771Z","steps":["trace[601066377] 'read index received' (duration: 120.022465ms)","trace[601066377] 'applied index is now lower than readState.Index' (duration: 146.59832ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:31:41.858728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.488598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-18T14:31:41.858821Z","caller":"traceutil/trace.go:171","msg":"trace[2078130420] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1137; }","duration":"480.626781ms","start":"2026-02-18T14:31:41.37818Z","end":"2026-02-18T14:31:41.858807Z","steps":["trace[2078130420] 'agreement among raft nodes before linearized reading' (duration: 480.348034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:31:41.858915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:31:41.378162Z","time spent":"480.710703ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-18T14:31:41.859184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.600917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:31:41.859324Z","caller":"traceutil/trace.go:171","msg":"trace[1761075257] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1137; }","duration":"165.774861ms","start":"2026-02-18T14:31:41.693537Z","end":"2026-02-18T14:31:41.859312Z","steps":["trace[1761075257] 'agreement among raft nodes before linearized reading' (duration: 165.614817ms)"],"step_count":1}