{"level":"warn","ts":"2026-02-11T01:50:05.11418Z","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-11T01:50:05.114253Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.205: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.205:2380","--initial-cluster=instance=https://199.204.45.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.205: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-11T01:50:05.114319Z","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-11T01:50:05.114332Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-02-11T01:50:05.114355Z","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-11T01:50:05.117348Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"]} {"level":"info","ts":"2026-02-11T01:50:05.117431Z","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.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.205: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-11T01:50:05.12368Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.99726ms"} {"level":"info","ts":"2026-02-11T01:50:05.12866Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"71d28e9ac1bc4e5d","cluster-id":"4a4ae660c7a9a6a7"} {"level":"info","ts":"2026-02-11T01:50:05.12871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=()"} {"level":"info","ts":"2026-02-11T01:50:05.128731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 0"} {"level":"info","ts":"2026-02-11T01:50:05.128742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 71d28e9ac1bc4e5d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-11T01:50:05.128749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 1"} {"level":"info","ts":"2026-02-11T01:50:05.128781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"warn","ts":"2026-02-11T01:50:05.135454Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-11T01:50:05.137606Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-11T01:50:05.141028Z","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-11T01:50:05.148708Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"71d28e9ac1bc4e5d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-11T01:50:05.15255Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"71d28e9ac1bc4e5d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-11T01:50:05.152755Z","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-11T01:50:05.152788Z","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-11T01:50:05.152797Z","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-11T01:50:05.164214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"info","ts":"2026-02-11T01:50:05.164316Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","added-peer-id":"71d28e9ac1bc4e5d","added-peer-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-02-11T01:50:05.16822Z","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-11T01:50:05.168568Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"71d28e9ac1bc4e5d","initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-11T01:50:05.168593Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-11T01:50:05.168616Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-02-11T01:50:05.168625Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-02-11T01:50:05.231392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d is starting a new election at term 1"} {"level":"info","ts":"2026-02-11T01:50:05.231434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became pre-candidate at term 1"} {"level":"info","ts":"2026-02-11T01:50:05.231452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgPreVoteResp from 71d28e9ac1bc4e5d at term 1"} {"level":"info","ts":"2026-02-11T01:50:05.231465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became candidate at term 2"} {"level":"info","ts":"2026-02-11T01:50:05.231488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgVoteResp from 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-02-11T01:50:05.231543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became leader at term 2"} {"level":"info","ts":"2026-02-11T01:50:05.231551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 71d28e9ac1bc4e5d elected leader 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-02-11T01:50:05.234724Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"71d28e9ac1bc4e5d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.205:2379]}","request-path":"/0/members/71d28e9ac1bc4e5d/attributes","cluster-id":"4a4ae660c7a9a6a7","publish-timeout":"7s"} {"level":"info","ts":"2026-02-11T01:50:05.234952Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:50:05.235055Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T01:50:05.235356Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T01:50:05.236226Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-11T01:50:05.236242Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-11T01:50:05.236258Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:50:05.2363Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:50:05.236315Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:50:05.237384Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-11T01:50:05.239744Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"} {"level":"info","ts":"2026-02-11T01:50:22.809234Z","caller":"traceutil/trace.go:171","msg":"trace[102211788] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"478.259204ms","start":"2026-02-11T01:50:22.330955Z","end":"2026-02-11T01:50:22.809214Z","steps":["trace[102211788] 'process raft request' (duration: 478.135801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:22.809666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:50:22.330938Z","time spent":"478.368156ms","remote":"127.0.0.1:33276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7222,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:50:28.315673Z","caller":"traceutil/trace.go:171","msg":"trace[2135520624] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"219.043822ms","start":"2026-02-11T01:50:28.096611Z","end":"2026-02-11T01:50:28.315655Z","steps":["trace[2135520624] 'process raft request' (duration: 215.981961ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:50:31.626999Z","caller":"traceutil/trace.go:171","msg":"trace[1459639559] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:439; }","duration":"123.416701ms","start":"2026-02-11T01:50:31.503561Z","end":"2026-02-11T01:50:31.626978Z","steps":["trace[1459639559] 'read index received' (duration: 123.224287ms)","trace[1459639559] 'applied index is now lower than readState.Index' (duration: 191.524µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:50:31.627121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.538755ms","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-11T01:50:31.627169Z","caller":"traceutil/trace.go:171","msg":"trace[155144402] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:428; }","duration":"123.633686ms","start":"2026-02-11T01:50:31.503524Z","end":"2026-02-11T01:50:31.627158Z","steps":["trace[155144402] 'agreement among raft nodes before linearized reading' (duration: 123.538384ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:50:31.627251Z","caller":"traceutil/trace.go:171","msg":"trace[905713439] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"341.115952ms","start":"2026-02-11T01:50:31.286112Z","end":"2026-02-11T01:50:31.627228Z","steps":["trace[905713439] 'process raft request' (duration: 340.696541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:31.627385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:50:31.286097Z","time spent":"341.216315ms","remote":"127.0.0.1:33340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:50:36.133545Z","caller":"traceutil/trace.go:171","msg":"trace[1494154620] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:484; }","duration":"204.515814ms","start":"2026-02-11T01:50:35.929014Z","end":"2026-02-11T01:50:36.13353Z","steps":["trace[1494154620] 'read index received' (duration: 204.413912ms)","trace[1494154620] 'applied index is now lower than readState.Index' (duration: 101.322µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:50:36.133623Z","caller":"traceutil/trace.go:171","msg":"trace[1518379160] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"204.863922ms","start":"2026-02-11T01:50:35.928754Z","end":"2026-02-11T01:50:36.133618Z","steps":["trace[1518379160] 'process raft request' (duration: 204.693248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:36.133723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.695668ms","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-11T01:50:36.13374Z","caller":"traceutil/trace.go:171","msg":"trace[909138526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:472; }","duration":"204.743729ms","start":"2026-02-11T01:50:35.928991Z","end":"2026-02-11T01:50:36.133734Z","steps":["trace[909138526] 'agreement among raft nodes before linearized reading' (duration: 204.702318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:36.133788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.625785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:50:36.133798Z","caller":"traceutil/trace.go:171","msg":"trace[2063465368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:472; }","duration":"105.658455ms","start":"2026-02-11T01:50:36.028137Z","end":"2026-02-11T01:50:36.133795Z","steps":["trace[2063465368] 'agreement among raft nodes before linearized reading' (duration: 105.641305ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:50:36.456892Z","caller":"traceutil/trace.go:171","msg":"trace[621080093] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"165.837542ms","start":"2026-02-11T01:50:36.291038Z","end":"2026-02-11T01:50:36.456876Z","steps":["trace[621080093] 'process raft request' (duration: 165.713929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:39.529642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:50:39.192711Z","time spent":"336.927074ms","remote":"127.0.0.1:33114","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-11T01:50:39.837877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.943428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-11T01:50:39.83795Z","caller":"traceutil/trace.go:171","msg":"trace[424065468] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:480; }","duration":"266.05381ms","start":"2026-02-11T01:50:39.57188Z","end":"2026-02-11T01:50:39.837933Z","steps":["trace[424065468] 'range keys from in-memory index tree' (duration: 265.850285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:39.838275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.254384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-11T01:50:39.838318Z","caller":"traceutil/trace.go:171","msg":"trace[624249007] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:480; }","duration":"182.366587ms","start":"2026-02-11T01:50:39.65594Z","end":"2026-02-11T01:50:39.838307Z","steps":["trace[624249007] 'range keys from in-memory index tree' (duration: 182.104051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:39.839061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.119402ms","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-11T01:50:39.839108Z","caller":"traceutil/trace.go:171","msg":"trace[653544458] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:480; }","duration":"179.178063ms","start":"2026-02-11T01:50:39.659919Z","end":"2026-02-11T01:50:39.839097Z","steps":["trace[653544458] 'range keys from in-memory index tree' (duration: 179.05068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:44.362616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.729865ms","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-11T01:50:44.362691Z","caller":"traceutil/trace.go:171","msg":"trace[1427567853] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:492; }","duration":"469.857128ms","start":"2026-02-11T01:50:43.892817Z","end":"2026-02-11T01:50:44.362675Z","steps":["trace[1427567853] 'range keys from in-memory index tree' (duration: 469.584461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:44.362728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:50:43.892795Z","time spent":"469.924249ms","remote":"127.0.0.1:33340","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-11T01:50:44.363561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.112446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-11T01:50:44.363603Z","caller":"traceutil/trace.go:171","msg":"trace[964163884] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:492; }","duration":"468.175258ms","start":"2026-02-11T01:50:43.895418Z","end":"2026-02-11T01:50:44.363593Z","steps":["trace[964163884] 'range keys from in-memory index tree' (duration: 467.986104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:44.363633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:50:43.895408Z","time spent":"468.218319ms","remote":"127.0.0.1:33340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T01:50:44.363793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.358789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:50:44.363831Z","caller":"traceutil/trace.go:171","msg":"trace[811074406] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:492; }","duration":"332.41831ms","start":"2026-02-11T01:50:44.031399Z","end":"2026-02-11T01:50:44.363817Z","steps":["trace[811074406] 'range keys from in-memory index tree' (duration: 332.314438ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:44.363856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:50:44.031368Z","time spent":"332.482021ms","remote":"127.0.0.1:33096","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-11T01:50:44.364086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.69833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-h6vcn\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-11T01:50:44.364121Z","caller":"traceutil/trace.go:171","msg":"trace[1815695489] range","detail":"{range_begin:/registry/pods/kube-system/cilium-h6vcn; range_end:; response_count:1; response_revision:492; }","duration":"318.7465ms","start":"2026-02-11T01:50:44.045366Z","end":"2026-02-11T01:50:44.364113Z","steps":["trace[1815695489] 'range keys from in-memory index tree' (duration: 318.623288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:44.364149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:50:44.045358Z","time spent":"318.782752ms","remote":"127.0.0.1:33276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16678,"request content":"key:\"/registry/pods/kube-system/cilium-h6vcn\" "} {"level":"info","ts":"2026-02-11T01:50:44.772962Z","caller":"traceutil/trace.go:171","msg":"trace[38545312] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"401.070752ms","start":"2026-02-11T01:50:44.371867Z","end":"2026-02-11T01:50:44.772938Z","steps":["trace[38545312] 'process raft request' (duration: 400.909619ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:44.773118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:50:44.371855Z","time spent":"401.184995ms","remote":"127.0.0.1:33340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:50:44.950476Z","caller":"traceutil/trace.go:171","msg":"trace[612841732] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"575.449833ms","start":"2026-02-11T01:50:44.375004Z","end":"2026-02-11T01:50:44.950454Z","steps":["trace[612841732] 'process raft request' (duration: 575.298599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:44.950597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:50:44.374998Z","time spent":"575.534285ms","remote":"127.0.0.1:33340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:50:44.950747Z","caller":"traceutil/trace.go:171","msg":"trace[1888333986] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"575.700799ms","start":"2026-02-11T01:50:44.375038Z","end":"2026-02-11T01:50:44.950739Z","steps":["trace[1888333986] 'process raft request' (duration: 575.387301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:44.950934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:50:44.375034Z","time spent":"575.876112ms","remote":"127.0.0.1:33340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:50:44.951333Z","caller":"traceutil/trace.go:171","msg":"trace[419852673] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"574.552771ms","start":"2026-02-11T01:50:44.376759Z","end":"2026-02-11T01:50:44.951312Z","steps":["trace[419852673] 'process raft request' (duration: 573.951047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:44.95138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:50:44.376754Z","time spent":"574.600482ms","remote":"127.0.0.1:33340","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-11T01:50:44.951433Z","caller":"traceutil/trace.go:171","msg":"trace[913651291] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"573.332092ms","start":"2026-02-11T01:50:44.378094Z","end":"2026-02-11T01:50:44.951426Z","steps":["trace[913651291] 'process raft request' (duration: 572.963034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:44.951464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:50:44.378088Z","time spent":"573.358294ms","remote":"127.0.0.1:33276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:50:58.388007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.923056ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:50:58.388112Z","caller":"traceutil/trace.go:171","msg":"trace[39970811] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"171.294508ms","start":"2026-02-11T01:50:58.2168Z","end":"2026-02-11T01:50:58.388094Z","steps":["trace[39970811] 'process raft request' (duration: 67.052185ms)","trace[39970811] 'compare' (duration: 103.722111ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:50:58.431639Z","caller":"traceutil/trace.go:171","msg":"trace[2097808747] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"211.711763ms","start":"2026-02-11T01:50:58.219909Z","end":"2026-02-11T01:50:58.431621Z","steps":["trace[2097808747] 'process raft request' (duration: 211.679052ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:50:58.431934Z","caller":"traceutil/trace.go:171","msg":"trace[2097423531] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"214.944438ms","start":"2026-02-11T01:50:58.216983Z","end":"2026-02-11T01:50:58.431927Z","steps":["trace[2097423531] 'process raft request' (duration: 214.497327ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:50:58.432067Z","caller":"traceutil/trace.go:171","msg":"trace[864709314] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"215.009429ms","start":"2026-02-11T01:50:58.217053Z","end":"2026-02-11T01:50:58.432063Z","steps":["trace[864709314] 'process raft request' (duration: 214.510487ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:50:58.722806Z","caller":"traceutil/trace.go:171","msg":"trace[1271861833] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"284.318205ms","start":"2026-02-11T01:50:58.438474Z","end":"2026-02-11T01:50:58.722792Z","steps":["trace[1271861833] 'process raft request' (duration: 284.219053ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:50:58.72841Z","caller":"traceutil/trace.go:171","msg":"trace[2027370386] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"287.14699ms","start":"2026-02-11T01:50:58.441248Z","end":"2026-02-11T01:50:58.728395Z","steps":["trace[2027370386] 'process raft request' (duration: 287.001037ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:50:58.728559Z","caller":"traceutil/trace.go:171","msg":"trace[1363704351] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"287.252822ms","start":"2026-02-11T01:50:58.441301Z","end":"2026-02-11T01:50:58.728554Z","steps":["trace[1363704351] 'process raft request' (duration: 287.054988ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:50:58.72881Z","caller":"traceutil/trace.go:171","msg":"trace[1687706453] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"283.023512ms","start":"2026-02-11T01:50:58.445776Z","end":"2026-02-11T01:50:58.7288Z","steps":["trace[1687706453] 'process raft request' (duration: 282.754457ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:50:58.728859Z","caller":"traceutil/trace.go:171","msg":"trace[211159257] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"178.823564ms","start":"2026-02-11T01:50:58.550023Z","end":"2026-02-11T01:50:58.728847Z","steps":["trace[211159257] 'process raft request' (duration: 178.710542ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:00.797313Z","caller":"traceutil/trace.go:171","msg":"trace[665643356] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"636.177882ms","start":"2026-02-11T01:51:00.16112Z","end":"2026-02-11T01:51:00.797298Z","steps":["trace[665643356] 'process raft request' (duration: 636.07625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:00.797425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:00.161105Z","time spent":"636.269634ms","remote":"127.0.0.1:33156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":723,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T01:51:01.927339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.681575ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:51:01.927426Z","caller":"traceutil/trace.go:171","msg":"trace[2124093739] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:689; }","duration":"903.833713ms","start":"2026-02-11T01:51:01.023578Z","end":"2026-02-11T01:51:01.927412Z","steps":["trace[2124093739] 'read index received' (duration: 353.980244ms)","trace[2124093739] 'applied index is now lower than readState.Index' (duration: 549.852299ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:51:01.92758Z","caller":"traceutil/trace.go:171","msg":"trace[275298665] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"1.080194431s","start":"2026-02-11T01:51:00.847374Z","end":"2026-02-11T01:51:01.927569Z","steps":["trace[275298665] 'process raft request' (duration: 530.196229ms)","trace[275298665] 'compare' (duration: 549.373979ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:01.927635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:00.847357Z","time spent":"1.080248922s","remote":"127.0.0.1:33340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:51:01.927873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.291682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-11T01:51:01.927901Z","caller":"traceutil/trace.go:171","msg":"trace[1375409671] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:670; }","duration":"904.355384ms","start":"2026-02-11T01:51:01.023537Z","end":"2026-02-11T01:51:01.927892Z","steps":["trace[1375409671] 'agreement among raft nodes before linearized reading' (duration: 904.251602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:01.927928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:01.023518Z","time spent":"904.404495ms","remote":"127.0.0.1:33340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-11T01:51:01.938363Z","caller":"traceutil/trace.go:171","msg":"trace[679968895] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"259.920171ms","start":"2026-02-11T01:51:01.67843Z","end":"2026-02-11T01:51:01.93835Z","steps":["trace[679968895] 'process raft request' (duration: 259.84034ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:02.246931Z","caller":"traceutil/trace.go:171","msg":"trace[1208353994] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"514.753003ms","start":"2026-02-11T01:51:01.732151Z","end":"2026-02-11T01:51:02.246904Z","steps":["trace[1208353994] 'process raft request' (duration: 440.688956ms)","trace[1208353994] 'compare' (duration: 73.944124ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:51:02.246947Z","caller":"traceutil/trace.go:171","msg":"trace[1385732000] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:691; }","duration":"319.44714ms","start":"2026-02-11T01:51:01.92745Z","end":"2026-02-11T01:51:02.246897Z","steps":["trace[1385732000] 'read index received' (duration: 11.183674ms)","trace[1385732000] 'applied index is now lower than readState.Index' (duration: 308.262266ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:02.247057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:01.73213Z","time spent":"514.856555ms","remote":"127.0.0.1:33156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T01:51:02.247101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.216535195s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:02.247134Z","caller":"traceutil/trace.go:171","msg":"trace[360161298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:672; }","duration":"1.216601087s","start":"2026-02-11T01:51:01.030524Z","end":"2026-02-11T01:51:02.247125Z","steps":["trace[360161298] 'agreement among raft nodes before linearized reading' (duration: 1.216504735s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:02.247167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:01.030513Z","time spent":"1.216647028s","remote":"127.0.0.1:33094","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-11T01:51:02.24749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.116228967s","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-11T01:51:02.247519Z","caller":"traceutil/trace.go:171","msg":"trace[237902952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:672; }","duration":"1.116270848s","start":"2026-02-11T01:51:01.13124Z","end":"2026-02-11T01:51:02.247511Z","steps":["trace[237902952] 'agreement among raft nodes before linearized reading' (duration: 1.116154915s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:02.247539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:01.131233Z","time spent":"1.116301658s","remote":"127.0.0.1:33340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T01:51:02.24759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.153287486s","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-11T01:51:02.247631Z","caller":"traceutil/trace.go:171","msg":"trace[1101622141] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:672; }","duration":"1.153350308s","start":"2026-02-11T01:51:01.09427Z","end":"2026-02-11T01:51:02.247621Z","steps":["trace[1101622141] 'agreement among raft nodes before linearized reading' (duration: 1.153213555s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:02.247659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:01.094259Z","time spent":"1.153392999s","remote":"127.0.0.1:33340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T01:51:02.247689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.116416471s","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-11T01:51:02.24771Z","caller":"traceutil/trace.go:171","msg":"trace[689343567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:672; }","duration":"1.116444422s","start":"2026-02-11T01:51:01.13126Z","end":"2026-02-11T01:51:02.247704Z","steps":["trace[689343567] 'agreement among raft nodes before linearized reading' (duration: 1.116387201s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:02.247727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:01.131257Z","time spent":"1.116466162s","remote":"127.0.0.1:33340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-11T01:51:02.479201Z","caller":"traceutil/trace.go:171","msg":"trace[1069012463] linearizableReadLoop","detail":"{readStateIndex:693; appliedIndex:692; }","duration":"229.026628ms","start":"2026-02-11T01:51:02.250153Z","end":"2026-02-11T01:51:02.47918Z","steps":["trace[1069012463] 'read index received' (duration: 187.344698ms)","trace[1069012463] 'applied index is now lower than readState.Index' (duration: 41.67986ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:51:02.479205Z","caller":"traceutil/trace.go:171","msg":"trace[1051399681] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"224.174952ms","start":"2026-02-11T01:51:02.255009Z","end":"2026-02-11T01:51:02.479184Z","steps":["trace[1051399681] 'process raft request' (duration: 224.141701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:02.479312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.14001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:02.479333Z","caller":"traceutil/trace.go:171","msg":"trace[348275066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:676; }","duration":"229.191051ms","start":"2026-02-11T01:51:02.250135Z","end":"2026-02-11T01:51:02.479326Z","steps":["trace[348275066] 'agreement among raft nodes before linearized reading' (duration: 229.109449ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:02.479484Z","caller":"traceutil/trace.go:171","msg":"trace[1056115196] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"226.469522ms","start":"2026-02-11T01:51:02.253Z","end":"2026-02-11T01:51:02.47947Z","steps":["trace[1056115196] 'process raft request' (duration: 226.078174ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:02.479555Z","caller":"traceutil/trace.go:171","msg":"trace[555895709] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"541.344293ms","start":"2026-02-11T01:51:01.938201Z","end":"2026-02-11T01:51:02.479545Z","steps":["trace[555895709] 'process raft request' (duration: 499.288995ms)","trace[555895709] 'compare' (duration: 41.451495ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:02.479605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:01.938184Z","time spent":"541.386503ms","remote":"127.0.0.1:33340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:51:02.479604Z","caller":"traceutil/trace.go:171","msg":"trace[520495928] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"225.178314ms","start":"2026-02-11T01:51:02.254418Z","end":"2026-02-11T01:51:02.479596Z","steps":["trace[520495928] 'process raft request' (duration: 224.704684ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:10.771723Z","caller":"traceutil/trace.go:171","msg":"trace[1220340021] linearizableReadLoop","detail":"{readStateIndex:722; appliedIndex:721; }","duration":"261.786883ms","start":"2026-02-11T01:51:10.509907Z","end":"2026-02-11T01:51:10.771694Z","steps":["trace[1220340021] 'read index received' (duration: 261.590209ms)","trace[1220340021] 'applied index is now lower than readState.Index' (duration: 196.094µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:51:10.772081Z","caller":"traceutil/trace.go:171","msg":"trace[1280217052] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"263.018899ms","start":"2026-02-11T01:51:10.509055Z","end":"2026-02-11T01:51:10.772074Z","steps":["trace[1280217052] 'process raft request' (duration: 262.512669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:10.772247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.327995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-11T01:51:10.772266Z","caller":"traceutil/trace.go:171","msg":"trace[1695308988] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:700; }","duration":"262.407516ms","start":"2026-02-11T01:51:10.509852Z","end":"2026-02-11T01:51:10.772259Z","steps":["trace[1695308988] 'agreement among raft nodes before linearized reading' (duration: 262.326455ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:10.772357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.125562ms","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-11T01:51:10.77237Z","caller":"traceutil/trace.go:171","msg":"trace[1262407435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:700; }","duration":"258.154463ms","start":"2026-02-11T01:51:10.514212Z","end":"2026-02-11T01:51:10.772366Z","steps":["trace[1262407435] 'agreement among raft nodes before linearized reading' (duration: 258.111492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:10.772562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.29551ms","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-11T01:51:10.773674Z","caller":"traceutil/trace.go:171","msg":"trace[260607474] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:700; }","duration":"255.416035ms","start":"2026-02-11T01:51:10.518252Z","end":"2026-02-11T01:51:10.773668Z","steps":["trace[260607474] 'agreement among raft nodes before linearized reading' (duration: 254.275239ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:11.22025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.653398ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:51:11.220311Z","caller":"traceutil/trace.go:171","msg":"trace[1666072032] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"445.505631ms","start":"2026-02-11T01:51:10.774793Z","end":"2026-02-11T01:51:11.220299Z","steps":["trace[1666072032] 'process raft request' (duration: 299.754911ms)","trace[1666072032] 'compare' (duration: 145.391222ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:11.220342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:10.774783Z","time spent":"445.545422ms","remote":"127.0.0.1:33340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:51:11.494497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.488399ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:51:11.494607Z","caller":"traceutil/trace.go:171","msg":"trace[1328043620] linearizableReadLoop","detail":"{readStateIndex:725; appliedIndex:722; }","duration":"462.925822ms","start":"2026-02-11T01:51:11.03167Z","end":"2026-02-11T01:51:11.494595Z","steps":["trace[1328043620] 'read index received' (duration: 42.841045ms)","trace[1328043620] 'applied index is now lower than readState.Index' (duration: 420.084277ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:11.494743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.059804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:11.494795Z","caller":"traceutil/trace.go:171","msg":"trace[993499715] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:703; }","duration":"463.139847ms","start":"2026-02-11T01:51:11.03164Z","end":"2026-02-11T01:51:11.49478Z","steps":["trace[993499715] 'agreement among raft nodes before linearized reading' (duration: 463.026044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:11.49483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:11.031624Z","time spent":"463.200827ms","remote":"127.0.0.1:33094","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-11T01:51:11.494859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.838028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T01:51:11.494879Z","caller":"traceutil/trace.go:171","msg":"trace[2097430645] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:703; }","duration":"247.880848ms","start":"2026-02-11T01:51:11.246991Z","end":"2026-02-11T01:51:11.494872Z","steps":["trace[2097430645] 'agreement among raft nodes before linearized reading' (duration: 247.826687ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:11.494966Z","caller":"traceutil/trace.go:171","msg":"trace[1105237211] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"719.005699ms","start":"2026-02-11T01:51:10.77595Z","end":"2026-02-11T01:51:11.494955Z","steps":["trace[1105237211] 'process raft request' (duration: 571.002889ms)","trace[1105237211] 'compare' (duration: 147.407167ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:11.495041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:10.775943Z","time spent":"719.05546ms","remote":"127.0.0.1:33340","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-11T01:51:11.495146Z","caller":"traceutil/trace.go:171","msg":"trace[105340526] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"718.423526ms","start":"2026-02-11T01:51:10.776717Z","end":"2026-02-11T01:51:11.49514Z","steps":["trace[105340526] 'process raft request' (duration: 717.846754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:11.495196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:10.776711Z","time spent":"718.453397ms","remote":"127.0.0.1:33340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:51:24.322783Z","caller":"traceutil/trace.go:171","msg":"trace[475026683] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"249.018444ms","start":"2026-02-11T01:51:24.073753Z","end":"2026-02-11T01:51:24.322771Z","steps":["trace[475026683] 'process raft request' (duration: 248.944072ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:24.360544Z","caller":"traceutil/trace.go:171","msg":"trace[941148323] linearizableReadLoop","detail":"{readStateIndex:764; appliedIndex:762; }","duration":"107.272111ms","start":"2026-02-11T01:51:24.253259Z","end":"2026-02-11T01:51:24.360531Z","steps":["trace[941148323] 'read index received' (duration: 69.495496ms)","trace[941148323] 'applied index is now lower than readState.Index' (duration: 37.775795ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:24.360617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.341142ms","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-11T01:51:24.360636Z","caller":"traceutil/trace.go:171","msg":"trace[1241558959] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:739; }","duration":"107.391744ms","start":"2026-02-11T01:51:24.25324Z","end":"2026-02-11T01:51:24.360632Z","steps":["trace[1241558959] 'agreement among raft nodes before linearized reading' (duration: 107.341043ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:25.574038Z","caller":"traceutil/trace.go:171","msg":"trace[1166237351] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"238.077425ms","start":"2026-02-11T01:51:25.335944Z","end":"2026-02-11T01:51:25.574022Z","steps":["trace[1166237351] 'process raft request' (duration: 237.990993ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:27.115081Z","caller":"traceutil/trace.go:171","msg":"trace[403187926] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"266.118577ms","start":"2026-02-11T01:51:26.848937Z","end":"2026-02-11T01:51:27.115056Z","steps":["trace[403187926] 'process raft request' (duration: 265.81098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:27.952743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.317638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:27.952949Z","caller":"traceutil/trace.go:171","msg":"trace[375948170] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:745; }","duration":"643.567104ms","start":"2026-02-11T01:51:27.309367Z","end":"2026-02-11T01:51:27.952934Z","steps":["trace[375948170] 'count revisions from in-memory index tree' (duration: 643.231436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:27.95303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:27.309351Z","time spent":"643.668256ms","remote":"127.0.0.1:33316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:51:27.953526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.456535ms","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-11T01:51:27.953668Z","caller":"traceutil/trace.go:171","msg":"trace[1244908374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:745; }","duration":"316.616658ms","start":"2026-02-11T01:51:27.637041Z","end":"2026-02-11T01:51:27.953657Z","steps":["trace[1244908374] 'range keys from in-memory index tree' (duration: 316.352573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:27.953741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:27.637026Z","time spent":"316.706851ms","remote":"127.0.0.1:33340","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-11T01:51:27.953514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.068186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-11T01:51:27.953858Z","caller":"traceutil/trace.go:171","msg":"trace[624165382] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:745; }","duration":"376.467284ms","start":"2026-02-11T01:51:27.577376Z","end":"2026-02-11T01:51:27.953843Z","steps":["trace[624165382] 'range keys from in-memory index tree' (duration: 375.918103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:27.953891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:27.577357Z","time spent":"376.527365ms","remote":"127.0.0.1:33340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T01:51:27.953756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.575582ms","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-11T01:51:27.954032Z","caller":"traceutil/trace.go:171","msg":"trace[2118168426] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:745; }","duration":"291.872799ms","start":"2026-02-11T01:51:27.662149Z","end":"2026-02-11T01:51:27.954022Z","steps":["trace[2118168426] 'range keys from in-memory index tree' (duration: 291.382059ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:28.473036Z","caller":"traceutil/trace.go:171","msg":"trace[287150840] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"514.724261ms","start":"2026-02-11T01:51:27.958289Z","end":"2026-02-11T01:51:28.473013Z","steps":["trace[287150840] 'process raft request' (duration: 514.554098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:28.473177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:27.958274Z","time spent":"514.829334ms","remote":"127.0.0.1:33340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:51:29.364219Z","caller":"traceutil/trace.go:171","msg":"trace[465841399] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:770; }","duration":"1.006133414s","start":"2026-02-11T01:51:28.35807Z","end":"2026-02-11T01:51:29.364203Z","steps":["trace[465841399] 'read index received' (duration: 114.798094ms)","trace[465841399] 'applied index is now lower than readState.Index' (duration: 891.33462ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:51:29.364917Z","caller":"traceutil/trace.go:171","msg":"trace[1787909506] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"1.40601835s","start":"2026-02-11T01:51:27.958889Z","end":"2026-02-11T01:51:29.364907Z","steps":["trace[1787909506] 'process raft request' (duration: 1.397786901s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:29.365109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:27.958872Z","time spent":"1.406083402s","remote":"127.0.0.1:33340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:51:29.365355Z","caller":"traceutil/trace.go:171","msg":"trace[18468559] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"1.405807776s","start":"2026-02-11T01:51:27.959539Z","end":"2026-02-11T01:51:29.365347Z","steps":["trace[18468559] 'process raft request' (duration: 1.40462888s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:29.365478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:27.959532Z","time spent":"1.405846717s","remote":"127.0.0.1:33340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:51:29.365763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.007706198s","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-11T01:51:29.365801Z","caller":"traceutil/trace.go:171","msg":"trace[1873629531] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:748; }","duration":"1.007746609s","start":"2026-02-11T01:51:28.358047Z","end":"2026-02-11T01:51:29.365794Z","steps":["trace[1873629531] 'agreement among raft nodes before linearized reading' (duration: 1.007630357s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:29.365887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:28.357984Z","time spent":"1.007895522s","remote":"127.0.0.1:33340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-11T01:51:29.684481Z","caller":"traceutil/trace.go:171","msg":"trace[154933218] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"313.615573ms","start":"2026-02-11T01:51:29.370845Z","end":"2026-02-11T01:51:29.68446Z","steps":["trace[154933218] 'process raft request' (duration: 313.574692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:29.684613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:29.370832Z","time spent":"313.712216ms","remote":"127.0.0.1:33340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:51:29.684946Z","caller":"traceutil/trace.go:171","msg":"trace[1534589898] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"581.716643ms","start":"2026-02-11T01:51:29.103217Z","end":"2026-02-11T01:51:29.684934Z","steps":["trace[1534589898] 'process raft request' (duration: 541.463565ms)","trace[1534589898] 'compare' (duration: 39.558873ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:29.685006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:29.103189Z","time spent":"581.782614ms","remote":"127.0.0.1:33340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:51:29.68515Z","caller":"traceutil/trace.go:171","msg":"trace[25308465] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:773; }","duration":"320.877271ms","start":"2026-02-11T01:51:29.364264Z","end":"2026-02-11T01:51:29.685141Z","steps":["trace[25308465] 'read index received' (duration: 280.430819ms)","trace[25308465] 'applied index is now lower than readState.Index' (duration: 40.445432ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:29.685197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.06029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:29.685222Z","caller":"traceutil/trace.go:171","msg":"trace[2053765907] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:750; }","duration":"653.113931ms","start":"2026-02-11T01:51:29.0321Z","end":"2026-02-11T01:51:29.685214Z","steps":["trace[2053765907] 'agreement among raft nodes before linearized reading' (duration: 653.07221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:29.685246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:29.03208Z","time spent":"653.161003ms","remote":"127.0.0.1:33094","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-11T01:51:29.685527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.47885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-11T01:51:29.685555Z","caller":"traceutil/trace.go:171","msg":"trace[14722742] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:750; }","duration":"489.546472ms","start":"2026-02-11T01:51:29.196001Z","end":"2026-02-11T01:51:29.685547Z","steps":["trace[14722742] 'agreement among raft nodes before linearized reading' (duration: 489.44877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:29.685583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:29.19599Z","time spent":"489.587533ms","remote":"127.0.0.1:33114","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.205\" "} {"level":"warn","ts":"2026-02-11T01:51:29.68573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.482639ms","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-11T01:51:29.685755Z","caller":"traceutil/trace.go:171","msg":"trace[1828546406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:750; }","duration":"565.55396ms","start":"2026-02-11T01:51:29.120194Z","end":"2026-02-11T01:51:29.685747Z","steps":["trace[1828546406] 'agreement among raft nodes before linearized reading' (duration: 565.486999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:29.685778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:29.120182Z","time spent":"565.591311ms","remote":"127.0.0.1:33340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-11T01:51:29.883241Z","caller":"traceutil/trace.go:171","msg":"trace[802002113] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"135.228721ms","start":"2026-02-11T01:51:29.74799Z","end":"2026-02-11T01:51:29.883219Z","steps":["trace[802002113] 'process raft request' (duration: 135.153759ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:29.883968Z","caller":"traceutil/trace.go:171","msg":"trace[522453870] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"189.858332ms","start":"2026-02-11T01:51:29.694078Z","end":"2026-02-11T01:51:29.883936Z","steps":["trace[522453870] 'process raft request' (duration: 139.82939ms)","trace[522453870] 'compare' (duration: 49.080682ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:51:41.902305Z","caller":"traceutil/trace.go:171","msg":"trace[1639962183] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"162.484745ms","start":"2026-02-11T01:51:41.739806Z","end":"2026-02-11T01:51:41.90229Z","steps":["trace[1639962183] 'process raft request' (duration: 162.389932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:42.578009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.910143ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:51:42.578074Z","caller":"traceutil/trace.go:171","msg":"trace[2127996812] linearizableReadLoop","detail":"{readStateIndex:866; appliedIndex:865; }","duration":"549.647653ms","start":"2026-02-11T01:51:42.028416Z","end":"2026-02-11T01:51:42.578063Z","steps":["trace[2127996812] 'read index received' (duration: 228.327921ms)","trace[2127996812] 'applied index is now lower than readState.Index' (duration: 321.318872ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:51:42.578128Z","caller":"traceutil/trace.go:171","msg":"trace[2144948452] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"642.399238ms","start":"2026-02-11T01:51:41.935721Z","end":"2026-02-11T01:51:42.57812Z","steps":["trace[2144948452] 'process raft request' (duration: 321.019315ms)","trace[2144948452] 'compare' (duration: 320.715559ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:42.578158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:41.935685Z","time spent":"642.456059ms","remote":"127.0.0.1:33340","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-11T01:51:42.578282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.865167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:42.578297Z","caller":"traceutil/trace.go:171","msg":"trace[908768171] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:836; }","duration":"549.879318ms","start":"2026-02-11T01:51:42.028411Z","end":"2026-02-11T01:51:42.578291Z","steps":["trace[908768171] 'agreement among raft nodes before linearized reading' (duration: 549.840687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:42.578311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:42.02837Z","time spent":"549.937459ms","remote":"127.0.0.1:33094","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-11T01:51:42.9288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.859326ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:51:42.928897Z","caller":"traceutil/trace.go:171","msg":"trace[21980259] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"109.142892ms","start":"2026-02-11T01:51:42.819743Z","end":"2026-02-11T01:51:42.928886Z","steps":["trace[21980259] 'process raft request' (duration: 109.116441ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:42.929059Z","caller":"traceutil/trace.go:171","msg":"trace[1432564134] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"348.457723ms","start":"2026-02-11T01:51:42.580595Z","end":"2026-02-11T01:51:42.929053Z","steps":["trace[1432564134] 'process raft request' (duration: 239.294042ms)","trace[1432564134] 'compare' (duration: 108.788083ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:42.929088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:42.580587Z","time spent":"348.483674ms","remote":"127.0.0.1:33340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:51:43.451727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.304544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:43.451776Z","caller":"traceutil/trace.go:171","msg":"trace[965191316] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:838; }","duration":"422.383157ms","start":"2026-02-11T01:51:43.029382Z","end":"2026-02-11T01:51:43.451765Z","steps":["trace[965191316] 'range keys from in-memory index tree' (duration: 422.255913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:43.451796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:43.029369Z","time spent":"422.422647ms","remote":"127.0.0.1:33094","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-11T01:51:51.087102Z","caller":"traceutil/trace.go:171","msg":"trace[1683345054] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"118.290901ms","start":"2026-02-11T01:51:50.968795Z","end":"2026-02-11T01:51:51.087086Z","steps":["trace[1683345054] 'process raft request' (duration: 117.962514ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:51.646517Z","caller":"traceutil/trace.go:171","msg":"trace[2041787759] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"141.16355ms","start":"2026-02-11T01:51:51.505339Z","end":"2026-02-11T01:51:51.646502Z","steps":["trace[2041787759] 'process raft request' (duration: 140.920274ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:53.635186Z","caller":"traceutil/trace.go:171","msg":"trace[970801117] linearizableReadLoop","detail":"{readStateIndex:898; appliedIndex:897; }","duration":"443.12787ms","start":"2026-02-11T01:51:53.192037Z","end":"2026-02-11T01:51:53.635165Z","steps":["trace[970801117] 'read index received' (duration: 361.743304ms)","trace[970801117] 'applied index is now lower than readState.Index' (duration: 81.383966ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:51:53.635255Z","caller":"traceutil/trace.go:171","msg":"trace[797742518] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"484.976313ms","start":"2026-02-11T01:51:53.150273Z","end":"2026-02-11T01:51:53.635249Z","steps":["trace[797742518] 'process raft request' (duration: 403.530395ms)","trace[797742518] 'compare' (duration: 81.284214ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:53.635314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:53.15026Z","time spent":"485.008053ms","remote":"127.0.0.1:33340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:51:53.63537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.449464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T01:51:53.635415Z","caller":"traceutil/trace.go:171","msg":"trace[1746770270] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:866; }","duration":"194.529005ms","start":"2026-02-11T01:51:53.440876Z","end":"2026-02-11T01:51:53.635405Z","steps":["trace[1746770270] 'agreement among raft nodes before linearized reading' (duration: 194.448363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:53.635506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.472327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:53.635526Z","caller":"traceutil/trace.go:171","msg":"trace[2129731391] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:866; }","duration":"443.514718ms","start":"2026-02-11T01:51:53.192007Z","end":"2026-02-11T01:51:53.635521Z","steps":["trace[2129731391] 'agreement among raft nodes before linearized reading' (duration: 443.481407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:53.635541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:53.191994Z","time spent":"443.543818ms","remote":"127.0.0.1:33094","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-11T01:51:53.860294Z","caller":"traceutil/trace.go:171","msg":"trace[1327805465] linearizableReadLoop","detail":"{readStateIndex:899; appliedIndex:898; }","duration":"207.692832ms","start":"2026-02-11T01:51:53.652586Z","end":"2026-02-11T01:51:53.860279Z","steps":["trace[1327805465] 'read index received' (duration: 155.058303ms)","trace[1327805465] 'applied index is now lower than readState.Index' (duration: 52.633939ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:53.860426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.826545ms","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-11T01:51:53.860443Z","caller":"traceutil/trace.go:171","msg":"trace[183541145] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:867; }","duration":"207.873066ms","start":"2026-02-11T01:51:53.652565Z","end":"2026-02-11T01:51:53.860438Z","steps":["trace[183541145] 'agreement among raft nodes before linearized reading' (duration: 207.767854ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:53.8606Z","caller":"traceutil/trace.go:171","msg":"trace[814972347] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"208.782676ms","start":"2026-02-11T01:51:53.651811Z","end":"2026-02-11T01:51:53.860594Z","steps":["trace[814972347] 'process raft request' (duration: 155.872521ms)","trace[814972347] 'compare' (duration: 52.500116ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:56.229102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.652373ms","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-11T01:51:56.229176Z","caller":"traceutil/trace.go:171","msg":"trace[1832193478] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:872; }","duration":"197.734845ms","start":"2026-02-11T01:51:56.03143Z","end":"2026-02-11T01:51:56.229165Z","steps":["trace[1832193478] 'range keys from in-memory index tree' (duration: 197.641393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:56.229377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.219317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:51:56.229411Z","caller":"traceutil/trace.go:171","msg":"trace[646379954] linearizableReadLoop","detail":"{readStateIndex:906; appliedIndex:905; }","duration":"149.118243ms","start":"2026-02-11T01:51:56.080287Z","end":"2026-02-11T01:51:56.229406Z","steps":["trace[646379954] 'read index received' (duration: 10.761074ms)","trace[646379954] 'applied index is now lower than readState.Index' (duration: 138.356639ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:56.229483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.195295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-11T01:51:56.229519Z","caller":"traceutil/trace.go:171","msg":"trace[889191215] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:873; }","duration":"149.253597ms","start":"2026-02-11T01:51:56.080261Z","end":"2026-02-11T01:51:56.229515Z","steps":["trace[889191215] 'agreement among raft nodes before linearized reading' (duration: 149.163645ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:56.229662Z","caller":"traceutil/trace.go:171","msg":"trace[845800512] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"301.342846ms","start":"2026-02-11T01:51:55.928313Z","end":"2026-02-11T01:51:56.229656Z","steps":["trace[845800512] 'process raft request' (duration: 162.804612ms)","trace[845800512] 'compare' (duration: 138.166876ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:56.229702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:55.928299Z","time spent":"301.387476ms","remote":"127.0.0.1:33340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:51:57.449592Z","caller":"traceutil/trace.go:171","msg":"trace[680091939] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"273.031807ms","start":"2026-02-11T01:51:57.176547Z","end":"2026-02-11T01:51:57.449578Z","steps":["trace[680091939] 'process raft request' (duration: 272.942095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:58.712365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.411351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:58.712451Z","caller":"traceutil/trace.go:171","msg":"trace[1164183852] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:879; }","duration":"298.532564ms","start":"2026-02-11T01:51:58.413901Z","end":"2026-02-11T01:51:58.712433Z","steps":["trace[1164183852] 'count revisions from in-memory index tree' (duration: 298.36209ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:00.445957Z","caller":"traceutil/trace.go:171","msg":"trace[1541717654] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"429.961252ms","start":"2026-02-11T01:52:00.015979Z","end":"2026-02-11T01:52:00.44594Z","steps":["trace[1541717654] 'process raft request' (duration: 429.833599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:00.446076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:00.015964Z","time spent":"430.047073ms","remote":"127.0.0.1:33340","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-11T01:52:00.446196Z","caller":"traceutil/trace.go:171","msg":"trace[910821974] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:917; }","duration":"417.438719ms","start":"2026-02-11T01:52:00.02875Z","end":"2026-02-11T01:52:00.446189Z","steps":["trace[910821974] 'read index received' (duration: 417.435529ms)","trace[910821974] 'applied index is now lower than readState.Index' (duration: 2.55µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:00.446281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.529391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:52:00.446302Z","caller":"traceutil/trace.go:171","msg":"trace[1719240015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:883; }","duration":"417.567741ms","start":"2026-02-11T01:52:00.028727Z","end":"2026-02-11T01:52:00.446295Z","steps":["trace[1719240015] 'agreement among raft nodes before linearized reading' (duration: 417.49707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:00.446321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:00.02872Z","time spent":"417.597233ms","remote":"127.0.0.1:33094","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-11T01:52:00.6683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.214171ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:52:00.668379Z","caller":"traceutil/trace.go:171","msg":"trace[201811595] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:917; }","duration":"222.153087ms","start":"2026-02-11T01:52:00.446212Z","end":"2026-02-11T01:52:00.668365Z","steps":["trace[201811595] 'read index received' (duration: 113.753492ms)","trace[201811595] 'applied index is now lower than readState.Index' (duration: 108.398715ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:52:00.668443Z","caller":"traceutil/trace.go:171","msg":"trace[697501652] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"368.97787ms","start":"2026-02-11T01:52:00.299458Z","end":"2026-02-11T01:52:00.668436Z","steps":["trace[697501652] 'process raft request' (duration: 260.549095ms)","trace[697501652] 'compare' (duration: 108.090208ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:00.668482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:00.299439Z","time spent":"369.023262ms","remote":"127.0.0.1:33340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:52:00.668688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.666486ms","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":"warn","ts":"2026-02-11T01:52:00.668728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.193516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:52:00.668754Z","caller":"traceutil/trace.go:171","msg":"trace[1763683137] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:884; }","duration":"221.239068ms","start":"2026-02-11T01:52:00.447507Z","end":"2026-02-11T01:52:00.668746Z","steps":["trace[1763683137] 'agreement among raft nodes before linearized reading' (duration: 221.196497ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:00.66877Z","caller":"traceutil/trace.go:171","msg":"trace[984618153] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:884; }","duration":"429.81028ms","start":"2026-02-11T01:52:00.238934Z","end":"2026-02-11T01:52:00.668744Z","steps":["trace[984618153] 'agreement among raft nodes before linearized reading' (duration: 429.592774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:00.668812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:00.238915Z","time spent":"429.888381ms","remote":"127.0.0.1:33340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T01:52:00.66905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.064638ms","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-11T01:52:00.669071Z","caller":"traceutil/trace.go:171","msg":"trace[4823047] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:884; }","duration":"382.117369ms","start":"2026-02-11T01:52:00.286948Z","end":"2026-02-11T01:52:00.669065Z","steps":["trace[4823047] 'agreement among raft nodes before linearized reading' (duration: 382.023247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:00.669093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:00.286933Z","time spent":"382.15526ms","remote":"127.0.0.1:33340","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-11T01:52:01.030084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.69308ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:52:01.030204Z","caller":"traceutil/trace.go:171","msg":"trace[112826740] linearizableReadLoop","detail":"{readStateIndex:920; appliedIndex:918; }","duration":"301.411188ms","start":"2026-02-11T01:52:00.72877Z","end":"2026-02-11T01:52:01.030181Z","steps":["trace[112826740] 'read index received' (duration: 116.572664ms)","trace[112826740] 'applied index is now lower than readState.Index' (duration: 184.837934ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:52:01.030365Z","caller":"traceutil/trace.go:171","msg":"trace[1187657198] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"355.400226ms","start":"2026-02-11T01:52:00.674957Z","end":"2026-02-11T01:52:01.030357Z","steps":["trace[1187657198] 'process raft request' (duration: 170.378368ms)","trace[1187657198] 'compare' (duration: 184.564937ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:01.030411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:00.674945Z","time spent":"355.445376ms","remote":"127.0.0.1:33340","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-11T01:52:01.030532Z","caller":"traceutil/trace.go:171","msg":"trace[1301096272] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"355.467607ms","start":"2026-02-11T01:52:00.675058Z","end":"2026-02-11T01:52:01.030526Z","steps":["trace[1301096272] 'process raft request' (duration: 355.092149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:01.030563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:00.675053Z","time spent":"355.493158ms","remote":"127.0.0.1:33340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:52:01.030684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.913068ms","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-11T01:52:01.030702Z","caller":"traceutil/trace.go:171","msg":"trace[1070119883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:886; }","duration":"301.95491ms","start":"2026-02-11T01:52:00.728741Z","end":"2026-02-11T01:52:01.030696Z","steps":["trace[1070119883] 'agreement among raft nodes before linearized reading' (duration: 301.890688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:01.030718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:00.72873Z","time spent":"301.98453ms","remote":"127.0.0.1:33340","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-11T01:52:01.030816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.986157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T01:52:01.030832Z","caller":"traceutil/trace.go:171","msg":"trace[2130092657] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:886; }","duration":"240.049439ms","start":"2026-02-11T01:52:00.790778Z","end":"2026-02-11T01:52:01.030828Z","steps":["trace[2130092657] 'agreement among raft nodes before linearized reading' (duration: 240.013618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:01.499309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.603104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:52:01.499368Z","caller":"traceutil/trace.go:171","msg":"trace[1323031765] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"462.006461ms","start":"2026-02-11T01:52:01.037351Z","end":"2026-02-11T01:52:01.499357Z","steps":["trace[1323031765] 'process raft request' (duration: 284.308494ms)","trace[1323031765] 'compare' (duration: 177.526293ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:01.499399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:01.037333Z","time spent":"462.052782ms","remote":"127.0.0.1:33340","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-11T01:52:02.175398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.941316ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:52:02.175488Z","caller":"traceutil/trace.go:171","msg":"trace[1420186023] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:921; }","duration":"264.487111ms","start":"2026-02-11T01:52:01.910986Z","end":"2026-02-11T01:52:02.175473Z","steps":["trace[1420186023] 'read index received' (duration: 32.411µs)","trace[1420186023] 'applied index is now lower than readState.Index' (duration: 264.45365ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:52:02.175703Z","caller":"traceutil/trace.go:171","msg":"trace[1832475883] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"655.853091ms","start":"2026-02-11T01:52:01.519825Z","end":"2026-02-11T01:52:02.175678Z","steps":["trace[1832475883] 'process raft request' (duration: 85.558438ms)","trace[1832475883] 'compare' (duration: 569.335503ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:02.17577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:01.519815Z","time spent":"655.917013ms","remote":"127.0.0.1:33340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:52:02.175953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.961602ms","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-11T01:52:02.175981Z","caller":"traceutil/trace.go:171","msg":"trace[1416165294] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:888; }","duration":"264.991333ms","start":"2026-02-11T01:52:01.910981Z","end":"2026-02-11T01:52:02.175972Z","steps":["trace[1416165294] 'agreement among raft nodes before linearized reading' (duration: 264.929691ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:02.176121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.482652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:52:02.176172Z","caller":"traceutil/trace.go:171","msg":"trace[2064649401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:888; }","duration":"143.539763ms","start":"2026-02-11T01:52:02.032599Z","end":"2026-02-11T01:52:02.176139Z","steps":["trace[2064649401] 'agreement among raft nodes before linearized reading' (duration: 143.502732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:02.849384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.740857ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:52:02.849471Z","caller":"traceutil/trace.go:171","msg":"trace[989617488] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"314.86067ms","start":"2026-02-11T01:52:02.534594Z","end":"2026-02-11T01:52:02.849455Z","steps":["trace[989617488] 'process raft request' (duration: 109.99045ms)","trace[989617488] 'compare' (duration: 204.465671ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:02.849526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:02.534578Z","time spent":"314.922661ms","remote":"127.0.0.1:33156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T01:52:03.306784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.248886ms","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-11T01:52:03.306879Z","caller":"traceutil/trace.go:171","msg":"trace[254127304] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:890; }","duration":"270.381909ms","start":"2026-02-11T01:52:03.036477Z","end":"2026-02-11T01:52:03.306859Z","steps":["trace[254127304] 'range keys from in-memory index tree' (duration: 270.099723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:03.307023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.537182ms","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-11T01:52:03.307091Z","caller":"traceutil/trace.go:171","msg":"trace[1961579112] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:890; }","duration":"242.626043ms","start":"2026-02-11T01:52:03.064444Z","end":"2026-02-11T01:52:03.30707Z","steps":["trace[1961579112] 'range keys from in-memory index tree' (duration: 242.417759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:03.30717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.641682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:52:03.307206Z","caller":"traceutil/trace.go:171","msg":"trace[2025709651] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:890; }","duration":"274.710364ms","start":"2026-02-11T01:52:03.032485Z","end":"2026-02-11T01:52:03.307196Z","steps":["trace[2025709651] 'range keys from in-memory index tree' (duration: 274.586001ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:18.506818Z","caller":"traceutil/trace.go:171","msg":"trace[1415316776] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1034; }","duration":"196.47788ms","start":"2026-02-11T01:52:18.310313Z","end":"2026-02-11T01:52:18.506791Z","steps":["trace[1415316776] 'read index received' (duration: 196.261416ms)","trace[1415316776] 'applied index is now lower than readState.Index' (duration: 215.544µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:18.506978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.642563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T01:52:18.507015Z","caller":"traceutil/trace.go:171","msg":"trace[647987701] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:998; }","duration":"196.785027ms","start":"2026-02-11T01:52:18.310216Z","end":"2026-02-11T01:52:18.507001Z","steps":["trace[647987701] 'agreement among raft nodes before linearized reading' (duration: 196.676554ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:18.507656Z","caller":"traceutil/trace.go:171","msg":"trace[1895985251] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"234.213575ms","start":"2026-02-11T01:52:18.273412Z","end":"2026-02-11T01:52:18.507626Z","steps":["trace[1895985251] 'process raft request' (duration: 233.230934ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:19.045096Z","caller":"traceutil/trace.go:171","msg":"trace[94072528] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"314.381316ms","start":"2026-02-11T01:52:18.730687Z","end":"2026-02-11T01:52:19.045068Z","steps":["trace[94072528] 'process raft request' (duration: 311.789521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:19.04551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:18.730673Z","time spent":"314.740653ms","remote":"127.0.0.1:33340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}