{"level":"warn","ts":"2026-02-17T19:16:19.843428Z","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-17T19:16:19.843529Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.78:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.78:2380","--initial-cluster=instance=https://199.204.45.78:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.78:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.78: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-17T19:16:19.846047Z","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-17T19:16:19.846063Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-02-17T19:16:19.846084Z","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-17T19:16:19.846704Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"]} {"level":"info","ts":"2026-02-17T19:16:19.84677Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.78: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-17T19:16:19.855389Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.413886ms"} {"level":"info","ts":"2026-02-17T19:16:19.877768Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6eb8766b1ec86e92","cluster-id":"9bbfe9e5d45d3718"} {"level":"info","ts":"2026-02-17T19:16:19.877962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=()"} {"level":"info","ts":"2026-02-17T19:16:19.878031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 0"} {"level":"info","ts":"2026-02-17T19:16:19.878086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6eb8766b1ec86e92 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-17T19:16:19.878119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 1"} {"level":"info","ts":"2026-02-17T19:16:19.878214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"warn","ts":"2026-02-17T19:16:19.885367Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-17T19:16:19.892385Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-17T19:16:19.908218Z","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-17T19:16:19.925196Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6eb8766b1ec86e92","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-17T19:16:19.926247Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6eb8766b1ec86e92","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-17T19:16:19.926457Z","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-17T19:16:19.926514Z","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-17T19:16:19.926551Z","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-17T19:16:19.932751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"info","ts":"2026-02-17T19:16:19.933476Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","added-peer-id":"6eb8766b1ec86e92","added-peer-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-02-17T19:16:19.934215Z","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-17T19:16:19.934486Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6eb8766b1ec86e92","initial-advertise-peer-urls":["https://199.204.45.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-17T19:16:19.934531Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-17T19:16:19.935972Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-02-17T19:16:19.936007Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-02-17T19:16:20.779533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 is starting a new election at term 1"} {"level":"info","ts":"2026-02-17T19:16:20.779588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-17T19:16:20.779616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgPreVoteResp from 6eb8766b1ec86e92 at term 1"} {"level":"info","ts":"2026-02-17T19:16:20.779634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became candidate at term 2"} {"level":"info","ts":"2026-02-17T19:16:20.779639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgVoteResp from 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-02-17T19:16:20.77965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became leader at term 2"} {"level":"info","ts":"2026-02-17T19:16:20.779657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6eb8766b1ec86e92 elected leader 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-02-17T19:16:20.782186Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T19:16:20.78444Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6eb8766b1ec86e92","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.78:2379]}","request-path":"/0/members/6eb8766b1ec86e92/attributes","cluster-id":"9bbfe9e5d45d3718","publish-timeout":"7s"} {"level":"info","ts":"2026-02-17T19:16:20.784932Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T19:16:20.785193Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T19:16:20.785942Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T19:16:20.786006Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T19:16:20.786024Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T19:16:20.787252Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.78:2379"} {"level":"info","ts":"2026-02-17T19:16:20.79341Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-17T19:16:20.793424Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-17T19:16:20.794177Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-17T19:16:36.049069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.384467ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:16:36.04918Z","caller":"traceutil/trace.go:171","msg":"trace[1453464032] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"169.373913ms","start":"2026-02-17T19:16:35.879774Z","end":"2026-02-17T19:16:36.049148Z","steps":["trace[1453464032] 'process raft request' (duration: 48.506022ms)","trace[1453464032] 'compare' (duration: 120.292216ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:16:36.239375Z","caller":"traceutil/trace.go:171","msg":"trace[1458344781] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"127.85635ms","start":"2026-02-17T19:16:36.111485Z","end":"2026-02-17T19:16:36.239341Z","steps":["trace[1458344781] 'process raft request' (duration: 46.29935ms)","trace[1458344781] 'compare' (duration: 81.238147ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:16:36.352074Z","caller":"traceutil/trace.go:171","msg":"trace[1741744864] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"104.031704ms","start":"2026-02-17T19:16:36.247802Z","end":"2026-02-17T19:16:36.351834Z","steps":["trace[1741744864] 'process raft request' (duration: 97.074901ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:16:36.353921Z","caller":"traceutil/trace.go:171","msg":"trace[863152722] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"104.511788ms","start":"2026-02-17T19:16:36.249399Z","end":"2026-02-17T19:16:36.35391Z","steps":["trace[863152722] 'process raft request' (duration: 104.485658ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:16:36.354221Z","caller":"traceutil/trace.go:171","msg":"trace[572662015] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"106.120823ms","start":"2026-02-17T19:16:36.24809Z","end":"2026-02-17T19:16:36.354211Z","steps":["trace[572662015] 'process raft request' (duration: 105.718749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:45.013977Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602682874767858,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T19:16:45.489205Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.023985533s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T19:16:45.490072Z","caller":"traceutil/trace.go:171","msg":"trace[2034426505] linearizableReadLoop","detail":"{readStateIndex:480; appliedIndex:479; }","duration":"976.418047ms","start":"2026-02-17T19:16:44.51363Z","end":"2026-02-17T19:16:45.490048Z","steps":["trace[2034426505] 'read index received' (duration: 976.248476ms)","trace[2034426505] 'applied index is now lower than readState.Index' (duration: 168.941µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:16:45.491174Z","caller":"traceutil/trace.go:171","msg":"trace[38242053] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"1.02599067s","start":"2026-02-17T19:16:44.465166Z","end":"2026-02-17T19:16:45.491157Z","steps":["trace[38242053] 'process raft request' (duration: 1.024737269s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:45.491608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:44.465158Z","time spent":"1.026056491s","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:16:45.49226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.619567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-17T19:16:45.492661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.120056ms","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-17T19:16:45.492689Z","caller":"traceutil/trace.go:171","msg":"trace[1041700200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:466; }","duration":"975.157006ms","start":"2026-02-17T19:16:44.517525Z","end":"2026-02-17T19:16:45.492682Z","steps":["trace[1041700200] 'agreement among raft nodes before linearized reading' (duration: 975.117056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:45.492709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:44.51752Z","time spent":"975.184617ms","remote":"127.0.0.1:55468","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-17T19:16:45.49279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"960.431967ms","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-17T19:16:45.492805Z","caller":"traceutil/trace.go:171","msg":"trace[1035310318] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:466; }","duration":"960.466438ms","start":"2026-02-17T19:16:44.532334Z","end":"2026-02-17T19:16:45.492801Z","steps":["trace[1035310318] 'agreement among raft nodes before linearized reading' (duration: 960.436658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:45.49282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:44.532322Z","time spent":"960.494739ms","remote":"127.0.0.1:55468","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":"info","ts":"2026-02-17T19:16:45.492308Z","caller":"traceutil/trace.go:171","msg":"trace[115576808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:466; }","duration":"978.693757ms","start":"2026-02-17T19:16:44.513603Z","end":"2026-02-17T19:16:45.492297Z","steps":["trace[115576808] 'agreement among raft nodes before linearized reading' (duration: 978.116392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:45.493003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:44.513589Z","time spent":"979.408753ms","remote":"127.0.0.1:55468","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-17T19:16:45.624111Z","caller":"traceutil/trace.go:171","msg":"trace[1442434672] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"124.344761ms","start":"2026-02-17T19:16:45.499748Z","end":"2026-02-17T19:16:45.624093Z","steps":["trace[1442434672] 'process raft request' (duration: 124.314921ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:16:45.624351Z","caller":"traceutil/trace.go:171","msg":"trace[120428999] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"126.659111ms","start":"2026-02-17T19:16:45.497649Z","end":"2026-02-17T19:16:45.624308Z","steps":["trace[120428999] 'process raft request' (duration: 79.567692ms)","trace[120428999] 'compare' (duration: 46.757886ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:16:46.558703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.619868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:16:46.558765Z","caller":"traceutil/trace.go:171","msg":"trace[1465337164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:469; }","duration":"408.72216ms","start":"2026-02-17T19:16:46.150031Z","end":"2026-02-17T19:16:46.558753Z","steps":["trace[1465337164] 'range keys from in-memory index tree' (duration: 408.555448ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:46.558786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:46.150012Z","time spent":"408.76927ms","remote":"127.0.0.1:55190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T19:16:46.55997Z","caller":"traceutil/trace.go:171","msg":"trace[2059408682] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"354.260668ms","start":"2026-02-17T19:16:46.205683Z","end":"2026-02-17T19:16:46.559944Z","steps":["trace[2059408682] 'process raft request' (duration: 354.092506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:46.560268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:46.205659Z","time spent":"354.53659ms","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:16:52.031786Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602682874767974,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-17T19:16:52.500826Z","caller":"traceutil/trace.go:171","msg":"trace[1903164645] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"984.860037ms","start":"2026-02-17T19:16:51.515951Z","end":"2026-02-17T19:16:52.500811Z","steps":["trace[1903164645] 'process raft request' (duration: 945.920394ms)","trace[1903164645] 'compare' (duration: 38.497986ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:16:52.500931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:51.515937Z","time spent":"984.944371ms","remote":"127.0.0.1:55242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4059,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:16:52.588998Z","caller":"traceutil/trace.go:171","msg":"trace[1227000160] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:546; }","duration":"1.057545164s","start":"2026-02-17T19:16:51.531432Z","end":"2026-02-17T19:16:52.588977Z","steps":["trace[1227000160] 'read index received' (duration: 930.445688ms)","trace[1227000160] 'applied index is now lower than readState.Index' (duration: 127.098726ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:16:52.589327Z","caller":"traceutil/trace.go:171","msg":"trace[1846692167] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"899.521099ms","start":"2026-02-17T19:16:51.689789Z","end":"2026-02-17T19:16:52.58931Z","steps":["trace[1846692167] 'process raft request' (duration: 899.0322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:52.58944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:51.68977Z","time spent":"899.601992ms","remote":"127.0.0.1:55242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T19:16:52.589634Z","caller":"traceutil/trace.go:171","msg":"trace[667020147] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"700.054213ms","start":"2026-02-17T19:16:51.889569Z","end":"2026-02-17T19:16:52.589623Z","steps":["trace[667020147] 'process raft request' (duration: 699.370086ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:52.589716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:51.889529Z","time spent":"700.161918ms","remote":"127.0.0.1:55242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-17T19:16:52.589847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058409258s","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-17T19:16:52.589885Z","caller":"traceutil/trace.go:171","msg":"trace[38502021] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:533; }","duration":"1.05847566s","start":"2026-02-17T19:16:51.5314Z","end":"2026-02-17T19:16:52.589875Z","steps":["trace[38502021] 'agreement among raft nodes before linearized reading' (duration: 1.058380977s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:52.589913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:51.53138Z","time spent":"1.058526253s","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-17T19:16:52.854299Z","caller":"traceutil/trace.go:171","msg":"trace[51348247] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:549; }","duration":"265.193735ms","start":"2026-02-17T19:16:52.589063Z","end":"2026-02-17T19:16:52.854257Z","steps":["trace[51348247] 'read index received' (duration: 264.925865ms)","trace[51348247] 'applied index is now lower than readState.Index' (duration: 267.1µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:16:52.85459Z","caller":"traceutil/trace.go:171","msg":"trace[190693345] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"360.064142ms","start":"2026-02-17T19:16:52.494513Z","end":"2026-02-17T19:16:52.854578Z","steps":["trace[190693345] 'process raft request' (duration: 359.550412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:52.854728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:52.494482Z","time spent":"360.156225ms","remote":"127.0.0.1:55242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T19:16:52.855027Z","caller":"traceutil/trace.go:171","msg":"trace[831535950] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"347.09426ms","start":"2026-02-17T19:16:52.507921Z","end":"2026-02-17T19:16:52.855016Z","steps":["trace[831535950] 'process raft request' (duration: 346.265998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:52.85517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:52.507899Z","time spent":"347.155243ms","remote":"127.0.0.1:55242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4068,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:16:52.855364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.145688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:16:52.855421Z","caller":"traceutil/trace.go:171","msg":"trace[721939663] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:535; }","duration":"566.17738ms","start":"2026-02-17T19:16:52.28921Z","end":"2026-02-17T19:16:52.855387Z","steps":["trace[721939663] 'agreement among raft nodes before linearized reading' (duration: 566.126068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:52.855453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:52.289174Z","time spent":"566.271113ms","remote":"127.0.0.1:55242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-17T19:16:52.855792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.333521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:16:52.855841Z","caller":"traceutil/trace.go:171","msg":"trace[299938717] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:535; }","duration":"769.410725ms","start":"2026-02-17T19:16:52.086406Z","end":"2026-02-17T19:16:52.855817Z","steps":["trace[299938717] 'agreement among raft nodes before linearized reading' (duration: 769.359243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:52.85587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:52.08638Z","time spent":"769.483877ms","remote":"127.0.0.1:55242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-02-17T19:16:52.85612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.620764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-9tjcb\" ","response":"range_response_count:1 size:4635"} {"level":"info","ts":"2026-02-17T19:16:52.856221Z","caller":"traceutil/trace.go:171","msg":"trace[875432061] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-9tjcb; range_end:; response_count:1; response_revision:535; }","duration":"957.833713ms","start":"2026-02-17T19:16:51.898313Z","end":"2026-02-17T19:16:52.856146Z","steps":["trace[875432061] 'agreement among raft nodes before linearized reading' (duration: 957.619754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:52.856254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:51.898298Z","time spent":"957.947388ms","remote":"127.0.0.1:55378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4658,"request content":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-9tjcb\" "} {"level":"warn","ts":"2026-02-17T19:16:52.85648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.166566576s","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-17T19:16:52.856507Z","caller":"traceutil/trace.go:171","msg":"trace[1174821083] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:535; }","duration":"1.166606038s","start":"2026-02-17T19:16:51.689893Z","end":"2026-02-17T19:16:52.856499Z","steps":["trace[1174821083] 'agreement among raft nodes before linearized reading' (duration: 1.166502424s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:52.856532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:51.689888Z","time spent":"1.16663853s","remote":"127.0.0.1:55468","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-17T19:16:52.856697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.166818707s","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-17T19:16:52.856724Z","caller":"traceutil/trace.go:171","msg":"trace[1502147162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:535; }","duration":"1.166883769s","start":"2026-02-17T19:16:51.689832Z","end":"2026-02-17T19:16:52.856716Z","steps":["trace[1502147162] 'agreement among raft nodes before linearized reading' (duration: 1.166811596s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:52.856749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:51.689811Z","time spent":"1.166932631s","remote":"127.0.0.1:55468","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-17T19:16:52.856935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.281478043s","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-17T19:16:52.856962Z","caller":"traceutil/trace.go:171","msg":"trace[1462747552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:535; }","duration":"1.281534386s","start":"2026-02-17T19:16:51.57542Z","end":"2026-02-17T19:16:52.856954Z","steps":["trace[1462747552] 'agreement among raft nodes before linearized reading' (duration: 1.281485945s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:52.857011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:51.575403Z","time spent":"1.281602178s","remote":"127.0.0.1:55468","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-17T19:16:53.002183Z","caller":"traceutil/trace.go:171","msg":"trace[708394409] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:551; }","duration":"147.809352ms","start":"2026-02-17T19:16:52.854353Z","end":"2026-02-17T19:16:53.002162Z","steps":["trace[708394409] 'read index received' (duration: 137.85925ms)","trace[708394409] 'applied index is now lower than readState.Index' (duration: 9.949192ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:16:53.002695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.328839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:16:53.003185Z","caller":"traceutil/trace.go:171","msg":"trace[1287689523] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:537; }","duration":"316.87897ms","start":"2026-02-17T19:16:52.686295Z","end":"2026-02-17T19:16:53.003174Z","steps":["trace[1287689523] 'agreement among raft nodes before linearized reading' (duration: 316.320468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:53.003319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:52.686276Z","time spent":"317.031817ms","remote":"127.0.0.1:55242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-17T19:16:53.002829Z","caller":"traceutil/trace.go:171","msg":"trace[465729500] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"406.323564ms","start":"2026-02-17T19:16:52.596497Z","end":"2026-02-17T19:16:53.002821Z","steps":["trace[465729500] 'process raft request' (duration: 395.703865ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:53.003687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:52.596484Z","time spent":"407.125605ms","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:16:53.002928Z","caller":"traceutil/trace.go:171","msg":"trace[1143282891] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"393.713916ms","start":"2026-02-17T19:16:52.609205Z","end":"2026-02-17T19:16:53.002919Z","steps":["trace[1143282891] 'process raft request' (duration: 392.837852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:53.004572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:52.609188Z","time spent":"395.351871ms","remote":"127.0.0.1:55242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13205,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:16:53.021017Z","caller":"traceutil/trace.go:171","msg":"trace[1738769880] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"151.228468ms","start":"2026-02-17T19:16:52.869777Z","end":"2026-02-17T19:16:53.021005Z","steps":["trace[1738769880] 'process raft request' (duration: 151.209638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:53.021277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.984468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:16:53.021333Z","caller":"traceutil/trace.go:171","msg":"trace[762943577] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:541; }","duration":"136.066022ms","start":"2026-02-17T19:16:52.885253Z","end":"2026-02-17T19:16:53.021319Z","steps":["trace[762943577] 'agreement among raft nodes before linearized reading' (duration: 135.952807ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:16:53.021404Z","caller":"traceutil/trace.go:171","msg":"trace[1774679153] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"154.06106ms","start":"2026-02-17T19:16:52.867336Z","end":"2026-02-17T19:16:53.021397Z","steps":["trace[1774679153] 'process raft request' (duration: 153.519069ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:16:53.021513Z","caller":"traceutil/trace.go:171","msg":"trace[1309095821] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"154.05387ms","start":"2026-02-17T19:16:52.867451Z","end":"2026-02-17T19:16:53.021505Z","steps":["trace[1309095821] 'process raft request' (duration: 153.511049ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:16:53.021616Z","caller":"traceutil/trace.go:171","msg":"trace[361919602] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"154.228287ms","start":"2026-02-17T19:16:52.867381Z","end":"2026-02-17T19:16:53.02161Z","steps":["trace[361919602] 'process raft request' (duration: 153.558451ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:16:53.189573Z","caller":"traceutil/trace.go:171","msg":"trace[511555473] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"102.474011ms","start":"2026-02-17T19:16:53.08708Z","end":"2026-02-17T19:16:53.189554Z","steps":["trace[511555473] 'process raft request' (duration: 102.296084ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:16:53.364588Z","caller":"traceutil/trace.go:171","msg":"trace[2998186] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"121.397389ms","start":"2026-02-17T19:16:53.243175Z","end":"2026-02-17T19:16:53.364572Z","steps":["trace[2998186] 'process raft request' (duration: 121.18801ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:16:54.267787Z","caller":"traceutil/trace.go:171","msg":"trace[1047593434] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"378.688485ms","start":"2026-02-17T19:16:53.889079Z","end":"2026-02-17T19:16:54.267767Z","steps":["trace[1047593434] 'process raft request' (duration: 378.538839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:54.267885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:53.889055Z","time spent":"378.782559ms","remote":"127.0.0.1:55242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T19:16:54.268302Z","caller":"traceutil/trace.go:171","msg":"trace[173418868] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:568; }","duration":"305.366159ms","start":"2026-02-17T19:16:53.962928Z","end":"2026-02-17T19:16:54.268294Z","steps":["trace[173418868] 'read index received' (duration: 305.361139ms)","trace[173418868] 'applied index is now lower than readState.Index' (duration: 4.4µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:16:54.268381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.449132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-17T19:16:54.268411Z","caller":"traceutil/trace.go:171","msg":"trace[1024995504] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:552; }","duration":"305.514636ms","start":"2026-02-17T19:16:53.96289Z","end":"2026-02-17T19:16:54.268405Z","steps":["trace[1024995504] 'agreement among raft nodes before linearized reading' (duration: 305.443103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:54.268431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:53.962871Z","time spent":"305.555347ms","remote":"127.0.0.1:55228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.78\" "} {"level":"info","ts":"2026-02-17T19:16:54.274784Z","caller":"traceutil/trace.go:171","msg":"trace[170858401] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"188.429868ms","start":"2026-02-17T19:16:54.086334Z","end":"2026-02-17T19:16:54.274764Z","steps":["trace[170858401] 'process raft request' (duration: 188.320683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:54.275249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.869131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:16:54.275294Z","caller":"traceutil/trace.go:171","msg":"trace[1555840201] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:0; response_revision:553; }","duration":"211.922103ms","start":"2026-02-17T19:16:54.063359Z","end":"2026-02-17T19:16:54.275281Z","steps":["trace[1555840201] 'agreement among raft nodes before linearized reading' (duration: 211.807948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:54.275497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.264642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:16:54.275534Z","caller":"traceutil/trace.go:171","msg":"trace[396508534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"127.392457ms","start":"2026-02-17T19:16:54.148128Z","end":"2026-02-17T19:16:54.275521Z","steps":["trace[396508534] 'agreement among raft nodes before linearized reading' (duration: 127.346135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:55.386527Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602682874768045,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T19:16:55.887645Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602682874768045,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T19:16:56.258634Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.537110053s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T19:16:56.284817Z","caller":"traceutil/trace.go:171","msg":"trace[1479471228] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"1.56334179s","start":"2026-02-17T19:16:54.721451Z","end":"2026-02-17T19:16:56.284793Z","steps":["trace[1479471228] 'process raft request' (duration: 1.538134681s)","trace[1479471228] 'compare' (duration: 25.098405ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:16:56.284842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.563518497s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-17T19:16:56.287199Z","caller":"traceutil/trace.go:171","msg":"trace[1391187153] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:567; }","duration":"1.565905867s","start":"2026-02-17T19:16:54.721271Z","end":"2026-02-17T19:16:56.287177Z","steps":["trace[1391187153] 'range keys from in-memory index tree' (duration: 1.563377162s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:56.287303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:54.721257Z","time spent":"1.566026412s","remote":"127.0.0.1:55242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-17T19:16:56.287439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:54.721444Z","time spent":"1.565455351s","remote":"127.0.0.1:55564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1109,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T19:16:56.289273Z","caller":"traceutil/trace.go:171","msg":"trace[766262722] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:584; }","duration":"1.402814334s","start":"2026-02-17T19:16:54.886443Z","end":"2026-02-17T19:16:56.289258Z","steps":["trace[766262722] 'read index received' (duration: 1.373068093s)","trace[766262722] 'applied index is now lower than readState.Index' (duration: 29.745431ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:16:56.289655Z","caller":"traceutil/trace.go:171","msg":"trace[1076340998] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"1.496346476s","start":"2026-02-17T19:16:54.793291Z","end":"2026-02-17T19:16:56.289637Z","steps":["trace[1076340998] 'process raft request' (duration: 1.495085318s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:56.296104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:54.793264Z","time spent":"1.502807859s","remote":"127.0.0.1:55242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:16:56.290478Z","caller":"traceutil/trace.go:171","msg":"trace[53550570] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"1.493402182s","start":"2026-02-17T19:16:54.79706Z","end":"2026-02-17T19:16:56.290462Z","steps":["trace[53550570] 'process raft request' (duration: 1.491789131s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:56.290903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.282022172s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-17T19:16:56.290979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.404541369s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"warn","ts":"2026-02-17T19:16:56.291146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.599481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188243"} {"level":"warn","ts":"2026-02-17T19:16:56.291308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.13457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"warn","ts":"2026-02-17T19:16:56.291378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.350026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"warn","ts":"2026-02-17T19:16:56.291436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.63659ms","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":"warn","ts":"2026-02-17T19:16:56.291584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.259789495s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-17T19:16:56.291631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139801525s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-17T19:16:56.291772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.206034857s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"warn","ts":"2026-02-17T19:16:56.291844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.260783903s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-17T19:16:56.292179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.261139067s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-17T19:16:56.29604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.009896465s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"warn","ts":"2026-02-17T19:16:56.29979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:54.797045Z","time spent":"1.502712093s","remote":"127.0.0.1:55242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:16:56.299872Z","caller":"traceutil/trace.go:171","msg":"trace[352149530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:571; }","duration":"1.291018131s","start":"2026-02-17T19:16:55.008831Z","end":"2026-02-17T19:16:56.299849Z","steps":["trace[352149530] 'agreement among raft nodes before linearized reading' (duration: 1.28197413s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:56.30193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:55.008813Z","time spent":"1.29310791s","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-17T19:16:56.299915Z","caller":"traceutil/trace.go:171","msg":"trace[1560947] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:571; }","duration":"1.413543018s","start":"2026-02-17T19:16:54.886366Z","end":"2026-02-17T19:16:56.299909Z","steps":["trace[1560947] 'agreement among raft nodes before linearized reading' (duration: 1.404561209s)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:16:56.29996Z","caller":"traceutil/trace.go:171","msg":"trace[225962857] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:571; }","duration":"413.454635ms","start":"2026-02-17T19:16:55.886501Z","end":"2026-02-17T19:16:56.299955Z","steps":["trace[225962857] 'agreement among raft nodes before linearized reading' (duration: 404.494587ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:16:56.299995Z","caller":"traceutil/trace.go:171","msg":"trace[1813553281] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:571; }","duration":"613.825417ms","start":"2026-02-17T19:16:55.686165Z","end":"2026-02-17T19:16:56.299991Z","steps":["trace[1813553281] 'agreement among raft nodes before linearized reading' (duration: 605.000264ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:16:56.30004Z","caller":"traceutil/trace.go:171","msg":"trace[2145694755] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:571; }","duration":"814.049214ms","start":"2026-02-17T19:16:55.485986Z","end":"2026-02-17T19:16:56.300035Z","steps":["trace[2145694755] 'agreement among raft nodes before linearized reading' (duration: 805.338275ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:16:56.300076Z","caller":"traceutil/trace.go:171","msg":"trace[1096079049] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:571; }","duration":"901.307187ms","start":"2026-02-17T19:16:55.398761Z","end":"2026-02-17T19:16:56.300068Z","steps":["trace[1096079049] 'agreement among raft nodes before linearized reading' (duration: 892.63156ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:16:56.300122Z","caller":"traceutil/trace.go:171","msg":"trace[4187644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:571; }","duration":"1.268385129s","start":"2026-02-17T19:16:55.031732Z","end":"2026-02-17T19:16:56.300118Z","steps":["trace[4187644] 'agreement among raft nodes before linearized reading' (duration: 1.259778644s)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:16:56.300156Z","caller":"traceutil/trace.go:171","msg":"trace[1650277231] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:571; }","duration":"1.148350457s","start":"2026-02-17T19:16:55.151801Z","end":"2026-02-17T19:16:56.300151Z","steps":["trace[1650277231] 'agreement among raft nodes before linearized reading' (duration: 1.139811435s)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:16:56.300241Z","caller":"traceutil/trace.go:171","msg":"trace[1971232321] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:571; }","duration":"1.214494797s","start":"2026-02-17T19:16:55.085728Z","end":"2026-02-17T19:16:56.300223Z","steps":["trace[1971232321] 'agreement among raft nodes before linearized reading' (duration: 1.205938514s)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:16:56.300278Z","caller":"traceutil/trace.go:171","msg":"trace[1480443606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:571; }","duration":"1.2692186s","start":"2026-02-17T19:16:55.031054Z","end":"2026-02-17T19:16:56.300273Z","steps":["trace[1480443606] 'agreement among raft nodes before linearized reading' (duration: 1.260767442s)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:16:56.300321Z","caller":"traceutil/trace.go:171","msg":"trace[1005147653] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:571; }","duration":"1.269369296s","start":"2026-02-17T19:16:55.030947Z","end":"2026-02-17T19:16:56.300317Z","steps":["trace[1005147653] 'agreement among raft nodes before linearized reading' (duration: 1.260926668s)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:16:56.300357Z","caller":"traceutil/trace.go:171","msg":"trace[1686572263] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:571; }","duration":"1.014264779s","start":"2026-02-17T19:16:55.286088Z","end":"2026-02-17T19:16:56.300353Z","steps":["trace[1686572263] 'agreement among raft nodes before linearized reading' (duration: 1.005362454s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:56.303146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:54.886345Z","time spent":"1.41679368s","remote":"127.0.0.1:55242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13252,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-17T19:16:56.303163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:55.886479Z","time spent":"416.680356ms","remote":"127.0.0.1:55242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":188266,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-17T19:16:56.303941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:55.686107Z","time spent":"617.824787ms","remote":"127.0.0.1:55242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":188518,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-17T19:16:56.303988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:55.485966Z","time spent":"818.016323ms","remote":"127.0.0.1:55242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4356,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-02-17T19:16:56.304032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:55.39874Z","time spent":"905.286957ms","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-17T19:16:56.304068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:55.031718Z","time spent":"1.272345268s","remote":"127.0.0.1:55468","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-17T19:16:56.304114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:55.151786Z","time spent":"1.152324076s","remote":"127.0.0.1:55190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T19:16:56.304146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:55.085647Z","time spent":"1.218494777s","remote":"127.0.0.1:55242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24267,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-02-17T19:16:56.304212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:55.031023Z","time spent":"1.27318389s","remote":"127.0.0.1:55468","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-17T19:16:56.304241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:55.030929Z","time spent":"1.273308325s","remote":"127.0.0.1:55468","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-17T19:16:56.304287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:16:55.286069Z","time spent":"1.018211358s","remote":"127.0.0.1:55242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10400,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-17T19:16:56.643997Z","caller":"traceutil/trace.go:171","msg":"trace[2073240874] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"157.245623ms","start":"2026-02-17T19:16:56.486733Z","end":"2026-02-17T19:16:56.643979Z","steps":["trace[2073240874] 'process raft request' (duration: 149.913617ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:16:56.658052Z","caller":"traceutil/trace.go:171","msg":"trace[1381629710] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"149.095597ms","start":"2026-02-17T19:16:56.508939Z","end":"2026-02-17T19:16:56.658034Z","steps":["trace[1381629710] 'process raft request' (duration: 148.971312ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:16:56.65823Z","caller":"traceutil/trace.go:171","msg":"trace[801890770] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"171.236441ms","start":"2026-02-17T19:16:56.486987Z","end":"2026-02-17T19:16:56.658223Z","steps":["trace[801890770] 'process raft request' (duration: 170.775444ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:16:58.586267Z","caller":"traceutil/trace.go:171","msg":"trace[95258400] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"161.532854ms","start":"2026-02-17T19:16:58.424719Z","end":"2026-02-17T19:16:58.586252Z","steps":["trace[95258400] 'read index received' (duration: 161.409069ms)","trace[95258400] 'applied index is now lower than readState.Index' (duration: 123.094µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:16:58.586347Z","caller":"traceutil/trace.go:171","msg":"trace[1370784478] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"205.062078ms","start":"2026-02-17T19:16:58.381279Z","end":"2026-02-17T19:16:58.586341Z","steps":["trace[1370784478] 'process raft request' (duration: 204.870201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:58.586491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.760592ms","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-17T19:16:58.586506Z","caller":"traceutil/trace.go:171","msg":"trace[24669332] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"161.822714ms","start":"2026-02-17T19:16:58.424679Z","end":"2026-02-17T19:16:58.586502Z","steps":["trace[24669332] 'agreement among raft nodes before linearized reading' (duration: 161.747891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:58.586576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.484061ms","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-17T19:16:58.586588Z","caller":"traceutil/trace.go:171","msg":"trace[291036152] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"161.505473ms","start":"2026-02-17T19:16:58.425079Z","end":"2026-02-17T19:16:58.586584Z","steps":["trace[291036152] 'agreement among raft nodes before linearized reading' (duration: 161.472132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:16:58.586644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.566528ms","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-17T19:16:58.586654Z","caller":"traceutil/trace.go:171","msg":"trace[197725046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"160.585458ms","start":"2026-02-17T19:16:58.426066Z","end":"2026-02-17T19:16:58.586651Z","steps":["trace[197725046] 'agreement among raft nodes before linearized reading' (duration: 160.552007ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:16:58.754128Z","caller":"traceutil/trace.go:171","msg":"trace[1188495762] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"163.447584ms","start":"2026-02-17T19:16:58.590668Z","end":"2026-02-17T19:16:58.754115Z","steps":["trace[1188495762] 'process raft request' (duration: 115.402893ms)","trace[1188495762] 'compare' (duration: 47.75893ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:16:58.754253Z","caller":"traceutil/trace.go:171","msg":"trace[837766699] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"162.909624ms","start":"2026-02-17T19:16:58.591339Z","end":"2026-02-17T19:16:58.754249Z","steps":["trace[837766699] 'process raft request' (duration: 162.637424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:00.025679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.565994ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-17T19:17:00.802832Z","caller":"traceutil/trace.go:171","msg":"trace[2119076957] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"151.201962ms","start":"2026-02-17T19:17:00.651615Z","end":"2026-02-17T19:17:00.802817Z","steps":["trace[2119076957] 'process raft request' (duration: 150.989735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:01.098448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.229063ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:17:01.098599Z","caller":"traceutil/trace.go:171","msg":"trace[2108840320] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"290.204389ms","start":"2026-02-17T19:17:00.808372Z","end":"2026-02-17T19:17:01.098577Z","steps":["trace[2108840320] 'process raft request' (duration: 109.645464ms)","trace[2108840320] 'compare' (duration: 180.080708ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:17:01.098901Z","caller":"traceutil/trace.go:171","msg":"trace[1566682779] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"289.822375ms","start":"2026-02-17T19:17:00.809073Z","end":"2026-02-17T19:17:01.098896Z","steps":["trace[1566682779] 'process raft request' (duration: 289.443631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:07.471534Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602682874768172,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T19:17:07.662941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.644631ms","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-17T19:17:07.662997Z","caller":"traceutil/trace.go:171","msg":"trace[1871242346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:619; }","duration":"996.753865ms","start":"2026-02-17T19:17:06.666232Z","end":"2026-02-17T19:17:07.662986Z","steps":["trace[1871242346] 'range keys from in-memory index tree' (duration: 996.491366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:07.66302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:06.666211Z","time spent":"996.803507ms","remote":"127.0.0.1:55468","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-17T19:17:07.663199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.897268ms","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-17T19:17:07.663213Z","caller":"traceutil/trace.go:171","msg":"trace[427605681] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:619; }","duration":"818.94536ms","start":"2026-02-17T19:17:06.844263Z","end":"2026-02-17T19:17:07.663208Z","steps":["trace[427605681] 'range keys from in-memory index tree' (duration: 818.776434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:07.663225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:06.844198Z","time spent":"819.023673ms","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-17T19:17:07.663401Z","caller":"traceutil/trace.go:171","msg":"trace[1686147248] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"692.663228ms","start":"2026-02-17T19:17:06.970732Z","end":"2026-02-17T19:17:07.663396Z","steps":["trace[1686147248] 'read index received' (duration: 615.05212ms)","trace[1686147248] 'applied index is now lower than readState.Index' (duration: 77.610548ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:17:07.663443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.640073ms","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-17T19:17:07.663503Z","caller":"traceutil/trace.go:171","msg":"trace[714775029] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"703.760914ms","start":"2026-02-17T19:17:06.959733Z","end":"2026-02-17T19:17:07.663494Z","steps":["trace[714775029] 'process raft request' (duration: 626.107855ms)","trace[714775029] 'compare' (duration: 77.315678ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:17:07.66351Z","caller":"traceutil/trace.go:171","msg":"trace[614867653] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:619; }","duration":"756.749656ms","start":"2026-02-17T19:17:06.906743Z","end":"2026-02-17T19:17:07.663492Z","steps":["trace[614867653] 'range keys from in-memory index tree' (duration: 756.499258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:07.663552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:06.959709Z","time spent":"703.808875ms","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:17:07.66355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:06.906723Z","time spent":"756.818569ms","remote":"127.0.0.1:55468","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-17T19:17:07.663639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.916787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jxnms\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-17T19:17:07.663654Z","caller":"traceutil/trace.go:171","msg":"trace[900627460] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jxnms; range_end:; response_count:1; response_revision:620; }","duration":"693.0157ms","start":"2026-02-17T19:17:06.970633Z","end":"2026-02-17T19:17:07.663649Z","steps":["trace[900627460] 'agreement among raft nodes before linearized reading' (duration: 692.974749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:07.663669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:06.970622Z","time spent":"693.042031ms","remote":"127.0.0.1:55378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16675,"request content":"key:\"/registry/pods/kube-system/cilium-jxnms\" "} {"level":"warn","ts":"2026-02-17T19:17:07.665451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.210549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:17:07.665887Z","caller":"traceutil/trace.go:171","msg":"trace[1109324164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"513.636593ms","start":"2026-02-17T19:17:07.152079Z","end":"2026-02-17T19:17:07.665716Z","steps":["trace[1109324164] 'agreement among raft nodes before linearized reading' (duration: 513.143147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:07.666021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:07.152063Z","time spent":"513.942684ms","remote":"127.0.0.1:55190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T19:17:07.675405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.251175ms","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-17T19:17:07.675511Z","caller":"traceutil/trace.go:171","msg":"trace[1222225906] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:620; }","duration":"552.38882ms","start":"2026-02-17T19:17:07.123087Z","end":"2026-02-17T19:17:07.675476Z","steps":["trace[1222225906] 'agreement among raft nodes before linearized reading' (duration: 552.256446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:07.675661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:07.123074Z","time spent":"552.539566ms","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-17T19:17:07.679578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.655933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-17T19:17:07.679685Z","caller":"traceutil/trace.go:171","msg":"trace[1722085174] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:620; }","duration":"556.784567ms","start":"2026-02-17T19:17:07.122887Z","end":"2026-02-17T19:17:07.679671Z","steps":["trace[1722085174] 'agreement among raft nodes before linearized reading' (duration: 541.239637ms)","trace[1722085174] 'range keys from bolt db' (duration: 15.415836ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:17:07.679796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:07.122868Z","time spent":"556.899662ms","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-17T19:17:09.003698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.234853171s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:17:09.003799Z","caller":"traceutil/trace.go:171","msg":"trace[2070797666] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"1.32354043s","start":"2026-02-17T19:17:07.680246Z","end":"2026-02-17T19:17:09.003786Z","steps":["trace[2070797666] 'process raft request' (duration: 88.551734ms)","trace[2070797666] 'compare' (duration: 1.234420297s)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:17:09.00383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:07.680233Z","time spent":"1.323583312s","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:17:09.035339Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.266540321s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T19:17:09.035633Z","caller":"traceutil/trace.go:171","msg":"trace[934997455] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"1.339273285s","start":"2026-02-17T19:17:07.696344Z","end":"2026-02-17T19:17:09.035617Z","steps":["trace[934997455] 'process raft request' (duration: 1.339252405s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:09.035704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:07.696335Z","time spent":"1.339319127s","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:17:09.035939Z","caller":"traceutil/trace.go:171","msg":"trace[729717307] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"1.34835726s","start":"2026-02-17T19:17:07.687575Z","end":"2026-02-17T19:17:09.035933Z","steps":["trace[729717307] 'process raft request' (duration: 1.347879924s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:09.035966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:07.687559Z","time spent":"1.34839194s","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:17:09.03603Z","caller":"traceutil/trace.go:171","msg":"trace[909750015] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"1.343950492s","start":"2026-02-17T19:17:07.692075Z","end":"2026-02-17T19:17:09.036026Z","steps":["trace[909750015] 'process raft request' (duration: 1.343455116s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:09.036061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:07.692057Z","time spent":"1.343990084s","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:17:09.036133Z","caller":"traceutil/trace.go:171","msg":"trace[920935883] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"1.342953578s","start":"2026-02-17T19:17:07.693153Z","end":"2026-02-17T19:17:09.036107Z","steps":["trace[920935883] 'process raft request' (duration: 1.342420151s)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:17:09.036318Z","caller":"traceutil/trace.go:171","msg":"trace[1613815370] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"1.344173949s","start":"2026-02-17T19:17:07.692138Z","end":"2026-02-17T19:17:09.036312Z","steps":["trace[1613815370] 'process raft request' (duration: 1.343410544s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:09.03635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:07.692134Z","time spent":"1.34419887s","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:17:09.036348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:07.693147Z","time spent":"1.343111603s","remote":"127.0.0.1:55378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:17:09.308906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.372166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:17:09.308984Z","caller":"traceutil/trace.go:171","msg":"trace[2065167508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"159.49773ms","start":"2026-02-17T19:17:09.149471Z","end":"2026-02-17T19:17:09.308969Z","steps":["trace[2065167508] 'range keys from in-memory index tree' (duration: 159.289723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:11.511007Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602682874768184,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T19:17:11.86051Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.324672293s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-17T19:17:11.998408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.774746ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-17T19:17:11.998469Z","caller":"traceutil/trace.go:171","msg":"trace[819088976] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"988.511821ms","start":"2026-02-17T19:17:11.009947Z","end":"2026-02-17T19:17:11.998459Z","steps":["trace[819088976] 'read index received' (duration: 850.649462ms)","trace[819088976] 'applied index is now lower than readState.Index' (duration: 137.861519ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:17:11.998637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.200896ms","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-17T19:17:11.998652Z","caller":"traceutil/trace.go:171","msg":"trace[956848293] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:626; }","duration":"952.242106ms","start":"2026-02-17T19:17:11.046405Z","end":"2026-02-17T19:17:11.998647Z","steps":["trace[956848293] 'agreement among raft nodes before linearized reading' (duration: 952.166564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:11.998637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.050671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-17T19:17:11.998668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:11.046391Z","time spent":"952.273158ms","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-17T19:17:11.998687Z","caller":"traceutil/trace.go:171","msg":"trace[1586816422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"848.103693ms","start":"2026-02-17T19:17:11.150567Z","end":"2026-02-17T19:17:11.99867Z","steps":["trace[1586816422] 'agreement among raft nodes before linearized reading' (duration: 848.032841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:11.998723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:11.150504Z","time spent":"848.210506ms","remote":"127.0.0.1:55190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T19:17:11.998784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.364158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-17T19:17:11.998797Z","caller":"traceutil/trace.go:171","msg":"trace[1923347993] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:626; }","duration":"951.387869ms","start":"2026-02-17T19:17:11.047405Z","end":"2026-02-17T19:17:11.998793Z","steps":["trace[1923347993] 'agreement among raft nodes before linearized reading' (duration: 951.343747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:11.998808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:11.047399Z","time spent":"951.406299ms","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-17T19:17:11.998886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.877215ms","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-17T19:17:11.998897Z","caller":"traceutil/trace.go:171","msg":"trace[733596676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:626; }","duration":"951.903206ms","start":"2026-02-17T19:17:11.046991Z","end":"2026-02-17T19:17:11.998894Z","steps":["trace[733596676] 'agreement among raft nodes before linearized reading' (duration: 951.866615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:11.99891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:11.046985Z","time spent":"951.921106ms","remote":"127.0.0.1:55468","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-17T19:17:11.999001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.971357ms","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-17T19:17:11.999014Z","caller":"traceutil/trace.go:171","msg":"trace[2022816314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:626; }","duration":"951.991889ms","start":"2026-02-17T19:17:11.047017Z","end":"2026-02-17T19:17:11.999009Z","steps":["trace[2022816314] 'agreement among raft nodes before linearized reading' (duration: 951.739211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:11.999025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:11.047014Z","time spent":"952.009039ms","remote":"127.0.0.1:55468","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-17T19:17:11.999043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.08987ms","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-17T19:17:11.999076Z","caller":"traceutil/trace.go:171","msg":"trace[177452538] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:626; }","duration":"989.144132ms","start":"2026-02-17T19:17:11.009922Z","end":"2026-02-17T19:17:11.999066Z","steps":["trace[177452538] 'agreement among raft nodes before linearized reading' (duration: 988.641595ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:11.999106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:11.009909Z","time spent":"989.189154ms","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-17T19:17:12.48829Z","caller":"traceutil/trace.go:171","msg":"trace[1086342902] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"484.00729ms","start":"2026-02-17T19:17:12.004234Z","end":"2026-02-17T19:17:12.488242Z","steps":["trace[1086342902] 'process raft request' (duration: 483.848435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:12.488376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:12.004228Z","time spent":"484.100083ms","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:17:12.489192Z","caller":"traceutil/trace.go:171","msg":"trace[1530261269] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"484.830316ms","start":"2026-02-17T19:17:12.004356Z","end":"2026-02-17T19:17:12.489186Z","steps":["trace[1530261269] 'process raft request' (duration: 483.836685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:12.489225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:12.00434Z","time spent":"484.867247ms","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:17:12.489689Z","caller":"traceutil/trace.go:171","msg":"trace[108868489] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"484.427263ms","start":"2026-02-17T19:17:12.005256Z","end":"2026-02-17T19:17:12.489683Z","steps":["trace[108868489] 'process raft request' (duration: 482.957616ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:12.489735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:12.005249Z","time spent":"484.460714ms","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:17:12.48991Z","caller":"traceutil/trace.go:171","msg":"trace[572161884] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"484.537857ms","start":"2026-02-17T19:17:12.005365Z","end":"2026-02-17T19:17:12.489903Z","steps":["trace[572161884] 'process raft request' (duration: 484.490295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:12.489994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:12.00536Z","time spent":"484.564568ms","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:17:14.465054Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602682874768194,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T19:17:14.966038Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602682874768194,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T19:17:15.466874Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602682874768194,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T19:17:15.967632Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602682874768194,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T19:17:16.23994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000461949s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-17T19:17:16.240005Z","caller":"traceutil/trace.go:171","msg":"trace[1913573144] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000559851s","start":"2026-02-17T19:17:14.239431Z","end":"2026-02-17T19:17:16.239991Z","steps":["trace[1913573144] 'agreement among raft nodes before linearized reading' (duration: 2.000473839s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:16.240088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:14.239416Z","time spent":"2.000656725s","remote":"127.0.0.1:55190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T19:17:16.468653Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602682874768194,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T19:17:16.644202Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.805584519s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-17T19:17:17.070369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.921555149s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:17:17.070418Z","caller":"traceutil/trace.go:171","msg":"trace[1486197146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"3.921638152s","start":"2026-02-17T19:17:13.148769Z","end":"2026-02-17T19:17:17.070407Z","steps":["trace[1486197146] 'range keys from in-memory index tree' (duration: 3.921506807s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:17.070438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:13.148755Z","time spent":"3.921679632s","remote":"127.0.0.1:55190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T19:17:17.07176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.385473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:17:17.071831Z","caller":"traceutil/trace.go:171","msg":"trace[1715179490] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:651; }","duration":"3.107785593s","start":"2026-02-17T19:17:13.964039Z","end":"2026-02-17T19:17:17.071825Z","steps":["trace[1715179490] 'read index received' (duration: 2.680311517s)","trace[1715179490] 'applied index is now lower than readState.Index' (duration: 427.473526ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:17:17.071939Z","caller":"traceutil/trace.go:171","msg":"trace[1601938206] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"3.233403647s","start":"2026-02-17T19:17:13.838529Z","end":"2026-02-17T19:17:17.071932Z","steps":["trace[1601938206] 'process raft request' (duration: 2.805814587s)","trace[1601938206] 'compare' (duration: 427.25495ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:17:17.071972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:13.838503Z","time spent":"3.233455139s","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:17:17.072055Z","caller":"traceutil/trace.go:171","msg":"trace[1848104122] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"829.147455ms","start":"2026-02-17T19:17:16.242902Z","end":"2026-02-17T19:17:17.072049Z","steps":["trace[1848104122] 'process raft request' (duration: 828.898338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:17.072081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:16.242885Z","time spent":"829.185526ms","remote":"127.0.0.1:55266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-17T19:17:17.072465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.975471847s","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-17T19:17:17.072534Z","caller":"traceutil/trace.go:171","msg":"trace[1180907868] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"2.975583s","start":"2026-02-17T19:17:14.096931Z","end":"2026-02-17T19:17:17.072514Z","steps":["trace[1180907868] 'agreement among raft nodes before linearized reading' (duration: 2.975361294s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:17.072578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:14.096912Z","time spent":"2.975656863s","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-17T19:17:17.072869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.923676058s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:17:17.0729Z","caller":"traceutil/trace.go:171","msg":"trace[869643653] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"1.92372734s","start":"2026-02-17T19:17:15.149163Z","end":"2026-02-17T19:17:17.07289Z","steps":["trace[869643653] 'agreement among raft nodes before linearized reading' (duration: 1.923681258s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:17.072926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:15.14915Z","time spent":"1.923770671s","remote":"127.0.0.1:55192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T19:17:17.073108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.579403114s","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-17T19:17:17.073134Z","caller":"traceutil/trace.go:171","msg":"trace[1262630002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:633; }","duration":"2.579434876s","start":"2026-02-17T19:17:14.49369Z","end":"2026-02-17T19:17:17.073125Z","steps":["trace[1262630002] 'agreement among raft nodes before linearized reading' (duration: 2.579353113s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:17.073159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:14.493688Z","time spent":"2.579465817s","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T19:17:17.073314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.579626621s","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-17T19:17:17.07334Z","caller":"traceutil/trace.go:171","msg":"trace[506310575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"2.579659002s","start":"2026-02-17T19:17:14.493673Z","end":"2026-02-17T19:17:17.073332Z","steps":["trace[506310575] 'agreement among raft nodes before linearized reading' (duration: 2.57958523s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:17.073385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:14.493668Z","time spent":"2.579694723s","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T19:17:17.073542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.580719385s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-17T19:17:17.073567Z","caller":"traceutil/trace.go:171","msg":"trace[1285280718] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:633; }","duration":"2.580744766s","start":"2026-02-17T19:17:14.492815Z","end":"2026-02-17T19:17:17.073559Z","steps":["trace[1285280718] 'agreement among raft nodes before linearized reading' (duration: 2.580666704s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:17.073593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:14.492809Z","time spent":"2.580777807s","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-17T19:17:17.073734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.581917872s","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-17T19:17:17.073759Z","caller":"traceutil/trace.go:171","msg":"trace[1449602906] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"2.581958664s","start":"2026-02-17T19:17:14.491792Z","end":"2026-02-17T19:17:17.073751Z","steps":["trace[1449602906] 'agreement among raft nodes before linearized reading' (duration: 2.581891732s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:17.073785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:14.49178Z","time spent":"2.581999665s","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-17T19:17:17.079025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.11497188s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-17T19:17:17.07906Z","caller":"traceutil/trace.go:171","msg":"trace[1567580849] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:633; }","duration":"3.115046273s","start":"2026-02-17T19:17:13.964005Z","end":"2026-02-17T19:17:17.079051Z","steps":["trace[1567580849] 'agreement among raft nodes before linearized reading' (duration: 3.108128244s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:17.079079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:13.96399Z","time spent":"3.115085254s","remote":"127.0.0.1:55228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.78\" "} {"level":"info","ts":"2026-02-17T19:17:18.156788Z","caller":"traceutil/trace.go:171","msg":"trace[960057983] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"106.455048ms","start":"2026-02-17T19:17:18.050321Z","end":"2026-02-17T19:17:18.156776Z","steps":["trace[960057983] 'process raft request' (duration: 106.146479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:19.344232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.783996ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:17:19.344319Z","caller":"traceutil/trace.go:171","msg":"trace[989292805] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"189.426898ms","start":"2026-02-17T19:17:19.154878Z","end":"2026-02-17T19:17:19.344305Z","steps":["trace[989292805] 'read index received' (duration: 50.432596ms)","trace[989292805] 'applied index is now lower than readState.Index' (duration: 138.993512ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:17:19.34447Z","caller":"traceutil/trace.go:171","msg":"trace[1471411875] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"189.711606ms","start":"2026-02-17T19:17:19.154747Z","end":"2026-02-17T19:17:19.344459Z","steps":["trace[1471411875] 'process raft request' (duration: 50.613031ms)","trace[1471411875] 'compare' (duration: 138.547749ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:17:19.344602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.720446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:17:19.344622Z","caller":"traceutil/trace.go:171","msg":"trace[456359932] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"189.754938ms","start":"2026-02-17T19:17:19.15486Z","end":"2026-02-17T19:17:19.344615Z","steps":["trace[456359932] 'agreement among raft nodes before linearized reading' (duration: 189.719757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:19.344783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.852234ms","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-17T19:17:19.344802Z","caller":"traceutil/trace.go:171","msg":"trace[312877888] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:650; }","duration":"178.896305ms","start":"2026-02-17T19:17:19.1659Z","end":"2026-02-17T19:17:19.344796Z","steps":["trace[312877888] 'agreement among raft nodes before linearized reading' (duration: 178.815343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:19.344959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.025141ms","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-17T19:17:19.344984Z","caller":"traceutil/trace.go:171","msg":"trace[258854991] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:650; }","duration":"175.049852ms","start":"2026-02-17T19:17:19.169928Z","end":"2026-02-17T19:17:19.344978Z","steps":["trace[258854991] 'agreement among raft nodes before linearized reading' (duration: 174.99255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:19.345105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.928667ms","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-17T19:17:19.345129Z","caller":"traceutil/trace.go:171","msg":"trace[1445401797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:650; }","duration":"175.969159ms","start":"2026-02-17T19:17:19.169154Z","end":"2026-02-17T19:17:19.345123Z","steps":["trace[1445401797] 'agreement among raft nodes before linearized reading' (duration: 175.901197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:19.345242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.065601ms","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-17T19:17:19.345265Z","caller":"traceutil/trace.go:171","msg":"trace[846597303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:650; }","duration":"176.109823ms","start":"2026-02-17T19:17:19.16915Z","end":"2026-02-17T19:17:19.34526Z","steps":["trace[846597303] 'agreement among raft nodes before linearized reading' (duration: 176.053211ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:17:21.133121Z","caller":"traceutil/trace.go:171","msg":"trace[1069844457] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"103.451579ms","start":"2026-02-17T19:17:21.029654Z","end":"2026-02-17T19:17:21.133105Z","steps":["trace[1069844457] 'process raft request' (duration: 103.079559ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:17:22.18737Z","caller":"traceutil/trace.go:171","msg":"trace[819719037] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"144.880693ms","start":"2026-02-17T19:17:22.042459Z","end":"2026-02-17T19:17:22.18734Z","steps":["trace[819719037] 'process raft request' (duration: 103.744369ms)","trace[819719037] 'compare' (duration: 40.923318ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:17:23.109756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.608113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:17:23.109824Z","caller":"traceutil/trace.go:171","msg":"trace[20755416] linearizableReadLoop","detail":"{readStateIndex:693; appliedIndex:692; }","duration":"306.323876ms","start":"2026-02-17T19:17:22.803491Z","end":"2026-02-17T19:17:23.109815Z","steps":["trace[20755416] 'read index received' (duration: 25.011µs)","trace[20755416] 'applied index is now lower than readState.Index' (duration: 306.297785ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:17:23.109898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.407478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T19:17:23.109866Z","caller":"traceutil/trace.go:171","msg":"trace[1904368025] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"737.618061ms","start":"2026-02-17T19:17:22.37222Z","end":"2026-02-17T19:17:23.109838Z","steps":["trace[1904368025] 'process raft request' (duration: 68.889274ms)","trace[1904368025] 'compare' (duration: 668.280134ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:17:23.109911Z","caller":"traceutil/trace.go:171","msg":"trace[791522190] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:671; }","duration":"306.4572ms","start":"2026-02-17T19:17:22.803449Z","end":"2026-02-17T19:17:23.109906Z","steps":["trace[791522190] 'agreement among raft nodes before linearized reading' (duration: 306.410179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:23.10993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:22.803434Z","time spent":"306.492081ms","remote":"127.0.0.1:55554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-17T19:17:23.109982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:22.372201Z","time spent":"737.726364ms","remote":"127.0.0.1:55266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T19:17:24.22376Z","caller":"traceutil/trace.go:171","msg":"trace[496426982] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"165.246641ms","start":"2026-02-17T19:17:24.05849Z","end":"2026-02-17T19:17:24.223736Z","steps":["trace[496426982] 'process raft request' (duration: 165.040255ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:17:24.320365Z","caller":"traceutil/trace.go:171","msg":"trace[188116827] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:703; }","duration":"171.827847ms","start":"2026-02-17T19:17:24.148522Z","end":"2026-02-17T19:17:24.32035Z","steps":["trace[188116827] 'read index received' (duration: 76.047999ms)","trace[188116827] 'applied index is now lower than readState.Index' (duration: 95.779078ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:17:24.320633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.093464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:17:24.320664Z","caller":"traceutil/trace.go:171","msg":"trace[547614741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:680; }","duration":"172.171576ms","start":"2026-02-17T19:17:24.148485Z","end":"2026-02-17T19:17:24.320657Z","steps":["trace[547614741] 'agreement among raft nodes before linearized reading' (duration: 171.925489ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:17:24.320973Z","caller":"traceutil/trace.go:171","msg":"trace[1101639948] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"186.80056ms","start":"2026-02-17T19:17:24.134164Z","end":"2026-02-17T19:17:24.320964Z","steps":["trace[1101639948] 'process raft request' (duration: 183.165797ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:17:27.474455Z","caller":"traceutil/trace.go:171","msg":"trace[851379188] transaction","detail":"{read_only:false; number_of_response:0; response_revision:693; }","duration":"192.414808ms","start":"2026-02-17T19:17:27.282012Z","end":"2026-02-17T19:17:27.474426Z","steps":["trace[851379188] 'process raft request' (duration: 137.005695ms)","trace[851379188] 'compare' (duration: 55.3149ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:17:27.47459Z","caller":"traceutil/trace.go:171","msg":"trace[532802125] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"143.698369ms","start":"2026-02-17T19:17:27.330869Z","end":"2026-02-17T19:17:27.474567Z","steps":["trace[532802125] 'process raft request' (duration: 143.611806ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:17:27.474749Z","caller":"traceutil/trace.go:171","msg":"trace[2078231307] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"169.436476ms","start":"2026-02-17T19:17:27.305306Z","end":"2026-02-17T19:17:27.474742Z","steps":["trace[2078231307] 'process raft request' (duration: 169.088687ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:17:27.474852Z","caller":"traceutil/trace.go:171","msg":"trace[1642275536] linearizableReadLoop","detail":"{readStateIndex:719; appliedIndex:717; }","duration":"147.128633ms","start":"2026-02-17T19:17:27.327712Z","end":"2026-02-17T19:17:27.474841Z","steps":["trace[1642275536] 'read index received' (duration: 91.314359ms)","trace[1642275536] 'applied index is now lower than readState.Index' (duration: 55.813224ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:17:27.474929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.202455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:1888"} {"level":"info","ts":"2026-02-17T19:17:27.474959Z","caller":"traceutil/trace.go:171","msg":"trace[557518329] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:695; }","duration":"147.280587ms","start":"2026-02-17T19:17:27.327667Z","end":"2026-02-17T19:17:27.474948Z","steps":["trace[557518329] 'agreement among raft nodes before linearized reading' (duration: 147.211375ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:17:27.61791Z","caller":"traceutil/trace.go:171","msg":"trace[1291357480] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:720; }","duration":"113.228121ms","start":"2026-02-17T19:17:27.504661Z","end":"2026-02-17T19:17:27.617889Z","steps":["trace[1291357480] 'read index received' (duration: 104.80645ms)","trace[1291357480] 'applied index is now lower than readState.Index' (duration: 8.420911ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:17:27.620498Z","caller":"traceutil/trace.go:171","msg":"trace[477261984] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"133.874929ms","start":"2026-02-17T19:17:27.486609Z","end":"2026-02-17T19:17:27.620484Z","steps":["trace[477261984] 'process raft request' (duration: 122.865526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:27.620621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.954977ms","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-17T19:17:27.622199Z","caller":"traceutil/trace.go:171","msg":"trace[1758420321] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:696; }","duration":"117.541951ms","start":"2026-02-17T19:17:27.504646Z","end":"2026-02-17T19:17:27.622188Z","steps":["trace[1758420321] 'agreement among raft nodes before linearized reading' (duration: 115.882655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:27.620958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.661094ms","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-17T19:17:27.624579Z","caller":"traceutil/trace.go:171","msg":"trace[1245054611] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:696; }","duration":"103.870085ms","start":"2026-02-17T19:17:27.519253Z","end":"2026-02-17T19:17:27.623123Z","steps":["trace[1245054611] 'agreement among raft nodes before linearized reading' (duration: 101.544421ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:17:28.212256Z","caller":"traceutil/trace.go:171","msg":"trace[1837440943] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"163.44108ms","start":"2026-02-17T19:17:28.04879Z","end":"2026-02-17T19:17:28.212231Z","steps":["trace[1837440943] 'process raft request' (duration: 87.42098ms)","trace[1837440943] 'compare' (duration: 75.593449ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:17:29.864405Z","caller":"traceutil/trace.go:171","msg":"trace[205319602] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"117.687575ms","start":"2026-02-17T19:17:29.746701Z","end":"2026-02-17T19:17:29.864389Z","steps":["trace[205319602] 'process raft request' (duration: 104.965302ms)","trace[205319602] 'compare' (duration: 10.167534ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:17:29.864722Z","caller":"traceutil/trace.go:171","msg":"trace[790918079] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"115.601509ms","start":"2026-02-17T19:17:29.749115Z","end":"2026-02-17T19:17:29.864716Z","steps":["trace[790918079] 'process raft request' (duration: 115.216908ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:17:30.116305Z","caller":"traceutil/trace.go:171","msg":"trace[912773783] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:739; }","duration":"194.742237ms","start":"2026-02-17T19:17:29.921549Z","end":"2026-02-17T19:17:30.116291Z","steps":["trace[912773783] 'read index received' (duration: 164.032926ms)","trace[912773783] 'applied index is now lower than readState.Index' (duration: 30.708231ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:17:30.116458Z","caller":"traceutil/trace.go:171","msg":"trace[112434875] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"248.204199ms","start":"2026-02-17T19:17:29.868248Z","end":"2026-02-17T19:17:30.116452Z","steps":["trace[112434875] 'process raft request' (duration: 217.327054ms)","trace[112434875] 'compare' (duration: 30.272919ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:17:30.116567Z","caller":"traceutil/trace.go:171","msg":"trace[862733887] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"248.24158ms","start":"2026-02-17T19:17:29.868321Z","end":"2026-02-17T19:17:30.116563Z","steps":["trace[862733887] 'process raft request' (duration: 247.828599ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:17:30.116635Z","caller":"traceutil/trace.go:171","msg":"trace[1549444437] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"248.23983ms","start":"2026-02-17T19:17:29.868391Z","end":"2026-02-17T19:17:30.116631Z","steps":["trace[1549444437] 'process raft request' (duration: 247.820999ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:17:30.11674Z","caller":"traceutil/trace.go:171","msg":"trace[1361673800] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"238.763554ms","start":"2026-02-17T19:17:29.877971Z","end":"2026-02-17T19:17:30.116735Z","steps":["trace[1361673800] 'process raft request' (duration: 238.267041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:30.116854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.293552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-kjjsx\" ","response":"range_response_count:1 size:3813"} {"level":"info","ts":"2026-02-17T19:17:30.116871Z","caller":"traceutil/trace.go:171","msg":"trace[1672775981] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-kjjsx; range_end:; response_count:1; response_revision:718; }","duration":"195.338862ms","start":"2026-02-17T19:17:29.921526Z","end":"2026-02-17T19:17:30.116865Z","steps":["trace[1672775981] 'agreement among raft nodes before linearized reading' (duration: 195.267501ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:17:30.118415Z","caller":"traceutil/trace.go:171","msg":"trace[1364691591] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"193.267027ms","start":"2026-02-17T19:17:29.925141Z","end":"2026-02-17T19:17:30.118408Z","steps":["trace[1364691591] 'process raft request' (duration: 193.137853ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:17:31.138897Z","caller":"traceutil/trace.go:171","msg":"trace[56333338] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"111.695989ms","start":"2026-02-17T19:17:31.027186Z","end":"2026-02-17T19:17:31.138882Z","steps":["trace[56333338] 'process raft request' (duration: 110.61663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:34.494299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.283757ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:17:34.494382Z","caller":"traceutil/trace.go:171","msg":"trace[1061891559] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"156.822605ms","start":"2026-02-17T19:17:34.337545Z","end":"2026-02-17T19:17:34.494368Z","steps":["trace[1061891559] 'process raft request' (duration: 56.389054ms)","trace[1061891559] 'compare' (duration: 100.163624ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:17:34.518513Z","caller":"traceutil/trace.go:171","msg":"trace[631644431] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"180.495505ms","start":"2026-02-17T19:17:34.338001Z","end":"2026-02-17T19:17:34.518496Z","steps":["trace[631644431] 'process raft request' (duration: 180.060814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:34.964833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.585524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:17:34.964917Z","caller":"traceutil/trace.go:171","msg":"trace[94785650] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"332.467615ms","start":"2026-02-17T19:17:34.632437Z","end":"2026-02-17T19:17:34.964905Z","steps":["trace[94785650] 'process raft request' (duration: 112.621135ms)","trace[94785650] 'compare' (duration: 219.475281ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:17:34.964953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:34.632419Z","time spent":"332.517606ms","remote":"127.0.0.1:55646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4164,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:17:34.965278Z","caller":"traceutil/trace.go:171","msg":"trace[815614119] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"285.880944ms","start":"2026-02-17T19:17:34.679392Z","end":"2026-02-17T19:17:34.965273Z","steps":["trace[815614119] 'process raft request' (duration: 285.73592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:34.966592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.883746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:17:34.966622Z","caller":"traceutil/trace.go:171","msg":"trace[1205866633] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:783; }","duration":"123.920106ms","start":"2026-02-17T19:17:34.842696Z","end":"2026-02-17T19:17:34.966616Z","steps":["trace[1205866633] 'agreement among raft nodes before linearized reading' (duration: 123.866615ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:17:34.96518Z","caller":"traceutil/trace.go:171","msg":"trace[405938690] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:808; }","duration":"122.473629ms","start":"2026-02-17T19:17:34.8427Z","end":"2026-02-17T19:17:34.965173Z","steps":["trace[405938690] 'read index received' (duration: 32.5504ms)","trace[405938690] 'applied index is now lower than readState.Index' (duration: 89.922739ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:17:35.1411Z","caller":"traceutil/trace.go:171","msg":"trace[1770492182] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"168.614888ms","start":"2026-02-17T19:17:34.972467Z","end":"2026-02-17T19:17:35.141082Z","steps":["trace[1770492182] 'process raft request' (duration: 116.958768ms)","trace[1770492182] 'compare' (duration: 51.296551ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:17:35.958258Z","caller":"traceutil/trace.go:171","msg":"trace[1268898265] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"154.208912ms","start":"2026-02-17T19:17:35.804012Z","end":"2026-02-17T19:17:35.958221Z","steps":["trace[1268898265] 'process raft request' (duration: 90.486363ms)","trace[1268898265] 'compare' (duration: 62.922899ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:17:36.109592Z","caller":"traceutil/trace.go:171","msg":"trace[696536219] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"142.197182ms","start":"2026-02-17T19:17:35.96738Z","end":"2026-02-17T19:17:36.109578Z","steps":["trace[696536219] 'process raft request' (duration: 142.177102ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:17:36.109826Z","caller":"traceutil/trace.go:171","msg":"trace[1565763105] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"145.076796ms","start":"2026-02-17T19:17:35.964742Z","end":"2026-02-17T19:17:36.109819Z","steps":["trace[1565763105] 'process raft request' (duration: 109.33088ms)","trace[1565763105] 'compare' (duration: 35.369066ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:17:36.109882Z","caller":"traceutil/trace.go:171","msg":"trace[408849027] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"142.596912ms","start":"2026-02-17T19:17:35.967281Z","end":"2026-02-17T19:17:36.109878Z","steps":["trace[408849027] 'process raft request' (duration: 142.255413ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:17:40.233578Z","caller":"traceutil/trace.go:171","msg":"trace[1927351248] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"107.780479ms","start":"2026-02-17T19:17:40.125786Z","end":"2026-02-17T19:17:40.233567Z","steps":["trace[1927351248] 'process raft request' (duration: 107.704607ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:17:40.23362Z","caller":"traceutil/trace.go:171","msg":"trace[454777439] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:834; }","duration":"104.771555ms","start":"2026-02-17T19:17:40.128824Z","end":"2026-02-17T19:17:40.233596Z","steps":["trace[454777439] 'read index received' (duration: 104.762935ms)","trace[454777439] 'applied index is now lower than readState.Index' (duration: 6.59µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:17:40.233825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.978421ms","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-17T19:17:40.233851Z","caller":"traceutil/trace.go:171","msg":"trace[556483072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:806; }","duration":"105.031662ms","start":"2026-02-17T19:17:40.128812Z","end":"2026-02-17T19:17:40.233844Z","steps":["trace[556483072] 'agreement among raft nodes before linearized reading' (duration: 104.890888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:40.253941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.56001ms","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-17T19:17:40.253997Z","caller":"traceutil/trace.go:171","msg":"trace[958187266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:807; }","duration":"124.645722ms","start":"2026-02-17T19:17:40.129338Z","end":"2026-02-17T19:17:40.253984Z","steps":["trace[958187266] 'agreement among raft nodes before linearized reading' (duration: 124.479498ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:17:42.117018Z","caller":"traceutil/trace.go:171","msg":"trace[1713626084] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"258.78126ms","start":"2026-02-17T19:17:41.858214Z","end":"2026-02-17T19:17:42.116995Z","steps":["trace[1713626084] 'process raft request' (duration: 258.657907ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:17:42.254681Z","caller":"traceutil/trace.go:171","msg":"trace[1469767472] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"124.729513ms","start":"2026-02-17T19:17:42.123351Z","end":"2026-02-17T19:17:42.248081Z","steps":["trace[1469767472] 'process raft request' (duration: 122.324475ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:17:43.254137Z","caller":"traceutil/trace.go:171","msg":"trace[630172134] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"103.275536ms","start":"2026-02-17T19:17:43.150846Z","end":"2026-02-17T19:17:43.254122Z","steps":["trace[630172134] 'process raft request' (duration: 103.187054ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:17:50.528066Z","caller":"traceutil/trace.go:171","msg":"trace[1105355948] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"118.774118ms","start":"2026-02-17T19:17:50.409271Z","end":"2026-02-17T19:17:50.528045Z","steps":["trace[1105355948] 'process raft request' (duration: 118.470482ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:17:52.73436Z","caller":"traceutil/trace.go:171","msg":"trace[1880985748] linearizableReadLoop","detail":"{readStateIndex:877; appliedIndex:875; }","duration":"157.734304ms","start":"2026-02-17T19:17:52.576608Z","end":"2026-02-17T19:17:52.734343Z","steps":["trace[1880985748] 'read index received' (duration: 89.373305ms)","trace[1880985748] 'applied index is now lower than readState.Index' (duration: 68.360319ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:17:52.734494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.883167ms","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-17T19:17:52.734516Z","caller":"traceutil/trace.go:171","msg":"trace[1669010228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:846; }","duration":"157.958459ms","start":"2026-02-17T19:17:52.576549Z","end":"2026-02-17T19:17:52.734508Z","steps":["trace[1669010228] 'agreement among raft nodes before linearized reading' (duration: 157.853246ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:17:52.73472Z","caller":"traceutil/trace.go:171","msg":"trace[517014763] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"176.112992ms","start":"2026-02-17T19:17:52.558598Z","end":"2026-02-17T19:17:52.734711Z","steps":["trace[517014763] 'process raft request' (duration: 107.374335ms)","trace[517014763] 'compare' (duration: 68.189685ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:17:52.734801Z","caller":"traceutil/trace.go:171","msg":"trace[450635318] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"176.076161ms","start":"2026-02-17T19:17:52.558719Z","end":"2026-02-17T19:17:52.734795Z","steps":["trace[450635318] 'process raft request' (duration: 175.59583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:55.299686Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602682874768643,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T19:17:55.800458Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602682874768643,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T19:17:56.30155Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602682874768643,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T19:17:56.802488Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602682874768643,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T19:17:56.987334Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.233315367s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T19:17:56.987668Z","caller":"traceutil/trace.go:171","msg":"trace[328992360] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"2.233694065s","start":"2026-02-17T19:17:54.753941Z","end":"2026-02-17T19:17:56.987635Z","steps":["trace[328992360] 'process raft request' (duration: 2.233540722s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:56.987791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:54.753921Z","time spent":"2.233806997s","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:17:57.116071Z","caller":"traceutil/trace.go:171","msg":"trace[335888226] linearizableReadLoop","detail":"{readStateIndex:884; appliedIndex:882; }","duration":"2.316797142s","start":"2026-02-17T19:17:54.799257Z","end":"2026-02-17T19:17:57.116054Z","steps":["trace[335888226] 'read index received' (duration: 2.188876324s)","trace[335888226] 'applied index is now lower than readState.Index' (duration: 127.920078ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:17:57.116811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.317535848s","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-17T19:17:57.116854Z","caller":"traceutil/trace.go:171","msg":"trace[580658747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:852; }","duration":"2.31761937s","start":"2026-02-17T19:17:54.799223Z","end":"2026-02-17T19:17:57.116842Z","steps":["trace[580658747] 'agreement among raft nodes before linearized reading' (duration: 2.317489767s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:57.117047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:54.799209Z","time spent":"2.317761883s","remote":"127.0.0.1:55468","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-17T19:17:57.117276Z","caller":"traceutil/trace.go:171","msg":"trace[715287124] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"2.362263626s","start":"2026-02-17T19:17:54.754979Z","end":"2026-02-17T19:17:57.117242Z","steps":["trace[715287124] 'process raft request' (duration: 2.300861883s)","trace[715287124] 'compare' (duration: 60.060515ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:17:57.117419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:54.754961Z","time spent":"2.362347489s","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:17:57.117592Z","caller":"traceutil/trace.go:171","msg":"trace[1541067706] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"2.359079867s","start":"2026-02-17T19:17:54.758502Z","end":"2026-02-17T19:17:57.117582Z","steps":["trace[1541067706] 'process raft request' (duration: 2.357522363s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:57.117727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:54.758493Z","time spent":"2.359150798s","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:17:57.117996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.968554376s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:17:57.118031Z","caller":"traceutil/trace.go:171","msg":"trace[1890890903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:852; }","duration":"1.968617668s","start":"2026-02-17T19:17:55.149405Z","end":"2026-02-17T19:17:57.118023Z","steps":["trace[1890890903] 'agreement among raft nodes before linearized reading' (duration: 1.968566807s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:57.118124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:55.149374Z","time spent":"1.968742871s","remote":"127.0.0.1:55190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T19:17:57.118522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.24386ms","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-17T19:17:57.118556Z","caller":"traceutil/trace.go:171","msg":"trace[1777818285] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:852; }","duration":"855.30061ms","start":"2026-02-17T19:17:56.263245Z","end":"2026-02-17T19:17:57.118546Z","steps":["trace[1777818285] 'agreement among raft nodes before linearized reading' (duration: 855.222639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:57.118658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:56.263231Z","time spent":"855.415912ms","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-17T19:17:57.118909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.856538235s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:17:57.118957Z","caller":"traceutil/trace.go:171","msg":"trace[1940705378] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:852; }","duration":"1.856594066s","start":"2026-02-17T19:17:55.262355Z","end":"2026-02-17T19:17:57.118949Z","steps":["trace[1940705378] 'agreement among raft nodes before linearized reading' (duration: 1.856525124s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:17:57.119064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:17:55.262302Z","time spent":"1.856749759s","remote":"127.0.0.1:55598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-02-17T19:18:05.354201Z","caller":"traceutil/trace.go:171","msg":"trace[218623200] linearizableReadLoop","detail":"{readStateIndex:909; appliedIndex:908; }","duration":"144.306863ms","start":"2026-02-17T19:18:05.209876Z","end":"2026-02-17T19:18:05.354183Z","steps":["trace[218623200] 'read index received' (duration: 144.13618ms)","trace[218623200] 'applied index is now lower than readState.Index' (duration: 169.733µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:18:05.354736Z","caller":"traceutil/trace.go:171","msg":"trace[780356029] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"146.697212ms","start":"2026-02-17T19:18:05.208023Z","end":"2026-02-17T19:18:05.35472Z","steps":["trace[780356029] 'process raft request' (duration: 146.042119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:18:05.355078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.13112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-17T19:18:05.356752Z","caller":"traceutil/trace.go:171","msg":"trace[413918116] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:875; }","duration":"144.215572ms","start":"2026-02-17T19:18:05.210902Z","end":"2026-02-17T19:18:05.355118Z","steps":["trace[413918116] 'agreement among raft nodes before linearized reading' (duration: 144.033019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:18:05.357088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.204352ms","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-17T19:18:05.357909Z","caller":"traceutil/trace.go:171","msg":"trace[170959767] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:875; }","duration":"147.268504ms","start":"2026-02-17T19:18:05.209851Z","end":"2026-02-17T19:18:05.35712Z","steps":["trace[170959767] 'agreement among raft nodes before linearized reading' (duration: 147.168772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:18:05.615107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.737174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:18:05.615177Z","caller":"traceutil/trace.go:171","msg":"trace[1463880308] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:878; }","duration":"151.867727ms","start":"2026-02-17T19:18:05.463293Z","end":"2026-02-17T19:18:05.615161Z","steps":["trace[1463880308] 'count revisions from in-memory index tree' (duration: 151.675343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:18:14.438848Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602682874768789,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-17T19:18:14.442758Z","caller":"traceutil/trace.go:171","msg":"trace[1773635938] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"873.740984ms","start":"2026-02-17T19:18:13.569002Z","end":"2026-02-17T19:18:14.442743Z","steps":["trace[1773635938] 'process raft request' (duration: 873.713103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:18:14.44297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:18:13.568994Z","time spent":"873.883806ms","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:18:14.44329Z","caller":"traceutil/trace.go:171","msg":"trace[1323321396] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"876.096628ms","start":"2026-02-17T19:18:13.567177Z","end":"2026-02-17T19:18:14.443273Z","steps":["trace[1323321396] 'process raft request' (duration: 874.917105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:18:14.44424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:18:13.567159Z","time spent":"877.059487ms","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:18:14.980706Z","caller":"traceutil/trace.go:171","msg":"trace[1762209085] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:932; }","duration":"1.227814485s","start":"2026-02-17T19:18:13.752851Z","end":"2026-02-17T19:18:14.980666Z","steps":["trace[1762209085] 'read index received' (duration: 689.247853ms)","trace[1762209085] 'applied index is now lower than readState.Index' (duration: 538.565572ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:18:14.980831Z","caller":"traceutil/trace.go:171","msg":"trace[2091193823] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"1.324115827s","start":"2026-02-17T19:18:13.656684Z","end":"2026-02-17T19:18:14.9808Z","steps":["trace[2091193823] 'process raft request' (duration: 1.288060822s)","trace[2091193823] 'compare' (duration: 35.821141ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:18:14.98468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:18:13.65639Z","time spent":"1.328212855s","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:18:14.983246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.230373223s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12727"} {"level":"info","ts":"2026-02-17T19:18:14.985607Z","caller":"traceutil/trace.go:171","msg":"trace[123686465] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:900; }","duration":"1.232752848s","start":"2026-02-17T19:18:13.752825Z","end":"2026-02-17T19:18:14.985578Z","steps":["trace[123686465] 'agreement among raft nodes before linearized reading' (duration: 1.228074369s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:18:14.985665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:18:13.752813Z","time spent":"1.23283663s","remote":"127.0.0.1:55286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":12750,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-02-17T19:18:14.986278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.176326578s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T19:18:14.986583Z","caller":"traceutil/trace.go:171","msg":"trace[1558622875] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:900; }","duration":"1.176705426s","start":"2026-02-17T19:18:13.809857Z","end":"2026-02-17T19:18:14.986562Z","steps":["trace[1558622875] 'agreement among raft nodes before linearized reading' (duration: 1.176033413s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:18:14.98902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:18:13.809842Z","time spent":"1.179172732s","remote":"127.0.0.1:55544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":13,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-02-17T19:18:14.988814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.77835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:18:14.989167Z","caller":"traceutil/trace.go:171","msg":"trace[18527762] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:900; }","duration":"484.159287ms","start":"2026-02-17T19:18:14.504992Z","end":"2026-02-17T19:18:14.989152Z","steps":["trace[18527762] 'agreement among raft nodes before linearized reading' (duration: 483.78014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:18:14.989196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:18:14.504958Z","time spent":"484.230949ms","remote":"127.0.0.1:55434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-17T19:18:14.988897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.203659ms","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-17T19:18:14.989327Z","caller":"traceutil/trace.go:171","msg":"trace[331819311] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:900; }","duration":"544.637837ms","start":"2026-02-17T19:18:14.444681Z","end":"2026-02-17T19:18:14.989319Z","steps":["trace[331819311] 'agreement among raft nodes before linearized reading' (duration: 544.152958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:18:14.989356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:18:14.444655Z","time spent":"544.691269ms","remote":"127.0.0.1:55266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":109,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-17T19:18:14.988925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.763774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:18:14.98975Z","caller":"traceutil/trace.go:171","msg":"trace[786453241] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:900; }","duration":"838.61398ms","start":"2026-02-17T19:18:14.151129Z","end":"2026-02-17T19:18:14.989743Z","steps":["trace[786453241] 'agreement among raft nodes before linearized reading' (duration: 837.781184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:18:14.989778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:18:14.151114Z","time spent":"838.653611ms","remote":"127.0.0.1:55190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T19:18:14.988987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020573456s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-17T19:18:14.990177Z","caller":"traceutil/trace.go:171","msg":"trace[849290031] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:900; }","duration":"1.021795079s","start":"2026-02-17T19:18:13.968371Z","end":"2026-02-17T19:18:14.990166Z","steps":["trace[849290031] 'agreement among raft nodes before linearized reading' (duration: 1.020568686s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:18:14.990205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:18:13.968351Z","time spent":"1.02184758s","remote":"127.0.0.1:55228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.78\" "} {"level":"info","ts":"2026-02-17T19:18:21.120155Z","caller":"traceutil/trace.go:171","msg":"trace[937208453] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"108.163929ms","start":"2026-02-17T19:18:21.011964Z","end":"2026-02-17T19:18:21.120127Z","steps":["trace[937208453] 'process raft request' (duration: 108.043317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:18:37.252285Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602682874769049,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T19:18:37.753296Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602682874769049,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T19:18:37.901272Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.15057991s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T19:18:37.901565Z","caller":"traceutil/trace.go:171","msg":"trace[691591088] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1038; }","duration":"1.150230373s","start":"2026-02-17T19:18:36.751315Z","end":"2026-02-17T19:18:37.901545Z","steps":["trace[691591088] 'read index received' (duration: 1.15005066s)","trace[691591088] 'applied index is now lower than readState.Index' (duration: 178.853µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:18:37.901877Z","caller":"traceutil/trace.go:171","msg":"trace[630375328] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"1.151258832s","start":"2026-02-17T19:18:36.750593Z","end":"2026-02-17T19:18:37.901852Z","steps":["trace[630375328] 'process raft request' (duration: 1.150844855s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:18:37.901992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:18:36.750573Z","time spent":"1.151333002s","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:18:37.902211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.150891815s","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-17T19:18:37.902242Z","caller":"traceutil/trace.go:171","msg":"trace[1119615589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:998; }","duration":"1.150944416s","start":"2026-02-17T19:18:36.751287Z","end":"2026-02-17T19:18:37.902231Z","steps":["trace[1119615589] 'agreement among raft nodes before linearized reading' (duration: 1.150847154s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:18:37.902268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:18:36.751275Z","time spent":"1.150987146s","remote":"127.0.0.1:55468","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-17T19:18:37.902397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.579042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:18:37.902422Z","caller":"traceutil/trace.go:171","msg":"trace[50887565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:998; }","duration":"750.627502ms","start":"2026-02-17T19:18:37.151786Z","end":"2026-02-17T19:18:37.902414Z","steps":["trace[50887565] 'agreement among raft nodes before linearized reading' (duration: 750.590651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:18:37.902446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:18:37.151772Z","time spent":"750.668102ms","remote":"127.0.0.1:55190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T19:18:37.902788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.811811ms","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-17T19:18:37.90282Z","caller":"traceutil/trace.go:171","msg":"trace[1605483738] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:998; }","duration":"445.887213ms","start":"2026-02-17T19:18:37.456924Z","end":"2026-02-17T19:18:37.902811Z","steps":["trace[1605483738] 'agreement among raft nodes before linearized reading' (duration: 445.808721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:18:37.902847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:18:37.456888Z","time spent":"445.950994ms","remote":"127.0.0.1:55468","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-17T19:18:37.902992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.89814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-17T19:18:37.903017Z","caller":"traceutil/trace.go:171","msg":"trace[953325537] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:998; }","duration":"674.947901ms","start":"2026-02-17T19:18:37.22806Z","end":"2026-02-17T19:18:37.903008Z","steps":["trace[953325537] 'agreement among raft nodes before linearized reading' (duration: 674.87724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:18:37.903042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:18:37.228048Z","time spent":"674.988471ms","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-17T19:18:38.114876Z","caller":"traceutil/trace.go:171","msg":"trace[43236791] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"204.796035ms","start":"2026-02-17T19:18:37.910052Z","end":"2026-02-17T19:18:38.114848Z","steps":["trace[43236791] 'process raft request' (duration: 112.000986ms)","trace[43236791] 'compare' (duration: 92.373122ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:18:38.116474Z","caller":"traceutil/trace.go:171","msg":"trace[2025809140] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1039; }","duration":"201.736464ms","start":"2026-02-17T19:18:37.912801Z","end":"2026-02-17T19:18:38.114538Z","steps":["trace[2025809140] 'read index received' (duration: 109.26406ms)","trace[2025809140] 'applied index is now lower than readState.Index' (duration: 92.471414ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:18:38.117506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.612311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:18:38.117782Z","caller":"traceutil/trace.go:171","msg":"trace[1964326273] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:999; }","duration":"204.997578ms","start":"2026-02-17T19:18:37.91277Z","end":"2026-02-17T19:18:38.117767Z","steps":["trace[1964326273] 'agreement among raft nodes before linearized reading' (duration: 204.608002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:18:38.118999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.572257ms","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-17T19:18:38.119613Z","caller":"traceutil/trace.go:171","msg":"trace[1439468839] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1002; }","duration":"188.220608ms","start":"2026-02-17T19:18:37.931384Z","end":"2026-02-17T19:18:38.119605Z","steps":["trace[1439468839] 'agreement among raft nodes before linearized reading' (duration: 187.477405ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:18:38.119295Z","caller":"traceutil/trace.go:171","msg":"trace[781553714] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"206.29193ms","start":"2026-02-17T19:18:37.912955Z","end":"2026-02-17T19:18:38.119246Z","steps":["trace[781553714] 'process raft request' (duration: 205.745631ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:18:38.119526Z","caller":"traceutil/trace.go:171","msg":"trace[199555349] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"206.477202ms","start":"2026-02-17T19:18:37.913037Z","end":"2026-02-17T19:18:38.119514Z","steps":["trace[199555349] 'process raft request' (duration: 205.73849ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:18:38.119566Z","caller":"traceutil/trace.go:171","msg":"trace[676019743] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"192.406378ms","start":"2026-02-17T19:18:37.927153Z","end":"2026-02-17T19:18:38.119559Z","steps":["trace[676019743] 'process raft request' (duration: 191.669936ms)"],"step_count":1}