{"level":"warn","ts":"2026-02-03T22:38:25.65066Z","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-03T22:38:25.650912Z","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-03T22:38:25.651045Z","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-03T22:38:25.651115Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-02-03T22:38:25.651179Z","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-03T22:38:25.652332Z","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-03T22:38:25.652558Z","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-03T22:38:25.657221Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.321774ms"} {"level":"info","ts":"2026-02-03T22:38:25.683366Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-02-03T22:38:25.68354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-02-03T22:38:25.683611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-02-03T22:38:25.683663Z","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-03T22:38:25.683725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-02-03T22:38:25.683804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-02-03T22:38:25.690802Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-03T22:38:25.69539Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-03T22:38:25.698349Z","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-03T22:38:25.7029Z","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-03T22:38:25.712364Z","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-03T22:38:25.713158Z","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-03T22:38:25.713233Z","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-03T22:38:25.713038Z","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-03T22:38:25.714726Z","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-03T22:38:25.714932Z","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-03T22:38:25.715007Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-03T22:38:25.715084Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-02-03T22:38:25.717025Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-02-03T22:38:25.715289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-02-03T22:38:25.71721Z","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-03T22:38:25.984622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-02-03T22:38:25.984709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-03T22:38:25.984833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-02-03T22:38:25.984887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-02-03T22:38:25.98493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-02-03T22:38:25.984992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-02-03T22:38:25.985034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-02-03T22:38:25.986308Z","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-03T22:38:25.986353Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T22:38:25.98638Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T22:38:25.986445Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T22:38:25.987452Z","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-03T22:38:25.987525Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T22:38:25.987592Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T22:38:25.986538Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-03T22:38:25.987641Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-03T22:38:25.990397Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-03T22:38:26.003827Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-02-03T22:38:40.718831Z","caller":"traceutil/trace.go:171","msg":"trace[33069311] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"291.83629ms","start":"2026-02-03T22:38:40.426974Z","end":"2026-02-03T22:38:40.718811Z","steps":["trace[33069311] 'process raft request' (duration: 288.782452ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:38:46.169485Z","caller":"traceutil/trace.go:171","msg":"trace[1118034568] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"295.417852ms","start":"2026-02-03T22:38:45.874048Z","end":"2026-02-03T22:38:46.169466Z","steps":["trace[1118034568] 'process raft request' (duration: 295.304619ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:38:46.169858Z","caller":"traceutil/trace.go:171","msg":"trace[1143426596] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:430; }","duration":"202.174892ms","start":"2026-02-03T22:38:45.967676Z","end":"2026-02-03T22:38:46.169851Z","steps":["trace[1143426596] 'read index received' (duration: 202.172192ms)","trace[1143426596] 'applied index is now lower than readState.Index' (duration: 2.14µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:38:46.169909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.219053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:38:46.169935Z","caller":"traceutil/trace.go:171","msg":"trace[340913441] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:418; }","duration":"202.282495ms","start":"2026-02-03T22:38:45.967646Z","end":"2026-02-03T22:38:46.169929Z","steps":["trace[340913441] 'agreement among raft nodes before linearized reading' (duration: 202.230714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:58.646228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.888768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-03T22:38:58.646309Z","caller":"traceutil/trace.go:171","msg":"trace[948995435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:480; }","duration":"230.008142ms","start":"2026-02-03T22:38:58.416283Z","end":"2026-02-03T22:38:58.646292Z","steps":["trace[948995435] 'range keys from in-memory index tree' (duration: 229.775915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:58.646646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.28538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T22:38:58.646679Z","caller":"traceutil/trace.go:171","msg":"trace[695749968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:480; }","duration":"230.322121ms","start":"2026-02-03T22:38:58.416346Z","end":"2026-02-03T22:38:58.646668Z","steps":["trace[695749968] 'range keys from in-memory index tree' (duration: 230.255078ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:38:58.951547Z","caller":"traceutil/trace.go:171","msg":"trace[745976117] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"296.962867ms","start":"2026-02-03T22:38:58.654547Z","end":"2026-02-03T22:38:58.95151Z","steps":["trace[745976117] 'process raft request' (duration: 296.687919ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:38:58.977965Z","caller":"traceutil/trace.go:171","msg":"trace[700771654] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"322.106509ms","start":"2026-02-03T22:38:58.655841Z","end":"2026-02-03T22:38:58.977948Z","steps":["trace[700771654] 'process raft request' (duration: 321.561733ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:38:58.978607Z","caller":"traceutil/trace.go:171","msg":"trace[953580660] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"322.047428ms","start":"2026-02-03T22:38:58.656517Z","end":"2026-02-03T22:38:58.978565Z","steps":["trace[953580660] 'process raft request' (duration: 321.022248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:58.980145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:58.655824Z","time spent":"322.200962ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T22:38:58.980367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:58.65649Z","time spent":"322.198222ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T22:39:02.636476Z","caller":"traceutil/trace.go:171","msg":"trace[899784037] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:504; }","duration":"335.518756ms","start":"2026-02-03T22:39:02.300939Z","end":"2026-02-03T22:39:02.636457Z","steps":["trace[899784037] 'read index received' (duration: 335.302149ms)","trace[899784037] 'applied index is now lower than readState.Index' (duration: 215.957µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:39:02.636635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.68142ms","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-03T22:39:02.636656Z","caller":"traceutil/trace.go:171","msg":"trace[1685766394] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:490; }","duration":"335.745682ms","start":"2026-02-03T22:39:02.300904Z","end":"2026-02-03T22:39:02.636649Z","steps":["trace[1685766394] 'agreement among raft nodes before linearized reading' (duration: 335.623349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:39:02.63669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:39:02.300889Z","time spent":"335.796263ms","remote":"127.0.0.1:42354","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-03T22:39:02.63678Z","caller":"traceutil/trace.go:171","msg":"trace[643630776] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"633.286365ms","start":"2026-02-03T22:39:02.003476Z","end":"2026-02-03T22:39:02.636762Z","steps":["trace[643630776] 'process raft request' (duration: 632.816352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:39:02.636893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:39:02.003464Z","time spent":"633.372367ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T22:39:03.331629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.718044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-03T22:39:03.332028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.674861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T22:39:03.332062Z","caller":"traceutil/trace.go:171","msg":"trace[350814096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:491; }","duration":"334.736153ms","start":"2026-02-03T22:39:02.997313Z","end":"2026-02-03T22:39:03.332049Z","steps":["trace[350814096] 'range keys from in-memory index tree' (duration: 334.602969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:39:03.332092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:39:02.997298Z","time spent":"334.788955ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T22:39:03.332264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.511127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-03T22:39:03.332288Z","caller":"traceutil/trace.go:171","msg":"trace[1271116294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:491; }","duration":"334.545187ms","start":"2026-02-03T22:39:02.997735Z","end":"2026-02-03T22:39:03.332281Z","steps":["trace[1271116294] 'range keys from in-memory index tree' (duration: 334.473425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:39:03.332306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:39:02.997729Z","time spent":"334.572698ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-03T22:39:03.333557Z","caller":"traceutil/trace.go:171","msg":"trace[1461968101] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:491; }","duration":"357.832777ms","start":"2026-02-03T22:39:02.973846Z","end":"2026-02-03T22:39:03.331679Z","steps":["trace[1461968101] 'range keys from in-memory index tree' (duration: 357.6202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:39:03.333956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:39:02.973829Z","time spent":"360.117042ms","remote":"127.0.0.1:42094","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-03T22:39:06.00838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.563959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:39:06.008455Z","caller":"traceutil/trace.go:171","msg":"trace[596376208] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:512; }","duration":"349.661561ms","start":"2026-02-03T22:39:05.658777Z","end":"2026-02-03T22:39:06.008438Z","steps":["trace[596376208] 'range keys from in-memory index tree' (duration: 349.527118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:39:06.008791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:39:05.658768Z","time spent":"350.009101ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-03T22:39:42.756439Z","caller":"traceutil/trace.go:171","msg":"trace[1640783031] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"272.567146ms","start":"2026-02-03T22:39:42.483847Z","end":"2026-02-03T22:39:42.756414Z","steps":["trace[1640783031] 'process raft request' (duration: 272.363161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:39:43.229744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.687022ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T22:39:43.229857Z","caller":"traceutil/trace.go:171","msg":"trace[1499796208] linearizableReadLoop","detail":"{readStateIndex:768; appliedIndex:767; }","duration":"261.790575ms","start":"2026-02-03T22:39:42.968025Z","end":"2026-02-03T22:39:43.229815Z","steps":["trace[1499796208] 'read index received' (duration: 130.744393ms)","trace[1499796208] 'applied index is now lower than readState.Index' (duration: 131.044952ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:39:43.229922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.899448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:39:43.229946Z","caller":"traceutil/trace.go:171","msg":"trace[454480349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:742; }","duration":"261.95334ms","start":"2026-02-03T22:39:42.967985Z","end":"2026-02-03T22:39:43.229938Z","steps":["trace[454480349] 'agreement among raft nodes before linearized reading' (duration: 261.896688ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:39:43.230121Z","caller":"traceutil/trace.go:171","msg":"trace[1504830067] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"420.580095ms","start":"2026-02-03T22:39:42.809519Z","end":"2026-02-03T22:39:43.2301Z","steps":["trace[1504830067] 'process raft request' (duration: 289.289647ms)","trace[1504830067] 'compare' (duration: 130.544688ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:39:43.230197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:39:42.809511Z","time spent":"420.646747ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T22:39:46.923167Z","caller":"traceutil/trace.go:171","msg":"trace[1295105098] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"151.847122ms","start":"2026-02-03T22:39:46.7713Z","end":"2026-02-03T22:39:46.923147Z","steps":["trace[1295105098] 'process raft request' (duration: 151.744049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:39:47.444209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.162626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:39:47.444301Z","caller":"traceutil/trace.go:171","msg":"trace[526877342] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:752; }","duration":"475.291799ms","start":"2026-02-03T22:39:46.968991Z","end":"2026-02-03T22:39:47.444283Z","steps":["trace[526877342] 'range keys from in-memory index tree' (duration: 475.095243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:39:47.444342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:39:46.968975Z","time spent":"475.357601ms","remote":"127.0.0.1:42094","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-03T22:39:47.444801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.397714ms","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-03T22:39:47.444834Z","caller":"traceutil/trace.go:171","msg":"trace[1946054533] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:752; }","duration":"202.457026ms","start":"2026-02-03T22:39:47.242367Z","end":"2026-02-03T22:39:47.444824Z","steps":["trace[1946054533] 'range keys from in-memory index tree' (duration: 202.22832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:39:48.115402Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338487124334975,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-03T22:39:48.209615Z","caller":"traceutil/trace.go:171","msg":"trace[752248328] linearizableReadLoop","detail":"{readStateIndex:781; appliedIndex:780; }","duration":"594.750558ms","start":"2026-02-03T22:39:47.614829Z","end":"2026-02-03T22:39:48.209579Z","steps":["trace[752248328] 'read index received' (duration: 570.953787ms)","trace[752248328] 'applied index is now lower than readState.Index' (duration: 23.794871ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T22:39:48.209959Z","caller":"traceutil/trace.go:171","msg":"trace[1121374945] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"671.459746ms","start":"2026-02-03T22:39:47.53848Z","end":"2026-02-03T22:39:48.20994Z","steps":["trace[1121374945] 'process raft request' (duration: 647.392447ms)","trace[1121374945] 'compare' (duration: 23.265977ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:39:48.210091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:39:47.538465Z","time spent":"671.526397ms","remote":"127.0.0.1:42354","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-03T22:39:48.210506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.679503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-03T22:39:48.210545Z","caller":"traceutil/trace.go:171","msg":"trace[668364987] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:754; }","duration":"595.754625ms","start":"2026-02-03T22:39:47.614778Z","end":"2026-02-03T22:39:48.210533Z","steps":["trace[668364987] 'agreement among raft nodes before linearized reading' (duration: 595.603501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:39:48.210577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:39:47.61476Z","time spent":"595.810847ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T22:39:48.210794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.831209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T22:39:48.210832Z","caller":"traceutil/trace.go:171","msg":"trace[93968306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:754; }","duration":"591.938042ms","start":"2026-02-03T22:39:47.618884Z","end":"2026-02-03T22:39:48.210822Z","steps":["trace[93968306] 'agreement among raft nodes before linearized reading' (duration: 591.84775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:39:48.21086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:39:47.618849Z","time spent":"592.005354ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T22:39:48.211051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.732194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:39:48.211079Z","caller":"traceutil/trace.go:171","msg":"trace[8161081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:754; }","duration":"238.908219ms","start":"2026-02-03T22:39:47.972161Z","end":"2026-02-03T22:39:48.211069Z","steps":["trace[8161081] 'agreement among raft nodes before linearized reading' (duration: 238.853128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:39:49.140766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.028936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T22:39:49.140922Z","caller":"traceutil/trace.go:171","msg":"trace[159387793] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"915.189466ms","start":"2026-02-03T22:39:48.225706Z","end":"2026-02-03T22:39:49.140895Z","steps":["trace[159387793] 'process raft request' (duration: 322.900163ms)","trace[159387793] 'compare' (duration: 589.716243ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:39:49.141034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:39:48.225692Z","time spent":"915.296958ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T22:39:49.396978Z","caller":"traceutil/trace.go:171","msg":"trace[343960235] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:781; }","duration":"1.167756622s","start":"2026-02-03T22:39:48.229197Z","end":"2026-02-03T22:39:49.396954Z","steps":["trace[343960235] 'read index received' (duration: 319.503641ms)","trace[343960235] 'applied index is now lower than readState.Index' (duration: 848.252071ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T22:39:49.398254Z","caller":"traceutil/trace.go:171","msg":"trace[1264803553] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"1.172385977s","start":"2026-02-03T22:39:48.225857Z","end":"2026-02-03T22:39:49.398243Z","steps":["trace[1264803553] 'process raft request' (duration: 1.171005639s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:39:49.398348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.169117198s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:39:49.398424Z","caller":"traceutil/trace.go:171","msg":"trace[200112891] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:756; }","duration":"1.169213171s","start":"2026-02-03T22:39:48.229194Z","end":"2026-02-03T22:39:49.398407Z","steps":["trace[200112891] 'agreement among raft nodes before linearized reading' (duration: 1.169053437s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:39:49.398488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:39:48.22916Z","time spent":"1.169318054s","remote":"127.0.0.1:42176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-02-03T22:39:49.398603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.752686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-03T22:39:49.398378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:39:48.225851Z","time spent":"1.17247235s","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T22:39:49.399135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.556537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T22:39:49.399177Z","caller":"traceutil/trace.go:171","msg":"trace[476034759] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:756; }","duration":"429.623588ms","start":"2026-02-03T22:39:48.969541Z","end":"2026-02-03T22:39:49.399165Z","steps":["trace[476034759] 'agreement among raft nodes before linearized reading' (duration: 429.499035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:39:49.399209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:39:48.969519Z","time spent":"429.68065ms","remote":"127.0.0.1:42536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-03T22:39:49.39938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.430383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:39:49.399406Z","caller":"traceutil/trace.go:171","msg":"trace[167441395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:756; }","duration":"429.468765ms","start":"2026-02-03T22:39:48.96993Z","end":"2026-02-03T22:39:49.399398Z","steps":["trace[167441395] 'agreement among raft nodes before linearized reading' (duration: 429.426594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:39:49.399429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:39:48.969924Z","time spent":"429.500815ms","remote":"127.0.0.1:42114","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-03T22:39:49.400483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.920578ms","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-03T22:39:49.400537Z","caller":"traceutil/trace.go:171","msg":"trace[1723605995] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:756; }","duration":"472.011811ms","start":"2026-02-03T22:39:48.928515Z","end":"2026-02-03T22:39:49.400526Z","steps":["trace[1723605995] 'agreement among raft nodes before linearized reading' (duration: 471.838056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:39:49.400575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:39:48.928494Z","time spent":"472.071722ms","remote":"127.0.0.1:42354","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-03T22:39:49.400928Z","caller":"traceutil/trace.go:171","msg":"trace[1889848682] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:756; }","duration":"960.018394ms","start":"2026-02-03T22:39:48.438613Z","end":"2026-02-03T22:39:49.398631Z","steps":["trace[1889848682] 'agreement among raft nodes before linearized reading' (duration: 959.790548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:39:49.400998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:39:48.438592Z","time spent":"962.394837ms","remote":"127.0.0.1:42322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-03T22:39:49.401214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.746275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T22:39:49.401308Z","caller":"traceutil/trace.go:171","msg":"trace[229727260] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:756; }","duration":"792.890249ms","start":"2026-02-03T22:39:48.608408Z","end":"2026-02-03T22:39:49.401298Z","steps":["trace[229727260] 'agreement among raft nodes before linearized reading' (duration: 792.775706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:39:49.401558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:39:48.608371Z","time spent":"793.065134ms","remote":"127.0.0.1:42140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-02-03T22:40:01.972668Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338487124335177,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-03T22:40:02.129331Z","caller":"traceutil/trace.go:171","msg":"trace[1156670277] linearizableReadLoop","detail":"{readStateIndex:874; appliedIndex:873; }","duration":"657.14445ms","start":"2026-02-03T22:40:01.472171Z","end":"2026-02-03T22:40:02.129315Z","steps":["trace[1156670277] 'read index received' (duration: 657.021867ms)","trace[1156670277] 'applied index is now lower than readState.Index' (duration: 121.713µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:40:02.129582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.391997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-03T22:40:02.129653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.687689ms","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-03T22:40:02.129676Z","caller":"traceutil/trace.go:171","msg":"trace[965331723] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:842; }","duration":"577.742791ms","start":"2026-02-03T22:40:01.551927Z","end":"2026-02-03T22:40:02.12967Z","steps":["trace[965331723] 'agreement among raft nodes before linearized reading' (duration: 577.69956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:02.129697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:01.551913Z","time spent":"577.779572ms","remote":"127.0.0.1:42354","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-03T22:40:02.12974Z","caller":"traceutil/trace.go:171","msg":"trace[708413712] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"930.792026ms","start":"2026-02-03T22:40:01.198939Z","end":"2026-02-03T22:40:02.129731Z","steps":["trace[708413712] 'process raft request' (duration: 930.298072ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:40:02.12965Z","caller":"traceutil/trace.go:171","msg":"trace[91717059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:842; }","duration":"657.49296ms","start":"2026-02-03T22:40:01.472143Z","end":"2026-02-03T22:40:02.129636Z","steps":["trace[91717059] 'agreement among raft nodes before linearized reading' (duration: 657.305775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:02.129795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:01.472125Z","time spent":"657.665564ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T22:40:02.129609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.653719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-03T22:40:02.129858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:01.198928Z","time spent":"930.844837ms","remote":"127.0.0.1:42354","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-03T22:40:02.129881Z","caller":"traceutil/trace.go:171","msg":"trace[988632380] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:842; }","duration":"580.956907ms","start":"2026-02-03T22:40:01.548917Z","end":"2026-02-03T22:40:02.129874Z","steps":["trace[988632380] 'agreement among raft nodes before linearized reading' (duration: 580.615108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:02.129999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:01.548904Z","time spent":"581.088121ms","remote":"127.0.0.1:42354","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-03T22:40:02.64715Z","caller":"traceutil/trace.go:171","msg":"trace[949159552] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"511.393181ms","start":"2026-02-03T22:40:02.135477Z","end":"2026-02-03T22:40:02.64687Z","steps":["trace[949159552] 'process raft request' (duration: 511.144325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:02.647625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:02.135456Z","time spent":"512.08049ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T22:40:02.684052Z","caller":"traceutil/trace.go:171","msg":"trace[1335176648] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"548.174693ms","start":"2026-02-03T22:40:02.135864Z","end":"2026-02-03T22:40:02.684039Z","steps":["trace[1335176648] 'process raft request' (duration: 548.022679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:02.684151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:02.135843Z","time spent":"548.250314ms","remote":"127.0.0.1:42354","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-03T22:40:02.684444Z","caller":"traceutil/trace.go:171","msg":"trace[272225748] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"546.852696ms","start":"2026-02-03T22:40:02.137586Z","end":"2026-02-03T22:40:02.684439Z","steps":["trace[272225748] 'process raft request' (duration: 546.401434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:02.684477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:02.13758Z","time spent":"546.881788ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T22:40:02.685438Z","caller":"traceutil/trace.go:171","msg":"trace[1105467103] linearizableReadLoop","detail":"{readStateIndex:877; appliedIndex:874; }","duration":"421.380827ms","start":"2026-02-03T22:40:02.262634Z","end":"2026-02-03T22:40:02.684015Z","steps":["trace[1105467103] 'read index received' (duration: 384.027651ms)","trace[1105467103] 'applied index is now lower than readState.Index' (duration: 37.352786ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:40:02.685525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.887768ms","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-03T22:40:02.68554Z","caller":"traceutil/trace.go:171","msg":"trace[903911284] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:845; }","duration":"422.927548ms","start":"2026-02-03T22:40:02.262608Z","end":"2026-02-03T22:40:02.685535Z","steps":["trace[903911284] 'agreement among raft nodes before linearized reading' (duration: 422.846336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:02.685989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:02.262594Z","time spent":"422.95626ms","remote":"127.0.0.1:42354","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-03T22:40:10.992958Z","caller":"traceutil/trace.go:171","msg":"trace[973755687] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"431.663324ms","start":"2026-02-03T22:40:10.561273Z","end":"2026-02-03T22:40:10.992936Z","steps":["trace[973755687] 'process raft request' (duration: 431.540861ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:40:10.993192Z","caller":"traceutil/trace.go:171","msg":"trace[1724909615] linearizableReadLoop","detail":"{readStateIndex:899; appliedIndex:899; }","duration":"275.33708ms","start":"2026-02-03T22:40:10.717843Z","end":"2026-02-03T22:40:10.99318Z","steps":["trace[1724909615] 'read index received' (duration: 275.33236ms)","trace[1724909615] 'applied index is now lower than readState.Index' (duration: 3.69µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:40:10.993413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.50252ms","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-03T22:40:10.993444Z","caller":"traceutil/trace.go:171","msg":"trace[669960287] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:865; }","duration":"247.567732ms","start":"2026-02-03T22:40:10.745866Z","end":"2026-02-03T22:40:10.993434Z","steps":["trace[669960287] 'agreement among raft nodes before linearized reading' (duration: 247.395638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:10.993684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.837204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T22:40:10.993712Z","caller":"traceutil/trace.go:171","msg":"trace[1413224085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:865; }","duration":"275.886756ms","start":"2026-02-03T22:40:10.717817Z","end":"2026-02-03T22:40:10.993704Z","steps":["trace[1413224085] 'agreement among raft nodes before linearized reading' (duration: 275.810363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:10.993872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.944797ms","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-03T22:40:10.993898Z","caller":"traceutil/trace.go:171","msg":"trace[1674246797] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:865; }","duration":"226.988588ms","start":"2026-02-03T22:40:10.766902Z","end":"2026-02-03T22:40:10.99389Z","steps":["trace[1674246797] 'agreement among raft nodes before linearized reading' (duration: 226.918786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:10.994248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:10.561258Z","time spent":"432.929628ms","remote":"127.0.0.1:42354","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-03T22:40:10.994682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.808413ms","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-03T22:40:10.994713Z","caller":"traceutil/trace.go:171","msg":"trace[696520385] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:865; }","duration":"239.863454ms","start":"2026-02-03T22:40:10.75484Z","end":"2026-02-03T22:40:10.994703Z","steps":["trace[696520385] 'agreement among raft nodes before linearized reading' (duration: 239.783422ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:40:14.380898Z","caller":"traceutil/trace.go:171","msg":"trace[579738473] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"215.002084ms","start":"2026-02-03T22:40:14.16588Z","end":"2026-02-03T22:40:14.380882Z","steps":["trace[579738473] 'process raft request' (duration: 214.895191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:15.279153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.162909ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T22:40:15.279219Z","caller":"traceutil/trace.go:171","msg":"trace[1060825793] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"1.022059806s","start":"2026-02-03T22:40:14.257146Z","end":"2026-02-03T22:40:15.279206Z","steps":["trace[1060825793] 'process raft request' (duration: 278.795435ms)","trace[1060825793] 'compare' (duration: 742.559522ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:40:15.279256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:14.257128Z","time spent":"1.022109597s","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T22:40:15.494473Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338487124335265,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T22:40:16.969836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.975373207s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-03T22:40:16.969891Z","caller":"traceutil/trace.go:171","msg":"trace[1017345490] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.975468969s","start":"2026-02-03T22:40:14.99441Z","end":"2026-02-03T22:40:16.969879Z","steps":["trace[1017345490] 'agreement among raft nodes before linearized reading' (duration: 1.975393937s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:16.969924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:14.994397Z","time spent":"1.975522241s","remote":"127.0.0.1:42094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/03 22:40:16 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-03T22:40:17.603653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.107810427s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-03T22:40:17.603779Z","caller":"traceutil/trace.go:171","msg":"trace[602334052] linearizableReadLoop","detail":"{readStateIndex:911; appliedIndex:910; }","duration":"2.609318382s","start":"2026-02-03T22:40:14.994444Z","end":"2026-02-03T22:40:17.603762Z","steps":["trace[602334052] 'read index received' (duration: 501.32703ms)","trace[602334052] 'applied index is now lower than readState.Index' (duration: 2.107989952s)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:40:17.604262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.573248371s","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-03T22:40:17.604306Z","caller":"traceutil/trace.go:171","msg":"trace[1514167759] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:876; }","duration":"2.573333874s","start":"2026-02-03T22:40:15.030961Z","end":"2026-02-03T22:40:17.604295Z","steps":["trace[1514167759] 'agreement among raft nodes before linearized reading' (duration: 2.573103557s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:17.604338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:15.030943Z","time spent":"2.573387144s","remote":"127.0.0.1:42354","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-03T22:40:17.604403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.221167131s","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-03T22:40:17.604455Z","caller":"traceutil/trace.go:171","msg":"trace[58232422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:876; }","duration":"1.221261024s","start":"2026-02-03T22:40:16.383181Z","end":"2026-02-03T22:40:17.604442Z","steps":["trace[58232422] 'agreement among raft nodes before linearized reading' (duration: 1.221094189s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:17.604481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:16.383164Z","time spent":"1.221312094s","remote":"127.0.0.1:42354","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-03T22:40:17.604541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.342724728s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T22:40:17.604572Z","caller":"traceutil/trace.go:171","msg":"trace[676522166] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:876; }","duration":"2.34277936s","start":"2026-02-03T22:40:15.261783Z","end":"2026-02-03T22:40:17.604562Z","steps":["trace[676522166] 'agreement among raft nodes before linearized reading' (duration: 2.342721178s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:17.604601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:15.261772Z","time spent":"2.342819981s","remote":"127.0.0.1:42556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-03T22:40:17.60465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.490925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:40:17.604672Z","caller":"traceutil/trace.go:171","msg":"trace[931410909] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:876; }","duration":"616.550077ms","start":"2026-02-03T22:40:16.988116Z","end":"2026-02-03T22:40:17.604666Z","steps":["trace[931410909] 'agreement among raft nodes before linearized reading' (duration: 616.512696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:17.604688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:16.988095Z","time spent":"616.590528ms","remote":"127.0.0.1:42114","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-03T22:40:17.604774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.559582672s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-03T22:40:17.604802Z","caller":"traceutil/trace.go:171","msg":"trace[1427984509] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:876; }","duration":"2.559628573s","start":"2026-02-03T22:40:15.045166Z","end":"2026-02-03T22:40:17.604794Z","steps":["trace[1427984509] 'agreement among raft nodes before linearized reading' (duration: 2.559552111s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:17.604828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:15.045157Z","time spent":"2.559665065s","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-03T22:40:17.604924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.564383411s","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-03T22:40:17.604945Z","caller":"traceutil/trace.go:171","msg":"trace[1784720825] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:876; }","duration":"2.564441693s","start":"2026-02-03T22:40:15.040498Z","end":"2026-02-03T22:40:17.604939Z","steps":["trace[1784720825] 'agreement among raft nodes before linearized reading' (duration: 2.564383041s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:17.604962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:15.040476Z","time spent":"2.564482024s","remote":"127.0.0.1:42354","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-03T22:40:17.605083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.562959553s","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-03T22:40:17.605101Z","caller":"traceutil/trace.go:171","msg":"trace[1066556028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:876; }","duration":"2.563021125s","start":"2026-02-03T22:40:15.042076Z","end":"2026-02-03T22:40:17.605097Z","steps":["trace[1066556028] 'agreement among raft nodes before linearized reading' (duration: 2.562970554s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:17.605117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:15.042064Z","time spent":"2.563049146s","remote":"127.0.0.1:42354","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-03T22:40:17.732357Z","caller":"traceutil/trace.go:171","msg":"trace[708043849] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"119.535331ms","start":"2026-02-03T22:40:17.612799Z","end":"2026-02-03T22:40:17.732334Z","steps":["trace[708043849] 'process raft request' (duration: 119.322675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:19.05398Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.321912166s","expected-duration":"1s"} {"level":"info","ts":"2026-02-03T22:40:19.054387Z","caller":"traceutil/trace.go:171","msg":"trace[1015280157] linearizableReadLoop","detail":"{readStateIndex:916; appliedIndex:912; }","duration":"1.437605094s","start":"2026-02-03T22:40:17.616767Z","end":"2026-02-03T22:40:19.054372Z","steps":["trace[1015280157] 'read index received' (duration: 115.885693ms)","trace[1015280157] 'applied index is now lower than readState.Index' (duration: 1.321718891s)"],"step_count":2} {"level":"info","ts":"2026-02-03T22:40:19.054637Z","caller":"traceutil/trace.go:171","msg":"trace[1253544151] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"1.441735286s","start":"2026-02-03T22:40:17.612895Z","end":"2026-02-03T22:40:19.05463Z","steps":["trace[1253544151] 'process raft request' (duration: 1.441237713s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:19.05474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:17.61289Z","time spent":"1.441767757s","remote":"127.0.0.1:42354","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-03T22:40:19.054876Z","caller":"traceutil/trace.go:171","msg":"trace[893123087] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"1.441058038s","start":"2026-02-03T22:40:17.613812Z","end":"2026-02-03T22:40:19.05487Z","steps":["trace[893123087] 'process raft request' (duration: 1.440432471s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:19.054908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:17.613807Z","time spent":"1.441083388s","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T22:40:19.054968Z","caller":"traceutil/trace.go:171","msg":"trace[349885465] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"1.441118679s","start":"2026-02-03T22:40:17.613845Z","end":"2026-02-03T22:40:19.054963Z","steps":["trace[349885465] 'process raft request' (duration: 1.440451531s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:19.05499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:17.61384Z","time spent":"1.441137689s","remote":"127.0.0.1:42354","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-03T22:40:19.055061Z","caller":"traceutil/trace.go:171","msg":"trace[1552725703] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"1.438648453s","start":"2026-02-03T22:40:17.616408Z","end":"2026-02-03T22:40:19.055056Z","steps":["trace[1552725703] 'process raft request' (duration: 1.437908073s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:19.055081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:17.616399Z","time spent":"1.438671643s","remote":"127.0.0.1:42170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T22:40:19.05516Z","caller":"traceutil/trace.go:171","msg":"trace[182412619] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"1.414502772s","start":"2026-02-03T22:40:17.640652Z","end":"2026-02-03T22:40:19.055155Z","steps":["trace[182412619] 'process raft request' (duration: 1.413701341s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:19.055184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:17.640636Z","time spent":"1.414533223s","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T22:40:19.05525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.438481219s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:40:19.055266Z","caller":"traceutil/trace.go:171","msg":"trace[73270396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:882; }","duration":"1.438509109s","start":"2026-02-03T22:40:17.61675Z","end":"2026-02-03T22:40:19.055259Z","steps":["trace[73270396] 'agreement among raft nodes before linearized reading' (duration: 1.438484788s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:19.055278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:17.616744Z","time spent":"1.438531959s","remote":"127.0.0.1:42094","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-03T22:40:19.055348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.229001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:40:19.05536Z","caller":"traceutil/trace.go:171","msg":"trace[768612426] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:882; }","duration":"172.278393ms","start":"2026-02-03T22:40:18.883078Z","end":"2026-02-03T22:40:19.055356Z","steps":["trace[768612426] 'agreement among raft nodes before linearized reading' (duration: 172.253992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:20.103463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:19.658068Z","time spent":"445.391982ms","remote":"127.0.0.1:42124","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-03T22:40:20.103912Z","caller":"traceutil/trace.go:171","msg":"trace[495060663] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:918; }","duration":"366.283711ms","start":"2026-02-03T22:40:19.73761Z","end":"2026-02-03T22:40:20.103893Z","steps":["trace[495060663] 'read index received' (duration: 366.279061ms)","trace[495060663] 'applied index is now lower than readState.Index' (duration: 3.44µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:40:20.105765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.225003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T22:40:20.105869Z","caller":"traceutil/trace.go:171","msg":"trace[1918176908] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:882; }","duration":"358.364218ms","start":"2026-02-03T22:40:19.747492Z","end":"2026-02-03T22:40:20.105856Z","steps":["trace[1918176908] 'agreement among raft nodes before linearized reading' (duration: 356.623331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:20.105941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:19.747482Z","time spent":"358.442329ms","remote":"127.0.0.1:42282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-02-03T22:40:20.106277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.661855ms","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-03T22:40:20.10636Z","caller":"traceutil/trace.go:171","msg":"trace[1438508780] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:882; }","duration":"368.771078ms","start":"2026-02-03T22:40:19.737577Z","end":"2026-02-03T22:40:20.106348Z","steps":["trace[1438508780] 'agreement among raft nodes before linearized reading' (duration: 368.596103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:20.10651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:19.737564Z","time spent":"368.934492ms","remote":"127.0.0.1:42354","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-03T22:40:21.096023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.743121ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T22:40:21.096096Z","caller":"traceutil/trace.go:171","msg":"trace[1788197089] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"987.413782ms","start":"2026-02-03T22:40:20.108669Z","end":"2026-02-03T22:40:21.096083Z","steps":["trace[1788197089] 'process raft request' (duration: 691.503807ms)","trace[1788197089] 'compare' (duration: 295.454873ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:40:21.096135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:20.108644Z","time spent":"987.473783ms","remote":"127.0.0.1:42124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T22:40:21.119961Z","caller":"traceutil/trace.go:171","msg":"trace[1667043076] linearizableReadLoop","detail":"{readStateIndex:920; appliedIndex:918; }","duration":"620.095922ms","start":"2026-02-03T22:40:20.499835Z","end":"2026-02-03T22:40:21.11993Z","steps":["trace[1667043076] 'read index received' (duration: 300.352064ms)","trace[1667043076] 'applied index is now lower than readState.Index' (duration: 319.742838ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T22:40:21.120714Z","caller":"traceutil/trace.go:171","msg":"trace[768040138] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"1.009289862s","start":"2026-02-03T22:40:20.111409Z","end":"2026-02-03T22:40:21.120699Z","steps":["trace[768040138] 'process raft request' (duration: 1.008421478s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:21.121326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:20.111397Z","time spent":"1.009386144s","remote":"127.0.0.1:42354","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-03T22:40:21.122119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.274801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T22:40:21.12216Z","caller":"traceutil/trace.go:171","msg":"trace[1555443378] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:884; }","duration":"622.319102ms","start":"2026-02-03T22:40:20.49983Z","end":"2026-02-03T22:40:21.122149Z","steps":["trace[1555443378] 'agreement among raft nodes before linearized reading' (duration: 622.24798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:21.122194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:20.499775Z","time spent":"622.410685ms","remote":"127.0.0.1:45942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":2,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-02-03T22:40:21.122413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.252981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:40:21.122447Z","caller":"traceutil/trace.go:171","msg":"trace[507032817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:884; }","duration":"153.309622ms","start":"2026-02-03T22:40:20.969125Z","end":"2026-02-03T22:40:21.122434Z","steps":["trace[507032817] 'agreement among raft nodes before linearized reading' (duration: 153.263121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:21.91588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.510749ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T22:40:21.916143Z","caller":"traceutil/trace.go:171","msg":"trace[14472581] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"487.847008ms","start":"2026-02-03T22:40:21.42828Z","end":"2026-02-03T22:40:21.916127Z","steps":["trace[14472581] 'process raft request' (duration: 487.813337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:21.916206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:21.428264Z","time spent":"487.91208ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T22:40:21.916496Z","caller":"traceutil/trace.go:171","msg":"trace[1263779839] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"783.541807ms","start":"2026-02-03T22:40:21.132942Z","end":"2026-02-03T22:40:21.916484Z","steps":["trace[1263779839] 'process raft request' (duration: 380.358731ms)","trace[1263779839] 'compare' (duration: 402.23707ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:40:21.916549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:21.13293Z","time spent":"783.590249ms","remote":"127.0.0.1:42354","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-03T22:40:21.91666Z","caller":"traceutil/trace.go:171","msg":"trace[780890881] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"781.466161ms","start":"2026-02-03T22:40:21.135186Z","end":"2026-02-03T22:40:21.916652Z","steps":["trace[780890881] 'process raft request' (duration: 780.779203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:21.916706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:21.135178Z","time spent":"781.502142ms","remote":"127.0.0.1:42354","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-03T22:40:21.916811Z","caller":"traceutil/trace.go:171","msg":"trace[1530083674] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"781.575894ms","start":"2026-02-03T22:40:21.135226Z","end":"2026-02-03T22:40:21.916802Z","steps":["trace[1530083674] 'process raft request' (duration: 780.790353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:21.916855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:21.135221Z","time spent":"781.609665ms","remote":"127.0.0.1:42354","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-03T22:40:21.916955Z","caller":"traceutil/trace.go:171","msg":"trace[1079863978] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"780.721451ms","start":"2026-02-03T22:40:21.136226Z","end":"2026-02-03T22:40:21.916947Z","steps":["trace[1079863978] 'process raft request' (duration: 779.836737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:21.916997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:21.13622Z","time spent":"780.755042ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T22:40:23.476406Z","caller":"traceutil/trace.go:171","msg":"trace[1464701629] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"341.70691ms","start":"2026-02-03T22:40:23.134685Z","end":"2026-02-03T22:40:23.476392Z","steps":["trace[1464701629] 'process raft request' (duration: 341.629408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:23.476498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:23.134659Z","time spent":"341.792342ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T22:40:24.067863Z","caller":"traceutil/trace.go:171","msg":"trace[2108181348] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"136.803597ms","start":"2026-02-03T22:40:23.931042Z","end":"2026-02-03T22:40:24.067846Z","steps":["trace[2108181348] 'process raft request' (duration: 136.715145ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:40:24.111072Z","caller":"traceutil/trace.go:171","msg":"trace[622395632] linearizableReadLoop","detail":"{readStateIndex:930; appliedIndex:926; }","duration":"142.09372ms","start":"2026-02-03T22:40:23.968967Z","end":"2026-02-03T22:40:24.111061Z","steps":["trace[622395632] 'read index received' (duration: 98.737741ms)","trace[622395632] 'applied index is now lower than readState.Index' (duration: 43.355549ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:40:24.11117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.190162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:40:24.111187Z","caller":"traceutil/trace.go:171","msg":"trace[729629661] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:894; }","duration":"142.291085ms","start":"2026-02-03T22:40:23.968891Z","end":"2026-02-03T22:40:24.111182Z","steps":["trace[729629661] 'agreement among raft nodes before linearized reading' (duration: 142.212163ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:40:24.111353Z","caller":"traceutil/trace.go:171","msg":"trace[1974074569] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"177.864563ms","start":"2026-02-03T22:40:23.933482Z","end":"2026-02-03T22:40:24.111347Z","steps":["trace[1974074569] 'process raft request' (duration: 177.480233ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:40:24.111428Z","caller":"traceutil/trace.go:171","msg":"trace[2023916558] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"176.556818ms","start":"2026-02-03T22:40:23.934867Z","end":"2026-02-03T22:40:24.111424Z","steps":["trace[2023916558] 'process raft request' (duration: 176.148167ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:40:24.111479Z","caller":"traceutil/trace.go:171","msg":"trace[1201774982] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"176.418925ms","start":"2026-02-03T22:40:23.935056Z","end":"2026-02-03T22:40:24.111475Z","steps":["trace[1201774982] 'process raft request' (duration: 175.988303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:25.407886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.315172ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-03T22:40:25.755955Z","caller":"traceutil/trace.go:171","msg":"trace[940902712] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:931; }","duration":"206.690451ms","start":"2026-02-03T22:40:25.549249Z","end":"2026-02-03T22:40:25.755939Z","steps":["trace[940902712] 'read index received' (duration: 206.521217ms)","trace[940902712] 'applied index is now lower than readState.Index' (duration: 168.354µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T22:40:25.756036Z","caller":"traceutil/trace.go:171","msg":"trace[1506797312] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"298.899897ms","start":"2026-02-03T22:40:25.45713Z","end":"2026-02-03T22:40:25.75603Z","steps":["trace[1506797312] 'process raft request' (duration: 298.697141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:25.756185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.922567ms","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-03T22:40:25.756205Z","caller":"traceutil/trace.go:171","msg":"trace[422807525] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:895; }","duration":"206.975739ms","start":"2026-02-03T22:40:25.549223Z","end":"2026-02-03T22:40:25.756198Z","steps":["trace[422807525] 'agreement among raft nodes before linearized reading' (duration: 206.887227ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:40:25.967228Z","caller":"traceutil/trace.go:171","msg":"trace[2059023231] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"207.80829ms","start":"2026-02-03T22:40:25.759407Z","end":"2026-02-03T22:40:25.967215Z","steps":["trace[2059023231] 'process raft request' (duration: 207.569064ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:26.291804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.223028ms","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-03T22:40:26.291869Z","caller":"traceutil/trace.go:171","msg":"trace[1268389946] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:896; }","duration":"219.323231ms","start":"2026-02-03T22:40:26.072532Z","end":"2026-02-03T22:40:26.291855Z","steps":["trace[1268389946] 'range keys from in-memory index tree' (duration: 218.996233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:26.29203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.349053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-03T22:40:26.292071Z","caller":"traceutil/trace.go:171","msg":"trace[1067502757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:896; }","duration":"176.402495ms","start":"2026-02-03T22:40:26.115657Z","end":"2026-02-03T22:40:26.292059Z","steps":["trace[1067502757] 'range keys from in-memory index tree' (duration: 176.281341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:26.292203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.065792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T22:40:26.292233Z","caller":"traceutil/trace.go:171","msg":"trace[769990856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:896; }","duration":"177.112513ms","start":"2026-02-03T22:40:26.115111Z","end":"2026-02-03T22:40:26.292224Z","steps":["trace[769990856] 'range keys from in-memory index tree' (duration: 176.99868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:27.708151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.632874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4783"} {"level":"info","ts":"2026-02-03T22:40:27.708207Z","caller":"traceutil/trace.go:171","msg":"trace[1535361096] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:902; }","duration":"424.718506ms","start":"2026-02-03T22:40:27.283474Z","end":"2026-02-03T22:40:27.708193Z","steps":["trace[1535361096] 'range keys from in-memory index tree' (duration: 424.515261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:27.708233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:27.283446Z","time spent":"424.781028ms","remote":"127.0.0.1:42262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4805,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-03T22:40:28.299475Z","caller":"traceutil/trace.go:171","msg":"trace[1671738197] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"384.554564ms","start":"2026-02-03T22:40:27.914899Z","end":"2026-02-03T22:40:28.299453Z","steps":["trace[1671738197] 'process raft request' (duration: 383.871045ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:28.300432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:27.914882Z","time spent":"384.685068ms","remote":"127.0.0.1:42170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-03T22:40:28.30112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.038325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:40:28.301161Z","caller":"traceutil/trace.go:171","msg":"trace[2001039934] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:903; }","duration":"330.082266ms","start":"2026-02-03T22:40:27.971066Z","end":"2026-02-03T22:40:28.301149Z","steps":["trace[2001039934] 'agreement among raft nodes before linearized reading' (duration: 329.983534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:28.301214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:27.971035Z","time spent":"330.151917ms","remote":"127.0.0.1:42094","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-03T22:40:28.298973Z","caller":"traceutil/trace.go:171","msg":"trace[1562606697] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:939; }","duration":"327.873147ms","start":"2026-02-03T22:40:27.97107Z","end":"2026-02-03T22:40:28.298943Z","steps":["trace[1562606697] 'read index received' (duration: 327.568469ms)","trace[1562606697] 'applied index is now lower than readState.Index' (duration: 303.608µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:40:28.308553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.002913ms","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-03T22:40:28.308653Z","caller":"traceutil/trace.go:171","msg":"trace[1670439610] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:904; }","duration":"337.307781ms","start":"2026-02-03T22:40:27.971275Z","end":"2026-02-03T22:40:28.308583Z","steps":["trace[1670439610] 'agreement among raft nodes before linearized reading' (duration: 336.91714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:28.308733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:27.971271Z","time spent":"337.452434ms","remote":"127.0.0.1:42354","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-03T22:40:28.309099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.240549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T22:40:28.309218Z","caller":"traceutil/trace.go:171","msg":"trace[1811615662] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:904; }","duration":"324.421244ms","start":"2026-02-03T22:40:27.984787Z","end":"2026-02-03T22:40:28.309208Z","steps":["trace[1811615662] 'agreement among raft nodes before linearized reading' (duration: 324.208368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:28.309339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:27.984765Z","time spent":"324.477826ms","remote":"127.0.0.1:42646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-02-03T22:40:42.530411Z","caller":"traceutil/trace.go:171","msg":"trace[1745840207] linearizableReadLoop","detail":"{readStateIndex:1045; appliedIndex:1044; }","duration":"240.195053ms","start":"2026-02-03T22:40:42.290192Z","end":"2026-02-03T22:40:42.530387Z","steps":["trace[1745840207] 'read index received' (duration: 239.902406ms)","trace[1745840207] 'applied index is now lower than readState.Index' (duration: 291.467µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T22:40:42.530671Z","caller":"traceutil/trace.go:171","msg":"trace[1122187269] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"475.502585ms","start":"2026-02-03T22:40:42.05515Z","end":"2026-02-03T22:40:42.530652Z","steps":["trace[1122187269] 'process raft request' (duration: 474.96605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:42.530889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:42.055132Z","time spent":"475.690539ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T22:40:42.531201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.007435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-02-03T22:40:42.532033Z","caller":"traceutil/trace.go:171","msg":"trace[948066151] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1005; }","duration":"241.869668ms","start":"2026-02-03T22:40:42.290152Z","end":"2026-02-03T22:40:42.532022Z","steps":["trace[948066151] 'agreement among raft nodes before linearized reading' (duration: 240.988685ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:40:42.91927Z","caller":"traceutil/trace.go:171","msg":"trace[1274251107] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"585.029611ms","start":"2026-02-03T22:40:42.334218Z","end":"2026-02-03T22:40:42.919248Z","steps":["trace[1274251107] 'process raft request' (duration: 584.908258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:42.91948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:42.334193Z","time spent":"585.215296ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T22:40:42.920075Z","caller":"traceutil/trace.go:171","msg":"trace[1216359174] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1046; }","duration":"389.563502ms","start":"2026-02-03T22:40:42.5305Z","end":"2026-02-03T22:40:42.920063Z","steps":["trace[1216359174] 'read index received' (duration: 389.557742ms)","trace[1216359174] 'applied index is now lower than readState.Index' (duration: 4.92µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:40:42.920239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.625435ms","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-03T22:40:42.920306Z","caller":"traceutil/trace.go:171","msg":"trace[686861109] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1006; }","duration":"534.737608ms","start":"2026-02-03T22:40:42.385558Z","end":"2026-02-03T22:40:42.920296Z","steps":["trace[686861109] 'agreement among raft nodes before linearized reading' (duration: 534.585574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:42.920369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:42.385546Z","time spent":"534.81286ms","remote":"127.0.0.1:42354","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-03T22:40:42.92059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.842239ms","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-03T22:40:42.921494Z","caller":"traceutil/trace.go:171","msg":"trace[591328827] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1006; }","duration":"416.749803ms","start":"2026-02-03T22:40:42.504727Z","end":"2026-02-03T22:40:42.921476Z","steps":["trace[591328827] 'agreement among raft nodes before linearized reading' (duration: 415.802278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:42.921592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:42.504718Z","time spent":"416.858566ms","remote":"127.0.0.1:42354","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-03T22:40:42.921846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.086359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-03T22:40:42.922906Z","caller":"traceutil/trace.go:171","msg":"trace[2029725597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1006; }","duration":"419.139398ms","start":"2026-02-03T22:40:42.503736Z","end":"2026-02-03T22:40:42.922876Z","steps":["trace[2029725597] 'agreement among raft nodes before linearized reading' (duration: 418.039908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:42.92298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:42.503729Z","time spent":"419.23727ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T22:40:42.922291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.771204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T22:40:42.923831Z","caller":"traceutil/trace.go:171","msg":"trace[1869068774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1006; }","duration":"421.341957ms","start":"2026-02-03T22:40:42.502465Z","end":"2026-02-03T22:40:42.923807Z","steps":["trace[1869068774] 'agreement among raft nodes before linearized reading' (duration: 419.58619ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:42.923918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:42.502451Z","time spent":"421.447669ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T22:40:42.922504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.963312ms","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-03T22:40:42.924604Z","caller":"traceutil/trace.go:171","msg":"trace[1948876342] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1006; }","duration":"444.078059ms","start":"2026-02-03T22:40:42.480502Z","end":"2026-02-03T22:40:42.92458Z","steps":["trace[1948876342] 'agreement among raft nodes before linearized reading' (duration: 441.90568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:42.924701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:42.480484Z","time spent":"444.193831ms","remote":"127.0.0.1:42354","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-03T22:40:43.023297Z","caller":"traceutil/trace.go:171","msg":"trace[667607722] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"487.394784ms","start":"2026-02-03T22:40:42.535883Z","end":"2026-02-03T22:40:43.023278Z","steps":["trace[667607722] 'process raft request' (duration: 480.292363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:43.023534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:40:42.535868Z","time spent":"487.502867ms","remote":"127.0.0.1:42354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T22:43:44.939262Z","caller":"traceutil/trace.go:171","msg":"trace[105033675] transaction","detail":"{read_only:false; response_revision:1782; number_of_response:1; }","duration":"110.796786ms","start":"2026-02-03T22:43:44.828439Z","end":"2026-02-03T22:43:44.939236Z","steps":["trace[105033675] 'process raft request' (duration: 110.637102ms)"],"step_count":1}