{"level":"warn","ts":"2026-02-06T05:58:42.932645Z","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-06T05:58:42.932857Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.22: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.22:2380","--initial-cluster=instance=https://199.204.45.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.22: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-06T05:58:42.932981Z","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-06T05:58:42.933046Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-02-06T05:58:42.933097Z","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-06T05:58:42.934019Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"]} {"level":"info","ts":"2026-02-06T05:58:42.934313Z","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.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.22: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-06T05:58:42.940976Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.742529ms"} {"level":"info","ts":"2026-02-06T05:58:42.967217Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fed19d9291630e09","cluster-id":"6ebf1e01320cc017"} {"level":"info","ts":"2026-02-06T05:58:42.96735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=()"} {"level":"info","ts":"2026-02-06T05:58:42.967441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 0"} {"level":"info","ts":"2026-02-06T05:58:42.96752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fed19d9291630e09 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-06T05:58:42.967637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 1"} {"level":"info","ts":"2026-02-06T05:58:42.967756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"warn","ts":"2026-02-06T05:58:42.992167Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-06T05:58:43.017176Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-06T05:58:43.022467Z","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-06T05:58:43.024807Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fed19d9291630e09","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-06T05:58:43.028305Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fed19d9291630e09","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-06T05:58:43.028533Z","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-06T05:58:43.028641Z","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-06T05:58:43.028703Z","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-06T05:58:43.029229Z","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-06T05:58:43.02972Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fed19d9291630e09","initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-06T05:58:43.029545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"info","ts":"2026-02-06T05:58:43.029576Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-02-06T05:58:43.032495Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-06T05:58:43.034709Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","added-peer-id":"fed19d9291630e09","added-peer-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-02-06T05:58:43.034758Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-02-06T05:58:43.069842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 is starting a new election at term 1"} {"level":"info","ts":"2026-02-06T05:58:43.069901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-06T05:58:43.069963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgPreVoteResp from fed19d9291630e09 at term 1"} {"level":"info","ts":"2026-02-06T05:58:43.069985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became candidate at term 2"} {"level":"info","ts":"2026-02-06T05:58:43.070042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgVoteResp from fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-02-06T05:58:43.070067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became leader at term 2"} {"level":"info","ts":"2026-02-06T05:58:43.07017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fed19d9291630e09 elected leader fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-02-06T05:58:43.073098Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T05:58:43.076676Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fed19d9291630e09","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.22:2379]}","request-path":"/0/members/fed19d9291630e09/attributes","cluster-id":"6ebf1e01320cc017","publish-timeout":"7s"} {"level":"info","ts":"2026-02-06T05:58:43.076761Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-06T05:58:43.07711Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-06T05:58:43.078601Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-06T05:58:43.079744Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.22:2379"} {"level":"info","ts":"2026-02-06T05:58:43.083584Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T05:58:43.107738Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T05:58:43.107846Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T05:58:43.091645Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-06T05:58:43.107881Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-06T05:58:58.986108Z","caller":"traceutil/trace.go:171","msg":"trace[636444277] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"142.487713ms","start":"2026-02-06T05:58:58.843598Z","end":"2026-02-06T05:58:58.986085Z","steps":["trace[636444277] 'process raft request' (duration: 142.369279ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:59:01.50765Z","caller":"traceutil/trace.go:171","msg":"trace[944336067] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"119.059668ms","start":"2026-02-06T05:59:01.388573Z","end":"2026-02-06T05:59:01.507633Z","steps":["trace[944336067] 'process raft request' (duration: 94.549808ms)","trace[944336067] 'compare' (duration: 24.171392ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T05:59:02.575287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.037908ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-06T05:59:12.37728Z","caller":"traceutil/trace.go:171","msg":"trace[1381827878] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"146.473625ms","start":"2026-02-06T05:59:12.23079Z","end":"2026-02-06T05:59:12.377263Z","steps":["trace[1381827878] 'process raft request' (duration: 146.336752ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:59:13.171534Z","caller":"traceutil/trace.go:171","msg":"trace[1190217685] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"128.77069ms","start":"2026-02-06T05:59:13.042747Z","end":"2026-02-06T05:59:13.171518Z","steps":["trace[1190217685] 'process raft request' (duration: 128.659898ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:59:15.316682Z","caller":"traceutil/trace.go:171","msg":"trace[1879541889] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"140.746142ms","start":"2026-02-06T05:59:15.175907Z","end":"2026-02-06T05:59:15.316653Z","steps":["trace[1879541889] 'process raft request' (duration: 140.65781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:59:19.942038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.522743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T05:59:19.942156Z","caller":"traceutil/trace.go:171","msg":"trace[186831515] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:550; }","duration":"612.713067ms","start":"2026-02-06T05:59:19.329425Z","end":"2026-02-06T05:59:19.942138Z","steps":["trace[186831515] 'read index received' (duration: 304.851879ms)","trace[186831515] 'applied index is now lower than readState.Index' (duration: 307.859548ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T05:59:19.942252Z","caller":"traceutil/trace.go:171","msg":"trace[944096560] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"790.906186ms","start":"2026-02-06T05:59:19.151332Z","end":"2026-02-06T05:59:19.942238Z","steps":["trace[944096560] 'process raft request' (duration: 485.593895ms)","trace[944096560] 'compare' (duration: 303.93585ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T05:59:19.942318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:59:19.151313Z","time spent":"790.967188ms","remote":"127.0.0.1:47102","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-06T05:59:19.942708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.288101ms","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-06T05:59:19.942749Z","caller":"traceutil/trace.go:171","msg":"trace[1008514925] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:533; }","duration":"613.327862ms","start":"2026-02-06T05:59:19.329406Z","end":"2026-02-06T05:59:19.942734Z","steps":["trace[1008514925] 'agreement among raft nodes before linearized reading' (duration: 613.129157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:59:19.942784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:59:19.329332Z","time spent":"613.444125ms","remote":"127.0.0.1:47102","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-06T05:59:19.943031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.04715ms","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-06T05:59:19.943066Z","caller":"traceutil/trace.go:171","msg":"trace[569132839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:533; }","duration":"523.166953ms","start":"2026-02-06T05:59:19.419889Z","end":"2026-02-06T05:59:19.943055Z","steps":["trace[569132839] 'agreement among raft nodes before linearized reading' (duration: 523.04228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:59:19.943097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:59:19.41987Z","time spent":"523.219004ms","remote":"127.0.0.1:47102","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-06T05:59:19.94322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.525776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T05:59:19.943274Z","caller":"traceutil/trace.go:171","msg":"trace[150768584] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"160.620388ms","start":"2026-02-06T05:59:19.782639Z","end":"2026-02-06T05:59:19.94326Z","steps":["trace[150768584] 'agreement among raft nodes before linearized reading' (duration: 160.528286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:59:19.943294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.395043ms","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-06T05:59:19.943328Z","caller":"traceutil/trace.go:171","msg":"trace[1685520244] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:533; }","duration":"351.453054ms","start":"2026-02-06T05:59:19.591865Z","end":"2026-02-06T05:59:19.943318Z","steps":["trace[1685520244] 'agreement among raft nodes before linearized reading' (duration: 351.353982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:59:19.943357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:59:19.591853Z","time spent":"351.496736ms","remote":"127.0.0.1:47102","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-06T05:59:19.943481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.421474ms","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-06T05:59:19.943508Z","caller":"traceutil/trace.go:171","msg":"trace[868084808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:533; }","duration":"365.528066ms","start":"2026-02-06T05:59:19.577971Z","end":"2026-02-06T05:59:19.943499Z","steps":["trace[868084808] 'agreement among raft nodes before linearized reading' (duration: 365.448804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:59:19.943536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:59:19.577948Z","time spent":"365.582107ms","remote":"127.0.0.1:47102","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-06T05:59:19.943678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.491043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zgtsl\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-06T05:59:19.943702Z","caller":"traceutil/trace.go:171","msg":"trace[275675793] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zgtsl; range_end:; response_count:1; response_revision:533; }","duration":"400.541475ms","start":"2026-02-06T05:59:19.543154Z","end":"2026-02-06T05:59:19.943695Z","steps":["trace[275675793] 'agreement among raft nodes before linearized reading' (duration: 400.495633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:59:19.943723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:59:19.543124Z","time spent":"400.593285ms","remote":"127.0.0.1:47034","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-zgtsl\" "} {"level":"warn","ts":"2026-02-06T05:59:21.175349Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.089616202s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-06T05:59:21.185381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.960371ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T05:59:21.185451Z","caller":"traceutil/trace.go:171","msg":"trace[2145004154] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:533; }","duration":"482.038742ms","start":"2026-02-06T05:59:20.7034Z","end":"2026-02-06T05:59:21.185439Z","steps":["trace[2145004154] 'range keys from in-memory index tree' (duration: 481.94974ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:59:21.185951Z","caller":"traceutil/trace.go:171","msg":"trace[1066022092] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.223334436s","start":"2026-02-06T05:59:19.96259Z","end":"2026-02-06T05:59:21.185925Z","steps":["trace[1066022092] 'process raft request' (duration: 1.213105392s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:59:21.186012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:59:19.962572Z","time spent":"1.223399077s","remote":"127.0.0.1:47102","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-06T05:59:21.21453Z","caller":"traceutil/trace.go:171","msg":"trace[1931861004] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.251122399s","start":"2026-02-06T05:59:19.963387Z","end":"2026-02-06T05:59:21.214509Z","steps":["trace[1931861004] 'process raft request' (duration: 1.25070065s)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:59:21.214561Z","caller":"traceutil/trace.go:171","msg":"trace[740188662] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:551; }","duration":"430.754831ms","start":"2026-02-06T05:59:20.783793Z","end":"2026-02-06T05:59:21.214548Z","steps":["trace[740188662] 'read index received' (duration: 391.911865ms)","trace[740188662] 'applied index is now lower than readState.Index' (duration: 38.842196ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T05:59:21.214637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:59:19.96337Z","time spent":"1.251208171s","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T05:59:21.214681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.880874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T05:59:21.214701Z","caller":"traceutil/trace.go:171","msg":"trace[389744611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:538; }","duration":"430.949106ms","start":"2026-02-06T05:59:20.783746Z","end":"2026-02-06T05:59:21.214696Z","steps":["trace[389744611] 'agreement among raft nodes before linearized reading' (duration: 430.842723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:59:21.214721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:59:20.783726Z","time spent":"430.990226ms","remote":"127.0.0.1:46866","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-06T05:59:21.214792Z","caller":"traceutil/trace.go:171","msg":"trace[1347366971] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.24978221s","start":"2026-02-06T05:59:19.965002Z","end":"2026-02-06T05:59:21.214784Z","steps":["trace[1347366971] 'process raft request' (duration: 1.249200037s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:59:21.214826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:59:19.964993Z","time spent":"1.24981445s","remote":"127.0.0.1:47102","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-06T05:59:21.214908Z","caller":"traceutil/trace.go:171","msg":"trace[1457240712] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.246903123s","start":"2026-02-06T05:59:19.967997Z","end":"2026-02-06T05:59:21.2149Z","steps":["trace[1457240712] 'process raft request' (duration: 1.24630368s)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:59:21.214927Z","caller":"traceutil/trace.go:171","msg":"trace[1298938335] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.24101373s","start":"2026-02-06T05:59:19.973902Z","end":"2026-02-06T05:59:21.214916Z","steps":["trace[1298938335] 'process raft request' (duration: 1.240539889s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:59:21.214942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:59:19.96799Z","time spent":"1.246931575s","remote":"127.0.0.1:47102","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-06T05:59:21.21498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:59:19.973894Z","time spent":"1.24105311s","remote":"127.0.0.1:47034","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-06T05:59:23.084928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.166738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T05:59:23.084976Z","caller":"traceutil/trace.go:171","msg":"trace[214257232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:546; }","duration":"302.25316ms","start":"2026-02-06T05:59:22.782711Z","end":"2026-02-06T05:59:23.084964Z","steps":["trace[214257232] 'range keys from in-memory index tree' (duration: 302.118087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:59:23.085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:59:22.782685Z","time spent":"302.310561ms","remote":"127.0.0.1:46862","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-06T05:59:23.559287Z","caller":"traceutil/trace.go:171","msg":"trace[612132258] transaction","detail":"{read_only:false; number_of_response:0; response_revision:561; }","duration":"109.295065ms","start":"2026-02-06T05:59:23.449974Z","end":"2026-02-06T05:59:23.559269Z","steps":["trace[612132258] 'process raft request' (duration: 79.577118ms)","trace[612132258] 'compare' (duration: 29.676946ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T05:59:23.559987Z","caller":"traceutil/trace.go:171","msg":"trace[1261113974] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"108.540948ms","start":"2026-02-06T05:59:23.451431Z","end":"2026-02-06T05:59:23.559971Z","steps":["trace[1261113974] 'process raft request' (duration: 108.499977ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:59:23.560298Z","caller":"traceutil/trace.go:171","msg":"trace[410209327] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"108.893196ms","start":"2026-02-06T05:59:23.451393Z","end":"2026-02-06T05:59:23.560287Z","steps":["trace[410209327] 'process raft request' (duration: 108.403095ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:59:26.851864Z","caller":"traceutil/trace.go:171","msg":"trace[2146126061] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"101.001716ms","start":"2026-02-06T05:59:26.750776Z","end":"2026-02-06T05:59:26.851778Z","steps":["trace[2146126061] 'process raft request' (duration: 100.704769ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:59:27.203696Z","caller":"traceutil/trace.go:171","msg":"trace[1607979407] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"116.013718ms","start":"2026-02-06T05:59:27.087607Z","end":"2026-02-06T05:59:27.20362Z","steps":["trace[1607979407] 'process raft request' (duration: 115.791563ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:59:27.446522Z","caller":"traceutil/trace.go:171","msg":"trace[425682597] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"191.982694ms","start":"2026-02-06T05:59:27.25452Z","end":"2026-02-06T05:59:27.446503Z","steps":["trace[425682597] 'read index received' (duration: 106.040601ms)","trace[425682597] 'applied index is now lower than readState.Index' (duration: 85.928832ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T05:59:27.446692Z","caller":"traceutil/trace.go:171","msg":"trace[1301099162] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"215.974741ms","start":"2026-02-06T05:59:27.230709Z","end":"2026-02-06T05:59:27.446683Z","steps":["trace[1301099162] 'process raft request' (duration: 129.862405ms)","trace[1301099162] 'compare' (duration: 85.597864ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T05:59:27.446929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.396963ms","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-06T05:59:27.446964Z","caller":"traceutil/trace.go:171","msg":"trace[53802069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:614; }","duration":"192.492185ms","start":"2026-02-06T05:59:27.254464Z","end":"2026-02-06T05:59:27.446956Z","steps":["trace[53802069] 'agreement among raft nodes before linearized reading' (duration: 192.330481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:59:27.450009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.384801ms","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-06T05:59:27.450139Z","caller":"traceutil/trace.go:171","msg":"trace[1023877211] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:615; }","duration":"195.512084ms","start":"2026-02-06T05:59:27.254578Z","end":"2026-02-06T05:59:27.45009Z","steps":["trace[1023877211] 'agreement among raft nodes before linearized reading' (duration: 195.097954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:59:27.450609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.960544ms","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-06T05:59:27.450639Z","caller":"traceutil/trace.go:171","msg":"trace[958529092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:615; }","duration":"196.012105ms","start":"2026-02-06T05:59:27.254618Z","end":"2026-02-06T05:59:27.45063Z","steps":["trace[958529092] 'agreement among raft nodes before linearized reading' (duration: 195.950093ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:59:27.450886Z","caller":"traceutil/trace.go:171","msg":"trace[766858492] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"145.188055ms","start":"2026-02-06T05:59:27.30569Z","end":"2026-02-06T05:59:27.450878Z","steps":["trace[766858492] 'process raft request' (duration: 143.464275ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:59:27.639317Z","caller":"traceutil/trace.go:171","msg":"trace[1305136759] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"176.307905ms","start":"2026-02-06T05:59:27.462992Z","end":"2026-02-06T05:59:27.6393Z","steps":["trace[1305136759] 'process raft request' (duration: 125.655619ms)","trace[1305136759] 'compare' (duration: 50.338469ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T05:59:27.639904Z","caller":"traceutil/trace.go:171","msg":"trace[810985964] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"163.346978ms","start":"2026-02-06T05:59:27.476549Z","end":"2026-02-06T05:59:27.639896Z","steps":["trace[810985964] 'process raft request' (duration: 163.120253ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:59:27.642482Z","caller":"traceutil/trace.go:171","msg":"trace[1826574542] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"156.37027ms","start":"2026-02-06T05:59:27.486085Z","end":"2026-02-06T05:59:27.642455Z","steps":["trace[1826574542] 'process raft request' (duration: 153.681878ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:59:27.643197Z","caller":"traceutil/trace.go:171","msg":"trace[1180927977] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"154.091367ms","start":"2026-02-06T05:59:27.485925Z","end":"2026-02-06T05:59:27.640016Z","steps":["trace[1180927977] 'process raft request' (duration: 153.810871ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:59:37.424587Z","caller":"traceutil/trace.go:171","msg":"trace[2109593069] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"125.877444ms","start":"2026-02-06T05:59:37.29868Z","end":"2026-02-06T05:59:37.424557Z","steps":["trace[2109593069] 'process raft request' (duration: 125.750491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:59:37.948971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.034826ms","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-06T05:59:37.949073Z","caller":"traceutil/trace.go:171","msg":"trace[1839716564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:711; }","duration":"155.166179ms","start":"2026-02-06T05:59:37.793876Z","end":"2026-02-06T05:59:37.949043Z","steps":["trace[1839716564] 'range keys from in-memory index tree' (duration: 154.858492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:59:37.949224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.345362ms","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-06T05:59:37.949322Z","caller":"traceutil/trace.go:171","msg":"trace[753050721] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:711; }","duration":"214.506115ms","start":"2026-02-06T05:59:37.734788Z","end":"2026-02-06T05:59:37.949294Z","steps":["trace[753050721] 'range keys from in-memory index tree' (duration: 214.170588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:59:37.949541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.87995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T05:59:37.949576Z","caller":"traceutil/trace.go:171","msg":"trace[1732764959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:711; }","duration":"167.93545ms","start":"2026-02-06T05:59:37.78163Z","end":"2026-02-06T05:59:37.949566Z","steps":["trace[1732764959] 'range keys from in-memory index tree' (duration: 167.843418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:59:37.949593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.881317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-06T05:59:37.949626Z","caller":"traceutil/trace.go:171","msg":"trace[859616018] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:711; }","duration":"210.926057ms","start":"2026-02-06T05:59:37.73869Z","end":"2026-02-06T05:59:37.949616Z","steps":["trace[859616018] 'range keys from in-memory index tree' (duration: 210.812445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:59:37.949763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.989181ms","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-06T05:59:37.949793Z","caller":"traceutil/trace.go:171","msg":"trace[1348625175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:711; }","duration":"168.029082ms","start":"2026-02-06T05:59:37.781755Z","end":"2026-02-06T05:59:37.949784Z","steps":["trace[1348625175] 'range keys from in-memory index tree' (duration: 167.92205ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:59:38.548895Z","caller":"traceutil/trace.go:171","msg":"trace[1492419812] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"590.001929ms","start":"2026-02-06T05:59:37.958811Z","end":"2026-02-06T05:59:38.548813Z","steps":["trace[1492419812] 'process raft request' (duration: 589.951048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:59:38.549634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:59:37.958786Z","time spent":"590.189883ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T05:59:38.550711Z","caller":"traceutil/trace.go:171","msg":"trace[988858414] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"594.762675ms","start":"2026-02-06T05:59:37.955909Z","end":"2026-02-06T05:59:38.550672Z","steps":["trace[988858414] 'process raft request' (duration: 592.664579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:59:38.550801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:59:37.955901Z","time spent":"594.847937ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T05:59:38.550915Z","caller":"traceutil/trace.go:171","msg":"trace[491473271] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"592.836603ms","start":"2026-02-06T05:59:37.958056Z","end":"2026-02-06T05:59:38.550892Z","steps":["trace[491473271] 'process raft request' (duration: 590.671255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:59:38.55097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:59:37.958033Z","time spent":"592.902794ms","remote":"127.0.0.1:47102","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-06T05:59:42.266677Z","caller":"traceutil/trace.go:171","msg":"trace[1496958516] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"262.274028ms","start":"2026-02-06T05:59:42.004378Z","end":"2026-02-06T05:59:42.266652Z","steps":["trace[1496958516] 'process raft request' (duration: 262.164056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:59:43.462334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.342652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T05:59:43.462428Z","caller":"traceutil/trace.go:171","msg":"trace[579487933] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:727; }","duration":"681.492476ms","start":"2026-02-06T05:59:42.780913Z","end":"2026-02-06T05:59:43.462406Z","steps":["trace[579487933] 'range keys from in-memory index tree' (duration: 681.282931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T05:59:43.462474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:59:42.780895Z","time spent":"681.569347ms","remote":"127.0.0.1:46866","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-06T05:59:43.636827Z","caller":"traceutil/trace.go:171","msg":"trace[1053482359] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"164.146046ms","start":"2026-02-06T05:59:43.472657Z","end":"2026-02-06T05:59:43.636803Z","steps":["trace[1053482359] 'process raft request' (duration: 164.012623ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:59:56.529406Z","caller":"traceutil/trace.go:171","msg":"trace[1085577782] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"130.22599ms","start":"2026-02-06T05:59:56.398938Z","end":"2026-02-06T05:59:56.529164Z","steps":["trace[1085577782] 'process raft request' (duration: 130.070736ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T05:59:58.639058Z","caller":"traceutil/trace.go:171","msg":"trace[518299912] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"100.334071ms","start":"2026-02-06T05:59:58.5387Z","end":"2026-02-06T05:59:58.639034Z","steps":["trace[518299912] 'process raft request' (duration: 100.217198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:00.294745Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011511327870557578,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-06T06:00:00.368591Z","caller":"traceutil/trace.go:171","msg":"trace[245251503] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:807; }","duration":"575.611422ms","start":"2026-02-06T05:59:59.792945Z","end":"2026-02-06T06:00:00.368556Z","steps":["trace[245251503] 'read index received' (duration: 575.418957ms)","trace[245251503] 'applied index is now lower than readState.Index' (duration: 191.335µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:00:00.368799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.817886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T06:00:00.368933Z","caller":"traceutil/trace.go:171","msg":"trace[2115802981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:779; }","duration":"576.00563ms","start":"2026-02-06T05:59:59.792914Z","end":"2026-02-06T06:00:00.36892Z","steps":["trace[2115802981] 'agreement among raft nodes before linearized reading' (duration: 575.813676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:00.369088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:59:59.792905Z","time spent":"576.169394ms","remote":"127.0.0.1:46866","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-06T06:00:00.36943Z","caller":"traceutil/trace.go:171","msg":"trace[473099612] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"596.881611ms","start":"2026-02-06T05:59:59.772535Z","end":"2026-02-06T06:00:00.369416Z","steps":["trace[473099612] 'process raft request' (duration: 595.819198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:00.369625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T05:59:59.772521Z","time spent":"596.985473ms","remote":"127.0.0.1:47102","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-06T06:00:00.802194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.018224ms","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-06T06:00:00.802274Z","caller":"traceutil/trace.go:171","msg":"trace[2107436684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:779; }","duration":"159.150577ms","start":"2026-02-06T06:00:00.643105Z","end":"2026-02-06T06:00:00.802255Z","steps":["trace[2107436684] 'range keys from in-memory index tree' (duration: 158.412811ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:00:01.063588Z","caller":"traceutil/trace.go:171","msg":"trace[459087898] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"253.26047ms","start":"2026-02-06T06:00:00.8103Z","end":"2026-02-06T06:00:01.06356Z","steps":["trace[459087898] 'process raft request' (duration: 253.199148ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:00:01.063809Z","caller":"traceutil/trace.go:171","msg":"trace[806190119] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"199.069336ms","start":"2026-02-06T06:00:00.864707Z","end":"2026-02-06T06:00:01.063776Z","steps":["trace[806190119] 'process raft request' (duration: 198.814991ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:00:01.063845Z","caller":"traceutil/trace.go:171","msg":"trace[1958232262] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"254.621011ms","start":"2026-02-06T06:00:00.809217Z","end":"2026-02-06T06:00:01.063838Z","steps":["trace[1958232262] 'process raft request' (duration: 254.086999ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:00:01.063939Z","caller":"traceutil/trace.go:171","msg":"trace[1677562960] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"253.69278ms","start":"2026-02-06T06:00:00.81024Z","end":"2026-02-06T06:00:01.063933Z","steps":["trace[1677562960] 'process raft request' (duration: 253.23367ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:00:04.928753Z","caller":"traceutil/trace.go:171","msg":"trace[1338842794] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"141.807745ms","start":"2026-02-06T06:00:04.786928Z","end":"2026-02-06T06:00:04.928736Z","steps":["trace[1338842794] 'read index received' (duration: 141.653371ms)","trace[1338842794] 'applied index is now lower than readState.Index' (duration: 153.664µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:00:04.929055Z","caller":"traceutil/trace.go:171","msg":"trace[1244481681] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"533.658558ms","start":"2026-02-06T06:00:04.395384Z","end":"2026-02-06T06:00:04.929043Z","steps":["trace[1244481681] 'process raft request' (duration: 533.240009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:04.930173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:04.395368Z","time spent":"533.860692ms","remote":"127.0.0.1:47102","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-06T06:00:04.930348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.402199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T06:00:04.937764Z","caller":"traceutil/trace.go:171","msg":"trace[112461366] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:790; }","duration":"150.857504ms","start":"2026-02-06T06:00:04.786895Z","end":"2026-02-06T06:00:04.937752Z","steps":["trace[112461366] 'agreement among raft nodes before linearized reading' (duration: 143.41609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:05.582374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.891956ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T06:00:05.582471Z","caller":"traceutil/trace.go:171","msg":"trace[377402946] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:820; }","duration":"501.837047ms","start":"2026-02-06T06:00:05.080619Z","end":"2026-02-06T06:00:05.582456Z","steps":["trace[377402946] 'read index received' (duration: 369.710136ms)","trace[377402946] 'applied index is now lower than readState.Index' (duration: 132.125841ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:00:05.582703Z","caller":"traceutil/trace.go:171","msg":"trace[353796430] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"642.870924ms","start":"2026-02-06T06:00:04.93982Z","end":"2026-02-06T06:00:05.582691Z","steps":["trace[353796430] 'process raft request' (duration: 510.59665ms)","trace[353796430] 'compare' (duration: 131.791474ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:00:05.582787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:04.939809Z","time spent":"642.928596ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T06:00:05.583013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.391449ms","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-06T06:00:05.58305Z","caller":"traceutil/trace.go:171","msg":"trace[216107958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:791; }","duration":"502.464351ms","start":"2026-02-06T06:00:05.080576Z","end":"2026-02-06T06:00:05.58304Z","steps":["trace[216107958] 'agreement among raft nodes before linearized reading' (duration: 502.356798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:05.58308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:05.080552Z","time spent":"502.519962ms","remote":"127.0.0.1:47102","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-06T06:00:05.583237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.358111ms","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-06T06:00:05.583264Z","caller":"traceutil/trace.go:171","msg":"trace[214807106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:791; }","duration":"468.406781ms","start":"2026-02-06T06:00:05.114849Z","end":"2026-02-06T06:00:05.583256Z","steps":["trace[214807106] 'agreement among raft nodes before linearized reading' (duration: 468.33625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:05.583316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:05.114835Z","time spent":"468.475382ms","remote":"127.0.0.1:47102","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-06T06:00:05.58352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.787856ms","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-06T06:00:05.583549Z","caller":"traceutil/trace.go:171","msg":"trace[2083707568] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:791; }","duration":"466.825267ms","start":"2026-02-06T06:00:05.116716Z","end":"2026-02-06T06:00:05.583541Z","steps":["trace[2083707568] 'agreement among raft nodes before linearized reading' (duration: 466.745575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:05.583574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:05.116712Z","time spent":"466.856617ms","remote":"127.0.0.1:47102","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-06T06:00:05.891929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.051242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T06:00:05.89208Z","caller":"traceutil/trace.go:171","msg":"trace[1860193654] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"298.292193ms","start":"2026-02-06T06:00:05.593772Z","end":"2026-02-06T06:00:05.892064Z","steps":["trace[1860193654] 'process raft request' (duration: 298.241792ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:00:05.892459Z","caller":"traceutil/trace.go:171","msg":"trace[1698277339] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"299.859247ms","start":"2026-02-06T06:00:05.592588Z","end":"2026-02-06T06:00:05.892447Z","steps":["trace[1698277339] 'process raft request' (duration: 156.215613ms)","trace[1698277339] 'compare' (duration: 142.889918ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:00:17.259709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.245242ms","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-06T06:00:17.259768Z","caller":"traceutil/trace.go:171","msg":"trace[859543074] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:875; }","duration":"278.332343ms","start":"2026-02-06T06:00:16.981416Z","end":"2026-02-06T06:00:17.259749Z","steps":["trace[859543074] 'range keys from in-memory index tree' (duration: 278.141089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:17.858463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.783001ms","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-06T06:00:17.85851Z","caller":"traceutil/trace.go:171","msg":"trace[1289177516] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:877; }","duration":"209.844142ms","start":"2026-02-06T06:00:17.648656Z","end":"2026-02-06T06:00:17.8585Z","steps":["trace[1289177516] 'count revisions from in-memory index tree' (duration: 209.759761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:17.858642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.016416ms","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-06T06:00:17.858662Z","caller":"traceutil/trace.go:171","msg":"trace[536555863] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:877; }","duration":"210.057917ms","start":"2026-02-06T06:00:17.648599Z","end":"2026-02-06T06:00:17.858657Z","steps":["trace[536555863] 'range keys from in-memory index tree' (duration: 209.897413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:17.858751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.363272ms","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-06T06:00:17.858767Z","caller":"traceutil/trace.go:171","msg":"trace[757828039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:877; }","duration":"174.402953ms","start":"2026-02-06T06:00:17.68436Z","end":"2026-02-06T06:00:17.858763Z","steps":["trace[757828039] 'range keys from in-memory index tree' (duration: 174.311761ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:00:18.139361Z","caller":"traceutil/trace.go:171","msg":"trace[890692734] linearizableReadLoop","detail":"{readStateIndex:914; appliedIndex:913; }","duration":"192.404849ms","start":"2026-02-06T06:00:17.946938Z","end":"2026-02-06T06:00:18.139342Z","steps":["trace[890692734] 'read index received' (duration: 140.683289ms)","trace[890692734] 'applied index is now lower than readState.Index' (duration: 51.72083ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:00:18.139451Z","caller":"traceutil/trace.go:171","msg":"trace[288723734] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"274.763224ms","start":"2026-02-06T06:00:17.864682Z","end":"2026-02-06T06:00:18.139445Z","steps":["trace[288723734] 'process raft request' (duration: 222.979142ms)","trace[288723734] 'compare' (duration: 51.596458ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:00:18.139557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.603937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-06T06:00:18.139613Z","caller":"traceutil/trace.go:171","msg":"trace[527632640] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:879; }","duration":"188.692469ms","start":"2026-02-06T06:00:17.950908Z","end":"2026-02-06T06:00:18.139601Z","steps":["trace[527632640] 'agreement among raft nodes before linearized reading' (duration: 188.566096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:18.139709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.772948ms","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-06T06:00:18.13972Z","caller":"traceutil/trace.go:171","msg":"trace[1376641013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:879; }","duration":"192.815708ms","start":"2026-02-06T06:00:17.946901Z","end":"2026-02-06T06:00:18.139716Z","steps":["trace[1376641013] 'agreement among raft nodes before linearized reading' (duration: 192.774538ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:00:18.72422Z","caller":"traceutil/trace.go:171","msg":"trace[1560566929] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"576.613684ms","start":"2026-02-06T06:00:18.147591Z","end":"2026-02-06T06:00:18.724205Z","steps":["trace[1560566929] 'process raft request' (duration: 576.489961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:18.724316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:18.147584Z","time spent":"576.683905ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T06:00:19.453081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.973207ms","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-06T06:00:19.453135Z","caller":"traceutil/trace.go:171","msg":"trace[874356241] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:881; }","duration":"171.055489ms","start":"2026-02-06T06:00:19.282067Z","end":"2026-02-06T06:00:19.453123Z","steps":["trace[874356241] 'range keys from in-memory index tree' (duration: 170.864434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:19.453263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.76964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T06:00:19.453282Z","caller":"traceutil/trace.go:171","msg":"trace[1695239627] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:881; }","duration":"164.800022ms","start":"2026-02-06T06:00:19.288476Z","end":"2026-02-06T06:00:19.453276Z","steps":["trace[1695239627] 'count revisions from in-memory index tree' (duration: 164.73772ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:00:24.549195Z","caller":"traceutil/trace.go:171","msg":"trace[1659645335] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:930; }","duration":"339.876419ms","start":"2026-02-06T06:00:24.209302Z","end":"2026-02-06T06:00:24.549178Z","steps":["trace[1659645335] 'read index received' (duration: 339.750476ms)","trace[1659645335] 'applied index is now lower than readState.Index' (duration: 125.313µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:00:24.54939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.068904ms","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-06T06:00:24.549422Z","caller":"traceutil/trace.go:171","msg":"trace[1172366864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:895; }","duration":"340.134605ms","start":"2026-02-06T06:00:24.209276Z","end":"2026-02-06T06:00:24.549411Z","steps":["trace[1172366864] 'agreement among raft nodes before linearized reading' (duration: 339.969252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:24.549457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:24.209264Z","time spent":"340.183886ms","remote":"127.0.0.1:47102","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":"info","ts":"2026-02-06T06:00:24.54976Z","caller":"traceutil/trace.go:171","msg":"trace[1917011532] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"387.999429ms","start":"2026-02-06T06:00:24.161701Z","end":"2026-02-06T06:00:24.549701Z","steps":["trace[1917011532] 'process raft request' (duration: 387.393346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:24.550058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:24.161692Z","time spent":"388.214294ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T06:00:24.956593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.092141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T06:00:24.956693Z","caller":"traceutil/trace.go:171","msg":"trace[1048151059] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:931; }","duration":"179.880853ms","start":"2026-02-06T06:00:24.776797Z","end":"2026-02-06T06:00:24.956678Z","steps":["trace[1048151059] 'read index received' (duration: 2.569477ms)","trace[1048151059] 'applied index is now lower than readState.Index' (duration: 177.310266ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:00:24.956762Z","caller":"traceutil/trace.go:171","msg":"trace[567132377] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"400.73989ms","start":"2026-02-06T06:00:24.556013Z","end":"2026-02-06T06:00:24.956753Z","steps":["trace[567132377] 'process raft request' (duration: 223.413773ms)","trace[567132377] 'compare' (duration: 177.00831ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:00:24.956801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:24.555967Z","time spent":"400.812381ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T06:00:24.956973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.92388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T06:00:24.957058Z","caller":"traceutil/trace.go:171","msg":"trace[1579095226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:896; }","duration":"173.063013ms","start":"2026-02-06T06:00:24.783974Z","end":"2026-02-06T06:00:24.957037Z","steps":["trace[1579095226] 'agreement among raft nodes before linearized reading' (duration: 172.91904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:24.959923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.126514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-06T06:00:24.959957Z","caller":"traceutil/trace.go:171","msg":"trace[113345677] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:896; }","duration":"183.210636ms","start":"2026-02-06T06:00:24.776735Z","end":"2026-02-06T06:00:24.959946Z","steps":["trace[113345677] 'agreement among raft nodes before linearized reading' (duration: 180.292302ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:00:26.914873Z","caller":"traceutil/trace.go:171","msg":"trace[120856949] linearizableReadLoop","detail":"{readStateIndex:936; appliedIndex:935; }","duration":"201.744466ms","start":"2026-02-06T06:00:26.713113Z","end":"2026-02-06T06:00:26.914858Z","steps":["trace[120856949] 'read index received' (duration: 201.596722ms)","trace[120856949] 'applied index is now lower than readState.Index' (duration: 147.204µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:00:26.914971Z","caller":"traceutil/trace.go:171","msg":"trace[304683447] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"352.373713ms","start":"2026-02-06T06:00:26.562589Z","end":"2026-02-06T06:00:26.914963Z","steps":["trace[304683447] 'process raft request' (duration: 352.163768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:26.915049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:26.56258Z","time spent":"352.408494ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T06:00:26.915193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.082653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-06T06:00:26.915209Z","caller":"traceutil/trace.go:171","msg":"trace[989546022] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:900; }","duration":"202.144194ms","start":"2026-02-06T06:00:26.713059Z","end":"2026-02-06T06:00:26.915203Z","steps":["trace[989546022] 'agreement among raft nodes before linearized reading' (duration: 202.073633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:26.915461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.113783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T06:00:26.915559Z","caller":"traceutil/trace.go:171","msg":"trace[1335279613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:900; }","duration":"133.274397ms","start":"2026-02-06T06:00:26.782267Z","end":"2026-02-06T06:00:26.915541Z","steps":["trace[1335279613] 'agreement among raft nodes before linearized reading' (duration: 133.131273ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:00:27.283193Z","caller":"traceutil/trace.go:171","msg":"trace[1795650831] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"252.857981ms","start":"2026-02-06T06:00:27.030314Z","end":"2026-02-06T06:00:27.283172Z","steps":["trace[1795650831] 'process raft request' (duration: 193.915813ms)","trace[1795650831] 'compare' (duration: 58.850536ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:00:35.312654Z","caller":"traceutil/trace.go:171","msg":"trace[447106431] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:960; }","duration":"264.016157ms","start":"2026-02-06T06:00:35.048617Z","end":"2026-02-06T06:00:35.312633Z","steps":["trace[447106431] 'read index received' (duration: 263.849023ms)","trace[447106431] 'applied index is now lower than readState.Index' (duration: 166.384µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:00:35.31289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.249902ms","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-06T06:00:35.312926Z","caller":"traceutil/trace.go:171","msg":"trace[1798396145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:923; }","duration":"264.343274ms","start":"2026-02-06T06:00:35.04857Z","end":"2026-02-06T06:00:35.312914Z","steps":["trace[1798396145] 'agreement among raft nodes before linearized reading' (duration: 264.15565ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:00:35.315416Z","caller":"traceutil/trace.go:171","msg":"trace[649401087] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"367.837284ms","start":"2026-02-06T06:00:34.947516Z","end":"2026-02-06T06:00:35.315354Z","steps":["trace[649401087] 'process raft request' (duration: 365.005242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:35.315717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:34.947506Z","time spent":"368.09883ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T06:00:37.06518Z","caller":"traceutil/trace.go:171","msg":"trace[1184777498] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"109.329259ms","start":"2026-02-06T06:00:36.955822Z","end":"2026-02-06T06:00:37.065151Z","steps":["trace[1184777498] 'process raft request' (duration: 23.754783ms)","trace[1184777498] 'compare' (duration: 85.353741ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:00:37.480878Z","caller":"traceutil/trace.go:171","msg":"trace[1912816972] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:967; }","duration":"150.497466ms","start":"2026-02-06T06:00:37.330356Z","end":"2026-02-06T06:00:37.480854Z","steps":["trace[1912816972] 'read index received' (duration: 150.290232ms)","trace[1912816972] 'applied index is now lower than readState.Index' (duration: 206.354µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:00:37.48116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.778742ms","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-06T06:00:37.481195Z","caller":"traceutil/trace.go:171","msg":"trace[871651008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:929; }","duration":"150.844033ms","start":"2026-02-06T06:00:37.330341Z","end":"2026-02-06T06:00:37.481185Z","steps":["trace[871651008] 'agreement among raft nodes before linearized reading' (duration: 150.604778ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:00:37.481482Z","caller":"traceutil/trace.go:171","msg":"trace[1195223362] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"153.415681ms","start":"2026-02-06T06:00:37.328055Z","end":"2026-02-06T06:00:37.481471Z","steps":["trace[1195223362] 'process raft request' (duration: 152.647954ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:00:37.635018Z","caller":"traceutil/trace.go:171","msg":"trace[1240679442] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"131.756074ms","start":"2026-02-06T06:00:37.503242Z","end":"2026-02-06T06:00:37.634999Z","steps":["trace[1240679442] 'read index received' (duration: 112.418647ms)","trace[1240679442] 'applied index is now lower than readState.Index' (duration: 19.336887ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:00:37.63519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.928727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-06T06:00:37.635212Z","caller":"traceutil/trace.go:171","msg":"trace[1025151393] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:930; }","duration":"131.968568ms","start":"2026-02-06T06:00:37.503236Z","end":"2026-02-06T06:00:37.635204Z","steps":["trace[1025151393] 'agreement among raft nodes before linearized reading' (duration: 131.830585ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:00:37.635492Z","caller":"traceutil/trace.go:171","msg":"trace[569618874] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"148.550123ms","start":"2026-02-06T06:00:37.486934Z","end":"2026-02-06T06:00:37.635484Z","steps":["trace[569618874] 'process raft request' (duration: 128.772817ms)","trace[569618874] 'compare' (duration: 19.216994ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:00:38.453688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.551486ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T06:00:38.453783Z","caller":"traceutil/trace.go:171","msg":"trace[617705341] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:970; }","duration":"665.216462ms","start":"2026-02-06T06:00:37.78855Z","end":"2026-02-06T06:00:38.453767Z","steps":["trace[617705341] 'read index received' (duration: 15.57µs)","trace[617705341] 'applied index is now lower than readState.Index' (duration: 665.199502ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:00:38.454022Z","caller":"traceutil/trace.go:171","msg":"trace[1205986843] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"769.432248ms","start":"2026-02-06T06:00:37.684578Z","end":"2026-02-06T06:00:38.45401Z","steps":["trace[1205986843] 'process raft request' (duration: 95.434253ms)","trace[1205986843] 'compare' (duration: 673.228159ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:00:38.454083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:37.684563Z","time spent":"769.487699ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T06:00:38.454248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.693242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T06:00:38.454276Z","caller":"traceutil/trace.go:171","msg":"trace[1479515341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:932; }","duration":"665.741344ms","start":"2026-02-06T06:00:37.788525Z","end":"2026-02-06T06:00:38.454266Z","steps":["trace[1479515341] 'agreement among raft nodes before linearized reading' (duration: 665.694983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:38.454302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:37.788513Z","time spent":"665.783595ms","remote":"127.0.0.1:46866","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-06T06:00:38.4545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.366073ms","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-06T06:00:38.454526Z","caller":"traceutil/trace.go:171","msg":"trace[429390162] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:932; }","duration":"629.400783ms","start":"2026-02-06T06:00:37.825118Z","end":"2026-02-06T06:00:38.454519Z","steps":["trace[429390162] 'agreement among raft nodes before linearized reading' (duration: 629.305331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:38.454555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:37.825112Z","time spent":"629.437765ms","remote":"127.0.0.1:47102","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-06T06:00:38.454741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.8776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T06:00:38.454768Z","caller":"traceutil/trace.go:171","msg":"trace[1190324912] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:932; }","duration":"191.924381ms","start":"2026-02-06T06:00:38.262836Z","end":"2026-02-06T06:00:38.45476Z","steps":["trace[1190324912] 'agreement among raft nodes before linearized reading' (duration: 191.88196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:38.454924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.204246ms","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-06T06:00:38.454948Z","caller":"traceutil/trace.go:171","msg":"trace[1808882259] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:932; }","duration":"349.255877ms","start":"2026-02-06T06:00:38.105685Z","end":"2026-02-06T06:00:38.454941Z","steps":["trace[1808882259] 'agreement among raft nodes before linearized reading' (duration: 349.187705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:38.454972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:38.105671Z","time spent":"349.295278ms","remote":"127.0.0.1:47102","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":"info","ts":"2026-02-06T06:00:38.611213Z","caller":"traceutil/trace.go:171","msg":"trace[1024781717] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"141.503335ms","start":"2026-02-06T06:00:38.469691Z","end":"2026-02-06T06:00:38.611194Z","steps":["trace[1024781717] 'process raft request' (duration: 141.345312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:38.899028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.987509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T06:00:38.899087Z","caller":"traceutil/trace.go:171","msg":"trace[1091973993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:934; }","duration":"105.082311ms","start":"2026-02-06T06:00:38.793989Z","end":"2026-02-06T06:00:38.899072Z","steps":["trace[1091973993] 'range keys from in-memory index tree' (duration: 104.931658ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:00:45.921006Z","caller":"traceutil/trace.go:171","msg":"trace[2021882015] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"123.429477ms","start":"2026-02-06T06:00:45.797557Z","end":"2026-02-06T06:00:45.920987Z","steps":["trace[2021882015] 'process raft request' (duration: 95.017179ms)","trace[2021882015] 'compare' (duration: 28.333806ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:00:55.075101Z","caller":"traceutil/trace.go:171","msg":"trace[137048793] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1085; }","duration":"328.338675ms","start":"2026-02-06T06:00:54.746739Z","end":"2026-02-06T06:00:55.075077Z","steps":["trace[137048793] 'read index received' (duration: 328.172911ms)","trace[137048793] 'applied index is now lower than readState.Index' (duration: 164.524µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:00:55.07539Z","caller":"traceutil/trace.go:171","msg":"trace[1769544929] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"434.188774ms","start":"2026-02-06T06:00:54.64115Z","end":"2026-02-06T06:00:55.075339Z","steps":["trace[1769544929] 'process raft request' (duration: 433.782055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:55.07565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:54.641138Z","time spent":"434.381208ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T06:00:55.075798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.570512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T06:00:55.076518Z","caller":"traceutil/trace.go:171","msg":"trace[1376475405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1044; }","duration":"292.321928ms","start":"2026-02-06T06:00:54.784178Z","end":"2026-02-06T06:00:55.0765Z","steps":["trace[1376475405] 'agreement among raft nodes before linearized reading' (duration: 291.50569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:55.076019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.279325ms","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-06T06:00:55.076825Z","caller":"traceutil/trace.go:171","msg":"trace[1288355671] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1044; }","duration":"330.185505ms","start":"2026-02-06T06:00:54.746626Z","end":"2026-02-06T06:00:55.076812Z","steps":["trace[1288355671] 'agreement among raft nodes before linearized reading' (duration: 329.301306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:55.076958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:54.746544Z","time spent":"330.40053ms","remote":"127.0.0.1:47102","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-06T06:00:55.076089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.387272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-06T06:00:55.07728Z","caller":"traceutil/trace.go:171","msg":"trace[987759789] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1044; }","duration":"198.803353ms","start":"2026-02-06T06:00:54.878462Z","end":"2026-02-06T06:00:55.077265Z","steps":["trace[987759789] 'agreement among raft nodes before linearized reading' (duration: 197.578306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:55.6445Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011511327870558188,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T06:00:55.975468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:55.483188Z","time spent":"492.274367ms","remote":"127.0.0.1:45086","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-06T06:00:55.980242Z","caller":"traceutil/trace.go:171","msg":"trace[568680836] linearizableReadLoop","detail":"{readStateIndex:1088; appliedIndex:1086; }","duration":"836.110785ms","start":"2026-02-06T06:00:55.144049Z","end":"2026-02-06T06:00:55.98016Z","steps":["trace[568680836] 'read index received' (duration: 764.88517ms)","trace[568680836] 'applied index is now lower than readState.Index' (duration: 71.224585ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:00:55.982573Z","caller":"traceutil/trace.go:171","msg":"trace[1115781273] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"900.011697ms","start":"2026-02-06T06:00:55.082552Z","end":"2026-02-06T06:00:55.982564Z","steps":["trace[1115781273] 'process raft request' (duration: 826.373439ms)","trace[1115781273] 'compare' (duration: 69.985987ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:00:55.982749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:55.082484Z","time spent":"900.193561ms","remote":"127.0.0.1:47102","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-06T06:00:55.982877Z","caller":"traceutil/trace.go:171","msg":"trace[558959585] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"895.628819ms","start":"2026-02-06T06:00:55.087241Z","end":"2026-02-06T06:00:55.98287Z","steps":["trace[558959585] 'process raft request' (duration: 892.787977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:55.982971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:55.087231Z","time spent":"895.66252ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T06:00:55.983312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.261344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"warn","ts":"2026-02-06T06:00:55.984091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.889638ms","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-06T06:00:55.984116Z","caller":"traceutil/trace.go:171","msg":"trace[2071966347] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1046; }","duration":"357.953189ms","start":"2026-02-06T06:00:55.626155Z","end":"2026-02-06T06:00:55.984108Z","steps":["trace[2071966347] 'agreement among raft nodes before linearized reading' (duration: 357.897078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:55.984354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:55.626134Z","time spent":"357.99359ms","remote":"127.0.0.1:47102","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-06T06:00:55.98372Z","caller":"traceutil/trace.go:171","msg":"trace[57005499] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1046; }","duration":"839.631622ms","start":"2026-02-06T06:00:55.144044Z","end":"2026-02-06T06:00:55.983675Z","steps":["trace[57005499] 'agreement among raft nodes before linearized reading' (duration: 839.168542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:55.984481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:55.144029Z","time spent":"840.44406ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-06T06:00:55.985061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.067163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T06:00:55.985082Z","caller":"traceutil/trace.go:171","msg":"trace[83194772] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1046; }","duration":"192.115784ms","start":"2026-02-06T06:00:55.792961Z","end":"2026-02-06T06:00:55.985077Z","steps":["trace[83194772] 'agreement among raft nodes before linearized reading' (duration: 192.087434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:55.98525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.470638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T06:00:55.985327Z","caller":"traceutil/trace.go:171","msg":"trace[126907190] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1046; }","duration":"247.586301ms","start":"2026-02-06T06:00:55.737734Z","end":"2026-02-06T06:00:55.98532Z","steps":["trace[126907190] 'agreement among raft nodes before linearized reading' (duration: 247.494589ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:00:56.245285Z","caller":"traceutil/trace.go:171","msg":"trace[1953255748] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"256.535387ms","start":"2026-02-06T06:00:55.988718Z","end":"2026-02-06T06:00:56.245253Z","steps":["trace[1953255748] 'process raft request' (duration: 188.441543ms)","trace[1953255748] 'compare' (duration: 67.828649ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:00:56.246374Z","caller":"traceutil/trace.go:171","msg":"trace[481787845] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"257.47839ms","start":"2026-02-06T06:00:55.988886Z","end":"2026-02-06T06:00:56.246365Z","steps":["trace[481787845] 'process raft request' (duration: 257.257315ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:00:56.246512Z","caller":"traceutil/trace.go:171","msg":"trace[1121273244] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"256.410146ms","start":"2026-02-06T06:00:55.990096Z","end":"2026-02-06T06:00:56.246506Z","steps":["trace[1121273244] 'process raft request' (duration: 256.113519ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:00:56.246257Z","caller":"traceutil/trace.go:171","msg":"trace[1311457221] linearizableReadLoop","detail":"{readStateIndex:1091; appliedIndex:1088; }","duration":"222.451376ms","start":"2026-02-06T06:00:56.023788Z","end":"2026-02-06T06:00:56.246239Z","steps":["trace[1311457221] 'read index received' (duration: 153.41842ms)","trace[1311457221] 'applied index is now lower than readState.Index' (duration: 69.032296ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:00:56.246664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.860325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-06T06:00:56.248169Z","caller":"traceutil/trace.go:171","msg":"trace[1503860219] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1049; }","duration":"224.404399ms","start":"2026-02-06T06:00:56.023755Z","end":"2026-02-06T06:00:56.248159Z","steps":["trace[1503860219] 'agreement among raft nodes before linearized reading' (duration: 222.792414ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:01:41.885587Z","caller":"traceutil/trace.go:171","msg":"trace[837229261] linearizableReadLoop","detail":"{readStateIndex:1287; appliedIndex:1286; }","duration":"101.263868ms","start":"2026-02-06T06:01:41.784293Z","end":"2026-02-06T06:01:41.885557Z","steps":["trace[837229261] 'read index received' (duration: 98.888736ms)","trace[837229261] 'applied index is now lower than readState.Index' (duration: 2.373462ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:01:41.885756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.424681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T06:01:41.885905Z","caller":"traceutil/trace.go:171","msg":"trace[474066308] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1235; }","duration":"101.621556ms","start":"2026-02-06T06:01:41.78426Z","end":"2026-02-06T06:01:41.885882Z","steps":["trace[474066308] 'agreement among raft nodes before linearized reading' (duration: 101.377839ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:03:39.470914Z","caller":"traceutil/trace.go:171","msg":"trace[935041056] transaction","detail":"{read_only:false; response_revision:1715; number_of_response:1; }","duration":"135.56593ms","start":"2026-02-06T06:03:39.335327Z","end":"2026-02-06T06:03:39.470893Z","steps":["trace[935041056] 'process raft request' (duration: 135.374606ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:03:39.471328Z","caller":"traceutil/trace.go:171","msg":"trace[1324478040] linearizableReadLoop","detail":"{readStateIndex:1790; appliedIndex:1790; }","duration":"133.104506ms","start":"2026-02-06T06:03:39.338208Z","end":"2026-02-06T06:03:39.471312Z","steps":["trace[1324478040] 'read index received' (duration: 133.099166ms)","trace[1324478040] 'applied index is now lower than readState.Index' (duration: 4.29µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:03:39.474006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.778225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-06T06:03:39.474056Z","caller":"traceutil/trace.go:171","msg":"trace[596875586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1715; }","duration":"135.864077ms","start":"2026-02-06T06:03:39.33818Z","end":"2026-02-06T06:03:39.474044Z","steps":["trace[596875586] 'agreement among raft nodes before linearized reading' (duration: 135.734994ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:03:43.364529Z","caller":"traceutil/trace.go:171","msg":"trace[1144234927] transaction","detail":"{read_only:false; response_revision:1730; number_of_response:1; }","duration":"107.602427ms","start":"2026-02-06T06:03:43.256903Z","end":"2026-02-06T06:03:43.364505Z","steps":["trace[1144234927] 'process raft request' (duration: 107.455103ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:03:43.429416Z","caller":"traceutil/trace.go:171","msg":"trace[173347282] linearizableReadLoop","detail":"{readStateIndex:1807; appliedIndex:1805; }","duration":"102.053106ms","start":"2026-02-06T06:03:43.327352Z","end":"2026-02-06T06:03:43.429405Z","steps":["trace[173347282] 'read index received' (duration: 37.00498ms)","trace[173347282] 'applied index is now lower than readState.Index' (duration: 65.047666ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:03:43.429471Z","caller":"traceutil/trace.go:171","msg":"trace[1342110440] transaction","detail":"{read_only:false; response_revision:1731; number_of_response:1; }","duration":"112.380822ms","start":"2026-02-06T06:03:43.317085Z","end":"2026-02-06T06:03:43.429466Z","steps":["trace[1342110440] 'process raft request' (duration: 110.167083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:03:43.429613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.25446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T06:03:43.42963Z","caller":"traceutil/trace.go:171","msg":"trace[1150790378] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1731; }","duration":"102.312312ms","start":"2026-02-06T06:03:43.327313Z","end":"2026-02-06T06:03:43.429625Z","steps":["trace[1150790378] 'agreement among raft nodes before linearized reading' (duration: 102.25556ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:03:47.501296Z","caller":"traceutil/trace.go:171","msg":"trace[13528295] transaction","detail":"{read_only:false; response_revision:1748; number_of_response:1; }","duration":"122.490574ms","start":"2026-02-06T06:03:47.378794Z","end":"2026-02-06T06:03:47.501285Z","steps":["trace[13528295] 'process raft request' (duration: 122.359811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:04:01.672956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.176788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-06T06:04:01.673Z","caller":"traceutil/trace.go:171","msg":"trace[911276107] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1828; }","duration":"102.25305ms","start":"2026-02-06T06:04:01.570737Z","end":"2026-02-06T06:04:01.67299Z","steps":["trace[911276107] 'range keys from in-memory index tree' (duration: 102.085936ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:04:01.867688Z","caller":"traceutil/trace.go:171","msg":"trace[1865751235] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"125.610361ms","start":"2026-02-06T06:04:01.742054Z","end":"2026-02-06T06:04:01.867664Z","steps":["trace[1865751235] 'process raft request' (duration: 39.900155ms)","trace[1865751235] 'compare' (duration: 85.603363ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:04:02.127254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.330284ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T06:04:02.127711Z","caller":"traceutil/trace.go:171","msg":"trace[115983021] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"256.96081ms","start":"2026-02-06T06:04:01.87073Z","end":"2026-02-06T06:04:02.127691Z","steps":["trace[115983021] 'process raft request' (duration: 131.137995ms)","trace[115983021] 'compare' (duration: 125.079519ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:04:08.59899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.071294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-06T06:04:08.599083Z","caller":"traceutil/trace.go:171","msg":"trace[1086767924] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1866; }","duration":"120.202127ms","start":"2026-02-06T06:04:08.478864Z","end":"2026-02-06T06:04:08.599066Z","steps":["trace[1086767924] 'range keys from in-memory index tree' (duration: 119.931281ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:04:09.02403Z","caller":"traceutil/trace.go:171","msg":"trace[1242827797] linearizableReadLoop","detail":"{readStateIndex:1949; appliedIndex:1948; }","duration":"242.939444ms","start":"2026-02-06T06:04:08.781066Z","end":"2026-02-06T06:04:09.024006Z","steps":["trace[1242827797] 'read index received' (duration: 242.75923ms)","trace[1242827797] 'applied index is now lower than readState.Index' (duration: 179.134µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:04:09.024194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.107317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T06:04:09.024235Z","caller":"traceutil/trace.go:171","msg":"trace[1125808752] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1867; }","duration":"243.1911ms","start":"2026-02-06T06:04:08.781035Z","end":"2026-02-06T06:04:09.024226Z","steps":["trace[1125808752] 'agreement among raft nodes before linearized reading' (duration: 243.073247ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:04:09.024571Z","caller":"traceutil/trace.go:171","msg":"trace[319927407] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"421.323201ms","start":"2026-02-06T06:04:08.603235Z","end":"2026-02-06T06:04:09.024558Z","steps":["trace[319927407] 'process raft request' (duration: 420.642206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:04:09.024663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:04:08.603215Z","time spent":"421.394232ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T06:04:17.536863Z","caller":"traceutil/trace.go:171","msg":"trace[375444353] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"118.56672ms","start":"2026-02-06T06:04:17.418285Z","end":"2026-02-06T06:04:17.536851Z","steps":["trace[375444353] 'process raft request' (duration: 118.172291ms)"],"step_count":1}