{"level":"warn","ts":"2026-02-09T18:53:45.590822Z","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-09T18:53:45.590953Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227: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.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227: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-09T18:53:45.591063Z","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-09T18:53:45.591075Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-02-09T18:53:45.591098Z","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-09T18:53:45.591925Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-02-09T18:53:45.592065Z","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.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227: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-09T18:53:45.598656Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.189666ms"} {"level":"info","ts":"2026-02-09T18:53:45.615038Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-02-09T18:53:45.61513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-02-09T18:53:45.61516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-02-09T18:53:45.61517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-09T18:53:45.615178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-02-09T18:53:45.615221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-02-09T18:53:45.621944Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-09T18:53:45.624974Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-09T18:53:45.62635Z","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-09T18:53:45.633421Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-09T18:53:45.634142Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-09T18:53:45.634322Z","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-09T18:53:45.634387Z","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-09T18:53:45.634398Z","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-09T18:53:45.63469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-02-09T18:53:45.634778Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-02-09T18:53:45.644215Z","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-09T18:53:45.644518Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-02-09T18:53:45.644591Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-02-09T18:53:45.645234Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-09T18:53:45.645358Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-09T18:53:45.816041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-02-09T18:53:45.816117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-09T18:53:45.816168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-02-09T18:53:45.816188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-02-09T18:53:45.816195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-02-09T18:53:45.816205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-02-09T18:53:45.816214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-02-09T18:53:45.820501Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T18:53:45.822913Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-02-09T18:53:45.825118Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-09T18:53:45.825215Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T18:53:45.825294Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T18:53:45.825316Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T18:53:45.825348Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-09T18:53:45.828829Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-09T18:53:45.828945Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-09T18:53:45.830408Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-09T18:53:45.834933Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"info","ts":"2026-02-09T18:54:01.761362Z","caller":"traceutil/trace.go:171","msg":"trace[1166296766] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"484.555063ms","start":"2026-02-09T18:54:01.276409Z","end":"2026-02-09T18:54:01.760964Z","steps":["trace[1166296766] 'process raft request' (duration: 484.394669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:01.763845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:01.276392Z","time spent":"485.095635ms","remote":"127.0.0.1:56410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:54:02.379449Z","caller":"traceutil/trace.go:171","msg":"trace[1162704860] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:426; }","duration":"789.360898ms","start":"2026-02-09T18:54:01.590067Z","end":"2026-02-09T18:54:02.379427Z","steps":["trace[1162704860] 'read index received' (duration: 170.95763ms)","trace[1162704860] 'applied index is now lower than readState.Index' (duration: 618.402258ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:54:02.37972Z","caller":"traceutil/trace.go:171","msg":"trace[440299458] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"913.740961ms","start":"2026-02-09T18:54:01.465966Z","end":"2026-02-09T18:54:02.379707Z","steps":["trace[440299458] 'process raft request' (duration: 886.153211ms)","trace[440299458] 'compare' (duration: 27.148199ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:54:02.379825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:01.465946Z","time spent":"913.810722ms","remote":"127.0.0.1:56394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4048,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:54:02.380101Z","caller":"traceutil/trace.go:171","msg":"trace[1827601714] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"758.245435ms","start":"2026-02-09T18:54:01.621845Z","end":"2026-02-09T18:54:02.38009Z","steps":["trace[1827601714] 'process raft request' (duration: 757.532978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:02.380167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:01.621831Z","time spent":"758.301326ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:54:02.38073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.652978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4062"} {"level":"info","ts":"2026-02-09T18:54:02.380786Z","caller":"traceutil/trace.go:171","msg":"trace[608917213] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:416; }","duration":"790.739541ms","start":"2026-02-09T18:54:01.590033Z","end":"2026-02-09T18:54:02.380773Z","steps":["trace[608917213] 'agreement among raft nodes before linearized reading' (duration: 790.647719ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:02.380819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:01.590021Z","time spent":"790.791342ms","remote":"127.0.0.1:56394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4085,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-09T18:54:02.380968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.895771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4729"} {"level":"info","ts":"2026-02-09T18:54:02.381002Z","caller":"traceutil/trace.go:171","msg":"trace[1714715102] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:416; }","duration":"610.950512ms","start":"2026-02-09T18:54:01.770037Z","end":"2026-02-09T18:54:02.380988Z","steps":["trace[1714715102] 'agreement among raft nodes before linearized reading' (duration: 610.896301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:02.381028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:01.770019Z","time spent":"611.003424ms","remote":"127.0.0.1:56410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4752,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-02-09T18:54:02.381203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.896881ms","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-09T18:54:02.38123Z","caller":"traceutil/trace.go:171","msg":"trace[1156165023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:416; }","duration":"165.936241ms","start":"2026-02-09T18:54:02.215286Z","end":"2026-02-09T18:54:02.381222Z","steps":["trace[1156165023] 'agreement among raft nodes before linearized reading' (duration: 165.88751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:02.381656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.083618ms","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-09T18:54:02.381698Z","caller":"traceutil/trace.go:171","msg":"trace[1005827568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:416; }","duration":"167.16758ms","start":"2026-02-09T18:54:02.21452Z","end":"2026-02-09T18:54:02.381688Z","steps":["trace[1005827568] 'agreement among raft nodes before linearized reading' (duration: 167.093569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:02.381788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.106688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:54:02.381823Z","caller":"traceutil/trace.go:171","msg":"trace[1568748741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:416; }","duration":"312.168799ms","start":"2026-02-09T18:54:02.069634Z","end":"2026-02-09T18:54:02.381803Z","steps":["trace[1568748741] 'agreement among raft nodes before linearized reading' (duration: 312.131018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:02.381848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:02.069613Z","time spent":"312.229291ms","remote":"127.0.0.1:56216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-09T18:54:14.535601Z","caller":"traceutil/trace.go:171","msg":"trace[433810121] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"124.227909ms","start":"2026-02-09T18:54:14.411342Z","end":"2026-02-09T18:54:14.53557Z","steps":["trace[433810121] 'process raft request' (duration: 123.593494ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:54:16.929071Z","caller":"traceutil/trace.go:171","msg":"trace[1341837898] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"198.793567ms","start":"2026-02-09T18:54:16.730252Z","end":"2026-02-09T18:54:16.929046Z","steps":["trace[1341837898] 'process raft request' (duration: 198.399397ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:54:18.842497Z","caller":"traceutil/trace.go:171","msg":"trace[1169471222] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:537; }","duration":"249.617905ms","start":"2026-02-09T18:54:18.592858Z","end":"2026-02-09T18:54:18.842476Z","steps":["trace[1169471222] 'read index received' (duration: 249.43155ms)","trace[1169471222] 'applied index is now lower than readState.Index' (duration: 185.215µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:54:18.842598Z","caller":"traceutil/trace.go:171","msg":"trace[305903980] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"343.796744ms","start":"2026-02-09T18:54:18.498792Z","end":"2026-02-09T18:54:18.842589Z","steps":["trace[305903980] 'process raft request' (duration: 343.554509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:18.842697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:18.498776Z","time spent":"343.847686ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:54:18.842766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.63115ms","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-09T18:54:18.842814Z","caller":"traceutil/trace.go:171","msg":"trace[419041828] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:521; }","duration":"245.706272ms","start":"2026-02-09T18:54:18.597095Z","end":"2026-02-09T18:54:18.842802Z","steps":["trace[419041828] 'agreement among raft nodes before linearized reading' (duration: 245.58562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:18.842913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.063825ms","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-09T18:54:18.842925Z","caller":"traceutil/trace.go:171","msg":"trace[147450645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:521; }","duration":"250.110436ms","start":"2026-02-09T18:54:18.59281Z","end":"2026-02-09T18:54:18.842921Z","steps":["trace[147450645] 'agreement among raft nodes before linearized reading' (duration: 250.071945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:18.84298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.737636ms","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-09T18:54:18.842993Z","caller":"traceutil/trace.go:171","msg":"trace[185572456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:521; }","duration":"243.758046ms","start":"2026-02-09T18:54:18.59923Z","end":"2026-02-09T18:54:18.842988Z","steps":["trace[185572456] 'agreement among raft nodes before linearized reading' (duration: 243.725055ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:54:18.969027Z","caller":"traceutil/trace.go:171","msg":"trace[1912975827] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"120.902871ms","start":"2026-02-09T18:54:18.848105Z","end":"2026-02-09T18:54:18.969008Z","steps":["trace[1912975827] 'process raft request' (duration: 111.017178ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:54:18.969461Z","caller":"traceutil/trace.go:171","msg":"trace[1497794776] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"119.772314ms","start":"2026-02-09T18:54:18.849667Z","end":"2026-02-09T18:54:18.969439Z","steps":["trace[1497794776] 'process raft request' (duration: 119.302813ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:54:20.181766Z","caller":"traceutil/trace.go:171","msg":"trace[2030280137] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"377.515449ms","start":"2026-02-09T18:54:19.804235Z","end":"2026-02-09T18:54:20.18175Z","steps":["trace[2030280137] 'process raft request' (duration: 374.865166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:20.181857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:19.804223Z","time spent":"377.586361ms","remote":"127.0.0.1:56230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:54:24.923288Z","caller":"traceutil/trace.go:171","msg":"trace[406995295] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"535.320859ms","start":"2026-02-09T18:54:24.387943Z","end":"2026-02-09T18:54:24.923264Z","steps":["trace[406995295] 'process raft request' (duration: 534.894829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:24.923421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:24.387914Z","time spent":"535.443273ms","remote":"127.0.0.1:56410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:54:25.401803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.938764ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-09T18:54:25.401957Z","caller":"traceutil/trace.go:171","msg":"trace[696061837] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:557; }","duration":"469.910887ms","start":"2026-02-09T18:54:24.931991Z","end":"2026-02-09T18:54:25.401902Z","steps":["trace[696061837] 'read index received' (duration: 31.563624ms)","trace[696061837] 'applied index is now lower than readState.Index' (duration: 438.346013ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:54:25.402284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.162083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-09T18:54:25.402405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.85649ms","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-09T18:54:25.40264Z","caller":"traceutil/trace.go:171","msg":"trace[441421301] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:539; }","duration":"404.125087ms","start":"2026-02-09T18:54:24.998506Z","end":"2026-02-09T18:54:25.402631Z","steps":["trace[441421301] 'agreement among raft nodes before linearized reading' (duration: 403.711947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:25.402713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:24.998487Z","time spent":"404.216848ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-09T18:54:25.402326Z","caller":"traceutil/trace.go:171","msg":"trace[1178403137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:539; }","duration":"470.415479ms","start":"2026-02-09T18:54:24.931897Z","end":"2026-02-09T18:54:25.402312Z","steps":["trace[1178403137] 'agreement among raft nodes before linearized reading' (duration: 470.114592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:25.403017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:24.931877Z","time spent":"471.126796ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-09T18:54:25.403077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.231748ms","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-09T18:54:25.403189Z","caller":"traceutil/trace.go:171","msg":"trace[1860257838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:539; }","duration":"398.414642ms","start":"2026-02-09T18:54:25.00475Z","end":"2026-02-09T18:54:25.403165Z","steps":["trace[1860257838] 'agreement among raft nodes before linearized reading' (duration: 398.037093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:25.402476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.899368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-09T18:54:25.403246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:25.004705Z","time spent":"398.530235ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-09T18:54:25.403254Z","caller":"traceutil/trace.go:171","msg":"trace[900679320] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"280.723388ms","start":"2026-02-09T18:54:25.122519Z","end":"2026-02-09T18:54:25.403242Z","steps":["trace[900679320] 'agreement among raft nodes before linearized reading' (duration: 279.936529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:25.402583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.785228ms","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-09T18:54:25.403462Z","caller":"traceutil/trace.go:171","msg":"trace[2108162366] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:539; }","duration":"334.71432ms","start":"2026-02-09T18:54:25.068738Z","end":"2026-02-09T18:54:25.403453Z","steps":["trace[2108162366] 'agreement among raft nodes before linearized reading' (duration: 333.760697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:25.403519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:25.068723Z","time spent":"334.785622ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-09T18:54:26.62787Z","caller":"traceutil/trace.go:171","msg":"trace[13124573] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"183.326791ms","start":"2026-02-09T18:54:26.444527Z","end":"2026-02-09T18:54:26.627853Z","steps":["trace[13124573] 'process raft request' (duration: 183.205018ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:54:30.851771Z","caller":"traceutil/trace.go:171","msg":"trace[1225501591] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"161.246911ms","start":"2026-02-09T18:54:30.69051Z","end":"2026-02-09T18:54:30.851757Z","steps":["trace[1225501591] 'process raft request' (duration: 161.147049ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:54:36.15236Z","caller":"traceutil/trace.go:171","msg":"trace[1040651685] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"379.380193ms","start":"2026-02-09T18:54:35.772962Z","end":"2026-02-09T18:54:36.152343Z","steps":["trace[1040651685] 'process raft request' (duration: 379.2492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:36.152974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:35.772946Z","time spent":"379.973027ms","remote":"127.0.0.1:48000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1238,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-09T18:54:45.799474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.580705ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T18:54:45.799645Z","caller":"traceutil/trace.go:171","msg":"trace[1222792357] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"156.571441ms","start":"2026-02-09T18:54:45.643055Z","end":"2026-02-09T18:54:45.799627Z","steps":["trace[1222792357] 'process raft request' (duration: 156.52235ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:54:45.800346Z","caller":"traceutil/trace.go:171","msg":"trace[2001540507] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"204.468072ms","start":"2026-02-09T18:54:45.595789Z","end":"2026-02-09T18:54:45.800257Z","steps":["trace[2001540507] 'process raft request' (duration: 95.110229ms)","trace[2001540507] 'compare' (duration: 108.31997ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:54:45.801221Z","caller":"traceutil/trace.go:171","msg":"trace[603369647] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:749; }","duration":"204.171406ms","start":"2026-02-09T18:54:45.597038Z","end":"2026-02-09T18:54:45.801209Z","steps":["trace[603369647] 'read index received' (duration: 15.303869ms)","trace[603369647] 'applied index is now lower than readState.Index' (duration: 188.866317ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:54:45.801376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.33675ms","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-09T18:54:45.801396Z","caller":"traceutil/trace.go:171","msg":"trace[159571280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:727; }","duration":"204.37218ms","start":"2026-02-09T18:54:45.597016Z","end":"2026-02-09T18:54:45.801388Z","steps":["trace[159571280] 'agreement among raft nodes before linearized reading' (duration: 204.253667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:45.801543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.819454ms","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-09T18:54:45.801562Z","caller":"traceutil/trace.go:171","msg":"trace[767997700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:727; }","duration":"198.862575ms","start":"2026-02-09T18:54:45.602693Z","end":"2026-02-09T18:54:45.801556Z","steps":["trace[767997700] 'agreement among raft nodes before linearized reading' (duration: 198.794153ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:54:46.050888Z","caller":"traceutil/trace.go:171","msg":"trace[1438916902] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"243.145705ms","start":"2026-02-09T18:54:45.807717Z","end":"2026-02-09T18:54:46.050862Z","steps":["trace[1438916902] 'process raft request' (duration: 240.643157ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:54:46.073692Z","caller":"traceutil/trace.go:171","msg":"trace[496302024] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"265.041244ms","start":"2026-02-09T18:54:45.808634Z","end":"2026-02-09T18:54:46.073675Z","steps":["trace[496302024] 'process raft request' (duration: 264.950812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:50.621501Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656050600397313316,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-09T18:54:50.811902Z","caller":"traceutil/trace.go:171","msg":"trace[1111963152] linearizableReadLoop","detail":"{readStateIndex:772; appliedIndex:771; }","duration":"690.969336ms","start":"2026-02-09T18:54:50.120915Z","end":"2026-02-09T18:54:50.811885Z","steps":["trace[1111963152] 'read index received' (duration: 649.864049ms)","trace[1111963152] 'applied index is now lower than readState.Index' (duration: 41.104747ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:54:50.81198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.052418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:54:50.811997Z","caller":"traceutil/trace.go:171","msg":"trace[1058302357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:746; }","duration":"691.093459ms","start":"2026-02-09T18:54:50.120898Z","end":"2026-02-09T18:54:50.811992Z","steps":["trace[1058302357] 'agreement among raft nodes before linearized reading' (duration: 691.043077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:50.812013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:50.120891Z","time spent":"691.118239ms","remote":"127.0.0.1:56216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-09T18:54:50.812156Z","caller":"traceutil/trace.go:171","msg":"trace[809861081] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"721.227876ms","start":"2026-02-09T18:54:50.090922Z","end":"2026-02-09T18:54:50.81215Z","steps":["trace[809861081] 'process raft request' (duration: 679.890264ms)","trace[809861081] 'compare' (duration: 41.017885ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:54:50.812198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:50.090912Z","time spent":"721.256017ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:55:02.331997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.712203ms","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-09T18:55:02.332099Z","caller":"traceutil/trace.go:171","msg":"trace[1941750494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:778; }","duration":"163.852107ms","start":"2026-02-09T18:55:02.168233Z","end":"2026-02-09T18:55:02.332085Z","steps":["trace[1941750494] 'range keys from in-memory index tree' (duration: 163.56861ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:03.841931Z","caller":"traceutil/trace.go:171","msg":"trace[1141591050] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:808; }","duration":"327.242362ms","start":"2026-02-09T18:55:03.514669Z","end":"2026-02-09T18:55:03.841911Z","steps":["trace[1141591050] 'read index received' (duration: 327.117089ms)","trace[1141591050] 'applied index is now lower than readState.Index' (duration: 124.453µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:55:03.842027Z","caller":"traceutil/trace.go:171","msg":"trace[1680911683] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"506.559481ms","start":"2026-02-09T18:55:03.335445Z","end":"2026-02-09T18:55:03.842005Z","steps":["trace[1680911683] 'process raft request' (duration: 506.360646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:03.842112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.422176ms","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-09T18:55:03.842136Z","caller":"traceutil/trace.go:171","msg":"trace[1668839052] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:781; }","duration":"327.490658ms","start":"2026-02-09T18:55:03.514639Z","end":"2026-02-09T18:55:03.842129Z","steps":["trace[1668839052] 'agreement among raft nodes before linearized reading' (duration: 327.368315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:03.84216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:03.514622Z","time spent":"327.532588ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-09T18:55:03.84216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:03.335428Z","time spent":"506.665854ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:55:03.849156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.846297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-09T18:55:03.849208Z","caller":"traceutil/trace.go:171","msg":"trace[841116520] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:781; }","duration":"149.903379ms","start":"2026-02-09T18:55:03.699292Z","end":"2026-02-09T18:55:03.849196Z","steps":["trace[841116520] 'agreement among raft nodes before linearized reading' (duration: 143.042542ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:07.138575Z","caller":"traceutil/trace.go:171","msg":"trace[1989402173] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"244.590668ms","start":"2026-02-09T18:55:06.893965Z","end":"2026-02-09T18:55:07.138556Z","steps":["trace[1989402173] 'process raft request' (duration: 244.463485ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:08.388706Z","caller":"traceutil/trace.go:171","msg":"trace[1307565470] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"520.375536ms","start":"2026-02-09T18:55:07.868311Z","end":"2026-02-09T18:55:08.388686Z","steps":["trace[1307565470] 'process raft request' (duration: 520.251023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:08.388857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:07.868282Z","time spent":"520.494759ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:55:09.05822Z","caller":"traceutil/trace.go:171","msg":"trace[17158728] linearizableReadLoop","detail":"{readStateIndex:822; appliedIndex:821; }","duration":"1.127887578s","start":"2026-02-09T18:55:07.930312Z","end":"2026-02-09T18:55:09.0582Z","steps":["trace[17158728] 'read index received' (duration: 458.946015ms)","trace[17158728] 'applied index is now lower than readState.Index' (duration: 668.940513ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:09.058387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.816832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-09T18:55:09.058404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.128085003s","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-09T18:55:09.058422Z","caller":"traceutil/trace.go:171","msg":"trace[133090795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:793; }","duration":"936.887773ms","start":"2026-02-09T18:55:08.121522Z","end":"2026-02-09T18:55:09.05841Z","steps":["trace[133090795] 'agreement among raft nodes before linearized reading' (duration: 936.791021ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:09.058433Z","caller":"traceutil/trace.go:171","msg":"trace[740615806] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:793; }","duration":"1.128137175s","start":"2026-02-09T18:55:07.930288Z","end":"2026-02-09T18:55:09.058425Z","steps":["trace[740615806] 'agreement among raft nodes before linearized reading' (duration: 1.128019512s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:09.058453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:07.930277Z","time spent":"1.128171465s","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-09T18:55:09.058454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:08.121505Z","time spent":"936.941695ms","remote":"127.0.0.1:56216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-09T18:55:09.058654Z","caller":"traceutil/trace.go:171","msg":"trace[681704894] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"1.190244921s","start":"2026-02-09T18:55:07.868401Z","end":"2026-02-09T18:55:09.058646Z","steps":["trace[681704894] 'process raft request' (duration: 1.187433567s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:09.05871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:07.868397Z","time spent":"1.190276621s","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:55:09.058724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.90689ms","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-09T18:55:09.058754Z","caller":"traceutil/trace.go:171","msg":"trace[1677006663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:793; }","duration":"610.968432ms","start":"2026-02-09T18:55:08.447777Z","end":"2026-02-09T18:55:09.058745Z","steps":["trace[1677006663] 'agreement among raft nodes before linearized reading' (duration: 610.87805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:09.058788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:08.44776Z","time spent":"611.018623ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-09T18:55:09.455927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.839107ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T18:55:09.455993Z","caller":"traceutil/trace.go:171","msg":"trace[115271390] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:823; }","duration":"314.443179ms","start":"2026-02-09T18:55:09.141539Z","end":"2026-02-09T18:55:09.455982Z","steps":["trace[115271390] 'read index received' (duration: 3.296955ms)","trace[115271390] 'applied index is now lower than readState.Index' (duration: 311.145404ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:55:09.456153Z","caller":"traceutil/trace.go:171","msg":"trace[830567402] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"387.987626ms","start":"2026-02-09T18:55:09.068157Z","end":"2026-02-09T18:55:09.456145Z","steps":["trace[830567402] 'process raft request' (duration: 237.879443ms)","trace[830567402] 'compare' (duration: 149.770855ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:09.456195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:09.06815Z","time spent":"388.022537ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:55:09.456396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.855078ms","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-09T18:55:09.456416Z","caller":"traceutil/trace.go:171","msg":"trace[1252179923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:795; }","duration":"314.90196ms","start":"2026-02-09T18:55:09.141508Z","end":"2026-02-09T18:55:09.45641Z","steps":["trace[1252179923] 'agreement among raft nodes before linearized reading' (duration: 314.821228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:09.456436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:09.141491Z","time spent":"314.9411ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-09T18:55:09.456533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.632866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:55:09.456552Z","caller":"traceutil/trace.go:171","msg":"trace[187977878] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:795; }","duration":"279.652236ms","start":"2026-02-09T18:55:09.176893Z","end":"2026-02-09T18:55:09.456545Z","steps":["trace[187977878] 'agreement among raft nodes before linearized reading' (duration: 279.620785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:09.855449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.789581ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T18:55:09.855525Z","caller":"traceutil/trace.go:171","msg":"trace[1977515019] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:824; }","duration":"239.282886ms","start":"2026-02-09T18:55:09.61623Z","end":"2026-02-09T18:55:09.855512Z","steps":["trace[1977515019] 'read index received' (duration: 30.320911ms)","trace[1977515019] 'applied index is now lower than readState.Index' (duration: 208.960965ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:09.855651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.426769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-09T18:55:09.855719Z","caller":"traceutil/trace.go:171","msg":"trace[2117819857] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:796; }","duration":"239.492551ms","start":"2026-02-09T18:55:09.616215Z","end":"2026-02-09T18:55:09.855708Z","steps":["trace[2117819857] 'agreement among raft nodes before linearized reading' (duration: 239.334047ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:09.855986Z","caller":"traceutil/trace.go:171","msg":"trace[1232702608] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"397.086314ms","start":"2026-02-09T18:55:09.458884Z","end":"2026-02-09T18:55:09.85597Z","steps":["trace[1232702608] 'process raft request' (duration: 187.69007ms)","trace[1232702608] 'compare' (duration: 208.496564ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:09.85604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:09.45887Z","time spent":"397.141575ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:55:11.975734Z","caller":"traceutil/trace.go:171","msg":"trace[1471520128] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"114.47384ms","start":"2026-02-09T18:55:11.861241Z","end":"2026-02-09T18:55:11.975715Z","steps":["trace[1471520128] 'process raft request' (duration: 114.368248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:13.462521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.648085ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T18:55:13.462923Z","caller":"traceutil/trace.go:171","msg":"trace[1958833428] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"293.623136ms","start":"2026-02-09T18:55:13.169253Z","end":"2026-02-09T18:55:13.462876Z","steps":["trace[1958833428] 'process raft request' (duration: 78.527681ms)","trace[1958833428] 'compare' (duration: 214.531202ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:14.107658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.162042ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T18:55:14.107753Z","caller":"traceutil/trace.go:171","msg":"trace[1451423326] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"637.20813ms","start":"2026-02-09T18:55:13.470528Z","end":"2026-02-09T18:55:14.107736Z","steps":["trace[1451423326] 'process raft request' (duration: 444.903725ms)","trace[1451423326] 'compare' (duration: 192.028899ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:14.108983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:13.470513Z","time spent":"638.271414ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:55:14.111465Z","caller":"traceutil/trace.go:171","msg":"trace[986992804] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:837; }","duration":"132.125213ms","start":"2026-02-09T18:55:13.979326Z","end":"2026-02-09T18:55:14.111451Z","steps":["trace[986992804] 'read index received' (duration: 131.186881ms)","trace[986992804] 'applied index is now lower than readState.Index' (duration: 937.422µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:55:14.111812Z","caller":"traceutil/trace.go:171","msg":"trace[1935994518] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"197.114894ms","start":"2026-02-09T18:55:13.914684Z","end":"2026-02-09T18:55:14.111799Z","steps":["trace[1935994518] 'process raft request' (duration: 195.863946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:14.112121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.787888ms","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-09T18:55:14.112158Z","caller":"traceutil/trace.go:171","msg":"trace[639702290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:808; }","duration":"132.851329ms","start":"2026-02-09T18:55:13.979297Z","end":"2026-02-09T18:55:14.112148Z","steps":["trace[639702290] 'agreement among raft nodes before linearized reading' (duration: 132.206425ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:14.345954Z","caller":"traceutil/trace.go:171","msg":"trace[1923630259] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"229.209956ms","start":"2026-02-09T18:55:14.116717Z","end":"2026-02-09T18:55:14.345927Z","steps":["trace[1923630259] 'process raft request' (duration: 186.705087ms)","trace[1923630259] 'compare' (duration: 42.385407ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:15.523702Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656050600397313505,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-09T18:55:15.918994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.008053016s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T18:55:15.919089Z","caller":"traceutil/trace.go:171","msg":"trace[1555857574] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"1.488613554s","start":"2026-02-09T18:55:14.430457Z","end":"2026-02-09T18:55:15.91907Z","steps":["trace[1555857574] 'process raft request' (duration: 480.411144ms)","trace[1555857574] 'compare' (duration: 1.007188277s)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:15.919145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:14.430438Z","time spent":"1.488680786s","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:55:16.151472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.31609ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-09T18:55:16.151564Z","caller":"traceutil/trace.go:171","msg":"trace[1918116343] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:840; }","duration":"1.128287998s","start":"2026-02-09T18:55:15.023257Z","end":"2026-02-09T18:55:16.151545Z","steps":["trace[1918116343] 'read index received' (duration: 901.827244ms)","trace[1918116343] 'applied index is now lower than readState.Index' (duration: 226.459294ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:16.151844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028309758s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:55:16.151879Z","caller":"traceutil/trace.go:171","msg":"trace[951281050] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:810; }","duration":"1.028392049s","start":"2026-02-09T18:55:15.123477Z","end":"2026-02-09T18:55:16.151869Z","steps":["trace[951281050] 'agreement among raft nodes before linearized reading' (duration: 1.028327448s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:16.151911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:15.123458Z","time spent":"1.028445671s","remote":"127.0.0.1:56216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-09T18:55:16.152573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.129324261s","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-09T18:55:16.152637Z","caller":"traceutil/trace.go:171","msg":"trace[1730296203] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:810; }","duration":"1.129424934s","start":"2026-02-09T18:55:15.023202Z","end":"2026-02-09T18:55:16.152627Z","steps":["trace[1730296203] 'agreement among raft nodes before linearized reading' (duration: 1.128456832s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:16.152681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:15.023182Z","time spent":"1.129489656s","remote":"127.0.0.1:56580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":51,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-09T18:55:16.153063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.254199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-09T18:55:16.153136Z","caller":"traceutil/trace.go:171","msg":"trace[1076393952] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:810; }","duration":"947.354592ms","start":"2026-02-09T18:55:15.205773Z","end":"2026-02-09T18:55:16.153128Z","steps":["trace[1076393952] 'agreement among raft nodes before linearized reading' (duration: 947.240249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:16.153194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:15.205757Z","time spent":"947.413163ms","remote":"127.0.0.1:56476","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-09T18:55:16.153454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.190346ms","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-09T18:55:16.1538Z","caller":"traceutil/trace.go:171","msg":"trace[2008207498] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:810; }","duration":"686.553975ms","start":"2026-02-09T18:55:15.467237Z","end":"2026-02-09T18:55:16.153791Z","steps":["trace[2008207498] 'agreement among raft nodes before linearized reading' (duration: 686.172107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:16.153866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:15.467222Z","time spent":"686.629157ms","remote":"127.0.0.1:56476","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-09T18:55:16.38543Z","caller":"traceutil/trace.go:171","msg":"trace[2143478986] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:841; }","duration":"218.293718ms","start":"2026-02-09T18:55:16.167122Z","end":"2026-02-09T18:55:16.385415Z","steps":["trace[2143478986] 'read index received' (duration: 34.893706ms)","trace[2143478986] 'applied index is now lower than readState.Index' (duration: 183.398982ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:55:16.385544Z","caller":"traceutil/trace.go:171","msg":"trace[1201748079] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"223.389254ms","start":"2026-02-09T18:55:16.162147Z","end":"2026-02-09T18:55:16.385536Z","steps":["trace[1201748079] 'process raft request' (duration: 210.352466ms)","trace[1201748079] 'compare' (duration: 12.220189ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:55:16.385789Z","caller":"traceutil/trace.go:171","msg":"trace[544978890] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"217.310955ms","start":"2026-02-09T18:55:16.168471Z","end":"2026-02-09T18:55:16.385782Z","steps":["trace[544978890] 'process raft request' (duration: 216.672701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:16.385878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.742408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:55:16.385898Z","caller":"traceutil/trace.go:171","msg":"trace[846351953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:813; }","duration":"218.788969ms","start":"2026-02-09T18:55:16.167103Z","end":"2026-02-09T18:55:16.385892Z","steps":["trace[846351953] 'agreement among raft nodes before linearized reading' (duration: 218.744158ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:30.538735Z","caller":"traceutil/trace.go:171","msg":"trace[1827223957] linearizableReadLoop","detail":"{readStateIndex:942; appliedIndex:941; }","duration":"217.308255ms","start":"2026-02-09T18:55:30.321412Z","end":"2026-02-09T18:55:30.53872Z","steps":["trace[1827223957] 'read index received' (duration: 217.285904ms)","trace[1827223957] 'applied index is now lower than readState.Index' (duration: 21.741µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:55:30.538905Z","caller":"traceutil/trace.go:171","msg":"trace[1546456701] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"237.735421ms","start":"2026-02-09T18:55:30.301162Z","end":"2026-02-09T18:55:30.538897Z","steps":["trace[1546456701] 'process raft request' (duration: 237.306861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:30.53935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.877988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-09T18:55:30.539379Z","caller":"traceutil/trace.go:171","msg":"trace[1290725046] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:906; }","duration":"217.984291ms","start":"2026-02-09T18:55:30.321386Z","end":"2026-02-09T18:55:30.53937Z","steps":["trace[1290725046] 'agreement among raft nodes before linearized reading' (duration: 217.884839ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:32.843282Z","caller":"traceutil/trace.go:171","msg":"trace[909528227] linearizableReadLoop","detail":"{readStateIndex:948; appliedIndex:947; }","duration":"291.115058ms","start":"2026-02-09T18:55:32.552146Z","end":"2026-02-09T18:55:32.843261Z","steps":["trace[909528227] 'read index received' (duration: 290.943704ms)","trace[909528227] 'applied index is now lower than readState.Index' (duration: 168.414µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:55:32.843383Z","caller":"traceutil/trace.go:171","msg":"trace[2080333484] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"298.690151ms","start":"2026-02-09T18:55:32.544684Z","end":"2026-02-09T18:55:32.843374Z","steps":["trace[2080333484] 'process raft request' (duration: 298.455315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:32.843573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.409365ms","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-09T18:55:32.843602Z","caller":"traceutil/trace.go:171","msg":"trace[845795097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:912; }","duration":"291.466446ms","start":"2026-02-09T18:55:32.552126Z","end":"2026-02-09T18:55:32.843593Z","steps":["trace[845795097] 'agreement among raft nodes before linearized reading' (duration: 291.353183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:32.843751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.931263ms","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-09T18:55:32.84381Z","caller":"traceutil/trace.go:171","msg":"trace[798569761] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:912; }","duration":"123.021625ms","start":"2026-02-09T18:55:32.720773Z","end":"2026-02-09T18:55:32.843795Z","steps":["trace[798569761] 'agreement among raft nodes before linearized reading' (duration: 122.868902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:34.49523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.495176142s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T18:55:34.495369Z","caller":"traceutil/trace.go:171","msg":"trace[990188130] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"1.646049943s","start":"2026-02-09T18:55:32.849276Z","end":"2026-02-09T18:55:34.495326Z","steps":["trace[990188130] 'process raft request' (duration: 150.662786ms)","trace[990188130] 'compare' (duration: 1.49375263s)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:34.496731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:32.849262Z","time spent":"1.646140955s","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:55:34.600551Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.600657058s","expected-duration":"1s"} {"level":"info","ts":"2026-02-09T18:55:34.604235Z","caller":"traceutil/trace.go:171","msg":"trace[1366826226] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"1.754853564s","start":"2026-02-09T18:55:32.849364Z","end":"2026-02-09T18:55:34.604218Z","steps":["trace[1366826226] 'process raft request' (duration: 1.751343735s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:34.604356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:32.849357Z","time spent":"1.754908246s","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:55:34.604698Z","caller":"traceutil/trace.go:171","msg":"trace[2061184846] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"1.75380658s","start":"2026-02-09T18:55:32.850885Z","end":"2026-02-09T18:55:34.604691Z","steps":["trace[2061184846] 'process raft request' (duration: 1.753285958s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:34.604728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:32.850866Z","time spent":"1.753845001s","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:55:34.60562Z","caller":"traceutil/trace.go:171","msg":"trace[1392361956] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:949; }","duration":"482.075191ms","start":"2026-02-09T18:55:34.123537Z","end":"2026-02-09T18:55:34.605613Z","steps":["trace[1392361956] 'read index received' (duration: 477.239731ms)","trace[1392361956] 'applied index is now lower than readState.Index' (duration: 4.83439ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:34.605671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.131882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:55:34.605686Z","caller":"traceutil/trace.go:171","msg":"trace[1721083139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:915; }","duration":"482.149073ms","start":"2026-02-09T18:55:34.123532Z","end":"2026-02-09T18:55:34.605681Z","steps":["trace[1721083139] 'agreement among raft nodes before linearized reading' (duration: 482.119292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:34.605699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:34.123479Z","time spent":"482.217385ms","remote":"127.0.0.1:56216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-09T18:55:34.606045Z","caller":"traceutil/trace.go:171","msg":"trace[1618212338] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"143.097023ms","start":"2026-02-09T18:55:34.462942Z","end":"2026-02-09T18:55:34.606039Z","steps":["trace[1618212338] 'process raft request' (duration: 141.254251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:34.606419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.077815ms","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-09T18:55:34.606447Z","caller":"traceutil/trace.go:171","msg":"trace[33621516] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:916; }","duration":"455.130317ms","start":"2026-02-09T18:55:34.151312Z","end":"2026-02-09T18:55:34.606442Z","steps":["trace[33621516] 'agreement among raft nodes before linearized reading' (duration: 454.636836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:34.606461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:34.151302Z","time spent":"455.155527ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-09T18:55:36.766895Z","caller":"traceutil/trace.go:171","msg":"trace[439091133] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:955; }","duration":"157.311148ms","start":"2026-02-09T18:55:36.609566Z","end":"2026-02-09T18:55:36.766878Z","steps":["trace[439091133] 'read index received' (duration: 157.193805ms)","trace[439091133] 'applied index is now lower than readState.Index' (duration: 116.853µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:55:36.767088Z","caller":"traceutil/trace.go:171","msg":"trace[1116234983] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"262.882884ms","start":"2026-02-09T18:55:36.504199Z","end":"2026-02-09T18:55:36.767082Z","steps":["trace[1116234983] 'process raft request' (duration: 262.601968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:36.767233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.655205ms","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-09T18:55:36.767248Z","caller":"traceutil/trace.go:171","msg":"trace[1554722339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:919; }","duration":"157.724967ms","start":"2026-02-09T18:55:36.609519Z","end":"2026-02-09T18:55:36.767244Z","steps":["trace[1554722339] 'agreement among raft nodes before linearized reading' (duration: 157.652245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:36.767329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.722117ms","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-09T18:55:36.76735Z","caller":"traceutil/trace.go:171","msg":"trace[2076049689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:919; }","duration":"157.748967ms","start":"2026-02-09T18:55:36.609595Z","end":"2026-02-09T18:55:36.767344Z","steps":["trace[2076049689] 'agreement among raft nodes before linearized reading' (duration: 157.708286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:36.767489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.783509ms","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-09T18:55:36.767503Z","caller":"traceutil/trace.go:171","msg":"trace[812233083] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:919; }","duration":"154.81491ms","start":"2026-02-09T18:55:36.612684Z","end":"2026-02-09T18:55:36.767499Z","steps":["trace[812233083] 'agreement among raft nodes before linearized reading' (duration: 154.769799ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:37.255798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.03166ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T18:55:37.255875Z","caller":"traceutil/trace.go:171","msg":"trace[1805588482] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"484.759333ms","start":"2026-02-09T18:55:36.7711Z","end":"2026-02-09T18:55:37.25586Z","steps":["trace[1805588482] 'process raft request' (duration: 277.60723ms)","trace[1805588482] 'compare' (duration: 206.440347ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:37.255929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:36.771075Z","time spent":"484.835735ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:55:37.994023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.125481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T18:55:37.994217Z","caller":"traceutil/trace.go:171","msg":"trace[1080857867] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:956; }","duration":"1.120885357s","start":"2026-02-09T18:55:36.873316Z","end":"2026-02-09T18:55:37.994201Z","steps":["trace[1080857867] 'read index received' (duration: 175.400269ms)","trace[1080857867] 'applied index is now lower than readState.Index' (duration: 945.484178ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:55:37.994478Z","caller":"traceutil/trace.go:171","msg":"trace[2117931191] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"1.222335171s","start":"2026-02-09T18:55:36.772089Z","end":"2026-02-09T18:55:37.994424Z","steps":["trace[2117931191] 'process raft request' (duration: 1.107743089s)","trace[2117931191] 'compare' (duration: 113.951317ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:37.994975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:36.772076Z","time spent":"1.222831692s","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:55:37.995312Z","caller":"traceutil/trace.go:171","msg":"trace[354802102] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"1.223031207s","start":"2026-02-09T18:55:36.772268Z","end":"2026-02-09T18:55:37.995299Z","steps":["trace[354802102] 'process raft request' (duration: 1.221880351s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:37.995564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:36.772259Z","time spent":"1.223230561s","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:55:37.996182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.122862343s","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-09T18:55:37.99694Z","caller":"traceutil/trace.go:171","msg":"trace[1157006280] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:922; }","duration":"1.12361413s","start":"2026-02-09T18:55:36.87331Z","end":"2026-02-09T18:55:37.996924Z","steps":["trace[1157006280] 'agreement among raft nodes before linearized reading' (duration: 1.122794421s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:37.997153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:36.873268Z","time spent":"1.123844455s","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-09T18:55:37.996219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.282873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:55:37.997619Z","caller":"traceutil/trace.go:171","msg":"trace[727462251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:922; }","duration":"864.708936ms","start":"2026-02-09T18:55:37.132898Z","end":"2026-02-09T18:55:37.997607Z","steps":["trace[727462251] 'agreement among raft nodes before linearized reading' (duration: 863.309644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:37.997667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:37.132879Z","time spent":"864.777847ms","remote":"127.0.0.1:56216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-09T18:55:37.996326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.6214ms","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-09T18:55:37.997854Z","caller":"traceutil/trace.go:171","msg":"trace[50224367] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:922; }","duration":"504.183216ms","start":"2026-02-09T18:55:37.493661Z","end":"2026-02-09T18:55:37.997845Z","steps":["trace[50224367] 'agreement among raft nodes before linearized reading' (duration: 502.61777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:37.997886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:37.493642Z","time spent":"504.234657ms","remote":"127.0.0.1:48000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":2,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-02-09T18:55:38.160162Z","caller":"traceutil/trace.go:171","msg":"trace[252765227] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"158.3298ms","start":"2026-02-09T18:55:38.001804Z","end":"2026-02-09T18:55:38.160134Z","steps":["trace[252765227] 'process raft request' (duration: 122.699427ms)","trace[252765227] 'compare' (duration: 31.12675ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:55:42.461225Z","caller":"traceutil/trace.go:171","msg":"trace[864307643] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"451.86536ms","start":"2026-02-09T18:55:42.009344Z","end":"2026-02-09T18:55:42.461209Z","steps":["trace[864307643] 'process raft request' (duration: 451.776188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:42.461316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:42.009333Z","time spent":"451.934923ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:55:42.46638Z","caller":"traceutil/trace.go:171","msg":"trace[1124140364] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:971; }","duration":"346.637118ms","start":"2026-02-09T18:55:42.119736Z","end":"2026-02-09T18:55:42.466373Z","steps":["trace[1124140364] 'read index received' (duration: 341.8403ms)","trace[1124140364] 'applied index is now lower than readState.Index' (duration: 4.796508ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:55:42.466487Z","caller":"traceutil/trace.go:171","msg":"trace[900603880] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"456.531307ms","start":"2026-02-09T18:55:42.009949Z","end":"2026-02-09T18:55:42.46648Z","steps":["trace[900603880] 'process raft request' (duration: 456.386823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:42.466527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:42.009944Z","time spent":"456.564737ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:55:42.466602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.869093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:55:42.466617Z","caller":"traceutil/trace.go:171","msg":"trace[1661772671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:934; }","duration":"346.906655ms","start":"2026-02-09T18:55:42.119705Z","end":"2026-02-09T18:55:42.466612Z","steps":["trace[1661772671] 'agreement among raft nodes before linearized reading' (duration: 346.882704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:42.46663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:42.119672Z","time spent":"346.955025ms","remote":"127.0.0.1:56216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-09T18:55:42.466749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.598738ms","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-09T18:55:42.466763Z","caller":"traceutil/trace.go:171","msg":"trace[764520043] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:934; }","duration":"297.637299ms","start":"2026-02-09T18:55:42.169121Z","end":"2026-02-09T18:55:42.466758Z","steps":["trace[764520043] 'agreement among raft nodes before linearized reading' (duration: 297.581068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:43.010465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.787598ms","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-09T18:55:43.01051Z","caller":"traceutil/trace.go:171","msg":"trace[1097314358] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:935; }","duration":"224.860119ms","start":"2026-02-09T18:55:42.785639Z","end":"2026-02-09T18:55:43.010499Z","steps":["trace[1097314358] 'range keys from in-memory index tree' (duration: 224.691255ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:49.210005Z","caller":"traceutil/trace.go:171","msg":"trace[637770980] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"112.696712ms","start":"2026-02-09T18:55:49.097293Z","end":"2026-02-09T18:55:49.209989Z","steps":["trace[637770980] 'process raft request' (duration: 112.396585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:49.487327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.261856ms","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-09T18:55:49.487542Z","caller":"traceutil/trace.go:171","msg":"trace[916445932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:952; }","duration":"106.516982ms","start":"2026-02-09T18:55:49.381003Z","end":"2026-02-09T18:55:49.48752Z","steps":["trace[916445932] 'range keys from in-memory index tree' (duration: 106.088242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:49.822077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.44303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-09T18:55:49.822124Z","caller":"traceutil/trace.go:171","msg":"trace[24564127] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:953; }","duration":"204.535622ms","start":"2026-02-09T18:55:49.617578Z","end":"2026-02-09T18:55:49.822113Z","steps":["trace[24564127] 'range keys from in-memory index tree' (duration: 204.304906ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:51.669923Z","caller":"traceutil/trace.go:171","msg":"trace[320822580] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"138.792962ms","start":"2026-02-09T18:55:51.531114Z","end":"2026-02-09T18:55:51.669907Z","steps":["trace[320822580] 'process raft request' (duration: 138.68229ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:55.897427Z","caller":"traceutil/trace.go:171","msg":"trace[1135008338] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1030; }","duration":"214.051796ms","start":"2026-02-09T18:55:55.683352Z","end":"2026-02-09T18:55:55.897404Z","steps":["trace[1135008338] 'read index received' (duration: 213.853641ms)","trace[1135008338] 'applied index is now lower than readState.Index' (duration: 197.205µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:55.897639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.268861ms","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-09T18:55:55.897696Z","caller":"traceutil/trace.go:171","msg":"trace[1886403012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:990; }","duration":"214.349583ms","start":"2026-02-09T18:55:55.683318Z","end":"2026-02-09T18:55:55.897667Z","steps":["trace[1886403012] 'agreement among raft nodes before linearized reading' (duration: 214.176889ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:55.897968Z","caller":"traceutil/trace.go:171","msg":"trace[1750565928] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"667.537453ms","start":"2026-02-09T18:55:55.230421Z","end":"2026-02-09T18:55:55.897958Z","steps":["trace[1750565928] 'process raft request' (duration: 666.841578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:55.898047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:55.23041Z","time spent":"667.587514ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:55:56.448557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.524673ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T18:55:56.448672Z","caller":"traceutil/trace.go:171","msg":"trace[666004983] linearizableReadLoop","detail":"{readStateIndex:1032; appliedIndex:1031; }","duration":"248.16953ms","start":"2026-02-09T18:55:56.200471Z","end":"2026-02-09T18:55:56.448641Z","steps":["trace[666004983] 'read index received' (duration: 55.172µs)","trace[666004983] 'applied index is now lower than readState.Index' (duration: 248.113208ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:56.448737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.290672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:55:56.448761Z","caller":"traceutil/trace.go:171","msg":"trace[220174098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:991; }","duration":"248.349523ms","start":"2026-02-09T18:55:56.200403Z","end":"2026-02-09T18:55:56.448753Z","steps":["trace[220174098] 'agreement among raft nodes before linearized reading' (duration: 248.306082ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:56.448877Z","caller":"traceutil/trace.go:171","msg":"trace[763370679] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"546.156656ms","start":"2026-02-09T18:55:55.902699Z","end":"2026-02-09T18:55:56.448856Z","steps":["trace[763370679] 'process raft request' (duration: 136.257044ms)","trace[763370679] 'compare' (duration: 409.426921ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:56.448985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:55.902684Z","time spent":"546.246658ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:55:57.041183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.913397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-09T18:55:57.041261Z","caller":"traceutil/trace.go:171","msg":"trace[948680981] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:991; }","duration":"465.04313ms","start":"2026-02-09T18:55:56.5762Z","end":"2026-02-09T18:55:57.041243Z","steps":["trace[948680981] 'range keys from in-memory index tree' (duration: 464.774003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:57.04133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:56.576186Z","time spent":"465.106261ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-09T18:55:57.041655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.522308ms","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-09T18:55:57.041697Z","caller":"traceutil/trace.go:171","msg":"trace[706154823] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:991; }","duration":"464.588939ms","start":"2026-02-09T18:55:56.577093Z","end":"2026-02-09T18:55:57.041682Z","steps":["trace[706154823] 'range keys from in-memory index tree' (duration: 464.415016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:57.041726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:56.577074Z","time spent":"464.64521ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-09T18:55:57.041958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.051174ms","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-09T18:55:57.041987Z","caller":"traceutil/trace.go:171","msg":"trace[815182573] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:991; }","duration":"402.113695ms","start":"2026-02-09T18:55:56.639865Z","end":"2026-02-09T18:55:57.041978Z","steps":["trace[815182573] 'range keys from in-memory index tree' (duration: 401.952381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:57.042017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:56.639844Z","time spent":"402.166576ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-09T18:56:04.659546Z","caller":"traceutil/trace.go:171","msg":"trace[1094020079] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"141.7675ms","start":"2026-02-09T18:56:04.517754Z","end":"2026-02-09T18:56:04.659522Z","steps":["trace[1094020079] 'process raft request' (duration: 141.603216ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:08.639576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.851277ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T18:56:08.639714Z","caller":"traceutil/trace.go:171","msg":"trace[2082569128] linearizableReadLoop","detail":"{readStateIndex:1105; appliedIndex:1103; }","duration":"882.43379ms","start":"2026-02-09T18:56:07.75727Z","end":"2026-02-09T18:56:08.639703Z","steps":["trace[2082569128] 'read index received' (duration: 102.401939ms)","trace[2082569128] 'applied index is now lower than readState.Index' (duration: 780.031211ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:56:08.639867Z","caller":"traceutil/trace.go:171","msg":"trace[344073111] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"1.417676419s","start":"2026-02-09T18:56:07.22218Z","end":"2026-02-09T18:56:08.639857Z","steps":["trace[344073111] 'process raft request' (duration: 637.485345ms)","trace[344073111] 'compare' (duration: 779.156411ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:56:08.639911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:07.222151Z","time spent":"1.41774008s","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:56:08.640023Z","caller":"traceutil/trace.go:171","msg":"trace[1566148627] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"1.4177067s","start":"2026-02-09T18:56:07.22231Z","end":"2026-02-09T18:56:08.640017Z","steps":["trace[1566148627] 'process raft request' (duration: 1.417339382s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:08.640061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:07.222305Z","time spent":"1.417737271s","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:56:08.640182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.925711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-09T18:56:08.6402Z","caller":"traceutil/trace.go:171","msg":"trace[978863014] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1062; }","duration":"882.977192ms","start":"2026-02-09T18:56:07.757216Z","end":"2026-02-09T18:56:08.640194Z","steps":["trace[978863014] 'agreement among raft nodes before linearized reading' (duration: 882.90736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:08.640217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:07.757197Z","time spent":"883.016204ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-09T18:56:08.640379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.025127ms","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-09T18:56:08.6404Z","caller":"traceutil/trace.go:171","msg":"trace[2041472875] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1062; }","duration":"684.046428ms","start":"2026-02-09T18:56:07.956347Z","end":"2026-02-09T18:56:08.640393Z","steps":["trace[2041472875] 'agreement among raft nodes before linearized reading' (duration: 683.938345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:08.640418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:07.95628Z","time spent":"684.13382ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-09T18:56:08.640693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.26597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-02-09T18:56:08.640719Z","caller":"traceutil/trace.go:171","msg":"trace[2045663790] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1062; }","duration":"365.321942ms","start":"2026-02-09T18:56:08.275389Z","end":"2026-02-09T18:56:08.640711Z","steps":["trace[2045663790] 'agreement among raft nodes before linearized reading' (duration: 365.25353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:08.64074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:08.275373Z","time spent":"365.362202ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-09T18:56:08.640838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.55052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-02-09T18:56:08.640859Z","caller":"traceutil/trace.go:171","msg":"trace[1663156399] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1062; }","duration":"438.57061ms","start":"2026-02-09T18:56:08.202283Z","end":"2026-02-09T18:56:08.640853Z","steps":["trace[1663156399] 'agreement among raft nodes before linearized reading' (duration: 438.520949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:08.640879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:08.20222Z","time spent":"438.654891ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-09T18:56:08.640947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.268902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:56:08.640966Z","caller":"traceutil/trace.go:171","msg":"trace[1636430410] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1062; }","duration":"517.320933ms","start":"2026-02-09T18:56:08.123641Z","end":"2026-02-09T18:56:08.640962Z","steps":["trace[1636430410] 'agreement among raft nodes before linearized reading' (duration: 517.292853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:08.64098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:08.123607Z","time spent":"517.370665ms","remote":"127.0.0.1:56216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-09T18:56:08.910764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.663441ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T18:56:08.910868Z","caller":"traceutil/trace.go:171","msg":"trace[1761825789] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"258.129994ms","start":"2026-02-09T18:56:08.652719Z","end":"2026-02-09T18:56:08.910849Z","steps":["trace[1761825789] 'process raft request' (duration: 130.294279ms)","trace[1761825789] 'compare' (duration: 127.548298ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:56:08.9759Z","caller":"traceutil/trace.go:171","msg":"trace[325522551] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1105; }","duration":"297.177188ms","start":"2026-02-09T18:56:08.678703Z","end":"2026-02-09T18:56:08.97588Z","steps":["trace[325522551] 'read index received' (duration: 104.24462ms)","trace[325522551] 'applied index is now lower than readState.Index' (duration: 192.931598ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:56:08.976051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.343813ms","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-09T18:56:08.976075Z","caller":"traceutil/trace.go:171","msg":"trace[237057041] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1066; }","duration":"297.403574ms","start":"2026-02-09T18:56:08.678664Z","end":"2026-02-09T18:56:08.976067Z","steps":["trace[237057041] 'agreement among raft nodes before linearized reading' (duration: 297.289511ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:56:08.976269Z","caller":"traceutil/trace.go:171","msg":"trace[1772778942] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"323.173397ms","start":"2026-02-09T18:56:08.653088Z","end":"2026-02-09T18:56:08.976261Z","steps":["trace[1772778942] 'process raft request' (duration: 322.645365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:08.976377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:08.653077Z","time spent":"323.254249ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:56:08.976481Z","caller":"traceutil/trace.go:171","msg":"trace[1141316009] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"321.203252ms","start":"2026-02-09T18:56:08.655269Z","end":"2026-02-09T18:56:08.976472Z","steps":["trace[1141316009] 'process raft request' (duration: 320.529946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:08.976522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:08.655262Z","time spent":"321.240733ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:56:08.976699Z","caller":"traceutil/trace.go:171","msg":"trace[692658218] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"320.870385ms","start":"2026-02-09T18:56:08.65582Z","end":"2026-02-09T18:56:08.97669Z","steps":["trace[692658218] 'process raft request' (duration: 320.037716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:08.976736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:56:08.655814Z","time spent":"320.902455ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:56:09.085089Z","caller":"traceutil/trace.go:171","msg":"trace[2130142936] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"100.334072ms","start":"2026-02-09T18:56:08.984739Z","end":"2026-02-09T18:56:09.085073Z","steps":["trace[2130142936] 'process raft request' (duration: 90.385217ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:56:33.323953Z","caller":"traceutil/trace.go:171","msg":"trace[670271020] linearizableReadLoop","detail":"{readStateIndex:1212; appliedIndex:1211; }","duration":"124.237533ms","start":"2026-02-09T18:56:33.199689Z","end":"2026-02-09T18:56:33.323926Z","steps":["trace[670271020] 'read index received' (duration: 124.120081ms)","trace[670271020] 'applied index is now lower than readState.Index' (duration: 116.812µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:56:33.324033Z","caller":"traceutil/trace.go:171","msg":"trace[1718651593] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"159.885921ms","start":"2026-02-09T18:56:33.16414Z","end":"2026-02-09T18:56:33.324026Z","steps":["trace[1718651593] 'process raft request' (duration: 159.678166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:33.324218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.510109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-09T18:56:33.324238Z","caller":"traceutil/trace.go:171","msg":"trace[932844968] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1164; }","duration":"124.577711ms","start":"2026-02-09T18:56:33.199655Z","end":"2026-02-09T18:56:33.324233Z","steps":["trace[932844968] 'agreement among raft nodes before linearized reading' (duration: 124.476639ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:58:54.957097Z","caller":"traceutil/trace.go:171","msg":"trace[1858909773] transaction","detail":"{read_only:false; response_revision:1744; number_of_response:1; }","duration":"118.417353ms","start":"2026-02-09T18:58:54.838647Z","end":"2026-02-09T18:58:54.957064Z","steps":["trace[1858909773] 'process raft request' (duration: 118.214628ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:59:04.626225Z","caller":"traceutil/trace.go:171","msg":"trace[526662252] transaction","detail":"{read_only:false; response_revision:1783; number_of_response:1; }","duration":"107.507202ms","start":"2026-02-09T18:59:04.518687Z","end":"2026-02-09T18:59:04.626195Z","steps":["trace[526662252] 'process raft request' (duration: 107.235146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:59:05.330049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.51579ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-09T18:59:05.33016Z","caller":"traceutil/trace.go:171","msg":"trace[680630470] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"149.691501ms","start":"2026-02-09T18:59:05.180447Z","end":"2026-02-09T18:59:05.330139Z","steps":["trace[680630470] 'process raft request' (duration: 46.013658ms)","trace[680630470] 'compare' (duration: 103.382597ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:59:05.35335Z","caller":"traceutil/trace.go:171","msg":"trace[694591616] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"127.252605ms","start":"2026-02-09T18:59:05.226075Z","end":"2026-02-09T18:59:05.353327Z","steps":["trace[694591616] 'process raft request' (duration: 127.203914ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:59:05.353384Z","caller":"traceutil/trace.go:171","msg":"trace[1727316148] transaction","detail":"{read_only:false; response_revision:1788; number_of_response:1; }","duration":"172.845283ms","start":"2026-02-09T18:59:05.18052Z","end":"2026-02-09T18:59:05.353365Z","steps":["trace[1727316148] 'process raft request' (duration: 172.671019ms)"],"step_count":1}