{"level":"warn","ts":"2026-02-08T22:13:21.439197Z","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-08T22:13:21.439353Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.233: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.233:2380","--initial-cluster=instance=https://199.204.45.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.233: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-08T22:13:21.439457Z","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-08T22:13:21.439762Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-02-08T22:13:21.439804Z","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-08T22:13:21.442942Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"]} {"level":"info","ts":"2026-02-08T22:13:21.448322Z","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.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.233: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-08T22:13:21.4581Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.512183ms"} {"level":"info","ts":"2026-02-08T22:13:21.479964Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"90a0cb2044183f95","cluster-id":"782fef34d5ea94f9"} {"level":"info","ts":"2026-02-08T22:13:21.480046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=()"} {"level":"info","ts":"2026-02-08T22:13:21.480093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 0"} {"level":"info","ts":"2026-02-08T22:13:21.480111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 90a0cb2044183f95 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-08T22:13:21.480123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 1"} {"level":"info","ts":"2026-02-08T22:13:21.480175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"warn","ts":"2026-02-08T22:13:21.490876Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-08T22:13:21.495313Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-08T22:13:21.498264Z","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-08T22:13:21.50129Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"90a0cb2044183f95","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-08T22:13:21.50141Z","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-08T22:13:21.501485Z","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-08T22:13:21.501491Z","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-08T22:13:21.50172Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"90a0cb2044183f95","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-08T22:13:21.502423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"info","ts":"2026-02-08T22:13:21.502549Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","added-peer-id":"90a0cb2044183f95","added-peer-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-02-08T22:13:21.506782Z","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-08T22:13:21.508993Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-02-08T22:13:21.50901Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-02-08T22:13:21.509161Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"90a0cb2044183f95","initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-08T22:13:21.509185Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-08T22:13:21.681399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 is starting a new election at term 1"} {"level":"info","ts":"2026-02-08T22:13:21.681435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-08T22:13:21.681454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgPreVoteResp from 90a0cb2044183f95 at term 1"} {"level":"info","ts":"2026-02-08T22:13:21.681465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became candidate at term 2"} {"level":"info","ts":"2026-02-08T22:13:21.681471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgVoteResp from 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-02-08T22:13:21.681479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became leader at term 2"} {"level":"info","ts":"2026-02-08T22:13:21.68149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 90a0cb2044183f95 elected leader 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-02-08T22:13:21.682677Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T22:13:21.683001Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:13:21.682655Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"90a0cb2044183f95","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.233:2379]}","request-path":"/0/members/90a0cb2044183f95/attributes","cluster-id":"782fef34d5ea94f9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-08T22:13:21.68323Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T22:13:21.683454Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-08T22:13:21.683468Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-08T22:13:21.684883Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-08T22:13:21.687884Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:13:21.687992Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:13:21.689382Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.233:2379"} {"level":"info","ts":"2026-02-08T22:13:21.689578Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:13:53.278253Z","caller":"traceutil/trace.go:171","msg":"trace[1340652418] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"109.599413ms","start":"2026-02-08T22:13:53.168633Z","end":"2026-02-08T22:13:53.278233Z","steps":["trace[1340652418] 'process raft request' (duration: 109.4909ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:13:54.189767Z","caller":"traceutil/trace.go:171","msg":"trace[1063043677] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"123.511889ms","start":"2026-02-08T22:13:54.06624Z","end":"2026-02-08T22:13:54.189752Z","steps":["trace[1063043677] 'process raft request' (duration: 80.876656ms)","trace[1063043677] 'compare' (duration: 42.431747ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:13:54.510001Z","caller":"traceutil/trace.go:171","msg":"trace[1138585270] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"141.597435ms","start":"2026-02-08T22:13:54.368385Z","end":"2026-02-08T22:13:54.509982Z","steps":["trace[1138585270] 'process raft request' (duration: 141.41541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:13:55.068474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.168894ms","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-08T22:13:55.068536Z","caller":"traceutil/trace.go:171","msg":"trace[164138056] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:556; }","duration":"302.285187ms","start":"2026-02-08T22:13:54.766237Z","end":"2026-02-08T22:13:55.068522Z","steps":["trace[164138056] 'range keys from in-memory index tree' (duration: 302.03561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:13:55.06856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:13:54.766213Z","time spent":"302.341059ms","remote":"127.0.0.1:56850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5609,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-02-08T22:13:55.06871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.280369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-08T22:13:55.068731Z","caller":"traceutil/trace.go:171","msg":"trace[1588541850] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:556; }","duration":"102.339071ms","start":"2026-02-08T22:13:54.966387Z","end":"2026-02-08T22:13:55.068727Z","steps":["trace[1588541850] 'range keys from in-memory index tree' (duration: 102.187357ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:13:56.987289Z","caller":"traceutil/trace.go:171","msg":"trace[2048468281] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"361.04675ms","start":"2026-02-08T22:13:56.626222Z","end":"2026-02-08T22:13:56.987269Z","steps":["trace[2048468281] 'process raft request' (duration: 360.799192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:13:56.987871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:13:56.626212Z","time spent":"361.155412ms","remote":"127.0.0.1:56850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:13:57.003926Z","caller":"traceutil/trace.go:171","msg":"trace[703052127] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:583; }","duration":"234.997097ms","start":"2026-02-08T22:13:56.768918Z","end":"2026-02-08T22:13:57.003915Z","steps":["trace[703052127] 'read index received' (duration: 220.126983ms)","trace[703052127] 'applied index is now lower than readState.Index' (duration: 14.869624ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:13:57.004135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.213263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-08T22:13:57.004505Z","caller":"traceutil/trace.go:171","msg":"trace[891860547] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:570; }","duration":"235.727127ms","start":"2026-02-08T22:13:56.768769Z","end":"2026-02-08T22:13:57.004496Z","steps":["trace[891860547] 'agreement among raft nodes before linearized reading' (duration: 235.296605ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:13:57.004158Z","caller":"traceutil/trace.go:171","msg":"trace[900156591] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"371.623007ms","start":"2026-02-08T22:13:56.63253Z","end":"2026-02-08T22:13:57.004153Z","steps":["trace[900156591] 'process raft request' (duration: 371.310818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:13:57.004688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:13:56.632523Z","time spent":"372.131492ms","remote":"127.0.0.1:57040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:13:57.466698Z","caller":"traceutil/trace.go:171","msg":"trace[1623538321] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:584; }","duration":"300.827465ms","start":"2026-02-08T22:13:57.165859Z","end":"2026-02-08T22:13:57.466687Z","steps":["trace[1623538321] 'read index received' (duration: 300.636949ms)","trace[1623538321] 'applied index is now lower than readState.Index' (duration: 190.006µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:13:57.466896Z","caller":"traceutil/trace.go:171","msg":"trace[336919420] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"392.967369ms","start":"2026-02-08T22:13:57.073923Z","end":"2026-02-08T22:13:57.46689Z","steps":["trace[336919420] 'process raft request' (duration: 392.6392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:13:57.466953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:13:57.073904Z","time spent":"393.0059ms","remote":"127.0.0.1:56850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:13:57.46707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.210306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-02-08T22:13:57.467086Z","caller":"traceutil/trace.go:171","msg":"trace[1720969094] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:571; }","duration":"301.256047ms","start":"2026-02-08T22:13:57.165825Z","end":"2026-02-08T22:13:57.467081Z","steps":["trace[1720969094] 'agreement among raft nodes before linearized reading' (duration: 301.202665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:13:57.467101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:13:57.165809Z","time spent":"301.288459ms","remote":"127.0.0.1:56850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":188275,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-08T22:14:01.709858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.38796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:14:01.709974Z","caller":"traceutil/trace.go:171","msg":"trace[1542278319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"313.527394ms","start":"2026-02-08T22:14:01.396406Z","end":"2026-02-08T22:14:01.709934Z","steps":["trace[1542278319] 'range keys from in-memory index tree' (duration: 313.323478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:01.710029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:01.396392Z","time spent":"313.626457ms","remote":"127.0.0.1:56818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T22:14:01.71029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.083329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-55n54\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-08T22:14:01.710355Z","caller":"traceutil/trace.go:171","msg":"trace[684082123] range","detail":"{range_begin:/registry/pods/kube-system/cilium-55n54; range_end:; response_count:1; response_revision:587; }","duration":"374.218333ms","start":"2026-02-08T22:14:01.336118Z","end":"2026-02-08T22:14:01.710336Z","steps":["trace[684082123] 'range keys from in-memory index tree' (duration: 373.913444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:01.710396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:01.336096Z","time spent":"374.289175ms","remote":"127.0.0.1:56972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16679,"request content":"key:\"/registry/pods/kube-system/cilium-55n54\" "} {"level":"info","ts":"2026-02-08T22:14:02.150502Z","caller":"traceutil/trace.go:171","msg":"trace[1498612292] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"423.217381ms","start":"2026-02-08T22:14:01.727262Z","end":"2026-02-08T22:14:02.15048Z","steps":["trace[1498612292] 'process raft request' (duration: 422.86308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:02.150625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:01.727247Z","time spent":"423.312503ms","remote":"127.0.0.1:56972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:14:02.394163Z","caller":"traceutil/trace.go:171","msg":"trace[632160832] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"236.713427ms","start":"2026-02-08T22:14:02.157432Z","end":"2026-02-08T22:14:02.394146Z","steps":["trace[632160832] 'process raft request' (duration: 236.628204ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:02.398573Z","caller":"traceutil/trace.go:171","msg":"trace[223654429] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"238.618923ms","start":"2026-02-08T22:14:02.159943Z","end":"2026-02-08T22:14:02.398562Z","steps":["trace[223654429] 'process raft request' (duration: 238.111978ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:07.668402Z","caller":"traceutil/trace.go:171","msg":"trace[1060918005] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"124.408954ms","start":"2026-02-08T22:14:07.543974Z","end":"2026-02-08T22:14:07.668383Z","steps":["trace[1060918005] 'process raft request' (duration: 124.286441ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:14.432516Z","caller":"traceutil/trace.go:171","msg":"trace[1912190236] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"105.464892ms","start":"2026-02-08T22:14:14.327028Z","end":"2026-02-08T22:14:14.432493Z","steps":["trace[1912190236] 'process raft request' (duration: 104.71557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:14.698925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.033813ms","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-08T22:14:14.698995Z","caller":"traceutil/trace.go:171","msg":"trace[1424376867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:669; }","duration":"171.142646ms","start":"2026-02-08T22:14:14.527837Z","end":"2026-02-08T22:14:14.69898Z","steps":["trace[1424376867] 'range keys from in-memory index tree' (duration: 170.890599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:14.699265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.694489ms","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-08T22:14:14.699291Z","caller":"traceutil/trace.go:171","msg":"trace[426515886] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:669; }","duration":"163.745021ms","start":"2026-02-08T22:14:14.535538Z","end":"2026-02-08T22:14:14.699283Z","steps":["trace[426515886] 'range keys from in-memory index tree' (duration: 163.631427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:14.699443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.983181ms","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-08T22:14:14.699467Z","caller":"traceutil/trace.go:171","msg":"trace[1449082756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:669; }","duration":"161.014701ms","start":"2026-02-08T22:14:14.538445Z","end":"2026-02-08T22:14:14.69946Z","steps":["trace[1449082756] 'range keys from in-memory index tree' (duration: 160.922749ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:14.878822Z","caller":"traceutil/trace.go:171","msg":"trace[1495825982] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"174.195935ms","start":"2026-02-08T22:14:14.704611Z","end":"2026-02-08T22:14:14.878806Z","steps":["trace[1495825982] 'process raft request' (duration: 174.100723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:15.309486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.942787ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T22:14:15.309643Z","caller":"traceutil/trace.go:171","msg":"trace[132670722] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:688; }","duration":"594.362576ms","start":"2026-02-08T22:14:14.715263Z","end":"2026-02-08T22:14:15.309626Z","steps":["trace[132670722] 'read index received' (duration: 163.867384ms)","trace[132670722] 'applied index is now lower than readState.Index' (duration: 430.494322ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:14:15.309889Z","caller":"traceutil/trace.go:171","msg":"trace[45129129] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"605.132191ms","start":"2026-02-08T22:14:14.704744Z","end":"2026-02-08T22:14:15.309876Z","steps":["trace[45129129] 'process raft request' (duration: 326.528984ms)","trace[45129129] 'compare' (duration: 277.310849ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:14:15.309996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:14.704738Z","time spent":"605.181762ms","remote":"127.0.0.1:57040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:14:15.310205Z","caller":"traceutil/trace.go:171","msg":"trace[481827641] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"603.380929ms","start":"2026-02-08T22:14:14.706812Z","end":"2026-02-08T22:14:15.310193Z","steps":["trace[481827641] 'process raft request' (duration: 602.752491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:15.310259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:14.706803Z","time spent":"603.426241ms","remote":"127.0.0.1:57040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:14:15.310458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.190331ms","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-08T22:14:15.310487Z","caller":"traceutil/trace.go:171","msg":"trace[1762843083] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:672; }","duration":"595.228862ms","start":"2026-02-08T22:14:14.715249Z","end":"2026-02-08T22:14:15.310478Z","steps":["trace[1762843083] 'agreement among raft nodes before linearized reading' (duration: 595.125309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:15.310515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:14.715243Z","time spent":"595.265433ms","remote":"127.0.0.1:57040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T22:14:15.568888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.896351ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:14:15.569004Z","caller":"traceutil/trace.go:171","msg":"trace[1280554890] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:672; }","duration":"158.037775ms","start":"2026-02-08T22:14:15.41095Z","end":"2026-02-08T22:14:15.568988Z","steps":["trace[1280554890] 'range keys from in-memory index tree' (duration: 157.8826ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:15.569315Z","caller":"traceutil/trace.go:171","msg":"trace[740945991] linearizableReadLoop","detail":"{readStateIndex:691; appliedIndex:690; }","duration":"259.610804ms","start":"2026-02-08T22:14:15.309693Z","end":"2026-02-08T22:14:15.569304Z","steps":["trace[740945991] 'read index received' (duration: 231.723891ms)","trace[740945991] 'applied index is now lower than readState.Index' (duration: 27.886283ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:14:15.569452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.742089ms","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-08T22:14:15.569477Z","caller":"traceutil/trace.go:171","msg":"trace[1913356897] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:673; }","duration":"465.797311ms","start":"2026-02-08T22:14:15.103672Z","end":"2026-02-08T22:14:15.569469Z","steps":["trace[1913356897] 'agreement among raft nodes before linearized reading' (duration: 465.670537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:15.569509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:15.103657Z","time spent":"465.842943ms","remote":"127.0.0.1:57040","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-08T22:14:15.572803Z","caller":"traceutil/trace.go:171","msg":"trace[1877274279] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"258.757568ms","start":"2026-02-08T22:14:15.314031Z","end":"2026-02-08T22:14:15.572789Z","steps":["trace[1877274279] 'process raft request' (duration: 258.565773ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:15.573048Z","caller":"traceutil/trace.go:171","msg":"trace[1266469875] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"281.255535ms","start":"2026-02-08T22:14:15.291779Z","end":"2026-02-08T22:14:15.573034Z","steps":["trace[1266469875] 'process raft request' (duration: 249.627393ms)","trace[1266469875] 'compare' (duration: 27.80495ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:14:15.573643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.438305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-08T22:14:15.573706Z","caller":"traceutil/trace.go:171","msg":"trace[288063295] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:674; }","duration":"367.541028ms","start":"2026-02-08T22:14:15.206155Z","end":"2026-02-08T22:14:15.573696Z","steps":["trace[288063295] 'agreement among raft nodes before linearized reading' (duration: 367.409754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:15.573738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:15.206136Z","time spent":"367.5929ms","remote":"127.0.0.1:56840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.233\" "} {"level":"info","ts":"2026-02-08T22:14:23.920532Z","caller":"traceutil/trace.go:171","msg":"trace[1558029558] linearizableReadLoop","detail":"{readStateIndex:758; appliedIndex:757; }","duration":"314.383759ms","start":"2026-02-08T22:14:23.606127Z","end":"2026-02-08T22:14:23.920511Z","steps":["trace[1558029558] 'read index received' (duration: 314.212125ms)","trace[1558029558] 'applied index is now lower than readState.Index' (duration: 170.744µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:14:23.920637Z","caller":"traceutil/trace.go:171","msg":"trace[55670347] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"417.316614ms","start":"2026-02-08T22:14:23.503311Z","end":"2026-02-08T22:14:23.920628Z","steps":["trace[55670347] 'process raft request' (duration: 417.075207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:23.92073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:23.503285Z","time spent":"417.375186ms","remote":"127.0.0.1:56870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-08T22:14:23.921049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.887724ms","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-08T22:14:23.921083Z","caller":"traceutil/trace.go:171","msg":"trace[1157795347] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:738; }","duration":"314.987936ms","start":"2026-02-08T22:14:23.606084Z","end":"2026-02-08T22:14:23.921072Z","steps":["trace[1157795347] 'agreement among raft nodes before linearized reading' (duration: 314.818441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:23.921111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:23.606068Z","time spent":"315.036587ms","remote":"127.0.0.1:57040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T22:14:23.921317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.530868ms","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-08T22:14:23.921346Z","caller":"traceutil/trace.go:171","msg":"trace[1222263696] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:738; }","duration":"232.58075ms","start":"2026-02-08T22:14:23.688757Z","end":"2026-02-08T22:14:23.921337Z","steps":["trace[1222263696] 'agreement among raft nodes before linearized reading' (duration: 232.499097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:24.315206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.419772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T22:14:24.31539Z","caller":"traceutil/trace.go:171","msg":"trace[1967162308] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"387.66185ms","start":"2026-02-08T22:14:23.927701Z","end":"2026-02-08T22:14:24.315362Z","steps":["trace[1967162308] 'process raft request' (duration: 127.014492ms)","trace[1967162308] 'compare' (duration: 260.332269ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:14:24.315541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:23.927685Z","time spent":"387.765152ms","remote":"127.0.0.1:57040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:14:24.315724Z","caller":"traceutil/trace.go:171","msg":"trace[1473372612] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"386.034514ms","start":"2026-02-08T22:14:23.929679Z","end":"2026-02-08T22:14:24.315713Z","steps":["trace[1473372612] 'process raft request' (duration: 385.630852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:24.315806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:23.929664Z","time spent":"386.109676ms","remote":"127.0.0.1:57040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:14:25.445417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.75731ms","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-08T22:14:25.445486Z","caller":"traceutil/trace.go:171","msg":"trace[36672695] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:743; }","duration":"102.850862ms","start":"2026-02-08T22:14:25.342618Z","end":"2026-02-08T22:14:25.445469Z","steps":["trace[36672695] 'range keys from in-memory index tree' (duration: 102.631136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:25.445711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.443254ms","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-08T22:14:25.445739Z","caller":"traceutil/trace.go:171","msg":"trace[498304654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:743; }","duration":"100.482895ms","start":"2026-02-08T22:14:25.345247Z","end":"2026-02-08T22:14:25.44573Z","steps":["trace[498304654] 'range keys from in-memory index tree' (duration: 100.366642ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:39.853365Z","caller":"traceutil/trace.go:171","msg":"trace[1779851608] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"344.039523ms","start":"2026-02-08T22:14:39.509304Z","end":"2026-02-08T22:14:39.853344Z","steps":["trace[1779851608] 'process raft request' (duration: 343.881729ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:39.85367Z","caller":"traceutil/trace.go:171","msg":"trace[1752267376] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:813; }","duration":"332.782397ms","start":"2026-02-08T22:14:39.520873Z","end":"2026-02-08T22:14:39.853655Z","steps":["trace[1752267376] 'read index received' (duration: 332.776107ms)","trace[1752267376] 'applied index is now lower than readState.Index' (duration: 5.35µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:14:39.854006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:39.509287Z","time spent":"344.596009ms","remote":"127.0.0.1:57040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:14:39.854306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.427395ms","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-08T22:14:39.855504Z","caller":"traceutil/trace.go:171","msg":"trace[799613222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:789; }","duration":"334.65234ms","start":"2026-02-08T22:14:39.520837Z","end":"2026-02-08T22:14:39.85549Z","steps":["trace[799613222] 'agreement among raft nodes before linearized reading' (duration: 333.389784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:39.855692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:39.520745Z","time spent":"334.856925ms","remote":"127.0.0.1:57040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-08T22:14:40.150475Z","caller":"traceutil/trace.go:171","msg":"trace[676995712] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"289.87798ms","start":"2026-02-08T22:14:39.860579Z","end":"2026-02-08T22:14:40.150457Z","steps":["trace[676995712] 'process raft request' (duration: 289.739486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:40.532163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.221125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T22:14:40.532593Z","caller":"traceutil/trace.go:171","msg":"trace[155627568] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:790; }","duration":"234.706049ms","start":"2026-02-08T22:14:40.297876Z","end":"2026-02-08T22:14:40.532582Z","steps":["trace[155627568] 'count revisions from in-memory index tree' (duration: 234.124093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:40.532442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.254079ms","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-08T22:14:40.532704Z","caller":"traceutil/trace.go:171","msg":"trace[1523202360] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:790; }","duration":"158.553018ms","start":"2026-02-08T22:14:40.374147Z","end":"2026-02-08T22:14:40.5327Z","steps":["trace[1523202360] 'range keys from in-memory index tree' (duration: 158.128826ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:40.532483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.588133ms","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-08T22:14:40.532784Z","caller":"traceutil/trace.go:171","msg":"trace[585915170] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:790; }","duration":"151.916131ms","start":"2026-02-08T22:14:40.380863Z","end":"2026-02-08T22:14:40.532779Z","steps":["trace[585915170] 'range keys from in-memory index tree' (duration: 151.51477ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:40.690598Z","caller":"traceutil/trace.go:171","msg":"trace[1745089600] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"153.856226ms","start":"2026-02-08T22:14:40.536725Z","end":"2026-02-08T22:14:40.690581Z","steps":["trace[1745089600] 'process raft request' (duration: 133.01443ms)","trace[1745089600] 'compare' (duration: 20.734513ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:14:56.096334Z","caller":"traceutil/trace.go:171","msg":"trace[1078959683] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"181.419394ms","start":"2026-02-08T22:14:55.9149Z","end":"2026-02-08T22:14:56.09632Z","steps":["trace[1078959683] 'process raft request' (duration: 181.335882ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:56.888629Z","caller":"traceutil/trace.go:171","msg":"trace[860679885] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"179.961864ms","start":"2026-02-08T22:14:56.708652Z","end":"2026-02-08T22:14:56.888614Z","steps":["trace[860679885] 'process raft request' (duration: 179.857222ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:14:56.888775Z","caller":"traceutil/trace.go:171","msg":"trace[1182535728] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:921; }","duration":"134.428585ms","start":"2026-02-08T22:14:56.754341Z","end":"2026-02-08T22:14:56.88877Z","steps":["trace[1182535728] 'read index received' (duration: 134.426465ms)","trace[1182535728] 'applied index is now lower than readState.Index' (duration: 1.83µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:14:56.888891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.543647ms","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-08T22:14:56.888907Z","caller":"traceutil/trace.go:171","msg":"trace[433920638] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:891; }","duration":"134.571348ms","start":"2026-02-08T22:14:56.754332Z","end":"2026-02-08T22:14:56.888903Z","steps":["trace[433920638] 'agreement among raft nodes before linearized reading' (duration: 134.455055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:57.630374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.651758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:14:57.630451Z","caller":"traceutil/trace.go:171","msg":"trace[1235323040] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:892; }","duration":"119.774872ms","start":"2026-02-08T22:14:57.510655Z","end":"2026-02-08T22:14:57.63043Z","steps":["trace[1235323040] 'count revisions from in-memory index tree' (duration: 119.588917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:57.630848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.584751ms","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-08T22:14:57.630863Z","caller":"traceutil/trace.go:171","msg":"trace[1213212478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:892; }","duration":"397.621752ms","start":"2026-02-08T22:14:57.233237Z","end":"2026-02-08T22:14:57.630859Z","steps":["trace[1213212478] 'range keys from in-memory index tree' (duration: 397.506579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:14:57.630879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:14:57.233225Z","time spent":"397.649113ms","remote":"127.0.0.1:57040","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-08T22:14:57.630946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.595407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:14:57.630957Z","caller":"traceutil/trace.go:171","msg":"trace[948034441] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:892; }","duration":"232.633178ms","start":"2026-02-08T22:14:57.39832Z","end":"2026-02-08T22:14:57.630953Z","steps":["trace[948034441] 'range keys from in-memory index tree' (duration: 232.551886ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:05.070107Z","caller":"traceutil/trace.go:171","msg":"trace[1963432696] linearizableReadLoop","detail":"{readStateIndex:943; appliedIndex:942; }","duration":"155.095599ms","start":"2026-02-08T22:15:04.914994Z","end":"2026-02-08T22:15:05.07009Z","steps":["trace[1963432696] 'read index received' (duration: 154.967505ms)","trace[1963432696] 'applied index is now lower than readState.Index' (duration: 127.524µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:05.070232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.227612ms","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-08T22:15:05.07025Z","caller":"traceutil/trace.go:171","msg":"trace[1699320184] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:912; }","duration":"155.267704ms","start":"2026-02-08T22:15:04.914976Z","end":"2026-02-08T22:15:05.070244Z","steps":["trace[1699320184] 'agreement among raft nodes before linearized reading' (duration: 155.176371ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:05.07046Z","caller":"traceutil/trace.go:171","msg":"trace[290813448] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"155.959543ms","start":"2026-02-08T22:15:04.914494Z","end":"2026-02-08T22:15:05.070454Z","steps":["trace[290813448] 'process raft request' (duration: 155.512131ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:09.414067Z","caller":"traceutil/trace.go:171","msg":"trace[738801199] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"326.632768ms","start":"2026-02-08T22:15:09.087413Z","end":"2026-02-08T22:15:09.414046Z","steps":["trace[738801199] 'process raft request' (duration: 326.449493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:09.414192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:09.087405Z","time spent":"326.72325ms","remote":"127.0.0.1:57040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:15:09.415778Z","caller":"traceutil/trace.go:171","msg":"trace[169812278] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:956; }","duration":"297.863387ms","start":"2026-02-08T22:15:09.117894Z","end":"2026-02-08T22:15:09.415758Z","steps":["trace[169812278] 'read index received' (duration: 296.705316ms)","trace[169812278] 'applied index is now lower than readState.Index' (duration: 1.157131ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:15:09.416006Z","caller":"traceutil/trace.go:171","msg":"trace[42867768] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"309.016166ms","start":"2026-02-08T22:15:09.106981Z","end":"2026-02-08T22:15:09.415997Z","steps":["trace[42867768] 'process raft request' (duration: 308.678366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:09.416085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:09.106972Z","time spent":"309.053557ms","remote":"127.0.0.1:57040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:15:09.416287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.390231ms","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-08T22:15:09.416308Z","caller":"traceutil/trace.go:171","msg":"trace[893459092] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:925; }","duration":"298.432503ms","start":"2026-02-08T22:15:09.117869Z","end":"2026-02-08T22:15:09.416302Z","steps":["trace[893459092] 'agreement among raft nodes before linearized reading' (duration: 298.33569ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:13.504491Z","caller":"traceutil/trace.go:171","msg":"trace[350605164] linearizableReadLoop","detail":"{readStateIndex:986; appliedIndex:985; }","duration":"106.865915ms","start":"2026-02-08T22:15:13.397603Z","end":"2026-02-08T22:15:13.504469Z","steps":["trace[350605164] 'read index received' (duration: 106.702231ms)","trace[350605164] 'applied index is now lower than readState.Index' (duration: 163.034µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:15:13.504695Z","caller":"traceutil/trace.go:171","msg":"trace[941647228] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"153.420154ms","start":"2026-02-08T22:15:13.351267Z","end":"2026-02-08T22:15:13.504687Z","steps":["trace[941647228] 'process raft request' (duration: 153.085975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:13.504837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.228016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:13.504862Z","caller":"traceutil/trace.go:171","msg":"trace[1390366862] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:953; }","duration":"107.286537ms","start":"2026-02-08T22:15:13.397564Z","end":"2026-02-08T22:15:13.504851Z","steps":["trace[1390366862] 'agreement among raft nodes before linearized reading' (duration: 107.242085ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:15.436317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.013779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:15.436369Z","caller":"traceutil/trace.go:171","msg":"trace[285728019] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:960; }","duration":"446.108872ms","start":"2026-02-08T22:15:14.990242Z","end":"2026-02-08T22:15:15.43635Z","steps":["trace[285728019] 'count revisions from in-memory index tree' (duration: 445.964238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:15.436395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:15:14.990227Z","time spent":"446.161303ms","remote":"127.0.0.1:57072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-02-08T22:15:15.436649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.336412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-08T22:15:15.436664Z","caller":"traceutil/trace.go:171","msg":"trace[751511272] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:960; }","duration":"228.375454ms","start":"2026-02-08T22:15:15.208283Z","end":"2026-02-08T22:15:15.436659Z","steps":["trace[751511272] 'range keys from in-memory index tree' (duration: 228.2493ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:15.71658Z","caller":"traceutil/trace.go:171","msg":"trace[1120368233] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"193.653013ms","start":"2026-02-08T22:15:15.522702Z","end":"2026-02-08T22:15:15.716355Z","steps":["trace[1120368233] 'process raft request' (duration: 162.083706ms)","trace[1120368233] 'compare' (duration: 31.454883ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:15:15.758306Z","caller":"traceutil/trace.go:171","msg":"trace[230647029] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:995; }","duration":"185.872344ms","start":"2026-02-08T22:15:15.572417Z","end":"2026-02-08T22:15:15.758289Z","steps":["trace[230647029] 'read index received' (duration: 112.377913ms)","trace[230647029] 'applied index is now lower than readState.Index' (duration: 73.493611ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:15:15.758435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.995997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:15.758466Z","caller":"traceutil/trace.go:171","msg":"trace[2051350148] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:963; }","duration":"186.072769ms","start":"2026-02-08T22:15:15.572385Z","end":"2026-02-08T22:15:15.758457Z","steps":["trace[2051350148] 'agreement among raft nodes before linearized reading' (duration: 185.969377ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:15.758774Z","caller":"traceutil/trace.go:171","msg":"trace[935863576] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"235.383551ms","start":"2026-02-08T22:15:15.523366Z","end":"2026-02-08T22:15:15.75875Z","steps":["trace[935863576] 'process raft request' (duration: 234.850107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:16.201092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.947326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-08T22:15:16.201149Z","caller":"traceutil/trace.go:171","msg":"trace[315197073] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:967; }","duration":"186.029208ms","start":"2026-02-08T22:15:16.015102Z","end":"2026-02-08T22:15:16.201131Z","steps":["trace[315197073] 'range keys from in-memory index tree' (duration: 185.893195ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:16.201473Z","caller":"traceutil/trace.go:171","msg":"trace[1053728177] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"184.789455ms","start":"2026-02-08T22:15:16.016677Z","end":"2026-02-08T22:15:16.201466Z","steps":["trace[1053728177] 'process raft request' (duration: 184.724023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:24.545466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.668452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:15:24.545577Z","caller":"traceutil/trace.go:171","msg":"trace[23701212] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1021; }","duration":"149.842007ms","start":"2026-02-08T22:15:24.395712Z","end":"2026-02-08T22:15:24.545554Z","steps":["trace[23701212] 'range keys from in-memory index tree' (duration: 149.613111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:24.545622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.853733ms","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-08T22:15:24.54568Z","caller":"traceutil/trace.go:171","msg":"trace[591542884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1021; }","duration":"116.956395ms","start":"2026-02-08T22:15:24.428709Z","end":"2026-02-08T22:15:24.545666Z","steps":["trace[591542884] 'range keys from in-memory index tree' (duration: 116.613327ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:15:24.734941Z","caller":"traceutil/trace.go:171","msg":"trace[2030436282] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"183.88403ms","start":"2026-02-08T22:15:24.551044Z","end":"2026-02-08T22:15:24.734928Z","steps":["trace[2030436282] 'process raft request' (duration: 183.610313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:15:24.951612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.373006ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T22:15:24.955962Z","caller":"traceutil/trace.go:171","msg":"trace[410804930] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"210.061093ms","start":"2026-02-08T22:15:24.745889Z","end":"2026-02-08T22:15:24.95595Z","steps":["trace[410804930] 'compare' (duration: 199.262413ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:18:36.931092Z","caller":"traceutil/trace.go:171","msg":"trace[301112925] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"111.28553ms","start":"2026-02-08T22:18:36.819777Z","end":"2026-02-08T22:18:36.931062Z","steps":["trace[301112925] 'process raft request' (duration: 109.926772ms)"],"step_count":1}