{"level":"warn","ts":"2026-02-03T17:24:45.769336Z","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-03T17:24:45.769441Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156: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.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156: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-03T17:24:45.769528Z","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-03T17:24:45.769538Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-02-03T17:24:45.769565Z","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-03T17:24:45.770181Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-02-03T17:24:45.770279Z","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.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156: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-03T17:24:45.773767Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.169561ms"} {"level":"info","ts":"2026-02-03T17:24:45.782187Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-02-03T17:24:45.782233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-02-03T17:24:45.782294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-02-03T17:24:45.782306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-03T17:24:45.782313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-02-03T17:24:45.782397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-02-03T17:24:45.788781Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-03T17:24:45.800353Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-03T17:24:45.804523Z","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-03T17:24:45.841902Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-03T17:24:45.843915Z","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-03T17:24:45.844059Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-03T17:24:45.844075Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-03T17:24:45.84416Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-02-03T17:24:45.844167Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-02-03T17:24:45.846946Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-03T17:24:45.846982Z","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-03T17:24:45.846996Z","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-03T17:24:45.847004Z","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-03T17:24:45.86336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-02-03T17:24:45.863538Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-02-03T17:24:46.284254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-02-03T17:24:46.284297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-03T17:24:46.284996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-02-03T17:24:46.285021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-02-03T17:24:46.285493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-02-03T17:24:46.285514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-02-03T17:24:46.285961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-02-03T17:24:46.289736Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T17:24:46.290555Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-02-03T17:24:46.290692Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T17:24:46.290844Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T17:24:46.291372Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-03T17:24:46.291387Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-03T17:24:46.295874Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-02-03T17:24:46.29595Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T17:24:46.296003Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T17:24:46.296023Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T17:24:46.303228Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-03T17:24:55.482029Z","caller":"traceutil/trace.go:171","msg":"trace[402975398] linearizableReadLoop","detail":"{readStateIndex:379; appliedIndex:378; }","duration":"186.278184ms","start":"2026-02-03T17:24:55.295717Z","end":"2026-02-03T17:24:55.481995Z","steps":["trace[402975398] 'read index received' (duration: 153.716637ms)","trace[402975398] 'applied index is now lower than readState.Index' (duration: 32.560527ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:24:55.482202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.419849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-03T17:24:55.482234Z","caller":"traceutil/trace.go:171","msg":"trace[520883883] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:371; }","duration":"186.534232ms","start":"2026-02-03T17:24:55.295689Z","end":"2026-02-03T17:24:55.482223Z","steps":["trace[520883883] 'agreement among raft nodes before linearized reading' (duration: 186.416548ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:24:55.482422Z","caller":"traceutil/trace.go:171","msg":"trace[1841226997] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"193.324808ms","start":"2026-02-03T17:24:55.289065Z","end":"2026-02-03T17:24:55.48239Z","steps":["trace[1841226997] 'process raft request' (duration: 160.337627ms)","trace[1841226997] 'compare' (duration: 32.494105ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:25:02.053917Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338482306439562,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-03T17:25:02.353066Z","caller":"traceutil/trace.go:171","msg":"trace[1141012520] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"860.74203ms","start":"2026-02-03T17:25:01.492281Z","end":"2026-02-03T17:25:02.353023Z","steps":["trace[1141012520] 'process raft request' (duration: 859.776529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:02.354198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:01.492268Z","time spent":"861.191885ms","remote":"127.0.0.1:38450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4583,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:25:03.484399Z","caller":"traceutil/trace.go:171","msg":"trace[1982677682] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:422; }","duration":"1.930713755s","start":"2026-02-03T17:25:01.553636Z","end":"2026-02-03T17:25:03.48435Z","steps":["trace[1982677682] 'read index received' (duration: 798.656692ms)","trace[1982677682] 'applied index is now lower than readState.Index' (duration: 1.132055783s)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:25:03.48484Z","caller":"traceutil/trace.go:171","msg":"trace[2105075598] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"1.839091095s","start":"2026-02-03T17:25:01.645714Z","end":"2026-02-03T17:25:03.484806Z","steps":["trace[2105075598] 'process raft request' (duration: 1.838410674s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:03.484939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:01.645696Z","time spent":"1.839175498s","remote":"127.0.0.1:38436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4042,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:25:03.485176Z","caller":"traceutil/trace.go:171","msg":"trace[76813350] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"1.679923156s","start":"2026-02-03T17:25:01.805242Z","end":"2026-02-03T17:25:03.485165Z","steps":["trace[76813350] 'process raft request' (duration: 1.679065098s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:03.485232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:01.80522Z","time spent":"1.679979548s","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:25:03.485363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.931739567s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:25:03.485395Z","caller":"traceutil/trace.go:171","msg":"trace[1650274702] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:415; }","duration":"1.93180955s","start":"2026-02-03T17:25:01.553575Z","end":"2026-02-03T17:25:03.485385Z","steps":["trace[1650274702] 'agreement among raft nodes before linearized reading' (duration: 1.931762598s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:03.485425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:01.553554Z","time spent":"1.931862671s","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-03T17:25:03.485579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.486615578s","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-03T17:25:03.485607Z","caller":"traceutil/trace.go:171","msg":"trace[1112833953] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:415; }","duration":"1.486677229s","start":"2026-02-03T17:25:01.998921Z","end":"2026-02-03T17:25:03.485598Z","steps":["trace[1112833953] 'agreement among raft nodes before linearized reading' (duration: 1.486628458s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:03.485636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:01.998904Z","time spent":"1.48672615s","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-03T17:25:03.485894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.168215545s","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-03T17:25:03.485924Z","caller":"traceutil/trace.go:171","msg":"trace[597618538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:415; }","duration":"1.168245546s","start":"2026-02-03T17:25:02.31767Z","end":"2026-02-03T17:25:03.485916Z","steps":["trace[597618538] 'agreement among raft nodes before linearized reading' (duration: 1.168180553s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:03.485951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:02.317644Z","time spent":"1.168301336s","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T17:25:03.486088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.168455122s","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-03T17:25:03.486114Z","caller":"traceutil/trace.go:171","msg":"trace[109252905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:415; }","duration":"1.168528735s","start":"2026-02-03T17:25:02.317578Z","end":"2026-02-03T17:25:03.486106Z","steps":["trace[109252905] 'agreement among raft nodes before linearized reading' (duration: 1.168487143s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:03.486141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:02.317551Z","time spent":"1.168583125s","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T17:25:03.48627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.315848437s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-03T17:25:03.486296Z","caller":"traceutil/trace.go:171","msg":"trace[1970391065] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:415; }","duration":"1.315907859s","start":"2026-02-03T17:25:02.17038Z","end":"2026-02-03T17:25:03.486288Z","steps":["trace[1970391065] 'agreement among raft nodes before linearized reading' (duration: 1.315863438s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:03.486322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:02.170315Z","time spent":"1.316000692s","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-03T17:25:06.428244Z","caller":"traceutil/trace.go:171","msg":"trace[1914563426] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"200.963892ms","start":"2026-02-03T17:25:06.227261Z","end":"2026-02-03T17:25:06.428225Z","steps":["trace[1914563426] 'process raft request' (duration: 183.227167ms)","trace[1914563426] 'compare' (duration: 17.626332ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:25:06.579213Z","caller":"traceutil/trace.go:171","msg":"trace[1455035724] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"143.184662ms","start":"2026-02-03T17:25:06.436013Z","end":"2026-02-03T17:25:06.579198Z","steps":["trace[1455035724] 'process raft request' (duration: 143.13425ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:25:06.579474Z","caller":"traceutil/trace.go:171","msg":"trace[511455195] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"144.840895ms","start":"2026-02-03T17:25:06.434628Z","end":"2026-02-03T17:25:06.579469Z","steps":["trace[511455195] 'process raft request' (duration: 142.612573ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:25:06.714464Z","caller":"traceutil/trace.go:171","msg":"trace[1619073055] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"125.89452ms","start":"2026-02-03T17:25:06.588266Z","end":"2026-02-03T17:25:06.71416Z","steps":["trace[1619073055] 'process raft request' (duration: 118.0551ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:25:06.715099Z","caller":"traceutil/trace.go:171","msg":"trace[875912910] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"126.040056ms","start":"2026-02-03T17:25:06.589044Z","end":"2026-02-03T17:25:06.715084Z","steps":["trace[875912910] 'process raft request' (duration: 125.921072ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:25:06.715165Z","caller":"traceutil/trace.go:171","msg":"trace[2066761062] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"118.569457ms","start":"2026-02-03T17:25:06.596578Z","end":"2026-02-03T17:25:06.715147Z","steps":["trace[2066761062] 'process raft request' (duration: 118.540526ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:25:06.715356Z","caller":"traceutil/trace.go:171","msg":"trace[1779484670] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"122.809632ms","start":"2026-02-03T17:25:06.59254Z","end":"2026-02-03T17:25:06.715349Z","steps":["trace[1779484670] 'process raft request' (duration: 122.532543ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:25:06.715432Z","caller":"traceutil/trace.go:171","msg":"trace[1742600379] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"126.315865ms","start":"2026-02-03T17:25:06.58911Z","end":"2026-02-03T17:25:06.715426Z","steps":["trace[1742600379] 'process raft request' (duration: 125.944553ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:25:09.860634Z","caller":"traceutil/trace.go:171","msg":"trace[1811466042] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"198.099081ms","start":"2026-02-03T17:25:09.662511Z","end":"2026-02-03T17:25:09.86061Z","steps":["trace[1811466042] 'process raft request' (duration: 142.729327ms)","trace[1811466042] 'compare' (duration: 55.290881ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:25:09.865677Z","caller":"traceutil/trace.go:171","msg":"trace[604859386] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"200.350313ms","start":"2026-02-03T17:25:09.665314Z","end":"2026-02-03T17:25:09.865665Z","steps":["trace[604859386] 'process raft request' (duration: 200.322532ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:25:09.865797Z","caller":"traceutil/trace.go:171","msg":"trace[928584957] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"201.82363ms","start":"2026-02-03T17:25:09.663965Z","end":"2026-02-03T17:25:09.865789Z","steps":["trace[928584957] 'process raft request' (duration: 201.616793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:14.975302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.911465ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-03T17:25:18.12839Z","caller":"traceutil/trace.go:171","msg":"trace[148582664] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:495; }","duration":"106.327928ms","start":"2026-02-03T17:25:18.022046Z","end":"2026-02-03T17:25:18.128374Z","steps":["trace[148582664] 'read index received' (duration: 13.528081ms)","trace[148582664] 'applied index is now lower than readState.Index' (duration: 92.799197ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:25:18.128519Z","caller":"traceutil/trace.go:171","msg":"trace[341229427] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"107.559058ms","start":"2026-02-03T17:25:18.020889Z","end":"2026-02-03T17:25:18.128448Z","steps":["trace[341229427] 'process raft request' (duration: 106.38025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:18.128624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.562066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:25:18.129517Z","caller":"traceutil/trace.go:171","msg":"trace[1952999784] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:0; response_revision:482; }","duration":"107.471814ms","start":"2026-02-03T17:25:18.022026Z","end":"2026-02-03T17:25:18.129498Z","steps":["trace[1952999784] 'agreement among raft nodes before linearized reading' (duration: 106.559035ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:25:19.984025Z","caller":"traceutil/trace.go:171","msg":"trace[411357261] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"197.74347ms","start":"2026-02-03T17:25:19.786258Z","end":"2026-02-03T17:25:19.984002Z","steps":["trace[411357261] 'process raft request' (duration: 197.575325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:20.364727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.599065ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:25:20.364823Z","caller":"traceutil/trace.go:171","msg":"trace[1067369305] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:536; }","duration":"223.352266ms","start":"2026-02-03T17:25:20.141454Z","end":"2026-02-03T17:25:20.364806Z","steps":["trace[1067369305] 'read index received' (duration: 36.674659ms)","trace[1067369305] 'applied index is now lower than readState.Index' (duration: 186.676277ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:25:20.364939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.013703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4062"} {"level":"info","ts":"2026-02-03T17:25:20.364965Z","caller":"traceutil/trace.go:171","msg":"trace[23256384] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:522; }","duration":"152.063915ms","start":"2026-02-03T17:25:20.212892Z","end":"2026-02-03T17:25:20.364956Z","steps":["trace[23256384] 'agreement among raft nodes before linearized reading' (duration: 152.005253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:20.364966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.505341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-03T17:25:20.365044Z","caller":"traceutil/trace.go:171","msg":"trace[1513516548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:522; }","duration":"223.654995ms","start":"2026-02-03T17:25:20.141373Z","end":"2026-02-03T17:25:20.365028Z","steps":["trace[1513516548] 'agreement among raft nodes before linearized reading' (duration: 223.518841ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:25:20.365237Z","caller":"traceutil/trace.go:171","msg":"trace[1113899268] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"224.017857ms","start":"2026-02-03T17:25:20.141192Z","end":"2026-02-03T17:25:20.36521Z","steps":["trace[1113899268] 'process raft request' (duration: 36.859374ms)","trace[1113899268] 'compare' (duration: 186.478182ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:25:20.744833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.397935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:25:20.744995Z","caller":"traceutil/trace.go:171","msg":"trace[894314586] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"372.462995ms","start":"2026-02-03T17:25:20.372445Z","end":"2026-02-03T17:25:20.744908Z","steps":["trace[894314586] 'process raft request' (duration: 138.914855ms)","trace[894314586] 'compare' (duration: 233.289152ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:25:20.745048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:20.372432Z","time spent":"372.594869ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:25:20.780623Z","caller":"traceutil/trace.go:171","msg":"trace[1739303943] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:538; }","duration":"226.707112ms","start":"2026-02-03T17:25:20.553902Z","end":"2026-02-03T17:25:20.780609Z","steps":["trace[1739303943] 'read index received' (duration: 226.561307ms)","trace[1739303943] 'applied index is now lower than readState.Index' (duration: 145.215µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:25:20.780734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.791345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:25:20.780755Z","caller":"traceutil/trace.go:171","msg":"trace[1740523262] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:524; }","duration":"226.876157ms","start":"2026-02-03T17:25:20.553874Z","end":"2026-02-03T17:25:20.78075Z","steps":["trace[1740523262] 'agreement among raft nodes before linearized reading' (duration: 226.791175ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:25:20.780916Z","caller":"traceutil/trace.go:171","msg":"trace[193900300] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"408.309307ms","start":"2026-02-03T17:25:20.372601Z","end":"2026-02-03T17:25:20.78091Z","steps":["trace[193900300] 'process raft request' (duration: 407.906384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:20.780976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:20.372596Z","time spent":"408.346148ms","remote":"127.0.0.1:38436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4254,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:25:24.816751Z","caller":"traceutil/trace.go:171","msg":"trace[1369377889] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:546; }","duration":"266.994785ms","start":"2026-02-03T17:25:24.54974Z","end":"2026-02-03T17:25:24.816734Z","steps":["trace[1369377889] 'read index received' (duration: 266.84789ms)","trace[1369377889] 'applied index is now lower than readState.Index' (duration: 146.145µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:25:24.816962Z","caller":"traceutil/trace.go:171","msg":"trace[2006594947] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"370.298496ms","start":"2026-02-03T17:25:24.446655Z","end":"2026-02-03T17:25:24.816954Z","steps":["trace[2006594947] 'process raft request' (duration: 369.984036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:24.817037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:24.446645Z","time spent":"370.342937ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:25:24.81719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.447329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:25:24.817225Z","caller":"traceutil/trace.go:171","msg":"trace[352213317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"267.50428ms","start":"2026-02-03T17:25:24.549712Z","end":"2026-02-03T17:25:24.817217Z","steps":["trace[352213317] 'agreement among raft nodes before linearized reading' (duration: 267.455939ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:25:25.492554Z","caller":"traceutil/trace.go:171","msg":"trace[880398815] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"670.124081ms","start":"2026-02-03T17:25:24.822414Z","end":"2026-02-03T17:25:25.492538Z","steps":["trace[880398815] 'process raft request' (duration: 669.998377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:25.492669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:24.822403Z","time spent":"670.206083ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:25:26.26132Z","caller":"traceutil/trace.go:171","msg":"trace[495529625] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"117.615497ms","start":"2026-02-03T17:25:26.143672Z","end":"2026-02-03T17:25:26.261287Z","steps":["trace[495529625] 'process raft request' (duration: 85.08141ms)","trace[495529625] 'compare' (duration: 32.440844ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:25:27.871939Z","caller":"traceutil/trace.go:171","msg":"trace[1670171024] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"370.985378ms","start":"2026-02-03T17:25:27.500937Z","end":"2026-02-03T17:25:27.871922Z","steps":["trace[1670171024] 'process raft request' (duration: 370.897625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:27.872051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:27.500926Z","time spent":"371.06397ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:25:28.412573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.622147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-02-03T17:25:28.41259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.01516ms","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-03T17:25:28.41265Z","caller":"traceutil/trace.go:171","msg":"trace[1676251587] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:537; }","duration":"138.74098ms","start":"2026-02-03T17:25:28.273898Z","end":"2026-02-03T17:25:28.412639Z","steps":["trace[1676251587] 'range keys from in-memory index tree' (duration: 138.491022ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:25:28.412655Z","caller":"traceutil/trace.go:171","msg":"trace[1953172658] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:537; }","duration":"269.115574ms","start":"2026-02-03T17:25:28.143531Z","end":"2026-02-03T17:25:28.412646Z","steps":["trace[1953172658] 'range keys from in-memory index tree' (duration: 268.896827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:30.536022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.744426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6534"} {"level":"info","ts":"2026-02-03T17:25:30.536097Z","caller":"traceutil/trace.go:171","msg":"trace[582655389] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:545; }","duration":"663.85347ms","start":"2026-02-03T17:25:29.872226Z","end":"2026-02-03T17:25:30.536079Z","steps":["trace[582655389] 'range keys from in-memory index tree' (duration: 663.598172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:30.536137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:29.872187Z","time spent":"663.941092ms","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":6556,"request content":"key:\"/registry/configmaps/kube-system/cilium-config\" "} {"level":"warn","ts":"2026-02-03T17:25:30.536383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.618829ms","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-03T17:25:30.536411Z","caller":"traceutil/trace.go:171","msg":"trace[1668283068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:545; }","duration":"661.6606ms","start":"2026-02-03T17:25:29.874742Z","end":"2026-02-03T17:25:30.536403Z","steps":["trace[1668283068] 'range keys from in-memory index tree' (duration: 661.532086ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:30.536438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:29.874737Z","time spent":"661.695381ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T17:25:30.537083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.453005ms","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-03T17:25:30.537113Z","caller":"traceutil/trace.go:171","msg":"trace[871274701] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:545; }","duration":"108.523428ms","start":"2026-02-03T17:25:30.428581Z","end":"2026-02-03T17:25:30.537104Z","steps":["trace[871274701] 'range keys from in-memory index tree' (duration: 108.342583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:30.537303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.905672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-03T17:25:30.537331Z","caller":"traceutil/trace.go:171","msg":"trace[536709039] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:545; }","duration":"104.976005ms","start":"2026-02-03T17:25:30.432346Z","end":"2026-02-03T17:25:30.537322Z","steps":["trace[536709039] 'range keys from in-memory index tree' (duration: 104.81711ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:25:30.797096Z","caller":"traceutil/trace.go:171","msg":"trace[184247781] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"254.305702ms","start":"2026-02-03T17:25:30.542768Z","end":"2026-02-03T17:25:30.797074Z","steps":["trace[184247781] 'process raft request' (duration: 254.121116ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:25:30.900083Z","caller":"traceutil/trace.go:171","msg":"trace[278418580] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:562; }","duration":"257.262616ms","start":"2026-02-03T17:25:30.642798Z","end":"2026-02-03T17:25:30.90006Z","steps":["trace[278418580] 'read index received' (duration: 154.172331ms)","trace[278418580] 'applied index is now lower than readState.Index' (duration: 103.088995ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:25:30.900532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.718871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xgvxc\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-03T17:25:30.900568Z","caller":"traceutil/trace.go:171","msg":"trace[680241781] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xgvxc; range_end:; response_count:1; response_revision:548; }","duration":"257.803893ms","start":"2026-02-03T17:25:30.642756Z","end":"2026-02-03T17:25:30.90056Z","steps":["trace[680241781] 'agreement among raft nodes before linearized reading' (duration: 257.679129ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:25:30.900874Z","caller":"traceutil/trace.go:171","msg":"trace[896060940] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"357.399916ms","start":"2026-02-03T17:25:30.543465Z","end":"2026-02-03T17:25:30.900865Z","steps":["trace[896060940] 'process raft request' (duration: 355.059051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:30.900942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:30.543457Z","time spent":"357.443398ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:25:30.901156Z","caller":"traceutil/trace.go:171","msg":"trace[467533930] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"355.779125ms","start":"2026-02-03T17:25:30.545367Z","end":"2026-02-03T17:25:30.901146Z","steps":["trace[467533930] 'process raft request' (duration: 354.638398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:30.901206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:30.545346Z","time spent":"355.834136ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:25:33.307353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.279948ms","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-03T17:25:33.307437Z","caller":"traceutil/trace.go:171","msg":"trace[2000904425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:559; }","duration":"398.391301ms","start":"2026-02-03T17:25:32.909028Z","end":"2026-02-03T17:25:33.307419Z","steps":["trace[2000904425] 'range keys from in-memory index tree' (duration: 398.03532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:33.307497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:32.909023Z","time spent":"398.463874ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T17:25:33.307774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.795524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-03T17:25:33.307812Z","caller":"traceutil/trace.go:171","msg":"trace[1829786062] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:559; }","duration":"398.873557ms","start":"2026-02-03T17:25:32.908928Z","end":"2026-02-03T17:25:33.307801Z","steps":["trace[1829786062] 'range keys from in-memory index tree' (duration: 398.604759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:33.30786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:32.908909Z","time spent":"398.934829ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-03T17:25:33.308082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.432467ms","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-03T17:25:33.308115Z","caller":"traceutil/trace.go:171","msg":"trace[1821854344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:559; }","duration":"390.491809ms","start":"2026-02-03T17:25:32.917612Z","end":"2026-02-03T17:25:33.308104Z","steps":["trace[1821854344] 'range keys from in-memory index tree' (duration: 390.333445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:33.308144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:32.917599Z","time spent":"390.538941ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-03T17:25:41.983787Z","caller":"traceutil/trace.go:171","msg":"trace[605685513] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"180.068074ms","start":"2026-02-03T17:25:41.803703Z","end":"2026-02-03T17:25:41.983771Z","steps":["trace[605685513] 'process raft request' (duration: 178.849515ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:25:44.685265Z","caller":"traceutil/trace.go:171","msg":"trace[1953935476] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:709; }","duration":"134.418253ms","start":"2026-02-03T17:25:44.550829Z","end":"2026-02-03T17:25:44.685247Z","steps":["trace[1953935476] 'read index received' (duration: 134.250247ms)","trace[1953935476] 'applied index is now lower than readState.Index' (duration: 165.246µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:25:44.685405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.562568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:25:44.685442Z","caller":"traceutil/trace.go:171","msg":"trace[1735161717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:689; }","duration":"134.632759ms","start":"2026-02-03T17:25:44.550791Z","end":"2026-02-03T17:25:44.685424Z","steps":["trace[1735161717] 'agreement among raft nodes before linearized reading' (duration: 134.538716ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:25:44.68577Z","caller":"traceutil/trace.go:171","msg":"trace[1591798136] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"172.467914ms","start":"2026-02-03T17:25:44.513293Z","end":"2026-02-03T17:25:44.685761Z","steps":["trace[1591798136] 'process raft request' (duration: 171.838844ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:45.369071Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338482306440121,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T17:25:45.880772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.710876ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:25:45.880857Z","caller":"traceutil/trace.go:171","msg":"trace[1480325809] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"1.153205669s","start":"2026-02-03T17:25:44.727624Z","end":"2026-02-03T17:25:45.88083Z","steps":["trace[1480325809] 'process raft request' (duration: 945.38059ms)","trace[1480325809] 'compare' (duration: 207.561051ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:25:45.880898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:44.727613Z","time spent":"1.153265691s","remote":"127.0.0.1:38450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4975,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:25:46.771988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.797346ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-03T17:25:46.77212Z","caller":"traceutil/trace.go:171","msg":"trace[1204763633] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:710; }","duration":"1.903665116s","start":"2026-02-03T17:25:44.868444Z","end":"2026-02-03T17:25:46.772109Z","steps":["trace[1204763633] 'read index received' (duration: 804.569585ms)","trace[1204763633] 'applied index is now lower than readState.Index' (duration: 1.099094951s)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:25:46.772353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.903901934s","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-03T17:25:46.772374Z","caller":"traceutil/trace.go:171","msg":"trace[1947617640] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:690; }","duration":"1.903952066s","start":"2026-02-03T17:25:44.868417Z","end":"2026-02-03T17:25:46.772369Z","steps":["trace[1947617640] 'agreement among raft nodes before linearized reading' (duration: 1.903872263s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:46.772409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:44.868403Z","time spent":"1.904001957s","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-03T17:25:46.772712Z","caller":"traceutil/trace.go:171","msg":"trace[260992843] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"887.828933ms","start":"2026-02-03T17:25:45.884876Z","end":"2026-02-03T17:25:46.772705Z","steps":["trace[260992843] 'process raft request' (duration: 887.175073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:46.772759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:45.884861Z","time spent":"887.866804ms","remote":"127.0.0.1:38434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":783,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:25:46.78902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.359834252s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-03T17:25:46.789073Z","caller":"traceutil/trace.go:171","msg":"trace[1480930237] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:693; }","duration":"1.359939135s","start":"2026-02-03T17:25:45.429122Z","end":"2026-02-03T17:25:46.789061Z","steps":["trace[1480930237] 'agreement among raft nodes before linearized reading' (duration: 1.35979176s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:46.789096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:45.429104Z","time spent":"1.359986876s","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-03T17:25:46.789165Z","caller":"traceutil/trace.go:171","msg":"trace[2053181005] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"904.149099ms","start":"2026-02-03T17:25:45.885009Z","end":"2026-02-03T17:25:46.789158Z","steps":["trace[2053181005] 'process raft request' (duration: 887.691449ms)","trace[2053181005] 'compare' (duration: 15.928373ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:25:46.789215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:45.884997Z","time spent":"904.180139ms","remote":"127.0.0.1:38554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1292,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:25:46.789332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.423254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-cd9pk\" ","response":"range_response_count:1 size:4990"} {"level":"info","ts":"2026-02-03T17:25:46.789348Z","caller":"traceutil/trace.go:171","msg":"trace[506975691] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-cd9pk; range_end:; response_count:1; response_revision:693; }","duration":"902.459335ms","start":"2026-02-03T17:25:45.886883Z","end":"2026-02-03T17:25:46.789343Z","steps":["trace[506975691] 'agreement among raft nodes before linearized reading' (duration: 902.406194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:46.789361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:45.886875Z","time spent":"902.482946ms","remote":"127.0.0.1:38450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5012,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-cd9pk\" "} {"level":"warn","ts":"2026-02-03T17:25:46.789405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.355947259s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-03T17:25:46.789452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.358931963s","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-03T17:25:46.789458Z","caller":"traceutil/trace.go:171","msg":"trace[1202972490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:693; }","duration":"1.356020701s","start":"2026-02-03T17:25:45.433422Z","end":"2026-02-03T17:25:46.789442Z","steps":["trace[1202972490] 'agreement among raft nodes before linearized reading' (duration: 1.355883836s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:46.789496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:45.433412Z","time spent":"1.356076443s","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-03T17:25:46.78947Z","caller":"traceutil/trace.go:171","msg":"trace[1138636376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:693; }","duration":"1.358966233s","start":"2026-02-03T17:25:45.430499Z","end":"2026-02-03T17:25:46.789465Z","steps":["trace[1138636376] 'agreement among raft nodes before linearized reading' (duration: 1.358922732s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:46.789627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:45.430489Z","time spent":"1.359132219s","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T17:25:46.789673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.641354ms","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":"warn","ts":"2026-02-03T17:25:46.7897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.235508688s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:25:46.789713Z","caller":"traceutil/trace.go:171","msg":"trace[1838452491] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:693; }","duration":"1.235547958s","start":"2026-02-03T17:25:45.554161Z","end":"2026-02-03T17:25:46.789709Z","steps":["trace[1838452491] 'agreement among raft nodes before linearized reading' (duration: 1.235526137s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:46.789725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:45.554143Z","time spent":"1.235579589s","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-03T17:25:46.789744Z","caller":"traceutil/trace.go:171","msg":"trace[740311804] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:693; }","duration":"961.695155ms","start":"2026-02-03T17:25:45.827998Z","end":"2026-02-03T17:25:46.789693Z","steps":["trace[740311804] 'agreement among raft nodes before linearized reading' (duration: 961.612762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:46.789776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:45.827986Z","time spent":"961.780828ms","remote":"127.0.0.1:38550","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":"info","ts":"2026-02-03T17:25:51.315218Z","caller":"traceutil/trace.go:171","msg":"trace[477252800] linearizableReadLoop","detail":"{readStateIndex:755; appliedIndex:754; }","duration":"470.594094ms","start":"2026-02-03T17:25:50.844593Z","end":"2026-02-03T17:25:51.315187Z","steps":["trace[477252800] 'read index received' (duration: 470.340736ms)","trace[477252800] 'applied index is now lower than readState.Index' (duration: 252.468µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:25:51.315667Z","caller":"traceutil/trace.go:171","msg":"trace[851971987] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"474.549018ms","start":"2026-02-03T17:25:50.841107Z","end":"2026-02-03T17:25:51.315656Z","steps":["trace[851971987] 'process raft request' (duration: 473.912768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:51.315802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:50.841079Z","time spent":"474.626051ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:25:51.316053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.455781ms","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-03T17:25:51.316086Z","caller":"traceutil/trace.go:171","msg":"trace[2122684172] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:731; }","duration":"471.515483ms","start":"2026-02-03T17:25:50.844562Z","end":"2026-02-03T17:25:51.316077Z","steps":["trace[2122684172] 'agreement among raft nodes before linearized reading' (duration: 471.39786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:51.316113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:50.844546Z","time spent":"471.562294ms","remote":"127.0.0.1:38550","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-03T17:25:51.316277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.89656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-03T17:25:51.316334Z","caller":"traceutil/trace.go:171","msg":"trace[680418479] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:731; }","duration":"436.988453ms","start":"2026-02-03T17:25:50.879337Z","end":"2026-02-03T17:25:51.316325Z","steps":["trace[680418479] 'agreement among raft nodes before linearized reading' (duration: 436.89188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:51.316361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:50.879321Z","time spent":"437.035744ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-03T17:25:51.316529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.326932ms","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-03T17:25:51.316556Z","caller":"traceutil/trace.go:171","msg":"trace[1021338521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:731; }","duration":"436.366823ms","start":"2026-02-03T17:25:50.880183Z","end":"2026-02-03T17:25:51.31655Z","steps":["trace[1021338521] 'agreement among raft nodes before linearized reading' (duration: 436.296461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:51.316603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:50.880176Z","time spent":"436.401294ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T17:25:51.316722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.268732ms","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-03T17:25:51.31675Z","caller":"traceutil/trace.go:171","msg":"trace[1307668150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:731; }","duration":"437.311103ms","start":"2026-02-03T17:25:50.879433Z","end":"2026-02-03T17:25:51.316744Z","steps":["trace[1307668150] 'agreement among raft nodes before linearized reading' (duration: 437.245291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:51.316772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:50.879429Z","time spent":"437.338784ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T17:25:51.70415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.120791ms","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-03T17:25:51.704295Z","caller":"traceutil/trace.go:171","msg":"trace[1146837692] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:731; }","duration":"107.281826ms","start":"2026-02-03T17:25:51.596994Z","end":"2026-02-03T17:25:51.704276Z","steps":["trace[1146837692] 'range keys from in-memory index tree' (duration: 107.10597ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:25:51.70458Z","caller":"traceutil/trace.go:171","msg":"trace[727886533] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"380.963514ms","start":"2026-02-03T17:25:51.323606Z","end":"2026-02-03T17:25:51.70457Z","steps":["trace[727886533] 'process raft request' (duration: 370.893636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:51.704688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:51.323588Z","time spent":"381.024755ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:25:51.769796Z","caller":"traceutil/trace.go:171","msg":"trace[601535982] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:755; }","duration":"216.934757ms","start":"2026-02-03T17:25:51.55283Z","end":"2026-02-03T17:25:51.769765Z","steps":["trace[601535982] 'read index received' (duration: 141.685102ms)","trace[601535982] 'applied index is now lower than readState.Index' (duration: 75.248235ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:25:51.770057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.169255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:25:51.770091Z","caller":"traceutil/trace.go:171","msg":"trace[1500439735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:735; }","duration":"217.298859ms","start":"2026-02-03T17:25:51.552781Z","end":"2026-02-03T17:25:51.77008Z","steps":["trace[1500439735] 'agreement among raft nodes before linearized reading' (duration: 217.105243ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:25:51.771528Z","caller":"traceutil/trace.go:171","msg":"trace[287179411] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"447.810524ms","start":"2026-02-03T17:25:51.323702Z","end":"2026-02-03T17:25:51.771513Z","steps":["trace[287179411] 'process raft request' (duration: 445.828122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:51.771655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:51.323696Z","time spent":"447.863886ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:25:51.771789Z","caller":"traceutil/trace.go:171","msg":"trace[992999734] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"446.572685ms","start":"2026-02-03T17:25:51.325179Z","end":"2026-02-03T17:25:51.771751Z","steps":["trace[992999734] 'process raft request' (duration: 444.50852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:51.771842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:51.325172Z","time spent":"446.645787ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:25:51.771974Z","caller":"traceutil/trace.go:171","msg":"trace[1580399838] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"445.521632ms","start":"2026-02-03T17:25:51.326445Z","end":"2026-02-03T17:25:51.771967Z","steps":["trace[1580399838] 'process raft request' (duration: 443.284361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:51.772023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:51.326439Z","time spent":"445.558153ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:25:53.645437Z","caller":"traceutil/trace.go:171","msg":"trace[1927252412] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"320.997502ms","start":"2026-02-03T17:25:53.32439Z","end":"2026-02-03T17:25:53.645388Z","steps":["trace[1927252412] 'process raft request' (duration: 320.420684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:53.645627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:53.324359Z","time spent":"321.159588ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:26:08.436073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.797548ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:26:08.436165Z","caller":"traceutil/trace.go:171","msg":"trace[1199774266] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:809; }","duration":"484.42319ms","start":"2026-02-03T17:26:07.951726Z","end":"2026-02-03T17:26:08.43615Z","steps":["trace[1199774266] 'read index received' (duration: 26.641µs)","trace[1199774266] 'applied index is now lower than readState.Index' (duration: 484.395639ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:26:08.436246Z","caller":"traceutil/trace.go:171","msg":"trace[186362942] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"622.273051ms","start":"2026-02-03T17:26:07.813964Z","end":"2026-02-03T17:26:08.436237Z","steps":["trace[186362942] 'process raft request' (duration: 127.231666ms)","trace[186362942] 'compare' (duration: 494.689984ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:26:08.436299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:07.813947Z","time spent":"622.330343ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:26:08.436383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.574019ms","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-03T17:26:08.436448Z","caller":"traceutil/trace.go:171","msg":"trace[1607446961] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:782; }","duration":"480.668762ms","start":"2026-02-03T17:26:07.955763Z","end":"2026-02-03T17:26:08.436432Z","steps":["trace[1607446961] 'agreement among raft nodes before linearized reading' (duration: 480.497457ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:08.436486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:07.955753Z","time spent":"480.725334ms","remote":"127.0.0.1:38550","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-03T17:26:08.436682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.953167ms","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-03T17:26:08.436709Z","caller":"traceutil/trace.go:171","msg":"trace[1324199212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:782; }","duration":"485.003769ms","start":"2026-02-03T17:26:07.951697Z","end":"2026-02-03T17:26:08.436701Z","steps":["trace[1324199212] 'agreement among raft nodes before linearized reading' (duration: 484.927386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:08.436762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:07.951682Z","time spent":"485.07371ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T17:26:08.436922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.963681ms","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-03T17:26:08.436947Z","caller":"traceutil/trace.go:171","msg":"trace[486706888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:782; }","duration":"481.044044ms","start":"2026-02-03T17:26:07.955895Z","end":"2026-02-03T17:26:08.436939Z","steps":["trace[486706888] 'agreement among raft nodes before linearized reading' (duration: 480.970362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:08.436971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:07.955876Z","time spent":"481.089395ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-03T17:26:09.916818Z","caller":"traceutil/trace.go:171","msg":"trace[498940975] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:813; }","duration":"134.890718ms","start":"2026-02-03T17:26:09.781908Z","end":"2026-02-03T17:26:09.916798Z","steps":["trace[498940975] 'read index received' (duration: 134.749913ms)","trace[498940975] 'applied index is now lower than readState.Index' (duration: 140.025µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:26:09.916989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:09.464495Z","time spent":"452.490793ms","remote":"127.0.0.1:38328","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-03T17:26:09.917321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.406784ms","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-03T17:26:09.91735Z","caller":"traceutil/trace.go:171","msg":"trace[1772296900] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:785; }","duration":"135.468296ms","start":"2026-02-03T17:26:09.781871Z","end":"2026-02-03T17:26:09.91734Z","steps":["trace[1772296900] 'agreement among raft nodes before linearized reading' (duration: 135.333372ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:10.657745Z","caller":"traceutil/trace.go:171","msg":"trace[1054215185] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:819; }","duration":"104.487677ms","start":"2026-02-03T17:26:10.553237Z","end":"2026-02-03T17:26:10.657724Z","steps":["trace[1054215185] 'read index received' (duration: 50.413621ms)","trace[1054215185] 'applied index is now lower than readState.Index' (duration: 54.073376ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:26:10.657918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.664774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:26:10.657947Z","caller":"traceutil/trace.go:171","msg":"trace[970739936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:792; }","duration":"104.735125ms","start":"2026-02-03T17:26:10.553205Z","end":"2026-02-03T17:26:10.65794Z","steps":["trace[970739936] 'agreement among raft nodes before linearized reading' (duration: 104.604551ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:10.658176Z","caller":"traceutil/trace.go:171","msg":"trace[1777047756] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"146.424122ms","start":"2026-02-03T17:26:10.511745Z","end":"2026-02-03T17:26:10.658169Z","steps":["trace[1777047756] 'process raft request' (duration: 145.856065ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:10.658262Z","caller":"traceutil/trace.go:171","msg":"trace[1608657591] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"146.462564ms","start":"2026-02-03T17:26:10.511793Z","end":"2026-02-03T17:26:10.658256Z","steps":["trace[1608657591] 'process raft request' (duration: 145.905077ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:12.032151Z","caller":"traceutil/trace.go:171","msg":"trace[1659389198] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"100.785471ms","start":"2026-02-03T17:26:11.931346Z","end":"2026-02-03T17:26:12.032132Z","steps":["trace[1659389198] 'process raft request' (duration: 100.673147ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:12.643988Z","caller":"traceutil/trace.go:171","msg":"trace[1326343684] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"128.535967ms","start":"2026-02-03T17:26:12.515431Z","end":"2026-02-03T17:26:12.643967Z","steps":["trace[1326343684] 'process raft request' (duration: 128.193626ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:12.848913Z","caller":"traceutil/trace.go:171","msg":"trace[695131359] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:823; }","duration":"187.279802ms","start":"2026-02-03T17:26:12.661611Z","end":"2026-02-03T17:26:12.848891Z","steps":["trace[695131359] 'read index received' (duration: 185.33189ms)","trace[695131359] 'applied index is now lower than readState.Index' (duration: 1.946962ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:26:12.849124Z","caller":"traceutil/trace.go:171","msg":"trace[233247811] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"192.92174ms","start":"2026-02-03T17:26:12.656191Z","end":"2026-02-03T17:26:12.849112Z","steps":["trace[233247811] 'process raft request' (duration: 190.804393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:12.849391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.760197ms","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-03T17:26:12.849437Z","caller":"traceutil/trace.go:171","msg":"trace[1918451110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:795; }","duration":"187.817349ms","start":"2026-02-03T17:26:12.661609Z","end":"2026-02-03T17:26:12.849426Z","steps":["trace[1918451110] 'agreement among raft nodes before linearized reading' (duration: 187.657294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:12.849609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.960243ms","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-03T17:26:12.849636Z","caller":"traceutil/trace.go:171","msg":"trace[1935912508] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:795; }","duration":"187.996734ms","start":"2026-02-03T17:26:12.661631Z","end":"2026-02-03T17:26:12.849628Z","steps":["trace[1935912508] 'agreement among raft nodes before linearized reading' (duration: 187.922352ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:13.252181Z","caller":"traceutil/trace.go:171","msg":"trace[1875840315] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"392.56024ms","start":"2026-02-03T17:26:12.8596Z","end":"2026-02-03T17:26:13.252161Z","steps":["trace[1875840315] 'process raft request' (duration: 371.937269ms)","trace[1875840315] 'compare' (duration: 20.210308ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:26:13.252292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:12.859572Z","time spent":"392.666083ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:26:13.437205Z","caller":"traceutil/trace.go:171","msg":"trace[1667622309] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"577.381973ms","start":"2026-02-03T17:26:12.859802Z","end":"2026-02-03T17:26:13.437184Z","steps":["trace[1667622309] 'process raft request' (duration: 575.182093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:13.437357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:12.859794Z","time spent":"577.477857ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:26:14.858886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.953354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:26:14.858978Z","caller":"traceutil/trace.go:171","msg":"trace[1815331547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:798; }","duration":"304.088378ms","start":"2026-02-03T17:26:14.554868Z","end":"2026-02-03T17:26:14.858956Z","steps":["trace[1815331547] 'range keys from in-memory index tree' (duration: 303.810889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:14.859025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:14.554849Z","time spent":"304.16501ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-03T17:26:14.859312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.012469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-03T17:26:14.859342Z","caller":"traceutil/trace.go:171","msg":"trace[225847427] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:798; }","duration":"204.078581ms","start":"2026-02-03T17:26:14.655254Z","end":"2026-02-03T17:26:14.859333Z","steps":["trace[225847427] 'range keys from in-memory index tree' (duration: 203.845504ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:14.988893Z","caller":"traceutil/trace.go:171","msg":"trace[1848347171] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"124.444318ms","start":"2026-02-03T17:26:14.864432Z","end":"2026-02-03T17:26:14.988876Z","steps":["trace[1848347171] 'process raft request' (duration: 124.338584ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:15.031598Z","caller":"traceutil/trace.go:171","msg":"trace[1346221967] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"164.947506ms","start":"2026-02-03T17:26:14.866614Z","end":"2026-02-03T17:26:15.031561Z","steps":["trace[1346221967] 'process raft request' (duration: 164.630936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:15.369989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.613438ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-03T17:26:15.370061Z","caller":"traceutil/trace.go:171","msg":"trace[1833339788] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:829; }","duration":"114.06312ms","start":"2026-02-03T17:26:15.255985Z","end":"2026-02-03T17:26:15.370048Z","steps":["trace[1833339788] 'read index received' (duration: 38.861µs)","trace[1833339788] 'applied index is now lower than readState.Index' (duration: 114.023039ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:26:15.370172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.178554ms","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-03T17:26:15.370191Z","caller":"traceutil/trace.go:171","msg":"trace[961101855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:800; }","duration":"114.240826ms","start":"2026-02-03T17:26:15.255944Z","end":"2026-02-03T17:26:15.370185Z","steps":["trace[961101855] 'agreement among raft nodes before linearized reading' (duration: 114.136652ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:28.560825Z","caller":"traceutil/trace.go:171","msg":"trace[1470512185] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:920; }","duration":"154.112134ms","start":"2026-02-03T17:26:28.4067Z","end":"2026-02-03T17:26:28.560812Z","steps":["trace[1470512185] 'read index received' (duration: 153.97007ms)","trace[1470512185] 'applied index is now lower than readState.Index' (duration: 141.134µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:26:28.560987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.272599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T17:26:28.561011Z","caller":"traceutil/trace.go:171","msg":"trace[1935309710] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:888; }","duration":"154.332621ms","start":"2026-02-03T17:26:28.40667Z","end":"2026-02-03T17:26:28.561003Z","steps":["trace[1935309710] 'agreement among raft nodes before linearized reading' (duration: 154.222508ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:28.561328Z","caller":"traceutil/trace.go:171","msg":"trace[521506945] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"343.685047ms","start":"2026-02-03T17:26:28.217636Z","end":"2026-02-03T17:26:28.561321Z","steps":["trace[521506945] 'process raft request' (duration: 343.071278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:28.561481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:28.217626Z","time spent":"343.817672ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:26:29.72964Z","caller":"traceutil/trace.go:171","msg":"trace[1217493489] linearizableReadLoop","detail":"{readStateIndex:924; appliedIndex:923; }","duration":"185.098722ms","start":"2026-02-03T17:26:29.544515Z","end":"2026-02-03T17:26:29.729614Z","steps":["trace[1217493489] 'read index received' (duration: 184.929667ms)","trace[1217493489] 'applied index is now lower than readState.Index' (duration: 167.795µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:26:29.730129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.601258ms","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-03T17:26:29.730179Z","caller":"traceutil/trace.go:171","msg":"trace[1503903600] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:890; }","duration":"185.66091ms","start":"2026-02-03T17:26:29.544505Z","end":"2026-02-03T17:26:29.730166Z","steps":["trace[1503903600] 'agreement among raft nodes before linearized reading' (duration: 185.492474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:29.730492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.718967ms","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-03T17:26:29.730534Z","caller":"traceutil/trace.go:171","msg":"trace[1880172409] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:890; }","duration":"156.784418ms","start":"2026-02-03T17:26:29.573739Z","end":"2026-02-03T17:26:29.730524Z","steps":["trace[1880172409] 'agreement among raft nodes before linearized reading' (duration: 156.676895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:31.383489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.641206ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:26:31.383605Z","caller":"traceutil/trace.go:171","msg":"trace[1660584141] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"1.652185828s","start":"2026-02-03T17:26:29.731402Z","end":"2026-02-03T17:26:31.383588Z","steps":["trace[1660584141] 'process raft request' (duration: 681.380937ms)","trace[1660584141] 'compare' (duration: 970.507222ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:26:31.383651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:29.731381Z","time spent":"1.652247691s","remote":"127.0.0.1:38328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:26:31.431321Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.018750635s","expected-duration":"1s"} {"level":"info","ts":"2026-02-03T17:26:31.43162Z","caller":"traceutil/trace.go:171","msg":"trace[1797677712] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:924; }","duration":"1.026440098s","start":"2026-02-03T17:26:30.40516Z","end":"2026-02-03T17:26:31.4316Z","steps":["trace[1797677712] 'read index received' (duration: 7.630541ms)","trace[1797677712] 'applied index is now lower than readState.Index' (duration: 1.018808747s)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:26:31.431825Z","caller":"traceutil/trace.go:171","msg":"trace[1405122703] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"1.698135718s","start":"2026-02-03T17:26:29.733681Z","end":"2026-02-03T17:26:31.431817Z","steps":["trace[1405122703] 'process raft request' (duration: 1.697790928s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:31.431913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:29.733666Z","time spent":"1.69817988s","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:26:31.432037Z","caller":"traceutil/trace.go:171","msg":"trace[2122515332] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"1.69787678s","start":"2026-02-03T17:26:29.734154Z","end":"2026-02-03T17:26:31.432031Z","steps":["trace[2122515332] 'process raft request' (duration: 1.697416156s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:31.432071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:29.734147Z","time spent":"1.697904992s","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:26:31.432158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.027001355s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:26:31.432177Z","caller":"traceutil/trace.go:171","msg":"trace[1780657334] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:893; }","duration":"1.027047737s","start":"2026-02-03T17:26:30.405124Z","end":"2026-02-03T17:26:31.432171Z","steps":["trace[1780657334] 'agreement among raft nodes before linearized reading' (duration: 1.027014536s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:31.4325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:30.40509Z","time spent":"1.027401638s","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-03T17:26:31.467292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.112972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:26:31.467364Z","caller":"traceutil/trace.go:171","msg":"trace[306194469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:894; }","duration":"914.235706ms","start":"2026-02-03T17:26:30.553112Z","end":"2026-02-03T17:26:31.467347Z","steps":["trace[306194469] 'agreement among raft nodes before linearized reading' (duration: 914.102662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:31.467428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:30.553096Z","time spent":"914.320138ms","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-03T17:26:31.467815Z","caller":"traceutil/trace.go:171","msg":"trace[1498086320] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"616.754596ms","start":"2026-02-03T17:26:30.851039Z","end":"2026-02-03T17:26:31.467794Z","steps":["trace[1498086320] 'process raft request' (duration: 616.067904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:31.467915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:30.851017Z","time spent":"616.844219ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:26:31.468292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.348843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-03T17:26:31.468331Z","caller":"traceutil/trace.go:171","msg":"trace[1492639749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:894; }","duration":"315.417926ms","start":"2026-02-03T17:26:31.152904Z","end":"2026-02-03T17:26:31.468322Z","steps":["trace[1492639749] 'agreement among raft nodes before linearized reading' (duration: 315.304102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:31.468368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:31.152891Z","time spent":"315.468478ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T17:26:31.468555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.165694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-03T17:26:31.468606Z","caller":"traceutil/trace.go:171","msg":"trace[1989677410] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:894; }","duration":"383.213745ms","start":"2026-02-03T17:26:31.085378Z","end":"2026-02-03T17:26:31.468592Z","steps":["trace[1989677410] 'agreement among raft nodes before linearized reading' (duration: 383.111012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:31.468648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:31.085361Z","time spent":"383.274838ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-03T17:26:31.468844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.431655ms","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-03T17:26:31.468887Z","caller":"traceutil/trace.go:171","msg":"trace[1956864020] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:894; }","duration":"903.493487ms","start":"2026-02-03T17:26:30.565385Z","end":"2026-02-03T17:26:31.468878Z","steps":["trace[1956864020] 'agreement among raft nodes before linearized reading' (duration: 903.401024ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:31.46895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:30.565371Z","time spent":"903.569659ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-03T17:26:31.640543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.967987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:26:31.64079Z","caller":"traceutil/trace.go:171","msg":"trace[1086707120] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:928; }","duration":"160.110524ms","start":"2026-02-03T17:26:31.480664Z","end":"2026-02-03T17:26:31.640775Z","steps":["trace[1086707120] 'read index received' (duration: 33.820348ms)","trace[1086707120] 'applied index is now lower than readState.Index' (duration: 126.289386ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:26:31.641008Z","caller":"traceutil/trace.go:171","msg":"trace[803772522] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"162.957954ms","start":"2026-02-03T17:26:31.478036Z","end":"2026-02-03T17:26:31.640994Z","steps":["trace[803772522] 'process raft request' (duration: 36.44224ms)","trace[803772522] 'compare' (duration: 125.272495ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:26:31.64117Z","caller":"traceutil/trace.go:171","msg":"trace[206565667] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"162.969914ms","start":"2026-02-03T17:26:31.47819Z","end":"2026-02-03T17:26:31.64116Z","steps":["trace[206565667] 'process raft request' (duration: 162.437867ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:31.641282Z","caller":"traceutil/trace.go:171","msg":"trace[106824028] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"163.034576ms","start":"2026-02-03T17:26:31.478239Z","end":"2026-02-03T17:26:31.641274Z","steps":["trace[106824028] 'process raft request' (duration: 162.441617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:31.641671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.995372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-03T17:26:31.641711Z","caller":"traceutil/trace.go:171","msg":"trace[90498981] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:897; }","duration":"161.050853ms","start":"2026-02-03T17:26:31.48065Z","end":"2026-02-03T17:26:31.641701Z","steps":["trace[90498981] 'agreement among raft nodes before linearized reading' (duration: 160.969281ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:33.578303Z","caller":"traceutil/trace.go:171","msg":"trace[2144107080] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:931; }","duration":"139.323277ms","start":"2026-02-03T17:26:33.438965Z","end":"2026-02-03T17:26:33.578288Z","steps":["trace[2144107080] 'read index received' (duration: 139.206224ms)","trace[2144107080] 'applied index is now lower than readState.Index' (duration: 116.483µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:26:33.578562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.550174ms","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-03T17:26:33.578607Z","caller":"traceutil/trace.go:171","msg":"trace[975323522] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:898; }","duration":"139.641698ms","start":"2026-02-03T17:26:33.438952Z","end":"2026-02-03T17:26:33.578594Z","steps":["trace[975323522] 'agreement among raft nodes before linearized reading' (duration: 139.458952ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:33.578767Z","caller":"traceutil/trace.go:171","msg":"trace[219705692] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"140.796804ms","start":"2026-02-03T17:26:33.43796Z","end":"2026-02-03T17:26:33.578756Z","steps":["trace[219705692] 'process raft request' (duration: 140.253937ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:33.74005Z","caller":"traceutil/trace.go:171","msg":"trace[1460563983] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"157.925555ms","start":"2026-02-03T17:26:33.582111Z","end":"2026-02-03T17:26:33.740036Z","steps":["trace[1460563983] 'process raft request' (duration: 154.748184ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:37.699913Z","caller":"traceutil/trace.go:171","msg":"trace[1691822361] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"102.671151ms","start":"2026-02-03T17:26:37.597224Z","end":"2026-02-03T17:26:37.699895Z","steps":["trace[1691822361] 'process raft request' (duration: 102.565608ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:39.894972Z","caller":"traceutil/trace.go:171","msg":"trace[1624587955] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"123.329913ms","start":"2026-02-03T17:26:39.771624Z","end":"2026-02-03T17:26:39.894954Z","steps":["trace[1624587955] 'process raft request' (duration: 123.224659ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:39.904558Z","caller":"traceutil/trace.go:171","msg":"trace[651779935] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:952; }","duration":"108.960379ms","start":"2026-02-03T17:26:39.79558Z","end":"2026-02-03T17:26:39.90454Z","steps":["trace[651779935] 'read index received' (duration: 99.447549ms)","trace[651779935] 'applied index is now lower than readState.Index' (duration: 9.5117ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:26:39.904674Z","caller":"traceutil/trace.go:171","msg":"trace[435591004] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"131.551562ms","start":"2026-02-03T17:26:39.773113Z","end":"2026-02-03T17:26:39.904664Z","steps":["trace[435591004] 'process raft request' (duration: 131.290434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:39.904842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.298739ms","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-03T17:26:39.904883Z","caller":"traceutil/trace.go:171","msg":"trace[248702299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:917; }","duration":"102.36826ms","start":"2026-02-03T17:26:39.802504Z","end":"2026-02-03T17:26:39.904872Z","steps":["trace[248702299] 'agreement among raft nodes before linearized reading' (duration: 102.231086ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:39.905019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.423574ms","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-03T17:26:39.905047Z","caller":"traceutil/trace.go:171","msg":"trace[390969625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:917; }","duration":"109.482546ms","start":"2026-02-03T17:26:39.795557Z","end":"2026-02-03T17:26:39.905039Z","steps":["trace[390969625] 'agreement among raft nodes before linearized reading' (duration: 109.358112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:40.069987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.778572ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:26:40.070059Z","caller":"traceutil/trace.go:171","msg":"trace[577049664] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:954; }","duration":"156.358075ms","start":"2026-02-03T17:26:39.913691Z","end":"2026-02-03T17:26:40.070049Z","steps":["trace[577049664] 'read index received' (duration: 41.459009ms)","trace[577049664] 'applied index is now lower than readState.Index' (duration: 114.898496ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:26:40.070107Z","caller":"traceutil/trace.go:171","msg":"trace[1841027199] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"159.842475ms","start":"2026-02-03T17:26:39.910257Z","end":"2026-02-03T17:26:40.0701Z","steps":["trace[1841027199] 'process raft request' (duration: 44.892857ms)","trace[1841027199] 'compare' (duration: 114.70325ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:26:40.070194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.744662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:26:40.070241Z","caller":"traceutil/trace.go:171","msg":"trace[2088787224] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:919; }","duration":"114.830534ms","start":"2026-02-03T17:26:39.9554Z","end":"2026-02-03T17:26:40.07023Z","steps":["trace[2088787224] 'agreement among raft nodes before linearized reading' (duration: 114.750001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:40.070367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.673305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T17:26:40.070387Z","caller":"traceutil/trace.go:171","msg":"trace[785218726] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:919; }","duration":"156.713966ms","start":"2026-02-03T17:26:39.913666Z","end":"2026-02-03T17:26:40.07038Z","steps":["trace[785218726] 'agreement among raft nodes before linearized reading' (duration: 156.679565ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:41.717328Z","caller":"traceutil/trace.go:171","msg":"trace[295295537] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"100.35451ms","start":"2026-02-03T17:26:41.616958Z","end":"2026-02-03T17:26:41.717312Z","steps":["trace[295295537] 'process raft request' (duration: 100.252157ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:44.076854Z","caller":"traceutil/trace.go:171","msg":"trace[1981423049] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"115.436546ms","start":"2026-02-03T17:26:43.961393Z","end":"2026-02-03T17:26:44.07683Z","steps":["trace[1981423049] 'read index received' (duration: 115.26174ms)","trace[1981423049] 'applied index is now lower than readState.Index' (duration: 174.216µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:26:44.076944Z","caller":"traceutil/trace.go:171","msg":"trace[1608821170] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"149.608352ms","start":"2026-02-03T17:26:43.927315Z","end":"2026-02-03T17:26:44.076924Z","steps":["trace[1608821170] 'process raft request' (duration: 149.386855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:44.077019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.609661ms","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-03T17:26:44.077038Z","caller":"traceutil/trace.go:171","msg":"trace[2120431826] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:927; }","duration":"115.668953ms","start":"2026-02-03T17:26:43.961364Z","end":"2026-02-03T17:26:44.077033Z","steps":["trace[2120431826] 'agreement among raft nodes before linearized reading' (duration: 115.556249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:44.077184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.240773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-03T17:26:44.077198Z","caller":"traceutil/trace.go:171","msg":"trace[1006226778] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:927; }","duration":"111.271874ms","start":"2026-02-03T17:26:43.965922Z","end":"2026-02-03T17:26:44.077193Z","steps":["trace[1006226778] 'agreement among raft nodes before linearized reading' (duration: 111.223463ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:46.289107Z","caller":"traceutil/trace.go:171","msg":"trace[16115061] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:969; }","duration":"177.254742ms","start":"2026-02-03T17:26:46.111837Z","end":"2026-02-03T17:26:46.289091Z","steps":["trace[16115061] 'read index received' (duration: 177.127428ms)","trace[16115061] 'applied index is now lower than readState.Index' (duration: 126.784µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:26:46.2893Z","caller":"traceutil/trace.go:171","msg":"trace[2124792660] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"206.149861ms","start":"2026-02-03T17:26:46.083141Z","end":"2026-02-03T17:26:46.289291Z","steps":["trace[2124792660] 'process raft request' (duration: 205.868863ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:46.289488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.640384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-03T17:26:46.289509Z","caller":"traceutil/trace.go:171","msg":"trace[1329149664] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:933; }","duration":"177.670575ms","start":"2026-02-03T17:26:46.111832Z","end":"2026-02-03T17:26:46.289503Z","steps":["trace[1329149664] 'agreement among raft nodes before linearized reading' (duration: 177.584982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:46.289617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.732164ms","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-03T17:26:46.289634Z","caller":"traceutil/trace.go:171","msg":"trace[1911028836] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:933; }","duration":"175.805386ms","start":"2026-02-03T17:26:46.113824Z","end":"2026-02-03T17:26:46.289629Z","steps":["trace[1911028836] 'agreement among raft nodes before linearized reading' (duration: 175.760625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:46.289759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.932594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-03T17:26:46.289775Z","caller":"traceutil/trace.go:171","msg":"trace[1171581544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:933; }","duration":"124.970706ms","start":"2026-02-03T17:26:46.1648Z","end":"2026-02-03T17:26:46.28977Z","steps":["trace[1171581544] 'agreement among raft nodes before linearized reading' (duration: 124.926244ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:50.035131Z","caller":"traceutil/trace.go:171","msg":"trace[776903230] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"279.057408ms","start":"2026-02-03T17:26:49.756059Z","end":"2026-02-03T17:26:50.035117Z","steps":["trace[776903230] 'process raft request' (duration: 278.761999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:50.471232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.435693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-03T17:26:50.471298Z","caller":"traceutil/trace.go:171","msg":"trace[1183044155] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:951; }","duration":"154.546847ms","start":"2026-02-03T17:26:50.31674Z","end":"2026-02-03T17:26:50.471287Z","steps":["trace[1183044155] 'range keys from in-memory index tree' (duration: 154.295269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:50.471664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.432993ms","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-03T17:26:50.47169Z","caller":"traceutil/trace.go:171","msg":"trace[1325179075] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:951; }","duration":"147.504045ms","start":"2026-02-03T17:26:50.324179Z","end":"2026-02-03T17:26:50.471683Z","steps":["trace[1325179075] 'range keys from in-memory index tree' (duration: 147.275648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:50.47191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.713561ms","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-03T17:26:50.47193Z","caller":"traceutil/trace.go:171","msg":"trace[592040749] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:951; }","duration":"140.749192ms","start":"2026-02-03T17:26:50.331176Z","end":"2026-02-03T17:26:50.471925Z","steps":["trace[592040749] 'range keys from in-memory index tree' (duration: 140.643879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:50.472102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.16794ms","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-03T17:26:50.472121Z","caller":"traceutil/trace.go:171","msg":"trace[1576422473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:951; }","duration":"137.20619ms","start":"2026-02-03T17:26:50.33491Z","end":"2026-02-03T17:26:50.472117Z","steps":["trace[1576422473] 'range keys from in-memory index tree' (duration: 136.86218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:52.555031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.109184ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:26:52.555086Z","caller":"traceutil/trace.go:171","msg":"trace[625312237] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1002; }","duration":"590.631828ms","start":"2026-02-03T17:26:51.964446Z","end":"2026-02-03T17:26:52.555078Z","steps":["trace[625312237] 'read index received' (duration: 37.121µs)","trace[625312237] 'applied index is now lower than readState.Index' (duration: 590.594107ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:26:52.555192Z","caller":"traceutil/trace.go:171","msg":"trace[1396502407] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"747.613412ms","start":"2026-02-03T17:26:51.807572Z","end":"2026-02-03T17:26:52.555186Z","steps":["trace[1396502407] 'process raft request' (duration: 149.304921ms)","trace[1396502407] 'compare' (duration: 597.834526ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:26:52.555235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:51.807555Z","time spent":"747.665474ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:26:52.555363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.915237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-z65tz\" ","response":"range_response_count:1 size:10928"} {"level":"info","ts":"2026-02-03T17:26:52.555378Z","caller":"traceutil/trace.go:171","msg":"trace[1357218556] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-z65tz; range_end:; response_count:1; response_revision:964; }","duration":"590.951699ms","start":"2026-02-03T17:26:51.96442Z","end":"2026-02-03T17:26:52.555372Z","steps":["trace[1357218556] 'agreement among raft nodes before linearized reading' (duration: 590.898187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:52.555391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:51.964406Z","time spent":"590.981399ms","remote":"127.0.0.1:38450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":10950,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-z65tz\" "} {"level":"warn","ts":"2026-02-03T17:26:52.555507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.878366ms","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-03T17:26:52.555521Z","caller":"traceutil/trace.go:171","msg":"trace[1615744309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:964; }","duration":"516.910897ms","start":"2026-02-03T17:26:52.038606Z","end":"2026-02-03T17:26:52.555517Z","steps":["trace[1615744309] 'agreement among raft nodes before linearized reading' (duration: 516.864986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:52.555537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:52.038593Z","time spent":"516.941538ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T17:26:53.405056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.690098ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:26:53.405272Z","caller":"traceutil/trace.go:171","msg":"trace[403871018] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1003; }","duration":"732.941122ms","start":"2026-02-03T17:26:52.672318Z","end":"2026-02-03T17:26:53.40526Z","steps":["trace[403871018] 'read index received' (duration: 198.052807ms)","trace[403871018] 'applied index is now lower than readState.Index' (duration: 534.887645ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:26:53.40544Z","caller":"traceutil/trace.go:171","msg":"trace[1896839150] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"845.114504ms","start":"2026-02-03T17:26:52.560317Z","end":"2026-02-03T17:26:53.405431Z","steps":["trace[1896839150] 'process raft request' (duration: 309.992342ms)","trace[1896839150] 'compare' (duration: 534.42113ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:26:53.405487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:52.560309Z","time spent":"845.155405ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:26:53.405595Z","caller":"traceutil/trace.go:171","msg":"trace[2141044962] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"844.265257ms","start":"2026-02-03T17:26:52.561324Z","end":"2026-02-03T17:26:53.405589Z","steps":["trace[2141044962] 'process raft request' (duration: 843.816613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:53.405628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:52.561318Z","time spent":"844.292568ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:26:53.405701Z","caller":"traceutil/trace.go:171","msg":"trace[691303781] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"842.411408ms","start":"2026-02-03T17:26:52.563284Z","end":"2026-02-03T17:26:53.405695Z","steps":["trace[691303781] 'process raft request' (duration: 841.900622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:53.405729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:52.563276Z","time spent":"842.43709ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:26:53.405877Z","caller":"traceutil/trace.go:171","msg":"trace[1614290111] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"705.168707ms","start":"2026-02-03T17:26:52.700702Z","end":"2026-02-03T17:26:53.405871Z","steps":["trace[1614290111] 'process raft request' (duration: 704.509966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:53.40591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:52.700684Z","time spent":"705.207398ms","remote":"127.0.0.1:38450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11176,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:26:53.405989Z","caller":"traceutil/trace.go:171","msg":"trace[1963261727] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"705.242489ms","start":"2026-02-03T17:26:52.700741Z","end":"2026-02-03T17:26:53.405983Z","steps":["trace[1963261727] 'process raft request' (duration: 704.498066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:53.406021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:52.700721Z","time spent":"705.28202ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:26:53.40615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.829369ms","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-03T17:26:53.406173Z","caller":"traceutil/trace.go:171","msg":"trace[434531977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:969; }","duration":"733.885101ms","start":"2026-02-03T17:26:52.672282Z","end":"2026-02-03T17:26:53.406167Z","steps":["trace[434531977] 'agreement among raft nodes before linearized reading' (duration: 733.811629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:53.406192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:52.672265Z","time spent":"733.922832ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T17:26:53.406289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.84319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T17:26:53.406311Z","caller":"traceutil/trace.go:171","msg":"trace[547913811] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:969; }","duration":"733.87753ms","start":"2026-02-03T17:26:52.672428Z","end":"2026-02-03T17:26:53.406305Z","steps":["trace[547913811] 'agreement among raft nodes before linearized reading' (duration: 733.839709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:53.406759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:52.672421Z","time spent":"734.324674ms","remote":"127.0.0.1:38450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":29,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-02-03T17:26:53.407208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.30575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T17:26:53.407238Z","caller":"traceutil/trace.go:171","msg":"trace[1484231197] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:969; }","duration":"617.371002ms","start":"2026-02-03T17:26:52.789859Z","end":"2026-02-03T17:26:53.40723Z","steps":["trace[1484231197] 'agreement among raft nodes before linearized reading' (duration: 617.3227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:53.407259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:52.789844Z","time spent":"617.409183ms","remote":"127.0.0.1:38378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":6,"response size":29,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-02-03T17:26:53.73743Z","caller":"traceutil/trace.go:171","msg":"trace[1482869335] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1009; }","duration":"186.356708ms","start":"2026-02-03T17:26:53.551054Z","end":"2026-02-03T17:26:53.737411Z","steps":["trace[1482869335] 'read index received' (duration: 184.632734ms)","trace[1482869335] 'applied index is now lower than readState.Index' (duration: 1.723184ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:26:53.737555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.484003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:26:53.737591Z","caller":"traceutil/trace.go:171","msg":"trace[1034366448] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:973; }","duration":"186.533114ms","start":"2026-02-03T17:26:53.551049Z","end":"2026-02-03T17:26:53.737582Z","steps":["trace[1034366448] 'agreement among raft nodes before linearized reading' (duration: 186.438452ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:53.737841Z","caller":"traceutil/trace.go:171","msg":"trace[1436308659] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"315.181285ms","start":"2026-02-03T17:26:53.42265Z","end":"2026-02-03T17:26:53.737831Z","steps":["trace[1436308659] 'process raft request' (duration: 313.088549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:53.737921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:53.422636Z","time spent":"315.233798ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:26:53.738192Z","caller":"traceutil/trace.go:171","msg":"trace[1399945167] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"314.70752ms","start":"2026-02-03T17:26:53.4234Z","end":"2026-02-03T17:26:53.738107Z","steps":["trace[1399945167] 'process raft request' (duration: 313.907675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:53.738255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:53.423393Z","time spent":"314.827785ms","remote":"127.0.0.1:38554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:26:53.73983Z","caller":"traceutil/trace.go:171","msg":"trace[1926789191] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"313.863864ms","start":"2026-02-03T17:26:53.425958Z","end":"2026-02-03T17:26:53.739821Z","steps":["trace[1926789191] 'process raft request' (duration: 311.404737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:53.739883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:53.425948Z","time spent":"313.910355ms","remote":"127.0.0.1:38738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:26:55.295318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.514858ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-03T17:26:55.295388Z","caller":"traceutil/trace.go:171","msg":"trace[1251533979] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1012; }","duration":"743.954458ms","start":"2026-02-03T17:26:54.551421Z","end":"2026-02-03T17:26:55.295376Z","steps":["trace[1251533979] 'read index received' (duration: 400.322457ms)","trace[1251533979] 'applied index is now lower than readState.Index' (duration: 343.631081ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:26:55.295433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.00474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:26:55.295447Z","caller":"traceutil/trace.go:171","msg":"trace[1175632921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:973; }","duration":"744.052201ms","start":"2026-02-03T17:26:54.55139Z","end":"2026-02-03T17:26:55.295442Z","steps":["trace[1175632921] 'agreement among raft nodes before linearized reading' (duration: 744.01404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:55.295464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:54.551376Z","time spent":"744.084782ms","remote":"127.0.0.1:38300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-03T17:26:59.629563Z","caller":"traceutil/trace.go:171","msg":"trace[1084033890] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"141.987801ms","start":"2026-02-03T17:26:59.487553Z","end":"2026-02-03T17:26:59.629541Z","steps":["trace[1084033890] 'process raft request' (duration: 139.320807ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:59.632441Z","caller":"traceutil/trace.go:171","msg":"trace[1030357762] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"140.03706ms","start":"2026-02-03T17:26:59.492389Z","end":"2026-02-03T17:26:59.632426Z","steps":["trace[1030357762] 'process raft request' (duration: 136.092015ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:11.653746Z","caller":"traceutil/trace.go:171","msg":"trace[996090641] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"113.269277ms","start":"2026-02-03T17:27:11.540462Z","end":"2026-02-03T17:27:11.653732Z","steps":["trace[996090641] 'process raft request' (duration: 108.373043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:27.907592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.771299ms","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-03T17:27:27.908111Z","caller":"traceutil/trace.go:171","msg":"trace[2090247913] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1140; }","duration":"111.325377ms","start":"2026-02-03T17:27:27.796726Z","end":"2026-02-03T17:27:27.908052Z","steps":["trace[2090247913] 'range keys from in-memory index tree' (duration: 110.44987ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:00.199162Z","caller":"traceutil/trace.go:171","msg":"trace[600031308] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"108.855356ms","start":"2026-02-03T17:28:00.090265Z","end":"2026-02-03T17:28:00.19912Z","steps":["trace[600031308] 'process raft request' (duration: 67.428503ms)","trace[600031308] 'compare' (duration: 41.359681ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:28:30.61071Z","caller":"traceutil/trace.go:171","msg":"trace[580880895] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"144.719135ms","start":"2026-02-03T17:28:30.465973Z","end":"2026-02-03T17:28:30.610692Z","steps":["trace[580880895] 'process raft request' (duration: 141.255086ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:30.781158Z","caller":"traceutil/trace.go:171","msg":"trace[978024969] linearizableReadLoop","detail":"{readStateIndex:1456; appliedIndex:1454; }","duration":"143.29524ms","start":"2026-02-03T17:28:30.637833Z","end":"2026-02-03T17:28:30.781128Z","steps":["trace[978024969] 'read index received' (duration: 46.354288ms)","trace[978024969] 'applied index is now lower than readState.Index' (duration: 96.939682ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:28:30.781642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.762924ms","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-03T17:28:30.781754Z","caller":"traceutil/trace.go:171","msg":"trace[860695084] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1397; }","duration":"143.947111ms","start":"2026-02-03T17:28:30.637793Z","end":"2026-02-03T17:28:30.78174Z","steps":["trace[860695084] 'agreement among raft nodes before linearized reading' (duration: 143.58842ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:30.782293Z","caller":"traceutil/trace.go:171","msg":"trace[361828238] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"163.113694ms","start":"2026-02-03T17:28:30.619163Z","end":"2026-02-03T17:28:30.782277Z","steps":["trace[361828238] 'process raft request' (duration: 121.737151ms)","trace[361828238] 'compare' (duration: 40.093533ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:28:38.56013Z","caller":"traceutil/trace.go:171","msg":"trace[1544213016] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"136.086143ms","start":"2026-02-03T17:28:38.424024Z","end":"2026-02-03T17:28:38.56011Z","steps":["trace[1544213016] 'process raft request' (duration: 132.51402ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:40.908488Z","caller":"traceutil/trace.go:171","msg":"trace[572552447] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"105.605284ms","start":"2026-02-03T17:28:40.802859Z","end":"2026-02-03T17:28:40.908465Z","steps":["trace[572552447] 'process raft request' (duration: 105.256763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:42.956008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.368957ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:28:42.956349Z","caller":"traceutil/trace.go:171","msg":"trace[1031041973] transaction","detail":"{read_only:false; response_revision:1446; number_of_response:1; }","duration":"151.348894ms","start":"2026-02-03T17:28:42.804974Z","end":"2026-02-03T17:28:42.956323Z","steps":["trace[1031041973] 'process raft request' (duration: 24.590284ms)","trace[1031041973] 'compare' (duration: 126.191082ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:28:44.820229Z","caller":"traceutil/trace.go:171","msg":"trace[1817897352] transaction","detail":"{read_only:false; response_revision:1451; number_of_response:1; }","duration":"114.873305ms","start":"2026-02-03T17:28:44.705327Z","end":"2026-02-03T17:28:44.8202Z","steps":["trace[1817897352] 'process raft request' (duration: 58.848001ms)","trace[1817897352] 'compare' (duration: 55.667353ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:28:59.678747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.550801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-03T17:28:59.678873Z","caller":"traceutil/trace.go:171","msg":"trace[618941319] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1514; }","duration":"105.746707ms","start":"2026-02-03T17:28:59.573099Z","end":"2026-02-03T17:28:59.678846Z","steps":["trace[618941319] 'range keys from in-memory index tree' (duration: 105.338094ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:29:29.111176Z","caller":"traceutil/trace.go:171","msg":"trace[423579931] transaction","detail":"{read_only:false; response_revision:1632; number_of_response:1; }","duration":"118.155108ms","start":"2026-02-03T17:29:28.99298Z","end":"2026-02-03T17:29:29.111135Z","steps":["trace[423579931] 'process raft request' (duration: 53.834094ms)","trace[423579931] 'compare' (duration: 64.153178ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:29:29.280645Z","caller":"traceutil/trace.go:171","msg":"trace[577115604] linearizableReadLoop","detail":"{readStateIndex:1703; appliedIndex:1702; }","duration":"104.754316ms","start":"2026-02-03T17:29:29.175871Z","end":"2026-02-03T17:29:29.280625Z","steps":["trace[577115604] 'read index received' (duration: 74.902837ms)","trace[577115604] 'applied index is now lower than readState.Index' (duration: 29.850869ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:29:29.2808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.905871ms","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-03T17:29:29.28082Z","caller":"traceutil/trace.go:171","msg":"trace[874749986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1633; }","duration":"104.943602ms","start":"2026-02-03T17:29:29.175868Z","end":"2026-02-03T17:29:29.280812Z","steps":["trace[874749986] 'agreement among raft nodes before linearized reading' (duration: 104.818708ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:29:29.28098Z","caller":"traceutil/trace.go:171","msg":"trace[1079332585] transaction","detail":"{read_only:false; response_revision:1633; number_of_response:1; }","duration":"109.679311ms","start":"2026-02-03T17:29:29.171263Z","end":"2026-02-03T17:29:29.280942Z","steps":["trace[1079332585] 'process raft request' (duration: 79.568224ms)","trace[1079332585] 'compare' (duration: 29.58169ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:29:29.682136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.21143ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:29:29.683295Z","caller":"traceutil/trace.go:171","msg":"trace[1986941500] transaction","detail":"{read_only:false; response_revision:1635; number_of_response:1; }","duration":"319.680658ms","start":"2026-02-03T17:29:29.363591Z","end":"2026-02-03T17:29:29.683271Z","steps":["trace[1986941500] 'process raft request' (duration: 117.121225ms)","trace[1986941500] 'compare' (duration: 201.089446ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:29:29.683666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.802241ms","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-03T17:29:29.683706Z","caller":"traceutil/trace.go:171","msg":"trace[1039122414] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1635; }","duration":"298.895993ms","start":"2026-02-03T17:29:29.384802Z","end":"2026-02-03T17:29:29.683698Z","steps":["trace[1039122414] 'agreement among raft nodes before linearized reading' (duration: 298.666986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:29:29.684129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:29:29.36358Z","time spent":"320.101021ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:29:29.683414Z","caller":"traceutil/trace.go:171","msg":"trace[1302307143] linearizableReadLoop","detail":"{readStateIndex:1705; appliedIndex:1704; }","duration":"298.555423ms","start":"2026-02-03T17:29:29.384828Z","end":"2026-02-03T17:29:29.683383Z","steps":["trace[1302307143] 'read index received' (duration: 95.834505ms)","trace[1302307143] 'applied index is now lower than readState.Index' (duration: 202.716438ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:29:29.684853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.352626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-03T17:29:29.684878Z","caller":"traceutil/trace.go:171","msg":"trace[836365880] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:1635; }","duration":"209.420299ms","start":"2026-02-03T17:29:29.475452Z","end":"2026-02-03T17:29:29.684872Z","steps":["trace[836365880] 'agreement among raft nodes before linearized reading' (duration: 209.339057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:29:29.68499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.017819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:29:29.68501Z","caller":"traceutil/trace.go:171","msg":"trace[271315667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1635; }","duration":"129.07719ms","start":"2026-02-03T17:29:29.555929Z","end":"2026-02-03T17:29:29.685006Z","steps":["trace[271315667] 'agreement among raft nodes before linearized reading' (duration: 129.047789ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:29:29.88159Z","caller":"traceutil/trace.go:171","msg":"trace[1155380706] transaction","detail":"{read_only:false; response_revision:1637; number_of_response:1; }","duration":"130.882847ms","start":"2026-02-03T17:29:29.750676Z","end":"2026-02-03T17:29:29.881559Z","steps":["trace[1155380706] 'process raft request' (duration: 46.912656ms)","trace[1155380706] 'compare' (duration: 83.814737ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:29:31.804401Z","caller":"traceutil/trace.go:171","msg":"trace[2061969773] transaction","detail":"{read_only:false; response_revision:1644; number_of_response:1; }","duration":"105.407577ms","start":"2026-02-03T17:29:31.698977Z","end":"2026-02-03T17:29:31.804384Z","steps":["trace[2061969773] 'process raft request' (duration: 105.054896ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:29:32.007428Z","caller":"traceutil/trace.go:171","msg":"trace[436799774] transaction","detail":"{read_only:false; response_revision:1645; number_of_response:1; }","duration":"198.544267ms","start":"2026-02-03T17:29:31.808852Z","end":"2026-02-03T17:29:32.007396Z","steps":["trace[436799774] 'process raft request' (duration: 150.994211ms)","trace[436799774] 'compare' (duration: 46.950607ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:29:33.362873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.223706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-03T17:29:33.363567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.726775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363650"} {"level":"info","ts":"2026-02-03T17:29:33.363671Z","caller":"traceutil/trace.go:171","msg":"trace[307385398] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:1648; }","duration":"207.88268ms","start":"2026-02-03T17:29:33.155764Z","end":"2026-02-03T17:29:33.363646Z","steps":["trace[307385398] 'range keys from in-memory index tree' (duration: 207.097275ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:29:33.364866Z","caller":"traceutil/trace.go:171","msg":"trace[2046374703] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1648; }","duration":"143.390291ms","start":"2026-02-03T17:29:33.219554Z","end":"2026-02-03T17:29:33.362944Z","steps":["trace[2046374703] 'count revisions from in-memory index tree' (duration: 143.085852ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:29:35.55178Z","caller":"traceutil/trace.go:171","msg":"trace[1519992369] transaction","detail":"{read_only:false; response_revision:1656; number_of_response:1; }","duration":"132.594372ms","start":"2026-02-03T17:29:35.419161Z","end":"2026-02-03T17:29:35.551756Z","steps":["trace[1519992369] 'process raft request' (duration: 132.446407ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:29:35.697062Z","caller":"traceutil/trace.go:171","msg":"trace[1488593056] transaction","detail":"{read_only:false; response_revision:1657; number_of_response:1; }","duration":"139.088565ms","start":"2026-02-03T17:29:35.557931Z","end":"2026-02-03T17:29:35.69702Z","steps":["trace[1488593056] 'process raft request' (duration: 44.218481ms)","trace[1488593056] 'compare' (duration: 94.706519ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:29:45.20577Z","caller":"traceutil/trace.go:171","msg":"trace[506030122] transaction","detail":"{read_only:false; response_revision:1696; number_of_response:1; }","duration":"105.659674ms","start":"2026-02-03T17:29:45.10007Z","end":"2026-02-03T17:29:45.20573Z","steps":["trace[506030122] 'process raft request' (duration: 105.450967ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:29:50.46875Z","caller":"traceutil/trace.go:171","msg":"trace[1089892268] transaction","detail":"{read_only:false; response_revision:1720; number_of_response:1; }","duration":"170.749942ms","start":"2026-02-03T17:29:50.297972Z","end":"2026-02-03T17:29:50.468722Z","steps":["trace[1089892268] 'process raft request' (duration: 170.282687ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:29:58.975031Z","caller":"traceutil/trace.go:171","msg":"trace[88262387] transaction","detail":"{read_only:false; response_revision:1754; number_of_response:1; }","duration":"107.732222ms","start":"2026-02-03T17:29:58.867276Z","end":"2026-02-03T17:29:58.975008Z","steps":["trace[88262387] 'process raft request' (duration: 107.560666ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:30:20.130265Z","caller":"traceutil/trace.go:171","msg":"trace[210577354] linearizableReadLoop","detail":"{readStateIndex:1942; appliedIndex:1941; }","duration":"103.54566ms","start":"2026-02-03T17:30:20.026691Z","end":"2026-02-03T17:30:20.130236Z","steps":["trace[210577354] 'read index received' (duration: 103.403865ms)","trace[210577354] 'applied index is now lower than readState.Index' (duration: 140.755µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:30:20.130879Z","caller":"traceutil/trace.go:171","msg":"trace[911950029] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"117.472599ms","start":"2026-02-03T17:30:20.013385Z","end":"2026-02-03T17:30:20.130858Z","steps":["trace[911950029] 'process raft request' (duration: 116.478898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:30:20.134961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.234498ms","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-03T17:30:20.135035Z","caller":"traceutil/trace.go:171","msg":"trace[1330178659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1860; }","duration":"108.364862ms","start":"2026-02-03T17:30:20.026652Z","end":"2026-02-03T17:30:20.135016Z","steps":["trace[1330178659] 'agreement among raft nodes before linearized reading' (duration: 103.735916ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:30:20.640651Z","caller":"traceutil/trace.go:171","msg":"trace[660033990] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"231.787458ms","start":"2026-02-03T17:30:20.408828Z","end":"2026-02-03T17:30:20.640616Z","steps":["trace[660033990] 'process raft request' (duration: 162.050092ms)","trace[660033990] 'compare' (duration: 69.325673ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:30:20.885484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.775743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:6365"} {"level":"info","ts":"2026-02-03T17:30:20.885616Z","caller":"traceutil/trace.go:171","msg":"trace[2097708228] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1864; }","duration":"140.960509ms","start":"2026-02-03T17:30:20.744625Z","end":"2026-02-03T17:30:20.885586Z","steps":["trace[2097708228] 'range keys from in-memory index tree' (duration: 140.638198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:30:21.216113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.230786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:30:21.216243Z","caller":"traceutil/trace.go:171","msg":"trace[289304998] linearizableReadLoop","detail":"{readStateIndex:1948; appliedIndex:1947; }","duration":"272.727917ms","start":"2026-02-03T17:30:20.943495Z","end":"2026-02-03T17:30:21.216223Z","steps":["trace[289304998] 'read index received' (duration: 78.174332ms)","trace[289304998] 'applied index is now lower than readState.Index' (duration: 194.549095ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:30:21.217113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.603915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/csi-a6a782beab2931dbbfa814ab777974532ffc29ee003557550d0a84bcee8fd994\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-02-03T17:30:21.21716Z","caller":"traceutil/trace.go:171","msg":"trace[1532842582] range","detail":"{range_begin:/registry/volumeattachments/csi-a6a782beab2931dbbfa814ab777974532ffc29ee003557550d0a84bcee8fd994; range_end:; response_count:1; response_revision:1866; }","duration":"273.683678ms","start":"2026-02-03T17:30:20.943462Z","end":"2026-02-03T17:30:21.217146Z","steps":["trace[1532842582] 'agreement among raft nodes before linearized reading' (duration: 273.295435ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:30:21.218873Z","caller":"traceutil/trace.go:171","msg":"trace[593439500] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"282.160264ms","start":"2026-02-03T17:30:20.936694Z","end":"2026-02-03T17:30:21.218854Z","steps":["trace[593439500] 'process raft request' (duration: 85.065508ms)","trace[593439500] 'compare' (duration: 194.111662ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:30:21.56096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.726687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-03T17:30:21.561045Z","caller":"traceutil/trace.go:171","msg":"trace[459224522] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1867; }","duration":"198.870902ms","start":"2026-02-03T17:30:21.362154Z","end":"2026-02-03T17:30:21.561025Z","steps":["trace[459224522] 'range keys from in-memory index tree' (duration: 198.527881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:30:30.722997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.340843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:30:30.723186Z","caller":"traceutil/trace.go:171","msg":"trace[1744699036] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1913; }","duration":"104.045025ms","start":"2026-02-03T17:30:30.619118Z","end":"2026-02-03T17:30:30.723163Z","steps":["trace[1744699036] 'count revisions from in-memory index tree' (duration: 103.210159ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:31:06.921531Z","caller":"traceutil/trace.go:171","msg":"trace[1618282778] linearizableReadLoop","detail":"{readStateIndex:2160; appliedIndex:2159; }","duration":"146.036797ms","start":"2026-02-03T17:31:06.775454Z","end":"2026-02-03T17:31:06.921491Z","steps":["trace[1618282778] 'read index received' (duration: 140.332606ms)","trace[1618282778] 'applied index is now lower than readState.Index' (duration: 5.703281ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:31:06.92275Z","caller":"traceutil/trace.go:171","msg":"trace[229597367] transaction","detail":"{read_only:false; response_revision:2069; number_of_response:1; }","duration":"164.520031ms","start":"2026-02-03T17:31:06.758217Z","end":"2026-02-03T17:31:06.922737Z","steps":["trace[229597367] 'process raft request' (duration: 157.581662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:31:06.922988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.791844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-03T17:31:06.923053Z","caller":"traceutil/trace.go:171","msg":"trace[224378676] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:2069; }","duration":"117.874467ms","start":"2026-02-03T17:31:06.805163Z","end":"2026-02-03T17:31:06.923038Z","steps":["trace[224378676] 'agreement among raft nodes before linearized reading' (duration: 117.741182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:31:06.923287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.829073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-03T17:31:06.923325Z","caller":"traceutil/trace.go:171","msg":"trace[178307905] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:2069; }","duration":"147.921626ms","start":"2026-02-03T17:31:06.775394Z","end":"2026-02-03T17:31:06.923315Z","steps":["trace[178307905] 'agreement among raft nodes before linearized reading' (duration: 147.856394ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:31:07.626939Z","caller":"traceutil/trace.go:171","msg":"trace[945877095] transaction","detail":"{read_only:false; response_revision:2071; number_of_response:1; }","duration":"115.334626ms","start":"2026-02-03T17:31:07.511581Z","end":"2026-02-03T17:31:07.626916Z","steps":["trace[945877095] 'process raft request' (duration: 115.189451ms)"],"step_count":1}