{"level":"warn","ts":"2026-02-04T18:20:39.024586Z","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-04T18:20:39.024749Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.74: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://162.253.55.74:2380","--initial-cluster=instance=https://162.253.55.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.74: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-04T18:20:39.025195Z","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-04T18:20:39.025262Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.74:2380"]} {"level":"info","ts":"2026-02-04T18:20:39.025397Z","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-04T18:20:39.026665Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"]} {"level":"info","ts":"2026-02-04T18:20:39.026781Z","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://162.253.55.74:2380"],"listen-peer-urls":["https://162.253.55.74:2380"],"advertise-client-urls":["https://162.253.55.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.74: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-04T18:20:39.034311Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.718713ms"} {"level":"info","ts":"2026-02-04T18:20:39.062656Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"30aa8136fb61a722","cluster-id":"e7ea3889c4872560"} {"level":"info","ts":"2026-02-04T18:20:39.06318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=()"} {"level":"info","ts":"2026-02-04T18:20:39.063255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 0"} {"level":"info","ts":"2026-02-04T18:20:39.063332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 30aa8136fb61a722 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-04T18:20:39.063387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 1"} {"level":"info","ts":"2026-02-04T18:20:39.063507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"warn","ts":"2026-02-04T18:20:39.073383Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-04T18:20:39.079626Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-04T18:20:39.082179Z","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-04T18:20:39.092416Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"30aa8136fb61a722","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-04T18:20:39.094741Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"30aa8136fb61a722","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-04T18:20:39.094891Z","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-04T18:20:39.094916Z","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-04T18:20:39.094925Z","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-04T18:20:39.095682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"info","ts":"2026-02-04T18:20:39.095782Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e7ea3889c4872560","local-member-id":"30aa8136fb61a722","added-peer-id":"30aa8136fb61a722","added-peer-peer-urls":["https://162.253.55.74:2380"]} {"level":"info","ts":"2026-02-04T18:20:39.110636Z","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-04T18:20:39.113243Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"30aa8136fb61a722","initial-advertise-peer-urls":["https://162.253.55.74:2380"],"listen-peer-urls":["https://162.253.55.74:2380"],"advertise-client-urls":["https://162.253.55.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-04T18:20:39.113286Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-04T18:20:39.110752Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-02-04T18:20:39.113795Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-02-04T18:20:39.864078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 is starting a new election at term 1"} {"level":"info","ts":"2026-02-04T18:20:39.864173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-04T18:20:39.864269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgPreVoteResp from 30aa8136fb61a722 at term 1"} {"level":"info","ts":"2026-02-04T18:20:39.864287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became candidate at term 2"} {"level":"info","ts":"2026-02-04T18:20:39.864321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgVoteResp from 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-02-04T18:20:39.864356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became leader at term 2"} {"level":"info","ts":"2026-02-04T18:20:39.864368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 30aa8136fb61a722 elected leader 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-02-04T18:20:39.866874Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"30aa8136fb61a722","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.74:2379]}","request-path":"/0/members/30aa8136fb61a722/attributes","cluster-id":"e7ea3889c4872560","publish-timeout":"7s"} {"level":"info","ts":"2026-02-04T18:20:39.867007Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T18:20:39.867373Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T18:20:39.867549Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T18:20:39.867752Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-04T18:20:39.867768Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-04T18:20:39.870179Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-04T18:20:39.88171Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.74:2379"} {"level":"info","ts":"2026-02-04T18:20:39.881798Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e7ea3889c4872560","local-member-id":"30aa8136fb61a722","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T18:20:39.881856Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T18:20:39.881875Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T18:20:55.306998Z","caller":"traceutil/trace.go:171","msg":"trace[532332016] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:382; }","duration":"345.026469ms","start":"2026-02-04T18:20:54.961949Z","end":"2026-02-04T18:20:55.306976Z","steps":["trace[532332016] 'read index received' (duration: 344.943087ms)","trace[532332016] 'applied index is now lower than readState.Index' (duration: 82.352µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:20:55.307193Z","caller":"traceutil/trace.go:171","msg":"trace[1058658709] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"370.396014ms","start":"2026-02-04T18:20:54.936779Z","end":"2026-02-04T18:20:55.307175Z","steps":["trace[1058658709] 'process raft request' (duration: 370.007686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:20:55.307222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.223613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4726"} {"level":"info","ts":"2026-02-04T18:20:55.307314Z","caller":"traceutil/trace.go:171","msg":"trace[33727586] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:373; }","duration":"345.369116ms","start":"2026-02-04T18:20:54.961909Z","end":"2026-02-04T18:20:55.307278Z","steps":["trace[33727586] 'agreement among raft nodes before linearized reading' (duration: 345.228523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:20:55.307353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:20:54.961884Z","time spent":"345.456768ms","remote":"127.0.0.1:45566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4749,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-02-04T18:20:55.307533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:20:54.936756Z","time spent":"370.477355ms","remote":"127.0.0.1:45628","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-04T18:20:55.546076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.476759ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:20:55.547441Z","caller":"traceutil/trace.go:171","msg":"trace[1160943949] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:373; }","duration":"155.818967ms","start":"2026-02-04T18:20:55.391579Z","end":"2026-02-04T18:20:55.547398Z","steps":["trace[1160943949] 'range keys from in-memory index tree' (duration: 154.466117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:20:55.547151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.047673ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T18:20:55.54923Z","caller":"traceutil/trace.go:171","msg":"trace[793507865] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"407.00571ms","start":"2026-02-04T18:20:55.142211Z","end":"2026-02-04T18:20:55.549217Z","steps":["trace[793507865] 'process raft request' (duration: 280.593986ms)","trace[793507865] 'compare' (duration: 123.087252ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:20:55.549422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:20:55.142193Z","time spent":"407.196405ms","remote":"127.0.0.1:45628","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-04T18:20:55.551003Z","caller":"traceutil/trace.go:171","msg":"trace[1292406319] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"374.735408ms","start":"2026-02-04T18:20:55.176256Z","end":"2026-02-04T18:20:55.550991Z","steps":["trace[1292406319] 'process raft request' (duration: 372.876329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:20:55.551421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:20:55.176235Z","time spent":"375.155476ms","remote":"127.0.0.1:45550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4047,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T18:20:55.595773Z","caller":"traceutil/trace.go:171","msg":"trace[1338948891] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:383; }","duration":"177.698825ms","start":"2026-02-04T18:20:55.418053Z","end":"2026-02-04T18:20:55.595752Z","steps":["trace[1338948891] 'read index received' (duration: 4.759372ms)","trace[1338948891] 'applied index is now lower than readState.Index' (duration: 172.938673ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:20:55.599547Z","caller":"traceutil/trace.go:171","msg":"trace[1724253334] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"277.755265ms","start":"2026-02-04T18:20:55.321779Z","end":"2026-02-04T18:20:55.599534Z","steps":["trace[1724253334] 'process raft request' (duration: 273.433382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:20:55.599716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.648401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:20:55.606624Z","caller":"traceutil/trace.go:171","msg":"trace[126314674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:376; }","duration":"188.560189ms","start":"2026-02-04T18:20:55.418048Z","end":"2026-02-04T18:20:55.606608Z","steps":["trace[126314674] 'agreement among raft nodes before linearized reading' (duration: 181.630111ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:21:11.717632Z","caller":"traceutil/trace.go:171","msg":"trace[1200844081] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:489; }","duration":"299.959191ms","start":"2026-02-04T18:21:11.417658Z","end":"2026-02-04T18:21:11.717617Z","steps":["trace[1200844081] 'read index received' (duration: 299.835578ms)","trace[1200844081] 'applied index is now lower than readState.Index' (duration: 123.103µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:21:11.717721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.046043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:21:11.717738Z","caller":"traceutil/trace.go:171","msg":"trace[1717066377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:477; }","duration":"300.084764ms","start":"2026-02-04T18:21:11.417647Z","end":"2026-02-04T18:21:11.717732Z","steps":["trace[1717066377] 'agreement among raft nodes before linearized reading' (duration: 300.025933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:11.717766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:11.417643Z","time spent":"300.119145ms","remote":"127.0.0.1:45354","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-04T18:21:11.717937Z","caller":"traceutil/trace.go:171","msg":"trace[874309433] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"308.547365ms","start":"2026-02-04T18:21:11.409384Z","end":"2026-02-04T18:21:11.717932Z","steps":["trace[874309433] 'process raft request' (duration: 308.146087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:11.717997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:11.409375Z","time spent":"308.592606ms","remote":"127.0.0.1:45628","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-04T18:21:12.680395Z","caller":"traceutil/trace.go:171","msg":"trace[1643373993] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"421.39168ms","start":"2026-02-04T18:21:12.258982Z","end":"2026-02-04T18:21:12.680373Z","steps":["trace[1643373993] 'process raft request' (duration: 421.256517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:12.680535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:12.258973Z","time spent":"421.483761ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T18:21:12.700927Z","caller":"traceutil/trace.go:171","msg":"trace[2028320835] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"419.715633ms","start":"2026-02-04T18:21:12.281195Z","end":"2026-02-04T18:21:12.70091Z","steps":["trace[2028320835] 'process raft request' (duration: 419.632662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:12.70105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:12.281139Z","time spent":"419.835376ms","remote":"127.0.0.1:45628","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-04T18:21:14.243247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.346372ms","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-04T18:21:14.243326Z","caller":"traceutil/trace.go:171","msg":"trace[763332546] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:484; }","duration":"348.471943ms","start":"2026-02-04T18:21:13.894834Z","end":"2026-02-04T18:21:14.243306Z","steps":["trace[763332546] 'range keys from in-memory index tree' (duration: 348.150538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:14.243368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:13.894816Z","time spent":"348.542956ms","remote":"127.0.0.1:45628","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-04T18:21:17.38404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.741154ms","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-04T18:21:17.384121Z","caller":"traceutil/trace.go:171","msg":"trace[229490149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:493; }","duration":"690.861797ms","start":"2026-02-04T18:21:16.69324Z","end":"2026-02-04T18:21:17.384102Z","steps":["trace[229490149] 'range keys from in-memory index tree' (duration: 690.619721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:17.38416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:16.693221Z","time spent":"690.930428ms","remote":"127.0.0.1:45628","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-04T18:21:17.384533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.1456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-b27c9\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-04T18:21:17.384567Z","caller":"traceutil/trace.go:171","msg":"trace[563490211] range","detail":"{range_begin:/registry/pods/kube-system/cilium-b27c9; range_end:; response_count:1; response_revision:493; }","duration":"290.209041ms","start":"2026-02-04T18:21:17.094349Z","end":"2026-02-04T18:21:17.384558Z","steps":["trace[563490211] 'range keys from in-memory index tree' (duration: 290.051148ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:21:17.722085Z","caller":"traceutil/trace.go:171","msg":"trace[1721655392] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"318.277735ms","start":"2026-02-04T18:21:17.403781Z","end":"2026-02-04T18:21:17.722059Z","steps":["trace[1721655392] 'process raft request' (duration: 318.141702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:17.722226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:17.403764Z","time spent":"318.386897ms","remote":"127.0.0.1:45566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T18:21:17.72286Z","caller":"traceutil/trace.go:171","msg":"trace[1762514971] linearizableReadLoop","detail":"{readStateIndex:508; appliedIndex:508; }","duration":"302.401014ms","start":"2026-02-04T18:21:17.420447Z","end":"2026-02-04T18:21:17.722848Z","steps":["trace[1762514971] 'read index received' (duration: 302.393943ms)","trace[1762514971] 'applied index is now lower than readState.Index' (duration: 3.611µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:21:17.724511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.045468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:21:17.724577Z","caller":"traceutil/trace.go:171","msg":"trace[1738902360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:494; }","duration":"304.160742ms","start":"2026-02-04T18:21:17.420398Z","end":"2026-02-04T18:21:17.724559Z","steps":["trace[1738902360] 'agreement among raft nodes before linearized reading' (duration: 302.491406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:17.724614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:17.42037Z","time spent":"304.236784ms","remote":"127.0.0.1:45358","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-04T18:21:17.741403Z","caller":"traceutil/trace.go:171","msg":"trace[188771245] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"232.184796ms","start":"2026-02-04T18:21:17.509198Z","end":"2026-02-04T18:21:17.741383Z","steps":["trace[188771245] 'process raft request' (duration: 232.055632ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:21:17.852394Z","caller":"traceutil/trace.go:171","msg":"trace[1574216785] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"102.040471ms","start":"2026-02-04T18:21:17.750337Z","end":"2026-02-04T18:21:17.852377Z","steps":["trace[1574216785] 'process raft request' (duration: 65.061567ms)","trace[1574216785] 'compare' (duration: 36.644697ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:21:19.473654Z","caller":"traceutil/trace.go:171","msg":"trace[2052714419] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"364.243792ms","start":"2026-02-04T18:21:19.109389Z","end":"2026-02-04T18:21:19.473633Z","steps":["trace[2052714419] 'process raft request' (duration: 364.131079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:19.473776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:19.109369Z","time spent":"364.353834ms","remote":"127.0.0.1:45456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":754,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T18:21:19.522169Z","caller":"traceutil/trace.go:171","msg":"trace[1240393210] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"400.856968ms","start":"2026-02-04T18:21:19.121291Z","end":"2026-02-04T18:21:19.522148Z","steps":["trace[1240393210] 'process raft request' (duration: 400.710865ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:19.522309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:19.121266Z","time spent":"400.96965ms","remote":"127.0.0.1:45566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17152,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T18:21:20.473919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.59299ms","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-04T18:21:20.473991Z","caller":"traceutil/trace.go:171","msg":"trace[785949728] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:512; }","duration":"127.689503ms","start":"2026-02-04T18:21:20.346283Z","end":"2026-02-04T18:21:20.473972Z","steps":["trace[785949728] 'range keys from in-memory index tree' (duration: 127.450258ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:21:29.073895Z","caller":"traceutil/trace.go:171","msg":"trace[655571124] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"148.11055ms","start":"2026-02-04T18:21:28.925739Z","end":"2026-02-04T18:21:29.07385Z","steps":["trace[655571124] 'process raft request' (duration: 147.826254ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:21:29.511395Z","caller":"traceutil/trace.go:171","msg":"trace[96892254] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"584.40485ms","start":"2026-02-04T18:21:28.926975Z","end":"2026-02-04T18:21:29.511379Z","steps":["trace[96892254] 'process raft request' (duration: 584.322608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:29.511524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:28.926963Z","time spent":"584.517573ms","remote":"127.0.0.1:48300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2259,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T18:21:29.512964Z","caller":"traceutil/trace.go:171","msg":"trace[998989971] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"302.66736ms","start":"2026-02-04T18:21:29.210281Z","end":"2026-02-04T18:21:29.512948Z","steps":["trace[998989971] 'read index received' (duration: 301.747289ms)","trace[998989971] 'applied index is now lower than readState.Index' (duration: 919.371µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:21:29.514026Z","caller":"traceutil/trace.go:171","msg":"trace[457843980] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"386.751666ms","start":"2026-02-04T18:21:29.127268Z","end":"2026-02-04T18:21:29.514019Z","steps":["trace[457843980] 'process raft request' (duration: 385.600901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:29.514157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:29.12725Z","time spent":"386.802526ms","remote":"127.0.0.1:48300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2259,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T18:21:29.515061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.768484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-b27c9\" ","response":"range_response_count:1 size:18246"} {"level":"info","ts":"2026-02-04T18:21:29.515131Z","caller":"traceutil/trace.go:171","msg":"trace[586282250] range","detail":"{range_begin:/registry/pods/kube-system/cilium-b27c9; range_end:; response_count:1; response_revision:617; }","duration":"304.859837ms","start":"2026-02-04T18:21:29.210249Z","end":"2026-02-04T18:21:29.515109Z","steps":["trace[586282250] 'agreement among raft nodes before linearized reading' (duration: 302.855754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:29.515188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:29.21023Z","time spent":"304.933518ms","remote":"127.0.0.1:45566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18269,"request content":"key:\"/registry/pods/kube-system/cilium-b27c9\" "} {"level":"info","ts":"2026-02-04T18:21:31.786283Z","caller":"traceutil/trace.go:171","msg":"trace[1211355685] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:676; }","duration":"347.623595ms","start":"2026-02-04T18:21:31.438645Z","end":"2026-02-04T18:21:31.786269Z","steps":["trace[1211355685] 'read index received' (duration: 347.489392ms)","trace[1211355685] 'applied index is now lower than readState.Index' (duration: 133.733µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:21:31.786403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.741628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" ","response":"range_response_count:1 size:3769"} {"level":"info","ts":"2026-02-04T18:21:31.786419Z","caller":"traceutil/trace.go:171","msg":"trace[1543136037] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-5dd5756b68; range_end:; response_count:1; response_revision:659; }","duration":"347.783788ms","start":"2026-02-04T18:21:31.43863Z","end":"2026-02-04T18:21:31.786414Z","steps":["trace[1543136037] 'agreement among raft nodes before linearized reading' (duration: 347.692596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:31.786435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:31.438624Z","time spent":"347.806729ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":3792,"request content":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" "} {"level":"info","ts":"2026-02-04T18:21:31.786528Z","caller":"traceutil/trace.go:171","msg":"trace[467544932] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"350.321012ms","start":"2026-02-04T18:21:31.436199Z","end":"2026-02-04T18:21:31.78652Z","steps":["trace[467544932] 'process raft request' (duration: 349.984285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:31.786573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:31.43619Z","time spent":"350.350324ms","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4182,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T18:21:32.476108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.033887ms","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-04T18:21:32.476152Z","caller":"traceutil/trace.go:171","msg":"trace[391735435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:659; }","duration":"554.121009ms","start":"2026-02-04T18:21:31.922021Z","end":"2026-02-04T18:21:32.476142Z","steps":["trace[391735435] 'range keys from in-memory index tree' (duration: 553.930014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:32.476172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:31.922002Z","time spent":"554.16583ms","remote":"127.0.0.1:45628","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-04T18:21:32.476316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.296402ms","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-04T18:21:32.476329Z","caller":"traceutil/trace.go:171","msg":"trace[2137783794] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:659; }","duration":"506.340273ms","start":"2026-02-04T18:21:31.969984Z","end":"2026-02-04T18:21:32.476324Z","steps":["trace[2137783794] 'range keys from in-memory index tree' (duration: 506.227961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:32.476341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:31.969967Z","time spent":"506.370874ms","remote":"127.0.0.1:45628","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-04T18:21:32.476415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.507231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-04T18:21:32.476427Z","caller":"traceutil/trace.go:171","msg":"trace[138596125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:659; }","duration":"502.537392ms","start":"2026-02-04T18:21:31.973886Z","end":"2026-02-04T18:21:32.476423Z","steps":["trace[138596125] 'range keys from in-memory index tree' (duration: 502.45262ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:32.476438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:31.973877Z","time spent":"502.558572ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-04T18:21:38.709875Z","caller":"traceutil/trace.go:171","msg":"trace[1554575187] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:698; }","duration":"134.836931ms","start":"2026-02-04T18:21:38.575016Z","end":"2026-02-04T18:21:38.709853Z","steps":["trace[1554575187] 'read index received' (duration: 99.057336ms)","trace[1554575187] 'applied index is now lower than readState.Index' (duration: 35.778105ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:21:38.71011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.072976ms","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-04T18:21:38.710139Z","caller":"traceutil/trace.go:171","msg":"trace[415465821] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:679; }","duration":"135.157878ms","start":"2026-02-04T18:21:38.574972Z","end":"2026-02-04T18:21:38.71013Z","steps":["trace[415465821] 'agreement among raft nodes before linearized reading' (duration: 135.003305ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:21:41.079917Z","caller":"traceutil/trace.go:171","msg":"trace[84856502] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:704; }","duration":"311.028341ms","start":"2026-02-04T18:21:40.768865Z","end":"2026-02-04T18:21:41.079893Z","steps":["trace[84856502] 'read index received' (duration: 310.781195ms)","trace[84856502] 'applied index is now lower than readState.Index' (duration: 245.666µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:21:41.080074Z","caller":"traceutil/trace.go:171","msg":"trace[957082607] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"318.86669ms","start":"2026-02-04T18:21:40.761195Z","end":"2026-02-04T18:21:41.080062Z","steps":["trace[957082607] 'process raft request' (duration: 318.478061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:41.080176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.236705ms","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-04T18:21:41.080198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:40.761178Z","time spent":"318.944211ms","remote":"127.0.0.1:45628","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-04T18:21:41.080222Z","caller":"traceutil/trace.go:171","msg":"trace[1299600265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:685; }","duration":"311.310717ms","start":"2026-02-04T18:21:40.768898Z","end":"2026-02-04T18:21:41.080209Z","steps":["trace[1299600265] 'agreement among raft nodes before linearized reading' (duration: 311.167214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:41.080247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:40.768895Z","time spent":"311.347739ms","remote":"127.0.0.1:45628","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-04T18:21:41.080328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.46547ms","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-04T18:21:41.080339Z","caller":"traceutil/trace.go:171","msg":"trace[786709672] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:685; }","duration":"311.515362ms","start":"2026-02-04T18:21:40.76882Z","end":"2026-02-04T18:21:41.080335Z","steps":["trace[786709672] 'agreement among raft nodes before linearized reading' (duration: 311.478461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:41.080352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:40.768799Z","time spent":"311.550633ms","remote":"127.0.0.1:45628","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-04T18:21:41.080409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.734304ms","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-04T18:21:41.080422Z","caller":"traceutil/trace.go:171","msg":"trace[1482698205] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:685; }","duration":"310.759935ms","start":"2026-02-04T18:21:40.769659Z","end":"2026-02-04T18:21:41.080419Z","steps":["trace[1482698205] 'agreement among raft nodes before linearized reading' (duration: 310.725564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:41.080433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:40.769652Z","time spent":"310.778226ms","remote":"127.0.0.1:45628","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-04T18:21:41.43927Z","caller":"traceutil/trace.go:171","msg":"trace[730423249] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"354.89504ms","start":"2026-02-04T18:21:41.084361Z","end":"2026-02-04T18:21:41.439256Z","steps":["trace[730423249] 'process raft request' (duration: 350.08578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:41.439378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:41.084341Z","time spent":"354.979732ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T18:21:41.440189Z","caller":"traceutil/trace.go:171","msg":"trace[1198745670] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:705; }","duration":"315.598394ms","start":"2026-02-04T18:21:41.124572Z","end":"2026-02-04T18:21:41.440171Z","steps":["trace[1198745670] 'read index received' (duration: 309.883594ms)","trace[1198745670] 'applied index is now lower than readState.Index' (duration: 5.71207ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:21:41.440379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.807879ms","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-04T18:21:41.440402Z","caller":"traceutil/trace.go:171","msg":"trace[272581177] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:688; }","duration":"315.86307ms","start":"2026-02-04T18:21:41.124532Z","end":"2026-02-04T18:21:41.440395Z","steps":["trace[272581177] 'agreement among raft nodes before linearized reading' (duration: 315.786489ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:41.44042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:41.124511Z","time spent":"315.903161ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-04T18:21:41.440491Z","caller":"traceutil/trace.go:171","msg":"trace[1773103414] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"355.931723ms","start":"2026-02-04T18:21:41.084554Z","end":"2026-02-04T18:21:41.440485Z","steps":["trace[1773103414] 'process raft request' (duration: 355.492134ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:41.44052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:41.084546Z","time spent":"355.955614ms","remote":"127.0.0.1:45628","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-04T18:21:41.440582Z","caller":"traceutil/trace.go:171","msg":"trace[1698539528] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"355.984904ms","start":"2026-02-04T18:21:41.084592Z","end":"2026-02-04T18:21:41.440577Z","steps":["trace[1698539528] 'process raft request' (duration: 355.553745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:41.440609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:41.084588Z","time spent":"356.006775ms","remote":"127.0.0.1:45628","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-04T18:21:57.83317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.461908ms","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-04T18:21:57.833229Z","caller":"traceutil/trace.go:171","msg":"trace[835395330] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:736; }","duration":"335.546679ms","start":"2026-02-04T18:21:57.497668Z","end":"2026-02-04T18:21:57.833214Z","steps":["trace[835395330] 'range keys from in-memory index tree' (duration: 335.356505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:57.833256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:57.497655Z","time spent":"335.595981ms","remote":"127.0.0.1:45628","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-04T18:21:57.833341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.144203ms","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-04T18:21:57.8334Z","caller":"traceutil/trace.go:171","msg":"trace[1611122898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:736; }","duration":"636.251465ms","start":"2026-02-04T18:21:57.197133Z","end":"2026-02-04T18:21:57.833384Z","steps":["trace[1611122898] 'range keys from in-memory index tree' (duration: 636.0192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:57.833447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:57.197125Z","time spent":"636.313966ms","remote":"127.0.0.1:45628","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-04T18:21:57.833457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.500831ms","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-04T18:21:57.833479Z","caller":"traceutil/trace.go:171","msg":"trace[2040034590] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:736; }","duration":"318.541372ms","start":"2026-02-04T18:21:57.514931Z","end":"2026-02-04T18:21:57.833473Z","steps":["trace[2040034590] 'range keys from in-memory index tree' (duration: 318.43619ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:57.833496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:57.514922Z","time spent":"318.570343ms","remote":"127.0.0.1:45628","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-04T18:21:57.833576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.797008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T18:21:57.833598Z","caller":"traceutil/trace.go:171","msg":"trace[1287046512] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:736; }","duration":"629.85279ms","start":"2026-02-04T18:21:57.203738Z","end":"2026-02-04T18:21:57.833591Z","steps":["trace[1287046512] 'count revisions from in-memory index tree' (duration: 629.744747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:57.833621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:57.203722Z","time spent":"629.89209ms","remote":"127.0.0.1:45570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-02-04T18:21:57.833636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.432465ms","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-04T18:21:57.833657Z","caller":"traceutil/trace.go:171","msg":"trace[1392951240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:736; }","duration":"313.479767ms","start":"2026-02-04T18:21:57.520171Z","end":"2026-02-04T18:21:57.833651Z","steps":["trace[1392951240] 'range keys from in-memory index tree' (duration: 313.340234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:57.833673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:57.52015Z","time spent":"313.519117ms","remote":"127.0.0.1:45628","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-04T18:21:57.833736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.6026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:21:57.833756Z","caller":"traceutil/trace.go:171","msg":"trace[552021484] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:736; }","duration":"606.62296ms","start":"2026-02-04T18:21:57.227128Z","end":"2026-02-04T18:21:57.833751Z","steps":["trace[552021484] 'count revisions from in-memory index tree' (duration: 606.565299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:57.833773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:57.227104Z","time spent":"606.664851ms","remote":"127.0.0.1:45672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-04T18:21:57.833775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.283917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-04T18:21:57.833793Z","caller":"traceutil/trace.go:171","msg":"trace[1574276563] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:736; }","duration":"300.318537ms","start":"2026-02-04T18:21:57.533469Z","end":"2026-02-04T18:21:57.833788Z","steps":["trace[1574276563] 'range keys from in-memory index tree' (duration: 300.217925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:57.833809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:57.533456Z","time spent":"300.349387ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-04T18:21:58.918975Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360057250618720,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-04T18:21:59.000007Z","caller":"traceutil/trace.go:171","msg":"trace[18551480] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"1.159771224s","start":"2026-02-04T18:21:57.840216Z","end":"2026-02-04T18:21:58.999987Z","steps":["trace[18551480] 'process raft request' (duration: 1.15966354s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:21:59.000217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:57.8402Z","time spent":"1.159956237s","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T18:22:00.313491Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.284579204s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-04T18:22:00.418265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000302079s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-04T18:22:00.418339Z","caller":"traceutil/trace.go:171","msg":"trace[373274028] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000413581s","start":"2026-02-04T18:21:58.417912Z","end":"2026-02-04T18:22:00.418326Z","steps":["trace[373274028] 'agreement among raft nodes before linearized reading' (duration: 2.000324909s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:00.418388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:58.417896Z","time spent":"2.000478223s","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/04 18:22:00 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-04T18:22:02.416887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.103157511s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T18:22:02.417089Z","caller":"traceutil/trace.go:171","msg":"trace[287195712] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"4.573875499s","start":"2026-02-04T18:21:57.8432Z","end":"2026-02-04T18:22:02.417076Z","steps":["trace[287195712] 'process raft request' (duration: 4.573837068s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:02.417134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:57.843194Z","time spent":"4.57391854s","remote":"127.0.0.1:45628","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-04T18:22:02.417258Z","caller":"traceutil/trace.go:171","msg":"trace[895634402] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"4.575335233s","start":"2026-02-04T18:21:57.841917Z","end":"2026-02-04T18:22:02.417252Z","steps":["trace[895634402] 'process raft request' (duration: 4.575038606s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:02.417288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:57.841901Z","time spent":"4.575370384s","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T18:22:02.417407Z","caller":"traceutil/trace.go:171","msg":"trace[1478572318] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"4.575420724s","start":"2026-02-04T18:21:57.84198Z","end":"2026-02-04T18:22:02.417401Z","steps":["trace[1478572318] 'process raft request' (duration: 4.575033396s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:02.417452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:57.841964Z","time spent":"4.575459636s","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T18:22:02.417737Z","caller":"traceutil/trace.go:171","msg":"trace[443754646] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"4.577423991s","start":"2026-02-04T18:21:57.840305Z","end":"2026-02-04T18:22:02.417729Z","steps":["trace[443754646] 'process raft request' (duration: 2.473346558s)","trace[443754646] 'compare' (duration: 2.103053889s)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:22:02.41778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:57.840299Z","time spent":"4.577461381s","remote":"127.0.0.1:45628","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-04T18:22:02.421273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.002672894s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-04T18:22:02.421308Z","caller":"traceutil/trace.go:171","msg":"trace[2028416881] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.002731245s","start":"2026-02-04T18:22:00.418568Z","end":"2026-02-04T18:22:02.4213Z","steps":["trace[2028416881] 'agreement among raft nodes before linearized reading' (duration: 2.002691644s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:02.421355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:00.418559Z","time spent":"2.002791456s","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/04 18:22:02 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-04T18:22:04.380503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00034603s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-04T18:22:04.380578Z","caller":"traceutil/trace.go:171","msg":"trace[569279486] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000465203s","start":"2026-02-04T18:22:02.380097Z","end":"2026-02-04T18:22:04.380562Z","steps":["trace[569279486] 'agreement among raft nodes before linearized reading' (duration: 2.000374841s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:04.38062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:02.380078Z","time spent":"2.000533334s","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T18:22:04.425553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000252068s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-04T18:22:04.425607Z","caller":"traceutil/trace.go:171","msg":"trace[1645546092] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000329419s","start":"2026-02-04T18:22:02.425266Z","end":"2026-02-04T18:22:04.425595Z","steps":["trace[1645546092] 'agreement among raft nodes before linearized reading' (duration: 2.000264398s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:04.425634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:02.425258Z","time spent":"2.00037013s","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T18:22:04.526758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.109685049s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-04T18:22:04.526865Z","caller":"traceutil/trace.go:171","msg":"trace[676475494] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:760; }","duration":"6.108896066s","start":"2026-02-04T18:21:58.417942Z","end":"2026-02-04T18:22:04.526839Z","steps":["trace[676475494] 'read index received' (duration: 582.671546ms)","trace[676475494] 'applied index is now lower than readState.Index' (duration: 5.52622306s)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:22:05.027522Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360057250618730,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T18:22:05.528718Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360057250618730,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T18:22:05.54221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015351956s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-02-04T18:22:05.542286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:02.423867Z","time spent":"3.118412964s","remote":"127.0.0.1:45456","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-04T18:22:05.543156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.264294ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:22:05.543266Z","caller":"traceutil/trace.go:171","msg":"trace[1984922174] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:741; }","duration":"151.351136ms","start":"2026-02-04T18:22:05.391877Z","end":"2026-02-04T18:22:05.543228Z","steps":["trace[1984922174] 'range keys from in-memory index tree' (duration: 151.254273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:05.788536Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.880346788s","expected-duration":"1s"} {"level":"info","ts":"2026-02-04T18:22:05.789018Z","caller":"traceutil/trace.go:171","msg":"trace[402458416] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"2.101017243s","start":"2026-02-04T18:22:03.687984Z","end":"2026-02-04T18:22:05.789001Z","steps":["trace[402458416] 'process raft request' (duration: 2.10090482s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:05.789127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:03.687968Z","time spent":"2.101083384s","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T18:22:05.789505Z","caller":"traceutil/trace.go:171","msg":"trace[400006516] linearizableReadLoop","detail":"{readStateIndex:767; appliedIndex:767; }","duration":"1.262606136s","start":"2026-02-04T18:22:04.526886Z","end":"2026-02-04T18:22:05.789492Z","steps":["trace[400006516] 'read index received' (duration: 1.262584945s)","trace[400006516] 'applied index is now lower than readState.Index' (duration: 20.051µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:22:05.789536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.39682918s","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-04T18:22:05.789562Z","caller":"traceutil/trace.go:171","msg":"trace[1236764500] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:742; }","duration":"1.396892792s","start":"2026-02-04T18:22:04.392661Z","end":"2026-02-04T18:22:05.789554Z","steps":["trace[1236764500] 'agreement among raft nodes before linearized reading' (duration: 1.396851841s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:05.789573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.603942636s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-04T18:22:05.789582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:04.392641Z","time spent":"1.396936432s","remote":"127.0.0.1:45738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-02-04T18:22:05.789593Z","caller":"traceutil/trace.go:171","msg":"trace[1090567348] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:742; }","duration":"6.604000468s","start":"2026-02-04T18:21:59.185585Z","end":"2026-02-04T18:22:05.789585Z","steps":["trace[1090567348] 'agreement among raft nodes before linearized reading' (duration: 6.603944826s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:05.789612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:21:59.185566Z","time spent":"6.604041118s","remote":"127.0.0.1:45794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-04T18:22:05.789718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.269923362s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-04T18:22:05.789734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.432314689s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T18:22:05.789736Z","caller":"traceutil/trace.go:171","msg":"trace[395801057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:742; }","duration":"1.269974252s","start":"2026-02-04T18:22:04.519756Z","end":"2026-02-04T18:22:05.789731Z","steps":["trace[395801057] 'agreement among raft nodes before linearized reading' (duration: 1.26990561s)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:22:05.789751Z","caller":"traceutil/trace.go:171","msg":"trace[1440819495] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:742; }","duration":"1.432482742s","start":"2026-02-04T18:22:04.357263Z","end":"2026-02-04T18:22:05.789746Z","steps":["trace[1440819495] 'agreement among raft nodes before linearized reading' (duration: 1.432450371s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:05.789769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:04.357176Z","time spent":"1.432589005s","remote":"127.0.0.1:45700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":13,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-02-04T18:22:05.789752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:04.519737Z","time spent":"1.270011083s","remote":"127.0.0.1:45628","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-04T18:22:05.789879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.358643391s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:22:05.789907Z","caller":"traceutil/trace.go:171","msg":"trace[230951882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:742; }","duration":"1.358684863s","start":"2026-02-04T18:22:04.431215Z","end":"2026-02-04T18:22:05.7899Z","steps":["trace[230951882] 'agreement among raft nodes before linearized reading' (duration: 1.358642102s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:05.789912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.848008967s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-04T18:22:05.789936Z","caller":"traceutil/trace.go:171","msg":"trace[793682637] range","detail":"{range_begin:/registry/masterleases/162.253.55.74; range_end:; response_count:1; response_revision:742; }","duration":"2.848088569s","start":"2026-02-04T18:22:02.941841Z","end":"2026-02-04T18:22:05.78993Z","steps":["trace[793682637] 'agreement among raft nodes before linearized reading' (duration: 2.848006968s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:05.789955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:02.941818Z","time spent":"2.848132031s","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.74\" "} {"level":"warn","ts":"2026-02-04T18:22:05.790087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.733017603s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:22:05.790108Z","caller":"traceutil/trace.go:171","msg":"trace[1857761249] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:742; }","duration":"4.733073474s","start":"2026-02-04T18:22:01.057027Z","end":"2026-02-04T18:22:05.7901Z","steps":["trace[1857761249] 'agreement among raft nodes before linearized reading' (duration: 4.733041303s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:05.790125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:01.056977Z","time spent":"4.733143336s","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-02-04T18:22:05.789943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:04.431206Z","time spent":"1.358731384s","remote":"127.0.0.1:45354","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-04T18:22:05.79025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.78607141s","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-04T18:22:05.790273Z","caller":"traceutil/trace.go:171","msg":"trace[683478183] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:742; }","duration":"4.786127032s","start":"2026-02-04T18:22:01.004139Z","end":"2026-02-04T18:22:05.790266Z","steps":["trace[683478183] 'agreement among raft nodes before linearized reading' (duration: 4.786066491s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:05.790292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:01.004119Z","time spent":"4.786168292s","remote":"127.0.0.1:45628","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-04T18:22:05.7903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.364586687s","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-04T18:22:05.79032Z","caller":"traceutil/trace.go:171","msg":"trace[1726231991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:742; }","duration":"1.364634018s","start":"2026-02-04T18:22:04.42568Z","end":"2026-02-04T18:22:05.790314Z","steps":["trace[1726231991] 'agreement among raft nodes before linearized reading' (duration: 1.364574897s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:05.790338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:04.42567Z","time spent":"1.364664268s","remote":"127.0.0.1:45628","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-04T18:22:05.790392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.871115206s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T18:22:05.790409Z","caller":"traceutil/trace.go:171","msg":"trace[2112585952] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:742; }","duration":"4.871156597s","start":"2026-02-04T18:22:00.919247Z","end":"2026-02-04T18:22:05.790404Z","steps":["trace[2112585952] 'agreement among raft nodes before linearized reading' (duration: 4.871128086s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:05.790426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:00.919234Z","time spent":"4.871188127s","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-04T18:22:05.790479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.366653224s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-04T18:22:05.790503Z","caller":"traceutil/trace.go:171","msg":"trace[67357142] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:742; }","duration":"1.366694995s","start":"2026-02-04T18:22:04.423801Z","end":"2026-02-04T18:22:05.790496Z","steps":["trace[67357142] 'agreement among raft nodes before linearized reading' (duration: 1.366612672s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:05.790522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:04.423794Z","time spent":"1.366722966s","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-04T18:22:05.790559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.425318244s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T18:22:05.790586Z","caller":"traceutil/trace.go:171","msg":"trace[1648999458] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:742; }","duration":"5.425382846s","start":"2026-02-04T18:22:00.365197Z","end":"2026-02-04T18:22:05.79058Z","steps":["trace[1648999458] 'agreement among raft nodes before linearized reading' (duration: 5.425327425s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:05.790605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:00.36518Z","time spent":"5.425419956s","remote":"127.0.0.1:45422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-02-04T18:22:05.790663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.369644102s","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-04T18:22:05.790682Z","caller":"traceutil/trace.go:171","msg":"trace[603037420] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:742; }","duration":"1.369696043s","start":"2026-02-04T18:22:04.42098Z","end":"2026-02-04T18:22:05.790676Z","steps":["trace[603037420] 'agreement among raft nodes before linearized reading' (duration: 1.369634892s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:05.790699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:04.420961Z","time spent":"1.369733973s","remote":"127.0.0.1:45628","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-04T18:22:06.293795Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360057250618732,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T18:22:07.299662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.91162ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T18:22:07.299804Z","caller":"traceutil/trace.go:171","msg":"trace[2021801297] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"1.754979114s","start":"2026-02-04T18:22:05.544801Z","end":"2026-02-04T18:22:07.29978Z","steps":["trace[2021801297] 'process raft request' (duration: 1.059857179s)","trace[2021801297] 'compare' (duration: 694.4269ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:22:07.299863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:05.544786Z","time spent":"1.755053877s","remote":"127.0.0.1:45456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T18:22:07.794502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001548868s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-04T18:22:07.794614Z","caller":"traceutil/trace.go:171","msg":"trace[344775369] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001708362s","start":"2026-02-04T18:22:05.792888Z","end":"2026-02-04T18:22:07.794596Z","steps":["trace[344775369] 'agreement among raft nodes before linearized reading' (duration: 2.001545298s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:07.794657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:05.792865Z","time spent":"2.001784194s","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/04 18:22:07 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-04T18:22:09.05832Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.45361649s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-04T18:22:09.060098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:05.793449Z","time spent":"3.266645539s","remote":"127.0.0.1:45400","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-04T18:22:09.061133Z","caller":"traceutil/trace.go:171","msg":"trace[1533572447] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"3.261727337s","start":"2026-02-04T18:22:05.799389Z","end":"2026-02-04T18:22:09.061116Z","steps":["trace[1533572447] 'process raft request' (duration: 3.260658433s)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:22:09.061152Z","caller":"traceutil/trace.go:171","msg":"trace[1674360577] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:767; }","duration":"3.268246296s","start":"2026-02-04T18:22:05.79289Z","end":"2026-02-04T18:22:09.061136Z","steps":["trace[1674360577] 'read index received' (duration: 811.776901ms)","trace[1674360577] 'applied index is now lower than readState.Index' (duration: 2.456468515s)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:22:09.061221Z","caller":"traceutil/trace.go:171","msg":"trace[1002091056] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"3.261761197s","start":"2026-02-04T18:22:05.799449Z","end":"2026-02-04T18:22:09.06121Z","steps":["trace[1002091056] 'process raft request' (duration: 3.261550433s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:09.061228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:05.799371Z","time spent":"3.261799078s","remote":"127.0.0.1:45628","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-04T18:22:09.061287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:05.799443Z","time spent":"3.261798488s","remote":"127.0.0.1:45628","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-04T18:22:09.062048Z","caller":"traceutil/trace.go:171","msg":"trace[86314875] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"3.261032912s","start":"2026-02-04T18:22:05.801002Z","end":"2026-02-04T18:22:09.062035Z","steps":["trace[86314875] 'process raft request' (duration: 3.26006915s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:09.062116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:05.800993Z","time spent":"3.261085643s","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T18:22:09.062357Z","caller":"traceutil/trace.go:171","msg":"trace[1672805510] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"3.260596271s","start":"2026-02-04T18:22:05.801751Z","end":"2026-02-04T18:22:09.062347Z","steps":["trace[1672805510] 'process raft request' (duration: 3.259349453s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:09.062416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:05.801742Z","time spent":"3.260644222s","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T18:22:09.061572Z","caller":"traceutil/trace.go:171","msg":"trace[1068676833] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"3.261055822s","start":"2026-02-04T18:22:05.800358Z","end":"2026-02-04T18:22:09.061414Z","steps":["trace[1068676833] 'process raft request' (duration: 3.260682884s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:09.06446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:05.80035Z","time spent":"3.26406708s","remote":"127.0.0.1:45628","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-04T18:22:09.099991Z","caller":"traceutil/trace.go:171","msg":"trace[1273938530] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"1.793696746s","start":"2026-02-04T18:22:07.306262Z","end":"2026-02-04T18:22:09.099959Z","steps":["trace[1273938530] 'process raft request' (duration: 1.793207585s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:09.100099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:07.306243Z","time spent":"1.793807168s","remote":"127.0.0.1:45456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T18:22:09.100373Z","caller":"traceutil/trace.go:171","msg":"trace[1072731208] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"1.580272607s","start":"2026-02-04T18:22:07.520081Z","end":"2026-02-04T18:22:09.100354Z","steps":["trace[1072731208] 'process raft request' (duration: 1.579474119s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:09.100471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:07.520045Z","time spent":"1.580366279s","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T18:22:09.168488Z","caller":"traceutil/trace.go:171","msg":"trace[532880190] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:774; }","duration":"107.284302ms","start":"2026-02-04T18:22:09.061187Z","end":"2026-02-04T18:22:09.168471Z","steps":["trace[532880190] 'read index received' (duration: 38.240861ms)","trace[532880190] 'applied index is now lower than readState.Index' (duration: 69.042541ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:22:09.1689Z","caller":"traceutil/trace.go:171","msg":"trace[335868676] transaction","detail":"{read_only:false; number_of_response:1; response_revision:751; }","duration":"107.816495ms","start":"2026-02-04T18:22:09.061071Z","end":"2026-02-04T18:22:09.168887Z","steps":["trace[335868676] 'process raft request' (duration: 107.362214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:09.168954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.370583123s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7036"} {"level":"info","ts":"2026-02-04T18:22:09.168997Z","caller":"traceutil/trace.go:171","msg":"trace[968113740] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:751; }","duration":"1.370663525s","start":"2026-02-04T18:22:07.798321Z","end":"2026-02-04T18:22:09.168985Z","steps":["trace[968113740] 'agreement among raft nodes before linearized reading' (duration: 1.370572913s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:09.169029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:07.798307Z","time spent":"1.370714676s","remote":"127.0.0.1:45566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7059,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-02-04T18:22:09.169103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.366932847s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T18:22:09.169134Z","caller":"traceutil/trace.go:171","msg":"trace[1962620751] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:751; }","duration":"2.366971267s","start":"2026-02-04T18:22:06.802154Z","end":"2026-02-04T18:22:09.169125Z","steps":["trace[1962620751] 'agreement among raft nodes before linearized reading' (duration: 2.366909306s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:09.169161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:06.802095Z","time spent":"2.367058589s","remote":"127.0.0.1:45478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":12,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-02-04T18:22:09.169384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.711035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:22:09.169429Z","caller":"traceutil/trace.go:171","msg":"trace[1983840535] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:751; }","duration":"509.852787ms","start":"2026-02-04T18:22:08.659563Z","end":"2026-02-04T18:22:09.169416Z","steps":["trace[1983840535] 'agreement among raft nodes before linearized reading' (duration: 509.722684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:09.169465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:08.659544Z","time spent":"509.912609ms","remote":"127.0.0.1:45766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-02-04T18:22:09.169652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.3673769s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:22:09.169687Z","caller":"traceutil/trace.go:171","msg":"trace[149690645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:751; }","duration":"1.367434681s","start":"2026-02-04T18:22:07.802244Z","end":"2026-02-04T18:22:09.169678Z","steps":["trace[149690645] 'agreement among raft nodes before linearized reading' (duration: 1.36738577s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:09.169714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:07.802234Z","time spent":"1.367473611s","remote":"127.0.0.1:45354","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-04T18:22:09.317159Z","caller":"traceutil/trace.go:171","msg":"trace[906478805] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:778; }","duration":"139.589097ms","start":"2026-02-04T18:22:09.177548Z","end":"2026-02-04T18:22:09.317137Z","steps":["trace[906478805] 'read index received' (duration: 139.383243ms)","trace[906478805] 'applied index is now lower than readState.Index' (duration: 205.114µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:22:09.317554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.987537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:22:09.317723Z","caller":"traceutil/trace.go:171","msg":"trace[1998399944] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:752; }","duration":"140.13953ms","start":"2026-02-04T18:22:09.177529Z","end":"2026-02-04T18:22:09.317669Z","steps":["trace[1998399944] 'agreement among raft nodes before linearized reading' (duration: 139.952126ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:22:09.318059Z","caller":"traceutil/trace.go:171","msg":"trace[2042187138] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"141.41179ms","start":"2026-02-04T18:22:09.176633Z","end":"2026-02-04T18:22:09.318045Z","steps":["trace[2042187138] 'process raft request' (duration: 140.018948ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:22:09.318152Z","caller":"traceutil/trace.go:171","msg":"trace[405491166] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"140.10748ms","start":"2026-02-04T18:22:09.178038Z","end":"2026-02-04T18:22:09.318146Z","steps":["trace[405491166] 'process raft request' (duration: 140.065489ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:22:09.536861Z","caller":"traceutil/trace.go:171","msg":"trace[1396433827] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:781; }","duration":"208.998688ms","start":"2026-02-04T18:22:09.327829Z","end":"2026-02-04T18:22:09.536828Z","steps":["trace[1396433827] 'read index received' (duration: 117.588627ms)","trace[1396433827] 'applied index is now lower than readState.Index' (duration: 91.408791ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:22:09.53741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.553741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T18:22:09.537908Z","caller":"traceutil/trace.go:171","msg":"trace[1519206365] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:754; }","duration":"210.067712ms","start":"2026-02-04T18:22:09.327827Z","end":"2026-02-04T18:22:09.537895Z","steps":["trace[1519206365] 'agreement among raft nodes before linearized reading' (duration: 209.463119ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:22:09.537464Z","caller":"traceutil/trace.go:171","msg":"trace[1044993385] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"211.803952ms","start":"2026-02-04T18:22:09.325646Z","end":"2026-02-04T18:22:09.53745Z","steps":["trace[1044993385] 'process raft request' (duration: 119.766467ms)","trace[1044993385] 'compare' (duration: 91.110055ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:22:09.54032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.061988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:22:09.540465Z","caller":"traceutil/trace.go:171","msg":"trace[2106322919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:756; }","duration":"212.107029ms","start":"2026-02-04T18:22:09.328237Z","end":"2026-02-04T18:22:09.540344Z","steps":["trace[2106322919] 'agreement among raft nodes before linearized reading' (duration: 212.027017ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:22:09.54143Z","caller":"traceutil/trace.go:171","msg":"trace[1764690956] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"212.265591ms","start":"2026-02-04T18:22:09.329155Z","end":"2026-02-04T18:22:09.541421Z","steps":["trace[1764690956] 'process raft request' (duration: 210.996123ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:22:09.541709Z","caller":"traceutil/trace.go:171","msg":"trace[1481791484] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"209.724505ms","start":"2026-02-04T18:22:09.331977Z","end":"2026-02-04T18:22:09.541701Z","steps":["trace[1481791484] 'process raft request' (duration: 208.251331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:11.920717Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360057250618769,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-04T18:22:12.069206Z","caller":"traceutil/trace.go:171","msg":"trace[1895708116] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"996.520177ms","start":"2026-02-04T18:22:11.072669Z","end":"2026-02-04T18:22:12.069189Z","steps":["trace[1895708116] 'process raft request' (duration: 996.429265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:12.06931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:11.072655Z","time spent":"996.600739ms","remote":"127.0.0.1:45628","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-04T18:22:13.419918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000192076s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-04T18:22:13.420011Z","caller":"traceutil/trace.go:171","msg":"trace[1898283416] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.0003575s","start":"2026-02-04T18:22:11.419635Z","end":"2026-02-04T18:22:13.419992Z","steps":["trace[1898283416] 'agreement among raft nodes before linearized reading' (duration: 2.000226187s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:13.420092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:11.419618Z","time spent":"2.000463593s","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T18:22:14.349188Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.28014024s","expected-duration":"1s"} {"level":"info","ts":"2026-02-04T18:22:14.349704Z","caller":"traceutil/trace.go:171","msg":"trace[2005174717] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:785; }","duration":"2.930004855s","start":"2026-02-04T18:22:11.419678Z","end":"2026-02-04T18:22:14.349683Z","steps":["trace[2005174717] 'read index received' (duration: 650.858457ms)","trace[2005174717] 'applied index is now lower than readState.Index' (duration: 2.279145428s)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:22:14.35004Z","caller":"traceutil/trace.go:171","msg":"trace[1141663360] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"3.276609127s","start":"2026-02-04T18:22:11.07342Z","end":"2026-02-04T18:22:14.350029Z","steps":["trace[1141663360] 'process raft request' (duration: 3.275958322s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:14.350134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:11.073412Z","time spent":"3.276657238s","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T18:22:14.350325Z","caller":"traceutil/trace.go:171","msg":"trace[1056900594] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"3.276013992s","start":"2026-02-04T18:22:11.074302Z","end":"2026-02-04T18:22:14.350316Z","steps":["trace[1056900594] 'process raft request' (duration: 3.275188044s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:14.350375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:11.074294Z","time spent":"3.276054063s","remote":"127.0.0.1:45628","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-04T18:22:14.350496Z","caller":"traceutil/trace.go:171","msg":"trace[727734298] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"3.275252005s","start":"2026-02-04T18:22:11.075235Z","end":"2026-02-04T18:22:14.350487Z","steps":["trace[727734298] 'process raft request' (duration: 3.274362075s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:14.350544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:11.075229Z","time spent":"3.275288116s","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T18:22:14.350671Z","caller":"traceutil/trace.go:171","msg":"trace[1218100010] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"3.274976999s","start":"2026-02-04T18:22:11.075667Z","end":"2026-02-04T18:22:14.350644Z","steps":["trace[1218100010] 'process raft request' (duration: 3.273981756s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:14.350719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:11.075662Z","time spent":"3.27503194s","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T18:22:14.350875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.848383565s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:22:14.350907Z","caller":"traceutil/trace.go:171","msg":"trace[352299145] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:761; }","duration":"2.848456377s","start":"2026-02-04T18:22:11.502441Z","end":"2026-02-04T18:22:14.350897Z","steps":["trace[352299145] 'agreement among raft nodes before linearized reading' (duration: 2.848404796s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:14.350938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:11.50242Z","time spent":"2.84850847s","remote":"127.0.0.1:45816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-02-04T18:22:14.351277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.01433ms","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-04T18:22:14.351308Z","caller":"traceutil/trace.go:171","msg":"trace[1695361318] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:761; }","duration":"278.07853ms","start":"2026-02-04T18:22:14.07322Z","end":"2026-02-04T18:22:14.351298Z","steps":["trace[1695361318] 'agreement among raft nodes before linearized reading' (duration: 277.975858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:14.351426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.172527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:22:14.351454Z","caller":"traceutil/trace.go:171","msg":"trace[467760538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"930.224238ms","start":"2026-02-04T18:22:13.42122Z","end":"2026-02-04T18:22:14.351444Z","steps":["trace[467760538] 'agreement among raft nodes before linearized reading' (duration: 930.186487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:14.351478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:13.421204Z","time spent":"930.26854ms","remote":"127.0.0.1:45358","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-04T18:22:14.351658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.408584058s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-04T18:22:14.351686Z","caller":"traceutil/trace.go:171","msg":"trace[1385441249] range","detail":"{range_begin:/registry/masterleases/162.253.55.74; range_end:; response_count:1; response_revision:761; }","duration":"1.40864752s","start":"2026-02-04T18:22:12.94303Z","end":"2026-02-04T18:22:14.351677Z","steps":["trace[1385441249] 'agreement among raft nodes before linearized reading' (duration: 1.408569998s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:14.351713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:12.942986Z","time spent":"1.408722231s","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.74\" "} {"level":"warn","ts":"2026-02-04T18:22:15.11995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.519722ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-04T18:22:15.120031Z","caller":"traceutil/trace.go:171","msg":"trace[1945117989] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:789; }","duration":"759.809068ms","start":"2026-02-04T18:22:14.360206Z","end":"2026-02-04T18:22:15.120015Z","steps":["trace[1945117989] 'read index received' (duration: 307.169012ms)","trace[1945117989] 'applied index is now lower than readState.Index' (duration: 452.638675ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:22:15.1201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:14.357767Z","time spent":"762.330555ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-04T18:22:15.120293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.076333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:22:15.120321Z","caller":"traceutil/trace.go:171","msg":"trace[1008070351] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"760.119785ms","start":"2026-02-04T18:22:14.360192Z","end":"2026-02-04T18:22:15.120312Z","steps":["trace[1008070351] 'agreement among raft nodes before linearized reading' (duration: 760.066374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:15.120349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:14.360186Z","time spent":"760.157736ms","remote":"127.0.0.1:45354","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-04T18:22:15.404945Z","caller":"traceutil/trace.go:171","msg":"trace[1954272566] linearizableReadLoop","detail":"{readStateIndex:792; appliedIndex:790; }","duration":"284.875557ms","start":"2026-02-04T18:22:15.120055Z","end":"2026-02-04T18:22:15.40493Z","steps":["trace[1954272566] 'read index received' (duration: 283.384222ms)","trace[1954272566] 'applied index is now lower than readState.Index' (duration: 1.490935ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:22:15.40501Z","caller":"traceutil/trace.go:171","msg":"trace[1235972100] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"1.04318679s","start":"2026-02-04T18:22:14.361816Z","end":"2026-02-04T18:22:15.405002Z","steps":["trace[1235972100] 'process raft request' (duration: 1.043070258s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:15.405093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:14.361807Z","time spent":"1.043218961s","remote":"127.0.0.1:45628","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-04T18:22:15.405092Z","caller":"traceutil/trace.go:171","msg":"trace[1174945659] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"1.044822607s","start":"2026-02-04T18:22:14.360248Z","end":"2026-02-04T18:22:15.405071Z","steps":["trace[1174945659] 'process raft request' (duration: 1.043182319s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:15.405188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:14.360241Z","time spent":"1.044890088s","remote":"127.0.0.1:45456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T18:22:15.405245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.3789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:22:15.405273Z","caller":"traceutil/trace.go:171","msg":"trace[1760402193] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:763; }","duration":"662.441572ms","start":"2026-02-04T18:22:14.74282Z","end":"2026-02-04T18:22:15.405262Z","steps":["trace[1760402193] 'agreement among raft nodes before linearized reading' (duration: 662.402941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:15.405293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:14.7428Z","time spent":"662.487883ms","remote":"127.0.0.1:45538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-02-04T18:22:15.717077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.584408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T18:22:15.717142Z","caller":"traceutil/trace.go:171","msg":"trace[2091054104] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:792; }","duration":"312.144506ms","start":"2026-02-04T18:22:15.404986Z","end":"2026-02-04T18:22:15.717131Z","steps":["trace[2091054104] 'read index received' (duration: 100.397144ms)","trace[2091054104] 'applied index is now lower than readState.Index' (duration: 211.746632ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:22:15.717213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.127248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:22:15.717229Z","caller":"traceutil/trace.go:171","msg":"trace[1744125894] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:764; }","duration":"595.1697ms","start":"2026-02-04T18:22:15.122053Z","end":"2026-02-04T18:22:15.717223Z","steps":["trace[1744125894] 'agreement among raft nodes before linearized reading' (duration: 595.106508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:15.717248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:15.122039Z","time spent":"595.20534ms","remote":"127.0.0.1:45358","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-04T18:22:15.717464Z","caller":"traceutil/trace.go:171","msg":"trace[113604147] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"595.311363ms","start":"2026-02-04T18:22:15.122141Z","end":"2026-02-04T18:22:15.717453Z","steps":["trace[113604147] 'process raft request' (duration: 383.296816ms)","trace[113604147] 'compare' (duration: 211.228349ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:22:15.717508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:15.12213Z","time spent":"595.356104ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T18:22:16.061197Z","caller":"traceutil/trace.go:171","msg":"trace[1779730822] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"151.380496ms","start":"2026-02-04T18:22:15.909795Z","end":"2026-02-04T18:22:16.061175Z","steps":["trace[1779730822] 'process raft request' (duration: 151.239883ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:22:16.512514Z","caller":"traceutil/trace.go:171","msg":"trace[239105326] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"146.357322ms","start":"2026-02-04T18:22:16.366142Z","end":"2026-02-04T18:22:16.512499Z","steps":["trace[239105326] 'process raft request' (duration: 146.26496ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:22:16.579655Z","caller":"traceutil/trace.go:171","msg":"trace[1769199834] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"211.308822ms","start":"2026-02-04T18:22:16.368324Z","end":"2026-02-04T18:22:16.579632Z","steps":["trace[1769199834] 'process raft request' (duration: 211.176319ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:22:16.580127Z","caller":"traceutil/trace.go:171","msg":"trace[355127212] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"213.89364ms","start":"2026-02-04T18:22:16.366224Z","end":"2026-02-04T18:22:16.580117Z","steps":["trace[355127212] 'process raft request' (duration: 213.154153ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:22:16.5803Z","caller":"traceutil/trace.go:171","msg":"trace[811547273] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:795; }","duration":"161.372024ms","start":"2026-02-04T18:22:16.418889Z","end":"2026-02-04T18:22:16.580261Z","steps":["trace[811547273] 'read index received' (duration: 93.99512ms)","trace[811547273] 'applied index is now lower than readState.Index' (duration: 67.376184ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:22:16.580435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.529447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:22:16.580492Z","caller":"traceutil/trace.go:171","msg":"trace[802305937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:769; }","duration":"161.601299ms","start":"2026-02-04T18:22:16.418883Z","end":"2026-02-04T18:22:16.580484Z","steps":["trace[802305937] 'agreement among raft nodes before linearized reading' (duration: 161.439895ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:22:16.581016Z","caller":"traceutil/trace.go:171","msg":"trace[518548432] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"211.392312ms","start":"2026-02-04T18:22:16.369613Z","end":"2026-02-04T18:22:16.581005Z","steps":["trace[518548432] 'process raft request' (duration: 209.95087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:29.384538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.47918ms","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-04T18:22:29.384596Z","caller":"traceutil/trace.go:171","msg":"trace[908247335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:850; }","duration":"679.580042ms","start":"2026-02-04T18:22:28.705003Z","end":"2026-02-04T18:22:29.384583Z","steps":["trace[908247335] 'range keys from in-memory index tree' (duration: 679.321496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:29.384622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:28.704984Z","time spent":"679.632043ms","remote":"127.0.0.1:45628","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-04T18:22:29.384812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.875616ms","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-04T18:22:29.384831Z","caller":"traceutil/trace.go:171","msg":"trace[775006466] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:850; }","duration":"653.920547ms","start":"2026-02-04T18:22:28.730905Z","end":"2026-02-04T18:22:29.384825Z","steps":["trace[775006466] 'range keys from in-memory index tree' (duration: 653.804435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:29.384847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:28.730889Z","time spent":"653.955098ms","remote":"127.0.0.1:45628","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-04T18:22:29.384953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.586489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-04T18:22:29.384969Z","caller":"traceutil/trace.go:171","msg":"trace[729289692] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:850; }","duration":"653.6151ms","start":"2026-02-04T18:22:28.731349Z","end":"2026-02-04T18:22:29.384964Z","steps":["trace[729289692] 'range keys from in-memory index tree' (duration: 653.536319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:29.384985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:28.731343Z","time spent":"653.637831ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-04T18:22:29.385104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:28.850542Z","time spent":"534.55874ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-04T18:22:29.535495Z","caller":"traceutil/trace.go:171","msg":"trace[383552626] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"163.496593ms","start":"2026-02-04T18:22:29.371977Z","end":"2026-02-04T18:22:29.535474Z","steps":["trace[383552626] 'process raft request' (duration: 163.37643ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:22:29.98246Z","caller":"traceutil/trace.go:171","msg":"trace[1426613651] linearizableReadLoop","detail":"{readStateIndex:887; appliedIndex:883; }","duration":"517.617444ms","start":"2026-02-04T18:22:29.46483Z","end":"2026-02-04T18:22:29.982447Z","steps":["trace[1426613651] 'read index received' (duration: 70.546167ms)","trace[1426613651] 'applied index is now lower than readState.Index' (duration: 447.070837ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:22:29.982601Z","caller":"traceutil/trace.go:171","msg":"trace[1126875028] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"593.135473ms","start":"2026-02-04T18:22:29.389459Z","end":"2026-02-04T18:22:29.982594Z","steps":["trace[1126875028] 'process raft request' (duration: 592.876217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:29.982658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:29.389447Z","time spent":"593.168114ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T18:22:29.982748Z","caller":"traceutil/trace.go:171","msg":"trace[155537755] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"591.166038ms","start":"2026-02-04T18:22:29.391577Z","end":"2026-02-04T18:22:29.982743Z","steps":["trace[155537755] 'process raft request' (duration: 590.833491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:29.982773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:29.391568Z","time spent":"591.189909ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T18:22:29.982828Z","caller":"traceutil/trace.go:171","msg":"trace[360536082] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"589.05179ms","start":"2026-02-04T18:22:29.393772Z","end":"2026-02-04T18:22:29.982824Z","steps":["trace[360536082] 'process raft request' (duration: 588.658971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:29.982851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:29.393765Z","time spent":"589.072431ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T18:22:29.982967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.136536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-04T18:22:29.982983Z","caller":"traceutil/trace.go:171","msg":"trace[1033987377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:854; }","duration":"518.175577ms","start":"2026-02-04T18:22:29.464802Z","end":"2026-02-04T18:22:29.982978Z","steps":["trace[1033987377] 'agreement among raft nodes before linearized reading' (duration: 518.110215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:29.982998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:29.464791Z","time spent":"518.204397ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T18:22:29.98309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.089044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T18:22:29.983104Z","caller":"traceutil/trace.go:171","msg":"trace[1572531575] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:854; }","duration":"236.138196ms","start":"2026-02-04T18:22:29.746961Z","end":"2026-02-04T18:22:29.983099Z","steps":["trace[1572531575] 'agreement among raft nodes before linearized reading' (duration: 236.102005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:30.366397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.932584ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T18:22:30.366461Z","caller":"traceutil/trace.go:171","msg":"trace[105754498] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"380.43592ms","start":"2026-02-04T18:22:29.986012Z","end":"2026-02-04T18:22:30.366448Z","steps":["trace[105754498] 'process raft request' (duration: 172.403974ms)","trace[105754498] 'compare' (duration: 207.858662ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:22:30.36649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:29.986Z","time spent":"380.477352ms","remote":"127.0.0.1:45628","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-04T18:22:30.623673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.977749ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-04T18:22:30.623738Z","caller":"traceutil/trace.go:171","msg":"trace[1877282422] linearizableReadLoop","detail":"{readStateIndex:889; appliedIndex:888; }","duration":"422.725095ms","start":"2026-02-04T18:22:30.201002Z","end":"2026-02-04T18:22:30.623727Z","steps":["trace[1877282422] 'read index received' (duration: 169.657204ms)","trace[1877282422] 'applied index is now lower than readState.Index' (duration: 253.067061ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:22:30.623845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.834397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T18:22:30.623864Z","caller":"traceutil/trace.go:171","msg":"trace[90705009] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:855; }","duration":"422.884917ms","start":"2026-02-04T18:22:30.200974Z","end":"2026-02-04T18:22:30.623859Z","steps":["trace[90705009] 'agreement among raft nodes before linearized reading' (duration: 422.835616ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:30.623887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:30.200959Z","time spent":"422.923299ms","remote":"127.0.0.1:45796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-04T18:22:30.62404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.673746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:22:30.624069Z","caller":"traceutil/trace.go:171","msg":"trace[827426585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:855; }","duration":"203.725807ms","start":"2026-02-04T18:22:30.420339Z","end":"2026-02-04T18:22:30.624065Z","steps":["trace[827426585] 'agreement among raft nodes before linearized reading' (duration: 203.687856ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:22:30.771602Z","caller":"traceutil/trace.go:171","msg":"trace[461921450] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"145.505522ms","start":"2026-02-04T18:22:30.626068Z","end":"2026-02-04T18:22:30.771574Z","steps":["trace[461921450] 'process raft request' (duration: 145.370469ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:22:32.113217Z","caller":"traceutil/trace.go:171","msg":"trace[1847158608] linearizableReadLoop","detail":"{readStateIndex:891; appliedIndex:890; }","duration":"125.12621ms","start":"2026-02-04T18:22:31.988074Z","end":"2026-02-04T18:22:32.1132Z","steps":["trace[1847158608] 'read index received' (duration: 124.984386ms)","trace[1847158608] 'applied index is now lower than readState.Index' (duration: 141.143µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:22:32.113476Z","caller":"traceutil/trace.go:171","msg":"trace[526837001] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"125.716953ms","start":"2026-02-04T18:22:31.987751Z","end":"2026-02-04T18:22:32.113468Z","steps":["trace[526837001] 'process raft request' (duration: 125.360025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:32.113697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.60599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-04T18:22:32.113721Z","caller":"traceutil/trace.go:171","msg":"trace[256954124] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:857; }","duration":"125.652351ms","start":"2026-02-04T18:22:31.988062Z","end":"2026-02-04T18:22:32.113714Z","steps":["trace[256954124] 'agreement among raft nodes before linearized reading' (duration: 125.553609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:32.113867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.266312ms","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-04T18:22:32.113885Z","caller":"traceutil/trace.go:171","msg":"trace[857786634] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:857; }","duration":"125.292643ms","start":"2026-02-04T18:22:31.988587Z","end":"2026-02-04T18:22:32.11388Z","steps":["trace[857786634] 'agreement among raft nodes before linearized reading' (duration: 125.243932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:34.82521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.884816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:22:34.825261Z","caller":"traceutil/trace.go:171","msg":"trace[430649993] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:866; }","duration":"176.941347ms","start":"2026-02-04T18:22:34.648305Z","end":"2026-02-04T18:22:34.825246Z","steps":["trace[430649993] 'count revisions from in-memory index tree' (duration: 176.805375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:34.825386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.03988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:22:34.825407Z","caller":"traceutil/trace.go:171","msg":"trace[1022569133] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:866; }","duration":"177.090651ms","start":"2026-02-04T18:22:34.648306Z","end":"2026-02-04T18:22:34.825396Z","steps":["trace[1022569133] 'count revisions from in-memory index tree' (duration: 176.988899ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:22:35.029078Z","caller":"traceutil/trace.go:171","msg":"trace[841617712] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"158.067469ms","start":"2026-02-04T18:22:34.870996Z","end":"2026-02-04T18:22:35.029063Z","steps":["trace[841617712] 'process raft request' (duration: 157.973907ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:22:37.288623Z","caller":"traceutil/trace.go:171","msg":"trace[768601280] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"254.552996ms","start":"2026-02-04T18:22:37.034057Z","end":"2026-02-04T18:22:37.28861Z","steps":["trace[768601280] 'process raft request' (duration: 254.347431ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:22:38.59823Z","caller":"traceutil/trace.go:171","msg":"trace[1228906468] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"460.33049ms","start":"2026-02-04T18:22:38.137878Z","end":"2026-02-04T18:22:38.598208Z","steps":["trace[1228906468] 'process raft request' (duration: 460.223808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:38.598359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:38.137866Z","time spent":"460.422182ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T18:22:38.656831Z","caller":"traceutil/trace.go:171","msg":"trace[1462675229] linearizableReadLoop","detail":"{readStateIndex:910; appliedIndex:909; }","duration":"478.030653ms","start":"2026-02-04T18:22:38.178781Z","end":"2026-02-04T18:22:38.656811Z","steps":["trace[1462675229] 'read index received' (duration: 419.92075ms)","trace[1462675229] 'applied index is now lower than readState.Index' (duration: 58.108803ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:22:38.657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.201267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-04T18:22:38.657016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.586923ms","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-04T18:22:38.657027Z","caller":"traceutil/trace.go:171","msg":"trace[1452596651] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:874; }","duration":"478.283148ms","start":"2026-02-04T18:22:38.178736Z","end":"2026-02-04T18:22:38.657019Z","steps":["trace[1452596651] 'agreement among raft nodes before linearized reading' (duration: 478.150495ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:22:38.657043Z","caller":"traceutil/trace.go:171","msg":"trace[1132290268] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:874; }","duration":"470.644515ms","start":"2026-02-04T18:22:38.186391Z","end":"2026-02-04T18:22:38.657036Z","steps":["trace[1132290268] 'agreement among raft nodes before linearized reading' (duration: 470.548173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:38.657052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:38.178717Z","time spent":"478.32977ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T18:22:38.657063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:38.186381Z","time spent":"470.677525ms","remote":"127.0.0.1:45628","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-04T18:22:38.657179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.009455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-04T18:22:38.657228Z","caller":"traceutil/trace.go:171","msg":"trace[650894172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:874; }","duration":"175.064975ms","start":"2026-02-04T18:22:38.482131Z","end":"2026-02-04T18:22:38.657196Z","steps":["trace[650894172] 'agreement among raft nodes before linearized reading' (duration: 174.999694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:38.657299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.165878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:22:38.657347Z","caller":"traceutil/trace.go:171","msg":"trace[249147041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:874; }","duration":"240.212769ms","start":"2026-02-04T18:22:38.417127Z","end":"2026-02-04T18:22:38.65734Z","steps":["trace[249147041] 'agreement among raft nodes before linearized reading' (duration: 240.153747ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:22:39.163998Z","caller":"traceutil/trace.go:171","msg":"trace[245854405] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"497.689121ms","start":"2026-02-04T18:22:38.666285Z","end":"2026-02-04T18:22:39.163974Z","steps":["trace[245854405] 'process raft request' (duration: 497.160919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:39.164621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:38.666279Z","time spent":"497.795473ms","remote":"127.0.0.1:45628","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-04T18:22:39.165268Z","caller":"traceutil/trace.go:171","msg":"trace[518585629] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"499.701006ms","start":"2026-02-04T18:22:38.665559Z","end":"2026-02-04T18:22:39.16526Z","steps":["trace[518585629] 'process raft request' (duration: 432.646899ms)","trace[518585629] 'compare' (duration: 65.057542ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:22:39.165321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:38.665551Z","time spent":"499.737716ms","remote":"127.0.0.1:45628","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-04T18:22:39.46733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.036158ms","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-04T18:22:39.467412Z","caller":"traceutil/trace.go:171","msg":"trace[1073493869] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:877; }","duration":"176.194971ms","start":"2026-02-04T18:22:39.291198Z","end":"2026-02-04T18:22:39.467393Z","steps":["trace[1073493869] 'range keys from in-memory index tree' (duration: 175.758092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:45.233589Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360057250619101,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-04T18:22:45.530106Z","caller":"traceutil/trace.go:171","msg":"trace[653276493] linearizableReadLoop","detail":"{readStateIndex:928; appliedIndex:927; }","duration":"797.144889ms","start":"2026-02-04T18:22:44.732945Z","end":"2026-02-04T18:22:45.530089Z","steps":["trace[653276493] 'read index received' (duration: 797.005206ms)","trace[653276493] 'applied index is now lower than readState.Index' (duration: 139.073µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:22:45.530345Z","caller":"traceutil/trace.go:171","msg":"trace[41776934] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"906.712855ms","start":"2026-02-04T18:22:44.623625Z","end":"2026-02-04T18:22:45.530338Z","steps":["trace[41776934] 'process raft request' (duration: 906.367407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:45.530413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:44.623617Z","time spent":"906.747835ms","remote":"127.0.0.1:45628","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-04T18:22:45.530575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.62832ms","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-04T18:22:45.530606Z","caller":"traceutil/trace.go:171","msg":"trace[520141843] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:891; }","duration":"797.679451ms","start":"2026-02-04T18:22:44.732921Z","end":"2026-02-04T18:22:45.5306Z","steps":["trace[520141843] 'agreement among raft nodes before linearized reading' (duration: 797.591939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:45.530623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:44.732908Z","time spent":"797.711372ms","remote":"127.0.0.1:45628","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-04T18:22:45.530729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.492801ms","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-04T18:22:45.530745Z","caller":"traceutil/trace.go:171","msg":"trace[380124102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:891; }","duration":"340.542392ms","start":"2026-02-04T18:22:45.190198Z","end":"2026-02-04T18:22:45.53074Z","steps":["trace[380124102] 'agreement among raft nodes before linearized reading' (duration: 340.49541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:45.53076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:45.190185Z","time spent":"340.572063ms","remote":"127.0.0.1:45628","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-04T18:22:45.530867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.585972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:22:45.530883Z","caller":"traceutil/trace.go:171","msg":"trace[1916663852] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:891; }","duration":"111.629364ms","start":"2026-02-04T18:22:45.419249Z","end":"2026-02-04T18:22:45.530878Z","steps":["trace[1916663852] 'agreement among raft nodes before linearized reading' (duration: 111.603033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:45.530997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.604258ms","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-04T18:22:45.531012Z","caller":"traceutil/trace.go:171","msg":"trace[455844109] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:891; }","duration":"332.646359ms","start":"2026-02-04T18:22:45.198362Z","end":"2026-02-04T18:22:45.531008Z","steps":["trace[455844109] 'agreement among raft nodes before linearized reading' (duration: 332.601068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:45.531029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:45.198346Z","time spent":"332.67889ms","remote":"127.0.0.1:45628","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-04T18:22:45.884137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.089518ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T18:22:45.884547Z","caller":"traceutil/trace.go:171","msg":"trace[188439955] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"347.783629ms","start":"2026-02-04T18:22:45.536718Z","end":"2026-02-04T18:22:45.884502Z","steps":["trace[188439955] 'process raft request' (duration: 147.79279ms)","trace[188439955] 'compare' (duration: 198.936244ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:22:45.885834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:45.536709Z","time spent":"349.058628ms","remote":"127.0.0.1:45628","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-04T18:22:46.170873Z","caller":"traceutil/trace.go:171","msg":"trace[651504271] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:928; }","duration":"627.527874ms","start":"2026-02-04T18:22:45.543318Z","end":"2026-02-04T18:22:46.170846Z","steps":["trace[651504271] 'read index received' (duration: 141.201818ms)","trace[651504271] 'applied index is now lower than readState.Index' (duration: 486.324866ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:22:46.171149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.81102ms","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-04T18:22:46.171192Z","caller":"traceutil/trace.go:171","msg":"trace[1806148386] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:894; }","duration":"627.878841ms","start":"2026-02-04T18:22:45.543303Z","end":"2026-02-04T18:22:46.171182Z","steps":["trace[1806148386] 'agreement among raft nodes before linearized reading' (duration: 627.675647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:46.171231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:45.543297Z","time spent":"627.924442ms","remote":"127.0.0.1:45628","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":"info","ts":"2026-02-04T18:22:46.171414Z","caller":"traceutil/trace.go:171","msg":"trace[1158790068] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"634.535405ms","start":"2026-02-04T18:22:45.536864Z","end":"2026-02-04T18:22:46.171399Z","steps":["trace[1158790068] 'process raft request' (duration: 633.256426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:46.171496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:45.536858Z","time spent":"634.587606ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T18:22:46.171582Z","caller":"traceutil/trace.go:171","msg":"trace[832225803] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"633.075412ms","start":"2026-02-04T18:22:45.538498Z","end":"2026-02-04T18:22:46.171573Z","steps":["trace[832225803] 'process raft request' (duration: 632.290224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:46.171634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:45.538478Z","time spent":"633.130043ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T18:22:47.14736Z","caller":"traceutil/trace.go:171","msg":"trace[104726462] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"582.03947ms","start":"2026-02-04T18:22:46.565299Z","end":"2026-02-04T18:22:47.147339Z","steps":["trace[104726462] 'process raft request' (duration: 581.894907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:47.147579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:46.565281Z","time spent":"582.142103ms","remote":"127.0.0.1:45628","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-04T18:22:48.680761Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360057250619119,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T18:22:48.931642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.207468ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-04T18:22:48.931716Z","caller":"traceutil/trace.go:171","msg":"trace[954609058] linearizableReadLoop","detail":"{readStateIndex:936; appliedIndex:935; }","duration":"753.573471ms","start":"2026-02-04T18:22:48.178125Z","end":"2026-02-04T18:22:48.931698Z","steps":["trace[954609058] 'read index received' (duration: 587.260191ms)","trace[954609058] 'applied index is now lower than readState.Index' (duration: 166.31238ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:22:48.932065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.931199ms","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-04T18:22:48.932093Z","caller":"traceutil/trace.go:171","msg":"trace[168196729] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:898; }","duration":"753.986261ms","start":"2026-02-04T18:22:48.1781Z","end":"2026-02-04T18:22:48.932086Z","steps":["trace[168196729] 'agreement among raft nodes before linearized reading' (duration: 753.853168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:48.932115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:48.178087Z","time spent":"754.022431ms","remote":"127.0.0.1:45628","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-04T18:22:48.932277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.043676ms","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-04T18:22:48.932299Z","caller":"traceutil/trace.go:171","msg":"trace[1720546363] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:898; }","duration":"752.075166ms","start":"2026-02-04T18:22:48.180218Z","end":"2026-02-04T18:22:48.932293Z","steps":["trace[1720546363] 'agreement among raft nodes before linearized reading' (duration: 752.018805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:48.932319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:48.180213Z","time spent":"752.101708ms","remote":"127.0.0.1:45628","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-04T18:22:48.932439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.398703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:22:48.932461Z","caller":"traceutil/trace.go:171","msg":"trace[1813232472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:898; }","duration":"516.452674ms","start":"2026-02-04T18:22:48.416003Z","end":"2026-02-04T18:22:48.932455Z","steps":["trace[1813232472] 'agreement among raft nodes before linearized reading' (duration: 516.423113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:48.932477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:48.415985Z","time spent":"516.488714ms","remote":"127.0.0.1:45354","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-04T18:22:48.932574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.693738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T18:22:48.932596Z","caller":"traceutil/trace.go:171","msg":"trace[937973392] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:898; }","duration":"605.743729ms","start":"2026-02-04T18:22:48.326847Z","end":"2026-02-04T18:22:48.932591Z","steps":["trace[937973392] 'agreement among raft nodes before linearized reading' (duration: 605.705788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:48.932616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:48.326833Z","time spent":"605.77773ms","remote":"127.0.0.1:45474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":6,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-02-04T18:22:48.932709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.564845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:22:48.93273Z","caller":"traceutil/trace.go:171","msg":"trace[661073568] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:898; }","duration":"646.613235ms","start":"2026-02-04T18:22:48.286111Z","end":"2026-02-04T18:22:48.932725Z","steps":["trace[661073568] 'agreement among raft nodes before linearized reading' (duration: 646.582724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:48.932748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:48.286098Z","time spent":"646.645337ms","remote":"127.0.0.1:48316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-04T18:22:48.932865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.507006ms","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-04T18:22:48.932886Z","caller":"traceutil/trace.go:171","msg":"trace[36572681] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:898; }","duration":"733.549348ms","start":"2026-02-04T18:22:48.199332Z","end":"2026-02-04T18:22:48.932881Z","steps":["trace[36572681] 'agreement among raft nodes before linearized reading' (duration: 733.495457ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:48.932905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:48.19932Z","time spent":"733.580668ms","remote":"127.0.0.1:45628","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-04T18:22:50.431166Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360057250619130,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T18:22:50.912669Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.245444835s","expected-duration":"1s"} {"level":"info","ts":"2026-02-04T18:22:50.912971Z","caller":"traceutil/trace.go:171","msg":"trace[744482035] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"1.245784292s","start":"2026-02-04T18:22:49.667167Z","end":"2026-02-04T18:22:50.912951Z","steps":["trace[744482035] 'process raft request' (duration: 1.24567488s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:50.913078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:49.667158Z","time spent":"1.245855775s","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T18:22:51.066897Z","caller":"traceutil/trace.go:171","msg":"trace[116818999] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:940; }","duration":"1.136444532s","start":"2026-02-04T18:22:49.930431Z","end":"2026-02-04T18:22:51.066875Z","steps":["trace[116818999] 'read index received' (duration: 983.472831ms)","trace[116818999] 'applied index is now lower than readState.Index' (duration: 152.969401ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:22:51.067232Z","caller":"traceutil/trace.go:171","msg":"trace[1068800000] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"964.888672ms","start":"2026-02-04T18:22:50.102331Z","end":"2026-02-04T18:22:51.06722Z","steps":["trace[1068800000] 'process raft request' (duration: 921.009296ms)","trace[1068800000] 'compare' (duration: 43.446116ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:22:51.067329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:50.102312Z","time spent":"964.954484ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T18:22:51.067594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.137156608s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-04T18:22:51.067664Z","caller":"traceutil/trace.go:171","msg":"trace[528494548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:903; }","duration":"1.137252931s","start":"2026-02-04T18:22:49.930401Z","end":"2026-02-04T18:22:51.067654Z","steps":["trace[528494548] 'agreement among raft nodes before linearized reading' (duration: 1.137108557s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:51.067699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:49.930387Z","time spent":"1.137304341s","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T18:22:51.067837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.769059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:22:51.067869Z","caller":"traceutil/trace.go:171","msg":"trace[1582944427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:903; }","duration":"646.864581ms","start":"2026-02-04T18:22:50.420996Z","end":"2026-02-04T18:22:51.06786Z","steps":["trace[1582944427] 'agreement among raft nodes before linearized reading' (duration: 646.82159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:51.067893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:50.420973Z","time spent":"646.914342ms","remote":"127.0.0.1:45354","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-04T18:22:51.471927Z","caller":"traceutil/trace.go:171","msg":"trace[535677723] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"390.314143ms","start":"2026-02-04T18:22:51.081589Z","end":"2026-02-04T18:22:51.471903Z","steps":["trace[535677723] 'process raft request' (duration: 389.964865ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:51.472014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:51.081576Z","time spent":"390.388295ms","remote":"127.0.0.1:45628","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-04T18:22:51.472098Z","caller":"traceutil/trace.go:171","msg":"trace[1743289521] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"390.441437ms","start":"2026-02-04T18:22:51.081652Z","end":"2026-02-04T18:22:51.472093Z","steps":["trace[1743289521] 'process raft request' (duration: 389.972606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:51.472125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:51.081644Z","time spent":"390.465977ms","remote":"127.0.0.1:45628","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-04T18:22:51.472214Z","caller":"traceutil/trace.go:171","msg":"trace[2096261629] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"389.637128ms","start":"2026-02-04T18:22:51.082572Z","end":"2026-02-04T18:22:51.472209Z","steps":["trace[2096261629] 'process raft request' (duration: 389.095866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:51.472241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:51.082561Z","time spent":"389.665409ms","remote":"127.0.0.1:45628","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-04T18:22:53.445536Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360057250619143,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-04T18:22:53.891261Z","caller":"traceutil/trace.go:171","msg":"trace[233960019] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:945; }","duration":"946.464635ms","start":"2026-02-04T18:22:52.944778Z","end":"2026-02-04T18:22:53.891243Z","steps":["trace[233960019] 'read index received' (duration: 946.292501ms)","trace[233960019] 'applied index is now lower than readState.Index' (duration: 171.344µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:22:53.891357Z","caller":"traceutil/trace.go:171","msg":"trace[2106179683] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"967.46582ms","start":"2026-02-04T18:22:52.923884Z","end":"2026-02-04T18:22:53.89135Z","steps":["trace[2106179683] 'process raft request' (duration: 967.198564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:53.891466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:52.923871Z","time spent":"967.506292ms","remote":"127.0.0.1:45628","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-04T18:22:53.891768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.988067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-04T18:22:53.891796Z","caller":"traceutil/trace.go:171","msg":"trace[1565412575] range","detail":"{range_begin:/registry/masterleases/162.253.55.74; range_end:; response_count:1; response_revision:908; }","duration":"947.063318ms","start":"2026-02-04T18:22:52.944726Z","end":"2026-02-04T18:22:53.89179Z","steps":["trace[1565412575] 'agreement among raft nodes before linearized reading' (duration: 946.943285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:53.891814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:52.944707Z","time spent":"947.10311ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.74\" "} {"level":"warn","ts":"2026-02-04T18:22:53.891998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.486912ms","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-04T18:22:53.892024Z","caller":"traceutil/trace.go:171","msg":"trace[1558353685] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:908; }","duration":"732.552623ms","start":"2026-02-04T18:22:53.159464Z","end":"2026-02-04T18:22:53.892016Z","steps":["trace[1558353685] 'agreement among raft nodes before linearized reading' (duration: 732.471022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:53.892065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:53.15943Z","time spent":"732.628695ms","remote":"127.0.0.1:45628","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-04T18:22:53.892292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.524557ms","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-04T18:22:53.892319Z","caller":"traceutil/trace.go:171","msg":"trace[266735535] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:908; }","duration":"412.567268ms","start":"2026-02-04T18:22:53.479746Z","end":"2026-02-04T18:22:53.892313Z","steps":["trace[266735535] 'agreement among raft nodes before linearized reading' (duration: 412.501817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:53.892338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:53.479738Z","time spent":"412.593109ms","remote":"127.0.0.1:45628","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-04T18:22:53.892472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.282264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-04T18:22:53.892518Z","caller":"traceutil/trace.go:171","msg":"trace[277830924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:908; }","duration":"413.341166ms","start":"2026-02-04T18:22:53.47917Z","end":"2026-02-04T18:22:53.892511Z","steps":["trace[277830924] 'agreement among raft nodes before linearized reading' (duration: 413.262194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:53.892542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:53.479166Z","time spent":"413.370116ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T18:22:53.892696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.53781ms","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-04T18:22:53.892721Z","caller":"traceutil/trace.go:171","msg":"trace[1067001488] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:908; }","duration":"413.592161ms","start":"2026-02-04T18:22:53.479123Z","end":"2026-02-04T18:22:53.892715Z","steps":["trace[1067001488] 'agreement among raft nodes before linearized reading' (duration: 413.51315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:53.892738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:53.479109Z","time spent":"413.624662ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T18:22:53.892835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.061428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:22:53.892885Z","caller":"traceutil/trace.go:171","msg":"trace[1335001085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:908; }","duration":"476.14321ms","start":"2026-02-04T18:22:53.416736Z","end":"2026-02-04T18:22:53.892879Z","steps":["trace[1335001085] 'agreement among raft nodes before linearized reading' (duration: 476.084769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:53.892909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:53.416718Z","time spent":"476.185241ms","remote":"127.0.0.1:45354","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-04T18:22:54.556868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.935223ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-04T18:22:54.557105Z","caller":"traceutil/trace.go:171","msg":"trace[732214314] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:946; }","duration":"658.824459ms","start":"2026-02-04T18:22:53.898269Z","end":"2026-02-04T18:22:54.557093Z","steps":["trace[732214314] 'read index received' (duration: 358.673621ms)","trace[732214314] 'applied index is now lower than readState.Index' (duration: 300.149538ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:22:54.557135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:53.898005Z","time spent":"659.125766ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-04T18:22:54.557221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.939031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:22:54.55727Z","caller":"traceutil/trace.go:171","msg":"trace[1037702578] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:908; }","duration":"659.004052ms","start":"2026-02-04T18:22:53.898254Z","end":"2026-02-04T18:22:54.557258Z","steps":["trace[1037702578] 'agreement among raft nodes before linearized reading' (duration: 658.926301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:54.557304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:53.898247Z","time spent":"659.050884ms","remote":"127.0.0.1:45358","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-04T18:22:56.564972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000880958s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-04T18:22:56.565043Z","caller":"traceutil/trace.go:171","msg":"trace[195608966] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000984459s","start":"2026-02-04T18:22:54.564043Z","end":"2026-02-04T18:22:56.565027Z","steps":["trace[195608966] 'agreement among raft nodes before linearized reading' (duration: 2.000895907s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:56.565082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:54.564035Z","time spent":"2.001039621s","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T18:22:56.624488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.232672049s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:22:56.624601Z","caller":"traceutil/trace.go:171","msg":"trace[1014134105] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:908; }","duration":"1.232791522s","start":"2026-02-04T18:22:55.391791Z","end":"2026-02-04T18:22:56.624582Z","steps":["trace[1014134105] 'range keys from in-memory index tree' (duration: 1.232659899s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:56.626197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.584085684s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T18:22:56.62656Z","caller":"traceutil/trace.go:171","msg":"trace[123907376] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"2.726328619s","start":"2026-02-04T18:22:53.90022Z","end":"2026-02-04T18:22:56.626548Z","steps":["trace[123907376] 'process raft request' (duration: 1.140962747s)","trace[123907376] 'compare' (duration: 1.582967437s)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:22:56.62661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:53.900209Z","time spent":"2.72637525s","remote":"127.0.0.1:45628","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-04T18:22:56.627032Z","caller":"traceutil/trace.go:171","msg":"trace[703208621] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"2.726139136s","start":"2026-02-04T18:22:53.900886Z","end":"2026-02-04T18:22:56.627025Z","steps":["trace[703208621] 'process raft request' (duration: 2.72592033s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:56.627083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:53.900879Z","time spent":"2.726183127s","remote":"127.0.0.1:45628","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-04T18:22:56.627273Z","caller":"traceutil/trace.go:171","msg":"trace[1559389986] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"2.724043696s","start":"2026-02-04T18:22:53.903223Z","end":"2026-02-04T18:22:56.627266Z","steps":["trace[1559389986] 'process raft request' (duration: 2.723640056s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:56.627311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:53.903175Z","time spent":"2.724115488s","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T18:22:56.627465Z","caller":"traceutil/trace.go:171","msg":"trace[1087581936] linearizableReadLoop","detail":"{readStateIndex:952; appliedIndex:947; }","duration":"2.063394047s","start":"2026-02-04T18:22:54.564065Z","end":"2026-02-04T18:22:56.627459Z","steps":["trace[1087581936] 'read index received' (duration: 477.127803ms)","trace[1087581936] 'applied index is now lower than readState.Index' (duration: 1.586265534s)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:22:56.627538Z","caller":"traceutil/trace.go:171","msg":"trace[362374772] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"2.724024376s","start":"2026-02-04T18:22:53.903507Z","end":"2026-02-04T18:22:56.627532Z","steps":["trace[362374772] 'process raft request' (duration: 2.72337685s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:56.62771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.304907511s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T18:22:56.627729Z","caller":"traceutil/trace.go:171","msg":"trace[523383862] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:913; }","duration":"1.304965723s","start":"2026-02-04T18:22:55.322757Z","end":"2026-02-04T18:22:56.627723Z","steps":["trace[523383862] 'agreement among raft nodes before linearized reading' (duration: 1.304913821s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:56.627747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:55.322739Z","time spent":"1.305002634s","remote":"127.0.0.1:45804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-02-04T18:22:56.627955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.812168ms","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-04T18:22:56.627974Z","caller":"traceutil/trace.go:171","msg":"trace[1120334839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:913; }","duration":"728.859989ms","start":"2026-02-04T18:22:55.899108Z","end":"2026-02-04T18:22:56.627968Z","steps":["trace[1120334839] 'agreement among raft nodes before linearized reading' (duration: 728.792898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:56.627988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:55.899094Z","time spent":"728.89112ms","remote":"127.0.0.1:45628","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-04T18:22:56.628064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.976489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:22:56.62808Z","caller":"traceutil/trace.go:171","msg":"trace[234235242] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:913; }","duration":"797.99349ms","start":"2026-02-04T18:22:55.830081Z","end":"2026-02-04T18:22:56.628075Z","steps":["trace[234235242] 'agreement among raft nodes before linearized reading' (duration: 797.966699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:56.628098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:55.830028Z","time spent":"798.065941ms","remote":"127.0.0.1:49842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-02-04T18:22:56.628442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:53.903501Z","time spent":"2.724140439s","remote":"127.0.0.1:45628","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-04T18:22:56.627563Z","caller":"traceutil/trace.go:171","msg":"trace[2141113391] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"2.068651318s","start":"2026-02-04T18:22:54.558907Z","end":"2026-02-04T18:22:56.627558Z","steps":["trace[2141113391] 'process raft request' (duration: 2.06832638s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:56.628498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:54.558888Z","time spent":"2.069592489s","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T18:22:57.127005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.534882ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T18:22:57.127137Z","caller":"traceutil/trace.go:171","msg":"trace[269211237] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"490.790117ms","start":"2026-02-04T18:22:56.636332Z","end":"2026-02-04T18:22:57.127122Z","steps":["trace[269211237] 'process raft request' (duration: 331.070691ms)","trace[269211237] 'compare' (duration: 159.364768ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:22:57.127191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:56.636322Z","time spent":"490.839019ms","remote":"127.0.0.1:45456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T18:22:57.127413Z","caller":"traceutil/trace.go:171","msg":"trace[1334308535] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:952; }","duration":"490.909011ms","start":"2026-02-04T18:22:56.636498Z","end":"2026-02-04T18:22:57.127407Z","steps":["trace[1334308535] 'read index received' (duration: 330.912528ms)","trace[1334308535] 'applied index is now lower than readState.Index' (duration: 159.995623ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:22:57.127512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.008953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-04T18:22:57.127525Z","caller":"traceutil/trace.go:171","msg":"trace[326836548] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:914; }","duration":"491.032263ms","start":"2026-02-04T18:22:56.636489Z","end":"2026-02-04T18:22:57.127521Z","steps":["trace[326836548] 'agreement among raft nodes before linearized reading' (duration: 490.959132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:57.127539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:56.636484Z","time spent":"491.050774ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-02-04T18:22:57.130632Z","caller":"traceutil/trace.go:171","msg":"trace[1085982446] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"492.764934ms","start":"2026-02-04T18:22:56.63786Z","end":"2026-02-04T18:22:57.130625Z","steps":["trace[1085982446] 'process raft request' (duration: 489.374236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:57.130684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:56.637854Z","time spent":"492.798335ms","remote":"127.0.0.1:45628","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-04T18:22:57.130796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.771987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:22:57.13081Z","caller":"traceutil/trace.go:171","msg":"trace[1793372131] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:915; }","duration":"493.793847ms","start":"2026-02-04T18:22:56.637011Z","end":"2026-02-04T18:22:57.130805Z","steps":["trace[1793372131] 'agreement among raft nodes before linearized reading' (duration: 493.771777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:22:57.130821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:22:56.637007Z","time spent":"493.811447ms","remote":"127.0.0.1:45354","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-04T18:22:58.353058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.332038ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-04T18:22:58.83555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.483159ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T18:22:58.835695Z","caller":"traceutil/trace.go:171","msg":"trace[1360971101] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"148.057737ms","start":"2026-02-04T18:22:58.687623Z","end":"2026-02-04T18:22:58.83568Z","steps":["trace[1360971101] 'process raft request' (duration: 148.011156ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:22:58.836009Z","caller":"traceutil/trace.go:171","msg":"trace[1166336522] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"195.679949ms","start":"2026-02-04T18:22:58.640318Z","end":"2026-02-04T18:22:58.835998Z","steps":["trace[1166336522] 'process raft request' (duration: 45.678378ms)","trace[1166336522] 'compare' (duration: 149.310835ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:22:58.836129Z","caller":"traceutil/trace.go:171","msg":"trace[1551060816] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"166.089954ms","start":"2026-02-04T18:22:58.67003Z","end":"2026-02-04T18:22:58.83612Z","steps":["trace[1551060816] 'read index received' (duration: 16.181215ms)","trace[1551060816] 'applied index is now lower than readState.Index' (duration: 149.907799ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:22:58.83624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.530181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-04T18:22:58.836266Z","caller":"traceutil/trace.go:171","msg":"trace[650263746] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:924; }","duration":"197.591663ms","start":"2026-02-04T18:22:58.638665Z","end":"2026-02-04T18:22:58.836257Z","steps":["trace[650263746] 'agreement among raft nodes before linearized reading' (duration: 197.48445ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:22:59.156982Z","caller":"traceutil/trace.go:171","msg":"trace[598581953] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"102.642365ms","start":"2026-02-04T18:22:59.054317Z","end":"2026-02-04T18:22:59.156959Z","steps":["trace[598581953] 'process raft request' (duration: 57.239154ms)","trace[598581953] 'compare' (duration: 45.175606ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:23:03.166467Z","caller":"traceutil/trace.go:171","msg":"trace[1737915605] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"204.514793ms","start":"2026-02-04T18:23:02.961938Z","end":"2026-02-04T18:23:03.166452Z","steps":["trace[1737915605] 'process raft request' (duration: 204.434911ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:23:03.166892Z","caller":"traceutil/trace.go:171","msg":"trace[1429748356] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:982; }","duration":"166.146675ms","start":"2026-02-04T18:23:03.000737Z","end":"2026-02-04T18:23:03.166884Z","steps":["trace[1429748356] 'read index received' (duration: 166.143385ms)","trace[1429748356] 'applied index is now lower than readState.Index' (duration: 2.65µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:23:03.170168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.41783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-04T18:23:03.170199Z","caller":"traceutil/trace.go:171","msg":"trace[1443914183] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:941; }","duration":"169.474461ms","start":"2026-02-04T18:23:03.000716Z","end":"2026-02-04T18:23:03.17019Z","steps":["trace[1443914183] 'agreement among raft nodes before linearized reading' (duration: 169.348628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:23:03.249776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.792178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T18:23:03.249856Z","caller":"traceutil/trace.go:171","msg":"trace[371585725] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:941; }","duration":"143.924321ms","start":"2026-02-04T18:23:03.105921Z","end":"2026-02-04T18:23:03.249846Z","steps":["trace[371585725] 'agreement among raft nodes before linearized reading' (duration: 143.815509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:23:03.250074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.656906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-04T18:23:03.250114Z","caller":"traceutil/trace.go:171","msg":"trace[793167028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:941; }","duration":"239.703108ms","start":"2026-02-04T18:23:03.010405Z","end":"2026-02-04T18:23:03.250108Z","steps":["trace[793167028] 'agreement among raft nodes before linearized reading' (duration: 239.618916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:23:03.250294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.892542ms","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-04T18:23:03.250336Z","caller":"traceutil/trace.go:171","msg":"trace[981052074] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:941; }","duration":"239.944413ms","start":"2026-02-04T18:23:03.010387Z","end":"2026-02-04T18:23:03.250331Z","steps":["trace[981052074] 'agreement among raft nodes before linearized reading' (duration: 239.873142ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:23:03.72562Z","caller":"traceutil/trace.go:171","msg":"trace[1854481538] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"186.076147ms","start":"2026-02-04T18:23:03.539519Z","end":"2026-02-04T18:23:03.725595Z","steps":["trace[1854481538] 'process raft request' (duration: 185.956344ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:23:05.461553Z","caller":"traceutil/trace.go:171","msg":"trace[1435326717] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:996; }","duration":"153.643656ms","start":"2026-02-04T18:23:05.307892Z","end":"2026-02-04T18:23:05.461535Z","steps":["trace[1435326717] 'read index received' (duration: 153.469262ms)","trace[1435326717] 'applied index is now lower than readState.Index' (duration: 173.613µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:23:05.461629Z","caller":"traceutil/trace.go:171","msg":"trace[635259975] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"199.080508ms","start":"2026-02-04T18:23:05.262543Z","end":"2026-02-04T18:23:05.461623Z","steps":["trace[635259975] 'process raft request' (duration: 198.876573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:23:05.461797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.891061ms","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-04T18:23:05.461817Z","caller":"traceutil/trace.go:171","msg":"trace[185776823] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:955; }","duration":"153.940363ms","start":"2026-02-04T18:23:05.307871Z","end":"2026-02-04T18:23:05.461811Z","steps":["trace[185776823] 'agreement among raft nodes before linearized reading' (duration: 153.850171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:23:05.461931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.074292ms","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-04T18:23:05.461978Z","caller":"traceutil/trace.go:171","msg":"trace[93216460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:955; }","duration":"150.158714ms","start":"2026-02-04T18:23:05.311808Z","end":"2026-02-04T18:23:05.461967Z","steps":["trace[93216460] 'agreement among raft nodes before linearized reading' (duration: 150.036382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:23:05.462041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.714564ms","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-04T18:23:05.462064Z","caller":"traceutil/trace.go:171","msg":"trace[1882818874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:955; }","duration":"139.765374ms","start":"2026-02-04T18:23:05.322293Z","end":"2026-02-04T18:23:05.462058Z","steps":["trace[1882818874] 'agreement among raft nodes before linearized reading' (duration: 139.707203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:23:06.077865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.712844ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T18:23:06.077987Z","caller":"traceutil/trace.go:171","msg":"trace[1090507806] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"611.278087ms","start":"2026-02-04T18:23:05.466691Z","end":"2026-02-04T18:23:06.077969Z","steps":["trace[1090507806] 'process raft request' (duration: 401.365999ms)","trace[1090507806] 'compare' (duration: 209.605141ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:23:06.078044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:23:05.466675Z","time spent":"611.342568ms","remote":"127.0.0.1:45628","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-04T18:23:06.153759Z","caller":"traceutil/trace.go:171","msg":"trace[197984709] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"686.502257ms","start":"2026-02-04T18:23:05.467244Z","end":"2026-02-04T18:23:06.153746Z","steps":["trace[197984709] 'process raft request' (duration: 686.364134ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:23:06.15386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:23:05.467222Z","time spent":"686.58737ms","remote":"127.0.0.1:45628","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-04T18:23:06.154226Z","caller":"traceutil/trace.go:171","msg":"trace[365479540] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"686.16149ms","start":"2026-02-04T18:23:05.468059Z","end":"2026-02-04T18:23:06.154221Z","steps":["trace[365479540] 'process raft request' (duration: 685.640948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:23:06.154271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:23:05.468052Z","time spent":"686.202361ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T18:23:06.154388Z","caller":"traceutil/trace.go:171","msg":"trace[519358872] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:997; }","duration":"684.569453ms","start":"2026-02-04T18:23:05.469151Z","end":"2026-02-04T18:23:06.15372Z","steps":["trace[519358872] 'read index received' (duration: 398.918852ms)","trace[519358872] 'applied index is now lower than readState.Index' (duration: 285.649861ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:23:06.154438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.269769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:23:06.154458Z","caller":"traceutil/trace.go:171","msg":"trace[1329912986] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:958; }","duration":"685.319211ms","start":"2026-02-04T18:23:05.469133Z","end":"2026-02-04T18:23:06.154453Z","steps":["trace[1329912986] 'agreement among raft nodes before linearized reading' (duration: 685.273359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:23:06.154473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:23:05.469128Z","time spent":"685.342231ms","remote":"127.0.0.1:45354","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-04T18:23:13.085878Z","caller":"traceutil/trace.go:171","msg":"trace[452905939] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"119.68302ms","start":"2026-02-04T18:23:12.966152Z","end":"2026-02-04T18:23:13.085835Z","steps":["trace[452905939] 'process raft request' (duration: 115.933782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:23:14.673576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.010733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T18:23:14.673642Z","caller":"traceutil/trace.go:171","msg":"trace[1160663279] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1039; }","duration":"251.933331ms","start":"2026-02-04T18:23:14.421698Z","end":"2026-02-04T18:23:14.673631Z","steps":["trace[1160663279] 'read index received' (duration: 2.777314ms)","trace[1160663279] 'applied index is now lower than readState.Index' (duration: 249.155227ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:23:14.673768Z","caller":"traceutil/trace.go:171","msg":"trace[1673167799] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"276.012389ms","start":"2026-02-04T18:23:14.397748Z","end":"2026-02-04T18:23:14.67376Z","steps":["trace[1673167799] 'process raft request' (duration: 26.7632ms)","trace[1673167799] 'compare' (duration: 248.705236ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:23:14.673885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.190237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:23:14.673902Z","caller":"traceutil/trace.go:171","msg":"trace[823924878] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:996; }","duration":"252.250418ms","start":"2026-02-04T18:23:14.421646Z","end":"2026-02-04T18:23:14.673896Z","steps":["trace[823924878] 'agreement among raft nodes before linearized reading' (duration: 252.218257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:23:15.462326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.05346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T18:23:15.462406Z","caller":"traceutil/trace.go:171","msg":"trace[561632959] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"299.702716ms","start":"2026-02-04T18:23:15.162692Z","end":"2026-02-04T18:23:15.462395Z","steps":["trace[561632959] 'process raft request' (duration: 13.538714ms)","trace[561632959] 'compare' (duration: 285.935117ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:23:15.793298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.283646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-04T18:23:15.793372Z","caller":"traceutil/trace.go:171","msg":"trace[1825761195] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:998; }","duration":"190.394978ms","start":"2026-02-04T18:23:15.602964Z","end":"2026-02-04T18:23:15.793359Z","steps":["trace[1825761195] 'range keys from in-memory index tree' (duration: 190.134472ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:23:16.078313Z","caller":"traceutil/trace.go:171","msg":"trace[1644069110] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"281.775821ms","start":"2026-02-04T18:23:15.796518Z","end":"2026-02-04T18:23:16.078294Z","steps":["trace[1644069110] 'process raft request' (duration: 281.430114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:23:16.683479Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360057250619345,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T18:23:17.97853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.072983554s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T18:23:17.980293Z","caller":"traceutil/trace.go:171","msg":"trace[1506962759] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"1.897907977s","start":"2026-02-04T18:23:16.082374Z","end":"2026-02-04T18:23:17.980281Z","steps":["trace[1506962759] 'process raft request' (duration: 823.11086ms)","trace[1506962759] 'compare' (duration: 1.072404281s)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:23:17.980358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:23:16.082358Z","time spent":"1.897972689s","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T18:23:17.986575Z","caller":"traceutil/trace.go:171","msg":"trace[21504757] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1043; }","duration":"1.803296338s","start":"2026-02-04T18:23:16.183256Z","end":"2026-02-04T18:23:17.986552Z","steps":["trace[21504757] 'read index received' (duration: 722.180685ms)","trace[21504757] 'applied index is now lower than readState.Index' (duration: 1.081110883s)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:23:17.986752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.803492173s","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-04T18:23:17.986769Z","caller":"traceutil/trace.go:171","msg":"trace[455387353] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1001; }","duration":"1.803557634s","start":"2026-02-04T18:23:16.183206Z","end":"2026-02-04T18:23:17.986764Z","steps":["trace[455387353] 'agreement among raft nodes before linearized reading' (duration: 1.803446081s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:23:17.986789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:23:16.183165Z","time spent":"1.803619775s","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T18:23:17.986932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.577275256s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-04T18:23:17.986944Z","caller":"traceutil/trace.go:171","msg":"trace[326778915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1001; }","duration":"1.577342678s","start":"2026-02-04T18:23:16.409597Z","end":"2026-02-04T18:23:17.98694Z","steps":["trace[326778915] 'agreement among raft nodes before linearized reading' (duration: 1.577304157s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:23:17.986956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:23:16.409579Z","time spent":"1.577373709s","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T18:23:17.98705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.978026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-04T18:23:17.987063Z","caller":"traceutil/trace.go:171","msg":"trace[1648929088] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1001; }","duration":"250.032367ms","start":"2026-02-04T18:23:17.737027Z","end":"2026-02-04T18:23:17.98706Z","steps":["trace[1648929088] 'agreement among raft nodes before linearized reading' (duration: 249.994627ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:23:17.978675Z","caller":"traceutil/trace.go:171","msg":"trace[1585854085] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"240.282511ms","start":"2026-02-04T18:23:17.738379Z","end":"2026-02-04T18:23:17.978662Z","steps":["trace[1585854085] 'process raft request' (duration: 240.22306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:23:17.987416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.2569042s","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-04T18:23:17.987432Z","caller":"traceutil/trace.go:171","msg":"trace[421340159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1001; }","duration":"1.256944851s","start":"2026-02-04T18:23:16.730482Z","end":"2026-02-04T18:23:17.987427Z","steps":["trace[421340159] 'agreement among raft nodes before linearized reading' (duration: 1.25689569s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:23:17.987444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:23:16.730468Z","time spent":"1.256973433s","remote":"127.0.0.1:45628","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-04T18:23:17.987498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.570185703s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:23:17.98751Z","caller":"traceutil/trace.go:171","msg":"trace[688721694] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1001; }","duration":"1.570209424s","start":"2026-02-04T18:23:16.417296Z","end":"2026-02-04T18:23:17.987506Z","steps":["trace[688721694] 'agreement among raft nodes before linearized reading' (duration: 1.570191173s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:23:17.987521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:23:16.417289Z","time spent":"1.570229684s","remote":"127.0.0.1:45354","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-04T18:23:17.987132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.177317ms","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-04T18:23:17.989821Z","caller":"traceutil/trace.go:171","msg":"trace[1527027729] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1001; }","duration":"919.87634ms","start":"2026-02-04T18:23:17.069922Z","end":"2026-02-04T18:23:17.989799Z","steps":["trace[1527027729] 'agreement among raft nodes before linearized reading' (duration: 917.184077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:23:17.989893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:23:17.069907Z","time spent":"919.955191ms","remote":"127.0.0.1:45628","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-04T18:23:38.249724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.946466ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T18:23:38.250029Z","caller":"traceutil/trace.go:171","msg":"trace[755798193] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1152; }","duration":"141.69601ms","start":"2026-02-04T18:23:38.108238Z","end":"2026-02-04T18:23:38.249934Z","steps":["trace[755798193] 'read index received' (duration: 5.379875ms)","trace[755798193] 'applied index is now lower than readState.Index' (duration: 136.314645ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:23:38.250238Z","caller":"traceutil/trace.go:171","msg":"trace[1321200078] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"158.655733ms","start":"2026-02-04T18:23:38.091568Z","end":"2026-02-04T18:23:38.250224Z","steps":["trace[1321200078] 'process raft request' (duration: 22.114062ms)","trace[1321200078] 'compare' (duration: 135.770722ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:23:38.270628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.367458ms","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-04T18:23:38.2712Z","caller":"traceutil/trace.go:171","msg":"trace[1106311645] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1105; }","duration":"162.978762ms","start":"2026-02-04T18:23:38.108198Z","end":"2026-02-04T18:23:38.271177Z","steps":["trace[1106311645] 'agreement among raft nodes before linearized reading' (duration: 143.259226ms)","trace[1106311645] 'range keys from in-memory index tree' (duration: 19.075542ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:23:38.271667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.571021ms","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-04T18:23:38.272304Z","caller":"traceutil/trace.go:171","msg":"trace[2132618036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1105; }","duration":"150.204266ms","start":"2026-02-04T18:23:38.122085Z","end":"2026-02-04T18:23:38.272289Z","steps":["trace[2132618036] 'agreement among raft nodes before linearized reading' (duration: 149.50635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:23:38.270987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.763465ms","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-04T18:23:38.273464Z","caller":"traceutil/trace.go:171","msg":"trace[118675901] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1105; }","duration":"104.266353ms","start":"2026-02-04T18:23:38.169183Z","end":"2026-02-04T18:23:38.273449Z","steps":["trace[118675901] 'agreement among raft nodes before linearized reading' (duration: 101.740954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:23:38.271755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.270187ms","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-04T18:23:38.277495Z","caller":"traceutil/trace.go:171","msg":"trace[42900932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1105; }","duration":"156.026661ms","start":"2026-02-04T18:23:38.121453Z","end":"2026-02-04T18:23:38.27748Z","steps":["trace[42900932] 'agreement among raft nodes before linearized reading' (duration: 150.257287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:23:38.270888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.273714ms","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-04T18:23:38.27974Z","caller":"traceutil/trace.go:171","msg":"trace[716172639] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1105; }","duration":"160.75661ms","start":"2026-02-04T18:23:38.118574Z","end":"2026-02-04T18:23:38.27933Z","steps":["trace[716172639] 'agreement among raft nodes before linearized reading' (duration: 150.467441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:23:38.493127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.075344ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T18:23:38.493419Z","caller":"traceutil/trace.go:171","msg":"trace[1435537714] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"199.654651ms","start":"2026-02-04T18:23:38.293744Z","end":"2026-02-04T18:23:38.493398Z","steps":["trace[1435537714] 'process raft request' (duration: 67.84899ms)","trace[1435537714] 'compare' (duration: 130.90872ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:23:38.496635Z","caller":"traceutil/trace.go:171","msg":"trace[844160430] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"202.814764ms","start":"2026-02-04T18:23:38.293801Z","end":"2026-02-04T18:23:38.496616Z","steps":["trace[844160430] 'process raft request' (duration: 199.436695ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:23:38.496896Z","caller":"traceutil/trace.go:171","msg":"trace[1047159929] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"199.104628ms","start":"2026-02-04T18:23:38.297786Z","end":"2026-02-04T18:23:38.496891Z","steps":["trace[1047159929] 'process raft request' (duration: 198.014782ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:23:38.496958Z","caller":"traceutil/trace.go:171","msg":"trace[827297822] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"199.244521ms","start":"2026-02-04T18:23:38.297708Z","end":"2026-02-04T18:23:38.496953Z","steps":["trace[827297822] 'process raft request' (duration: 197.902099ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:23:38.497151Z","caller":"traceutil/trace.go:171","msg":"trace[1906758981] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"196.328134ms","start":"2026-02-04T18:23:38.300818Z","end":"2026-02-04T18:23:38.497146Z","steps":["trace[1906758981] 'process raft request' (duration: 195.482235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:24:12.871327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.608826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-04T18:24:12.871401Z","caller":"traceutil/trace.go:171","msg":"trace[1679022667] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1248; }","duration":"101.716969ms","start":"2026-02-04T18:24:12.769666Z","end":"2026-02-04T18:24:12.871383Z","steps":["trace[1679022667] 'range keys from in-memory index tree' (duration: 101.455133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:24:12.871738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.237778ms","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-04T18:24:12.871867Z","caller":"traceutil/trace.go:171","msg":"trace[553227933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1248; }","duration":"101.387301ms","start":"2026-02-04T18:24:12.770463Z","end":"2026-02-04T18:24:12.871851Z","steps":["trace[553227933] 'range keys from in-memory index tree' (duration: 100.799258ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:24:12.985015Z","caller":"traceutil/trace.go:171","msg":"trace[960910195] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"109.386054ms","start":"2026-02-04T18:24:12.875614Z","end":"2026-02-04T18:24:12.985Z","steps":["trace[960910195] 'process raft request' (duration: 109.301472ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:24:13.004375Z","caller":"traceutil/trace.go:171","msg":"trace[714789408] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"128.553861ms","start":"2026-02-04T18:24:12.87581Z","end":"2026-02-04T18:24:13.004364Z","steps":["trace[714789408] 'process raft request' (duration: 128.312626ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:24:13.599319Z","caller":"traceutil/trace.go:171","msg":"trace[2017517753] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"159.20283ms","start":"2026-02-04T18:24:13.439915Z","end":"2026-02-04T18:24:13.599118Z","steps":["trace[2017517753] 'process raft request' (duration: 150.463871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:24:37.784495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.895703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:24:37.784581Z","caller":"traceutil/trace.go:171","msg":"trace[1247018999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1347; }","duration":"363.052078ms","start":"2026-02-04T18:24:37.421512Z","end":"2026-02-04T18:24:37.784564Z","steps":["trace[1247018999] 'range keys from in-memory index tree' (duration: 362.76937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:24:37.784615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:24:37.421458Z","time spent":"363.13894ms","remote":"127.0.0.1:45354","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-04T18:24:37.784925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.384289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-04T18:24:37.784953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.199784ms","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-04T18:24:37.78497Z","caller":"traceutil/trace.go:171","msg":"trace[1661456701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1347; }","duration":"625.455961ms","start":"2026-02-04T18:24:37.159502Z","end":"2026-02-04T18:24:37.784958Z","steps":["trace[1661456701] 'range keys from in-memory index tree' (duration: 625.250216ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:24:37.78498Z","caller":"traceutil/trace.go:171","msg":"trace[301115485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1347; }","duration":"518.274246ms","start":"2026-02-04T18:24:37.266696Z","end":"2026-02-04T18:24:37.784971Z","steps":["trace[301115485] 'range keys from in-memory index tree' (duration: 518.045981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:24:37.784992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:24:37.159484Z","time spent":"625.503381ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T18:24:37.785001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:24:37.266678Z","time spent":"518.318488ms","remote":"127.0.0.1:45628","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-04T18:24:37.922019Z","caller":"traceutil/trace.go:171","msg":"trace[1509847087] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"132.169274ms","start":"2026-02-04T18:24:37.789819Z","end":"2026-02-04T18:24:37.921989Z","steps":["trace[1509847087] 'process raft request' (duration: 131.805625ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:24:37.956596Z","caller":"traceutil/trace.go:171","msg":"trace[1907172237] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"165.651716ms","start":"2026-02-04T18:24:37.79092Z","end":"2026-02-04T18:24:37.956572Z","steps":["trace[1907172237] 'process raft request' (duration: 165.465452ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:24:38.50522Z","caller":"traceutil/trace.go:171","msg":"trace[1133917278] linearizableReadLoop","detail":"{readStateIndex:1410; appliedIndex:1409; }","duration":"180.089896ms","start":"2026-02-04T18:24:38.32511Z","end":"2026-02-04T18:24:38.5052Z","steps":["trace[1133917278] 'read index received' (duration: 135.94832ms)","trace[1133917278] 'applied index is now lower than readState.Index' (duration: 44.140666ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:24:38.505894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.764062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-04T18:24:38.506183Z","caller":"traceutil/trace.go:171","msg":"trace[191100972] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1349; }","duration":"180.867274ms","start":"2026-02-04T18:24:38.325067Z","end":"2026-02-04T18:24:38.505934Z","steps":["trace[191100972] 'agreement among raft nodes before linearized reading' (duration: 180.451385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:24:38.89393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.225399ms","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-04T18:24:38.894016Z","caller":"traceutil/trace.go:171","msg":"trace[260207073] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1352; }","duration":"123.360403ms","start":"2026-02-04T18:24:38.770632Z","end":"2026-02-04T18:24:38.893992Z","steps":["trace[260207073] 'range keys from in-memory index tree' (duration: 123.023783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:24:38.894564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.653865ms","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-04T18:24:38.8946Z","caller":"traceutil/trace.go:171","msg":"trace[1418790966] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1352; }","duration":"182.748546ms","start":"2026-02-04T18:24:38.711841Z","end":"2026-02-04T18:24:38.89459Z","steps":["trace[1418790966] 'range keys from in-memory index tree' (duration: 182.556141ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:24:41.131727Z","caller":"traceutil/trace.go:171","msg":"trace[1403195201] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"153.050908ms","start":"2026-02-04T18:24:40.978649Z","end":"2026-02-04T18:24:41.131699Z","steps":["trace[1403195201] 'process raft request' (duration: 112.157987ms)","trace[1403195201] 'compare' (duration: 40.359679ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:24:44.173138Z","caller":"traceutil/trace.go:171","msg":"trace[1157183148] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"103.251925ms","start":"2026-02-04T18:24:44.069581Z","end":"2026-02-04T18:24:44.172833Z","steps":["trace[1157183148] 'process raft request' (duration: 72.726879ms)","trace[1157183148] 'compare' (duration: 30.415663ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:24:53.217362Z","caller":"traceutil/trace.go:171","msg":"trace[685645120] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"121.430315ms","start":"2026-02-04T18:24:53.095913Z","end":"2026-02-04T18:24:53.217343Z","steps":["trace[685645120] 'process raft request' (duration: 103.686053ms)","trace[685645120] 'compare' (duration: 17.624248ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:24:53.746819Z","caller":"traceutil/trace.go:171","msg":"trace[1962498623] transaction","detail":"{read_only:false; response_revision:1415; number_of_response:1; }","duration":"106.603338ms","start":"2026-02-04T18:24:53.64002Z","end":"2026-02-04T18:24:53.746623Z","steps":["trace[1962498623] 'process raft request' (duration: 88.733545ms)","trace[1962498623] 'compare' (duration: 17.766221ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:24:58.507284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.205345ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-04T18:25:07.393087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.901562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-02-04T18:25:07.393178Z","caller":"traceutil/trace.go:171","msg":"trace[1673168825] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1466; }","duration":"102.043206ms","start":"2026-02-04T18:25:07.29112Z","end":"2026-02-04T18:25:07.393163Z","steps":["trace[1673168825] 'range keys from in-memory index tree' (duration: 101.77159ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:25:07.512444Z","caller":"traceutil/trace.go:171","msg":"trace[1285796670] transaction","detail":"{read_only:false; response_revision:1467; number_of_response:1; }","duration":"116.593583ms","start":"2026-02-04T18:25:07.395813Z","end":"2026-02-04T18:25:07.512407Z","steps":["trace[1285796670] 'process raft request' (duration: 116.325557ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:25:07.708833Z","caller":"traceutil/trace.go:171","msg":"trace[126600221] transaction","detail":"{read_only:false; response_revision:1469; number_of_response:1; }","duration":"119.400838ms","start":"2026-02-04T18:25:07.589418Z","end":"2026-02-04T18:25:07.708819Z","steps":["trace[126600221] 'process raft request' (duration: 72.547329ms)","trace[126600221] 'compare' (duration: 46.522871ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:26:42.261134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.49643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:26:42.261452Z","caller":"traceutil/trace.go:171","msg":"trace[526523069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1882; }","duration":"841.718935ms","start":"2026-02-04T18:26:41.419589Z","end":"2026-02-04T18:26:42.261308Z","steps":["trace[526523069] 'range keys from in-memory index tree' (duration: 841.433079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:26:42.261829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:26:41.419574Z","time spent":"842.180995ms","remote":"127.0.0.1:45354","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-04T18:26:42.262104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.844459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-02-04T18:26:42.262417Z","caller":"traceutil/trace.go:171","msg":"trace[1246395939] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1882; }","duration":"806.194728ms","start":"2026-02-04T18:26:41.456208Z","end":"2026-02-04T18:26:42.262403Z","steps":["trace[1246395939] 'range keys from in-memory index tree' (duration: 805.785488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:26:42.262447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:26:41.456198Z","time spent":"806.238948ms","remote":"127.0.0.1:45450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-02-04T18:26:42.263898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.596506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:127425"} {"level":"info","ts":"2026-02-04T18:26:42.263939Z","caller":"traceutil/trace.go:171","msg":"trace[1495526972] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1882; }","duration":"802.737399ms","start":"2026-02-04T18:26:41.461194Z","end":"2026-02-04T18:26:42.263931Z","steps":["trace[1495526972] 'range keys from in-memory index tree' (duration: 801.541562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:26:42.26396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:26:41.461178Z","time spent":"802.777731ms","remote":"127.0.0.1:45436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":127448,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-02-04T18:26:42.264332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.16309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-04T18:26:42.264363Z","caller":"traceutil/trace.go:171","msg":"trace[558982244] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1882; }","duration":"358.229072ms","start":"2026-02-04T18:26:41.906127Z","end":"2026-02-04T18:26:42.264356Z","steps":["trace[558982244] 'range keys from in-memory index tree' (duration: 358.076568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:26:42.264383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:26:41.90611Z","time spent":"358.267842ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T18:26:42.265292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.017848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-04T18:26:42.265373Z","caller":"traceutil/trace.go:171","msg":"trace[531038390] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1882; }","duration":"277.127211ms","start":"2026-02-04T18:26:41.988236Z","end":"2026-02-04T18:26:42.265364Z","steps":["trace[531038390] 'range keys from in-memory index tree' (duration: 276.923816ms)"],"step_count":1}