{"level":"warn","ts":"2026-02-08T17:04:29.268096Z","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-08T17:04:29.268875Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129: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.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129: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-08T17:04:29.269002Z","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-08T17:04:29.26959Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-02-08T17:04:29.269646Z","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-08T17:04:29.270355Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-02-08T17:04:29.270726Z","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.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129: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-08T17:04:29.276753Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.938137ms"} {"level":"info","ts":"2026-02-08T17:04:29.293594Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-02-08T17:04:29.293705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-02-08T17:04:29.29374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-02-08T17:04:29.293773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-08T17:04:29.293797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-02-08T17:04:29.293845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-02-08T17:04:29.301972Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-08T17:04:29.30504Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-08T17:04:29.307928Z","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-08T17:04:29.309552Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-08T17:04:29.311381Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-08T17:04:29.311651Z","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-08T17:04:29.3117Z","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-08T17:04:29.31178Z","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-08T17:04:29.313472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-02-08T17:04:29.314866Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-02-08T17:04:29.318529Z","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-08T17:04:29.318887Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-02-08T17:04:29.319001Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-02-08T17:04:29.327715Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-08T17:04:29.328329Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-08T17:04:29.894049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-02-08T17:04:29.894092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-02-08T17:04:29.894114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-02-08T17:04:29.894124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-02-08T17:04:29.894129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-02-08T17:04:29.894137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-02-08T17:04:29.894145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-02-08T17:04:29.896205Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-02-08T17:04:29.896227Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T17:04:29.896476Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T17:04:29.897371Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T17:04:29.89874Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"info","ts":"2026-02-08T17:04:29.898884Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T17:04:29.898921Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T17:04:29.898941Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T17:04:29.900658Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-08T17:04:29.908992Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-08T17:04:29.909006Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-02-08T17:04:43.962456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.185984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4260"} {"level":"info","ts":"2026-02-08T17:04:43.962533Z","caller":"traceutil/trace.go:171","msg":"trace[2075141837] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:402; }","duration":"114.308158ms","start":"2026-02-08T17:04:43.848213Z","end":"2026-02-08T17:04:43.962521Z","steps":["trace[2075141837] 'range keys from in-memory index tree' (duration: 114.093502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:04:43.96275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.994817ms","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-08T17:04:43.962765Z","caller":"traceutil/trace.go:171","msg":"trace[180731277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:402; }","duration":"275.040218ms","start":"2026-02-08T17:04:43.687719Z","end":"2026-02-08T17:04:43.96276Z","steps":["trace[180731277] 'range keys from in-memory index tree' (duration: 274.873324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:04:43.962856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.126621ms","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-08T17:04:43.962868Z","caller":"traceutil/trace.go:171","msg":"trace[824250460] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:402; }","duration":"275.219303ms","start":"2026-02-08T17:04:43.687645Z","end":"2026-02-08T17:04:43.962864Z","steps":["trace[824250460] 'range keys from in-memory index tree' (duration: 274.970857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:04:43.962929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.136051ms","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-08T17:04:43.962941Z","caller":"traceutil/trace.go:171","msg":"trace[771563552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:402; }","duration":"275.155831ms","start":"2026-02-08T17:04:43.687782Z","end":"2026-02-08T17:04:43.962937Z","steps":["trace[771563552] 'range keys from in-memory index tree' (duration: 275.025788ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T17:04:44.265064Z","caller":"traceutil/trace.go:171","msg":"trace[428509566] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"296.273755ms","start":"2026-02-08T17:04:43.968769Z","end":"2026-02-08T17:04:44.265042Z","steps":["trace[428509566] 'process raft request' (duration: 296.127251ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T17:04:44.906012Z","caller":"traceutil/trace.go:171","msg":"trace[989167857] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"937.017174ms","start":"2026-02-08T17:04:43.968967Z","end":"2026-02-08T17:04:44.905984Z","steps":["trace[989167857] 'process raft request' (duration: 936.726186ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T17:04:44.906053Z","caller":"traceutil/trace.go:171","msg":"trace[1849279839] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"928.706385ms","start":"2026-02-08T17:04:43.977314Z","end":"2026-02-08T17:04:44.90602Z","steps":["trace[1849279839] 'process raft request' (duration: 928.623413ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T17:04:44.906469Z","caller":"traceutil/trace.go:171","msg":"trace[279278394] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"934.584647ms","start":"2026-02-08T17:04:43.971873Z","end":"2026-02-08T17:04:44.906458Z","steps":["trace[279278394] 'process raft request' (duration: 934.021802ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:04:44.906534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T17:04:43.968932Z","time spent":"937.167188ms","remote":"127.0.0.1:40574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4053,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T17:04:44.906587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T17:04:43.971866Z","time spent":"934.620478ms","remote":"127.0.0.1:40654","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-08T17:04:44.90686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T17:04:43.977303Z","time spent":"928.854589ms","remote":"127.0.0.1:40654","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-08T17:05:05.15756Z","caller":"traceutil/trace.go:171","msg":"trace[92413549] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:567; }","duration":"141.970842ms","start":"2026-02-08T17:05:05.015564Z","end":"2026-02-08T17:05:05.157535Z","steps":["trace[92413549] 'read index received' (duration: 141.839269ms)","trace[92413549] 'applied index is now lower than readState.Index' (duration: 130.663µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T17:05:05.157675Z","caller":"traceutil/trace.go:171","msg":"trace[681801482] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"184.138766ms","start":"2026-02-08T17:05:04.973528Z","end":"2026-02-08T17:05:05.157667Z","steps":["trace[681801482] 'process raft request' (duration: 183.864899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:05:05.158047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.472865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-08T17:05:05.158022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.284368ms","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-08T17:05:05.158072Z","caller":"traceutil/trace.go:171","msg":"trace[936556203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:553; }","duration":"142.548987ms","start":"2026-02-08T17:05:05.015515Z","end":"2026-02-08T17:05:05.158064Z","steps":["trace[936556203] 'agreement among raft nodes before linearized reading' (duration: 142.436634ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T17:05:05.158102Z","caller":"traceutil/trace.go:171","msg":"trace[298189490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:553; }","duration":"135.415412ms","start":"2026-02-08T17:05:05.022666Z","end":"2026-02-08T17:05:05.158081Z","steps":["trace[298189490] 'agreement among raft nodes before linearized reading' (duration: 135.132034ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T17:05:05.267836Z","caller":"traceutil/trace.go:171","msg":"trace[570476916] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"103.255388ms","start":"2026-02-08T17:05:05.164539Z","end":"2026-02-08T17:05:05.267794Z","steps":["trace[570476916] 'process raft request' (duration: 35.712487ms)","trace[570476916] 'compare' (duration: 67.423788ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T17:05:06.193368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.25216ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T17:05:06.193612Z","caller":"traceutil/trace.go:171","msg":"trace[1791223876] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:568; }","duration":"1.021864744s","start":"2026-02-08T17:05:05.171717Z","end":"2026-02-08T17:05:06.193581Z","steps":["trace[1791223876] 'read index received' (duration: 28.547901ms)","trace[1791223876] 'applied index is now lower than readState.Index' (duration: 993.315793ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T17:05:06.193831Z","caller":"traceutil/trace.go:171","msg":"trace[630594372] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"1.028994258s","start":"2026-02-08T17:05:05.164823Z","end":"2026-02-08T17:05:06.193817Z","steps":["trace[630594372] 'process raft request' (duration: 416.01347ms)","trace[630594372] 'compare' (duration: 611.90596ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T17:05:06.193983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T17:05:05.164817Z","time spent":"1.029049549s","remote":"127.0.0.1:40404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9296,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T17:05:06.194647Z","caller":"traceutil/trace.go:171","msg":"trace[983619024] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"1.028542326s","start":"2026-02-08T17:05:05.166088Z","end":"2026-02-08T17:05:06.194631Z","steps":["trace[983619024] 'process raft request' (duration: 1.027424617s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:05:06.194714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T17:05:05.166083Z","time spent":"1.028595329s","remote":"127.0.0.1:40654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T17:05:06.194821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023089336s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T17:05:06.19485Z","caller":"traceutil/trace.go:171","msg":"trace[365331129] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:556; }","duration":"1.023142357s","start":"2026-02-08T17:05:05.171696Z","end":"2026-02-08T17:05:06.194838Z","steps":["trace[365331129] 'agreement among raft nodes before linearized reading' (duration: 1.023087155s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:05:06.194874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T17:05:05.171688Z","time spent":"1.023180098s","remote":"127.0.0.1:40404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-08T17:05:06.265439Z","caller":"traceutil/trace.go:171","msg":"trace[1370069027] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"690.96967ms","start":"2026-02-08T17:05:05.574432Z","end":"2026-02-08T17:05:06.265402Z","steps":["trace[1370069027] 'process raft request' (duration: 690.887308ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T17:05:06.265621Z","caller":"traceutil/trace.go:171","msg":"trace[57312894] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"892.301222ms","start":"2026-02-08T17:05:05.373301Z","end":"2026-02-08T17:05:06.265602Z","steps":["trace[57312894] 'process raft request' (duration: 891.680776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:05:06.265625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T17:05:05.574406Z","time spent":"691.136794ms","remote":"127.0.0.1:40404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-08T17:05:06.265704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T17:05:05.373274Z","time spent":"892.388414ms","remote":"127.0.0.1:40404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-08T17:05:06.265812Z","caller":"traceutil/trace.go:171","msg":"trace[1084919170] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"703.338831ms","start":"2026-02-08T17:05:05.562459Z","end":"2026-02-08T17:05:06.265798Z","steps":["trace[1084919170] 'process raft request' (duration: 702.679244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:05:06.265938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T17:05:05.562431Z","time spent":"703.410463ms","remote":"127.0.0.1:40654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T17:05:06.444831Z","caller":"traceutil/trace.go:171","msg":"trace[250090430] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:571; }","duration":"251.151993ms","start":"2026-02-08T17:05:06.193647Z","end":"2026-02-08T17:05:06.444799Z","steps":["trace[250090430] 'read index received' (duration: 71.37381ms)","trace[250090430] 'applied index is now lower than readState.Index' (duration: 179.777083ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T17:05:06.44495Z","caller":"traceutil/trace.go:171","msg":"trace[198426554] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"692.396687ms","start":"2026-02-08T17:05:05.752543Z","end":"2026-02-08T17:05:06.444939Z","steps":["trace[198426554] 'process raft request' (duration: 692.009097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:05:06.445072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T17:05:05.752518Z","time spent":"692.461349ms","remote":"127.0.0.1:40560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4365,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T17:05:06.445482Z","caller":"traceutil/trace.go:171","msg":"trace[473218850] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"243.401662ms","start":"2026-02-08T17:05:06.202069Z","end":"2026-02-08T17:05:06.44547Z","steps":["trace[473218850] 'process raft request' (duration: 242.675123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:05:06.445711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.901315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-08T17:05:06.445754Z","caller":"traceutil/trace.go:171","msg":"trace[1731420847] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:561; }","duration":"270.967527ms","start":"2026-02-08T17:05:06.174772Z","end":"2026-02-08T17:05:06.445739Z","steps":["trace[1731420847] 'agreement among raft nodes before linearized reading' (duration: 270.818753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:05:06.445973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.713626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-08T17:05:06.446003Z","caller":"traceutil/trace.go:171","msg":"trace[1005121118] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:561; }","duration":"473.793918ms","start":"2026-02-08T17:05:05.9722Z","end":"2026-02-08T17:05:06.445994Z","steps":["trace[1005121118] 'agreement among raft nodes before linearized reading' (duration: 473.707345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:05:06.446032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T17:05:05.972177Z","time spent":"473.845489ms","remote":"127.0.0.1:40404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5608,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-02-08T17:05:06.446155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.61111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T17:05:06.446188Z","caller":"traceutil/trace.go:171","msg":"trace[2059263172] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:561; }","duration":"528.687451ms","start":"2026-02-08T17:05:05.917492Z","end":"2026-02-08T17:05:06.446179Z","steps":["trace[2059263172] 'agreement among raft nodes before linearized reading' (duration: 528.64198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:05:06.446216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T17:05:05.917466Z","time spent":"528.744423ms","remote":"127.0.0.1:40326","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-08T17:05:06.446378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.295886ms","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-08T17:05:06.44641Z","caller":"traceutil/trace.go:171","msg":"trace[2047779911] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:561; }","duration":"630.397329ms","start":"2026-02-08T17:05:05.816004Z","end":"2026-02-08T17:05:06.446401Z","steps":["trace[2047779911] 'agreement among raft nodes before linearized reading' (duration: 630.313527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:05:06.446436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T17:05:05.815973Z","time spent":"630.456661ms","remote":"127.0.0.1:40654","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-08T17:05:06.446569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.592279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T17:05:06.446599Z","caller":"traceutil/trace.go:171","msg":"trace[553933789] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:561; }","duration":"673.659211ms","start":"2026-02-08T17:05:05.772928Z","end":"2026-02-08T17:05:06.446587Z","steps":["trace[553933789] 'agreement among raft nodes before linearized reading' (duration: 673.61542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:05:06.446626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T17:05:05.772912Z","time spent":"673.707483ms","remote":"127.0.0.1:40404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-08T17:05:06.446801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012957542s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T17:05:06.446829Z","caller":"traceutil/trace.go:171","msg":"trace[315099614] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:561; }","duration":"1.013038794s","start":"2026-02-08T17:05:05.433782Z","end":"2026-02-08T17:05:06.446821Z","steps":["trace[315099614] 'agreement among raft nodes before linearized reading' (duration: 1.012936301s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:05:06.446854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T17:05:05.433761Z","time spent":"1.013087165s","remote":"127.0.0.1:40654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-08T17:05:06.448011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.252176ms","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-08T17:05:06.448096Z","caller":"traceutil/trace.go:171","msg":"trace[534995331] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:562; }","duration":"101.371409ms","start":"2026-02-08T17:05:06.346717Z","end":"2026-02-08T17:05:06.448089Z","steps":["trace[534995331] 'agreement among raft nodes before linearized reading' (duration: 101.194424ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T17:05:06.448288Z","caller":"traceutil/trace.go:171","msg":"trace[1301081705] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"168.900781ms","start":"2026-02-08T17:05:06.27938Z","end":"2026-02-08T17:05:06.448281Z","steps":["trace[1301081705] 'process raft request' (duration: 168.417678ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T17:05:09.16557Z","caller":"traceutil/trace.go:171","msg":"trace[248374674] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"167.812852ms","start":"2026-02-08T17:05:08.997731Z","end":"2026-02-08T17:05:09.165544Z","steps":["trace[248374674] 'read index received' (duration: 167.607067ms)","trace[248374674] 'applied index is now lower than readState.Index' (duration: 204.425µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T17:05:09.166101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.316095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T17:05:09.166202Z","caller":"traceutil/trace.go:171","msg":"trace[1994980661] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:580; }","duration":"168.48833ms","start":"2026-02-08T17:05:08.997695Z","end":"2026-02-08T17:05:09.166183Z","steps":["trace[1994980661] 'agreement among raft nodes before linearized reading' (duration: 168.253354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:05:09.166206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.370787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T17:05:09.166246Z","caller":"traceutil/trace.go:171","msg":"trace[1808191978] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:580; }","duration":"168.468189ms","start":"2026-02-08T17:05:08.997766Z","end":"2026-02-08T17:05:09.166235Z","steps":["trace[1808191978] 'agreement among raft nodes before linearized reading' (duration: 168.377527ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T17:05:09.166532Z","caller":"traceutil/trace.go:171","msg":"trace[1019105548] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"171.20535ms","start":"2026-02-08T17:05:08.995316Z","end":"2026-02-08T17:05:09.166521Z","steps":["trace[1019105548] 'process raft request' (duration: 170.091551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:05:09.166702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.877659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T17:05:09.166728Z","caller":"traceutil/trace.go:171","msg":"trace[343987546] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:580; }","duration":"168.911641ms","start":"2026-02-08T17:05:08.997808Z","end":"2026-02-08T17:05:09.166719Z","steps":["trace[343987546] 'agreement among raft nodes before linearized reading' (duration: 168.87296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:05:09.525716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.500877ms","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-08T17:05:09.525807Z","caller":"traceutil/trace.go:171","msg":"trace[139103324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:580; }","duration":"223.664481ms","start":"2026-02-08T17:05:09.302122Z","end":"2026-02-08T17:05:09.525787Z","steps":["trace[139103324] 'range keys from in-memory index tree' (duration: 223.286511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:05:14.083817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.602743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T17:05:14.083908Z","caller":"traceutil/trace.go:171","msg":"trace[1436629858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"163.736236ms","start":"2026-02-08T17:05:13.920154Z","end":"2026-02-08T17:05:14.08389Z","steps":["trace[1436629858] 'range keys from in-memory index tree' (duration: 163.497801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:05:14.084194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.835165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-f9fw7\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-08T17:05:14.084295Z","caller":"traceutil/trace.go:171","msg":"trace[869894601] range","detail":"{range_begin:/registry/pods/kube-system/cilium-f9fw7; range_end:; response_count:1; response_revision:596; }","duration":"100.996209ms","start":"2026-02-08T17:05:13.983277Z","end":"2026-02-08T17:05:14.084273Z","steps":["trace[869894601] 'range keys from in-memory index tree' (duration: 100.64125ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T17:05:14.324276Z","caller":"traceutil/trace.go:171","msg":"trace[1311844446] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"222.788099ms","start":"2026-02-08T17:05:14.101473Z","end":"2026-02-08T17:05:14.324261Z","steps":["trace[1311844446] 'process raft request' (duration: 222.340497ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T17:05:25.858824Z","caller":"traceutil/trace.go:171","msg":"trace[781043204] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"188.621543ms","start":"2026-02-08T17:05:25.670173Z","end":"2026-02-08T17:05:25.858794Z","steps":["trace[781043204] 'process raft request' (duration: 188.423228ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T17:05:27.29454Z","caller":"traceutil/trace.go:171","msg":"trace[764199324] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"101.368249ms","start":"2026-02-08T17:05:27.193137Z","end":"2026-02-08T17:05:27.294505Z","steps":["trace[764199324] 'process raft request' (duration: 36.892967ms)","trace[764199324] 'compare' (duration: 64.299127ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T17:05:52.894538Z","caller":"traceutil/trace.go:171","msg":"trace[1684501959] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"127.007724ms","start":"2026-02-08T17:05:52.76747Z","end":"2026-02-08T17:05:52.894478Z","steps":["trace[1684501959] 'process raft request' (duration: 96.052132ms)","trace[1684501959] 'compare' (duration: 30.718376ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T17:06:07.509316Z","caller":"traceutil/trace.go:171","msg":"trace[1083008347] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"179.672751ms","start":"2026-02-08T17:06:07.329627Z","end":"2026-02-08T17:06:07.5093Z","steps":["trace[1083008347] 'process raft request' (duration: 179.537288ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T17:06:09.093317Z","caller":"traceutil/trace.go:171","msg":"trace[682518898] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"101.428348ms","start":"2026-02-08T17:06:08.991869Z","end":"2026-02-08T17:06:09.093298Z","steps":["trace[682518898] 'process raft request' (duration: 101.311555ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T17:06:12.418309Z","caller":"traceutil/trace.go:171","msg":"trace[837820462] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"159.804812ms","start":"2026-02-08T17:06:12.258461Z","end":"2026-02-08T17:06:12.418265Z","steps":["trace[837820462] 'process raft request' (duration: 159.625828ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T17:06:21.446729Z","caller":"traceutil/trace.go:171","msg":"trace[1586394573] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"308.097913ms","start":"2026-02-08T17:06:21.138613Z","end":"2026-02-08T17:06:21.446711Z","steps":["trace[1586394573] 'process raft request' (duration: 307.98757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:06:21.44687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T17:06:21.138603Z","time spent":"308.182135ms","remote":"127.0.0.1:40654","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-08T17:06:24.117679Z","caller":"traceutil/trace.go:171","msg":"trace[1191337557] linearizableReadLoop","detail":"{readStateIndex:974; appliedIndex:973; }","duration":"201.941723ms","start":"2026-02-08T17:06:23.915719Z","end":"2026-02-08T17:06:24.117661Z","steps":["trace[1191337557] 'read index received' (duration: 201.754228ms)","trace[1191337557] 'applied index is now lower than readState.Index' (duration: 186.755µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T17:06:24.117808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.064186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T17:06:24.117829Z","caller":"traceutil/trace.go:171","msg":"trace[1050118018] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:939; }","duration":"202.131658ms","start":"2026-02-08T17:06:23.91569Z","end":"2026-02-08T17:06:24.117822Z","steps":["trace[1050118018] 'agreement among raft nodes before linearized reading' (duration: 202.052186ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T17:06:24.118154Z","caller":"traceutil/trace.go:171","msg":"trace[991887907] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"254.30226ms","start":"2026-02-08T17:06:23.863844Z","end":"2026-02-08T17:06:24.118146Z","steps":["trace[991887907] 'process raft request' (duration: 253.682173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:06:24.579558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.189589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T17:06:24.579609Z","caller":"traceutil/trace.go:171","msg":"trace[584061574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:939; }","duration":"459.274212ms","start":"2026-02-08T17:06:24.120322Z","end":"2026-02-08T17:06:24.579597Z","steps":["trace[584061574] 'range keys from in-memory index tree' (duration: 459.157118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:06:24.579631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T17:06:24.120312Z","time spent":"459.315463ms","remote":"127.0.0.1:40342","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-08T17:06:24.853824Z","caller":"traceutil/trace.go:171","msg":"trace[597079517] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"270.480282ms","start":"2026-02-08T17:06:24.583327Z","end":"2026-02-08T17:06:24.853807Z","steps":["trace[597079517] 'process raft request' (duration: 270.00976ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T17:06:36.283166Z","caller":"traceutil/trace.go:171","msg":"trace[1275076626] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"124.815999ms","start":"2026-02-08T17:06:36.158312Z","end":"2026-02-08T17:06:36.283128Z","steps":["trace[1275076626] 'process raft request' (duration: 124.262494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:06:43.532613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.255322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T17:06:43.532969Z","caller":"traceutil/trace.go:171","msg":"trace[1299585014] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1055; }","duration":"536.664622ms","start":"2026-02-08T17:06:42.996279Z","end":"2026-02-08T17:06:43.532944Z","steps":["trace[1299585014] 'range keys from in-memory index tree' (duration: 536.051516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:06:43.533234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T17:06:42.996247Z","time spent":"536.96986ms","remote":"127.0.0.1:40654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-08T17:06:43.533903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.502481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-02-08T17:06:43.534172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.145145ms","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-08T17:06:43.534285Z","caller":"traceutil/trace.go:171","msg":"trace[1212762263] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1055; }","duration":"250.891601ms","start":"2026-02-08T17:06:43.283293Z","end":"2026-02-08T17:06:43.534185Z","steps":["trace[1212762263] 'range keys from in-memory index tree' (duration: 250.383258ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T17:06:43.534239Z","caller":"traceutil/trace.go:171","msg":"trace[1693770418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1055; }","duration":"506.206497ms","start":"2026-02-08T17:06:43.028009Z","end":"2026-02-08T17:06:43.534215Z","steps":["trace[1693770418] 'range keys from in-memory index tree' (duration: 506.025902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:06:43.534486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T17:06:43.028004Z","time spent":"506.444774ms","remote":"127.0.0.1:40654","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-08T17:06:43.533854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.473607ms","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-08T17:06:43.535515Z","caller":"traceutil/trace.go:171","msg":"trace[1587180834] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1055; }","duration":"533.180881ms","start":"2026-02-08T17:06:43.002318Z","end":"2026-02-08T17:06:43.535498Z","steps":["trace[1587180834] 'range keys from in-memory index tree' (duration: 531.294122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:06:43.535856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T17:06:43.002307Z","time spent":"533.457169ms","remote":"127.0.0.1:40654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T17:06:43.534049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.272638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-08T17:06:43.535097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.049936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-08T17:06:43.536899Z","caller":"traceutil/trace.go:171","msg":"trace[978840036] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:1055; }","duration":"496.903554ms","start":"2026-02-08T17:06:43.039977Z","end":"2026-02-08T17:06:43.53688Z","steps":["trace[978840036] 'range keys from in-memory index tree' (duration: 494.892112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:06:43.537086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T17:06:43.039941Z","time spent":"497.12567ms","remote":"127.0.0.1:40376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.129\" "} {"level":"info","ts":"2026-02-08T17:06:43.536378Z","caller":"traceutil/trace.go:171","msg":"trace[1601885882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1055; }","duration":"508.675502ms","start":"2026-02-08T17:06:43.027692Z","end":"2026-02-08T17:06:43.536368Z","steps":["trace[1601885882] 'range keys from in-memory index tree' (duration: 506.194106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T17:06:43.537268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T17:06:43.027678Z","time spent":"509.578385ms","remote":"127.0.0.1:40654","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\" "}