{"level":"warn","ts":"2026-02-01T14:31:49.724652Z","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-01T14:31:49.724745Z","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-01T14:31:49.724845Z","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-01T14:31:49.724855Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.74:2380"]} {"level":"info","ts":"2026-02-01T14:31:49.724869Z","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-01T14:31:49.726443Z","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-01T14:31:49.726575Z","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-01T14:31:49.732419Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.550767ms"} {"level":"info","ts":"2026-02-01T14:31:49.745102Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"30aa8136fb61a722","cluster-id":"e7ea3889c4872560"} {"level":"info","ts":"2026-02-01T14:31:49.745158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=()"} {"level":"info","ts":"2026-02-01T14:31:49.745184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 0"} {"level":"info","ts":"2026-02-01T14:31:49.745191Z","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-01T14:31:49.745198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 1"} {"level":"info","ts":"2026-02-01T14:31:49.745225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"warn","ts":"2026-02-01T14:31:49.762402Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-01T14:31:49.772689Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-01T14:31:49.774313Z","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-01T14:31:49.777021Z","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-01T14:31:49.779312Z","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-01T14:31:49.779475Z","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-01T14:31:49.779505Z","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-01T14:31:49.779538Z","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-01T14:31:49.779753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"info","ts":"2026-02-01T14:31:49.779838Z","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-01T14:31:49.780742Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-02-01T14:31:49.780755Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-02-01T14:31:49.780643Z","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-01T14:31:49.781997Z","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-01T14:31:49.78202Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-01T14:31:50.445726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 is starting a new election at term 1"} {"level":"info","ts":"2026-02-01T14:31:50.445765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-01T14:31:50.445783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgPreVoteResp from 30aa8136fb61a722 at term 1"} {"level":"info","ts":"2026-02-01T14:31:50.445814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became candidate at term 2"} {"level":"info","ts":"2026-02-01T14:31:50.445825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgVoteResp from 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-02-01T14:31:50.445833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became leader at term 2"} {"level":"info","ts":"2026-02-01T14:31:50.445862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 30aa8136fb61a722 elected leader 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-02-01T14:31:50.494117Z","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-01T14:31:50.494156Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-01T14:31:50.494515Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T14:31:50.497514Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-01T14:31:50.49778Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-01T14:31:50.500693Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.74:2379"} {"level":"info","ts":"2026-02-01T14:31:50.508893Z","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-01T14:31:50.511261Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T14:31:50.511284Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T14:31:50.511315Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-01T14:31:50.511338Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-01T14:32:00.898063Z","caller":"traceutil/trace.go:171","msg":"trace[1527986925] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"107.374514ms","start":"2026-02-01T14:32:00.790667Z","end":"2026-02-01T14:32:00.898041Z","steps":["trace[1527986925] 'process raft request' (duration: 63.35145ms)","trace[1527986925] 'compare' (duration: 43.755915ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T14:32:01.162334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.040766ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T14:32:01.16241Z","caller":"traceutil/trace.go:171","msg":"trace[659671491] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"235.646854ms","start":"2026-02-01T14:32:00.926746Z","end":"2026-02-01T14:32:01.162392Z","steps":["trace[659671491] 'process raft request' (duration: 131.266637ms)","trace[659671491] 'compare' (duration: 103.920423ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T14:32:01.163729Z","caller":"traceutil/trace.go:171","msg":"trace[1891387418] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:385; }","duration":"161.283568ms","start":"2026-02-01T14:32:01.002438Z","end":"2026-02-01T14:32:01.163722Z","steps":["trace[1891387418] 'read index received' (duration: 55.580689ms)","trace[1891387418] 'applied index is now lower than readState.Index' (duration: 105.702409ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T14:32:01.163833Z","caller":"traceutil/trace.go:171","msg":"trace[1577583675] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"162.127506ms","start":"2026-02-01T14:32:01.0017Z","end":"2026-02-01T14:32:01.163828Z","steps":["trace[1577583675] 'process raft request' (duration: 161.9495ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:32:01.163895Z","caller":"traceutil/trace.go:171","msg":"trace[1094062574] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"162.148906ms","start":"2026-02-01T14:32:01.001742Z","end":"2026-02-01T14:32:01.163891Z","steps":["trace[1094062574] 'process raft request' (duration: 161.946849ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:32:01.163948Z","caller":"traceutil/trace.go:171","msg":"trace[1744680296] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"161.400062ms","start":"2026-02-01T14:32:01.002545Z","end":"2026-02-01T14:32:01.163945Z","steps":["trace[1744680296] 'process raft request' (duration: 161.163814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:01.164007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.564337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-lfxxt\" ","response":"range_response_count:1 size:3416"} {"level":"info","ts":"2026-02-01T14:32:01.164021Z","caller":"traceutil/trace.go:171","msg":"trace[1172958340] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-lfxxt; range_end:; response_count:1; response_revision:381; }","duration":"161.595268ms","start":"2026-02-01T14:32:01.002421Z","end":"2026-02-01T14:32:01.164016Z","steps":["trace[1172958340] 'agreement among raft nodes before linearized reading' (duration: 161.565487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:01.164092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.216723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-01T14:32:01.164104Z","caller":"traceutil/trace.go:171","msg":"trace[234080284] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:381; }","duration":"160.238645ms","start":"2026-02-01T14:32:01.003862Z","end":"2026-02-01T14:32:01.164101Z","steps":["trace[234080284] 'agreement among raft nodes before linearized reading' (duration: 160.216174ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:32:01.719409Z","caller":"traceutil/trace.go:171","msg":"trace[1488077290] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"368.252114ms","start":"2026-02-01T14:32:01.351134Z","end":"2026-02-01T14:32:01.719386Z","steps":["trace[1488077290] 'process raft request' (duration: 299.709096ms)","trace[1488077290] 'compare' (duration: 67.898407ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T14:32:01.719514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:01.351129Z","time spent":"368.335347ms","remote":"127.0.0.1:32898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3737,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T14:32:01.719595Z","caller":"traceutil/trace.go:171","msg":"trace[742524426] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"366.996663ms","start":"2026-02-01T14:32:01.352592Z","end":"2026-02-01T14:32:01.719588Z","steps":["trace[742524426] 'process raft request' (duration: 366.463776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:01.719624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:01.352586Z","time spent":"367.022264ms","remote":"127.0.0.1:60700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-01T14:32:01.720105Z","caller":"traceutil/trace.go:171","msg":"trace[1205036509] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"366.216668ms","start":"2026-02-01T14:32:01.353878Z","end":"2026-02-01T14:32:01.720094Z","steps":["trace[1205036509] 'process raft request' (duration: 365.225586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:01.720153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:01.353872Z","time spent":"366.254439ms","remote":"127.0.0.1:60802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3979,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T14:32:01.952393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.67415ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T14:32:01.952713Z","caller":"traceutil/trace.go:171","msg":"trace[813892145] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"224.258535ms","start":"2026-02-01T14:32:01.72843Z","end":"2026-02-01T14:32:01.952688Z","steps":["trace[813892145] 'process raft request' (duration: 113.214743ms)","trace[813892145] 'compare' (duration: 110.394151ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T14:32:01.961921Z","caller":"traceutil/trace.go:171","msg":"trace[1586075990] linearizableReadLoop","detail":"{readStateIndex:402; appliedIndex:400; }","duration":"176.018255ms","start":"2026-02-01T14:32:01.78588Z","end":"2026-02-01T14:32:01.961899Z","steps":["trace[1586075990] 'read index received' (duration: 55.770994ms)","trace[1586075990] 'applied index is now lower than readState.Index' (duration: 120.24439ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T14:32:01.962056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.147749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T14:32:01.962091Z","caller":"traceutil/trace.go:171","msg":"trace[588243460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:394; }","duration":"176.236272ms","start":"2026-02-01T14:32:01.785846Z","end":"2026-02-01T14:32:01.962082Z","steps":["trace[588243460] 'agreement among raft nodes before linearized reading' (duration: 176.148589ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:32:01.962198Z","caller":"traceutil/trace.go:171","msg":"trace[2078339107] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"233.263907ms","start":"2026-02-01T14:32:01.728921Z","end":"2026-02-01T14:32:01.962185Z","steps":["trace[2078339107] 'process raft request' (duration: 232.844803ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:32:05.520902Z","caller":"traceutil/trace.go:171","msg":"trace[1447318366] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"131.224335ms","start":"2026-02-01T14:32:05.389653Z","end":"2026-02-01T14:32:05.520877Z","steps":["trace[1447318366] 'process raft request' (duration: 131.037659ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:32:08.493639Z","caller":"traceutil/trace.go:171","msg":"trace[1821046999] linearizableReadLoop","detail":"{readStateIndex:432; appliedIndex:431; }","duration":"456.913993ms","start":"2026-02-01T14:32:08.036708Z","end":"2026-02-01T14:32:08.493622Z","steps":["trace[1821046999] 'read index received' (duration: 456.772558ms)","trace[1821046999] 'applied index is now lower than readState.Index' (duration: 140.815µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T14:32:08.493863Z","caller":"traceutil/trace.go:171","msg":"trace[118137522] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"568.012076ms","start":"2026-02-01T14:32:07.925844Z","end":"2026-02-01T14:32:08.493856Z","steps":["trace[118137522] 'process raft request' (duration: 567.685536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:08.493946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:07.925832Z","time spent":"568.061388ms","remote":"127.0.0.1:60802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4241,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T14:32:08.494083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.380417ms","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-01T14:32:08.494108Z","caller":"traceutil/trace.go:171","msg":"trace[42702416] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:422; }","duration":"457.441179ms","start":"2026-02-01T14:32:08.036661Z","end":"2026-02-01T14:32:08.494102Z","steps":["trace[42702416] 'agreement among raft nodes before linearized reading' (duration: 457.397088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:08.494126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:08.036649Z","time spent":"457.473411ms","remote":"127.0.0.1:60892","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-01T14:32:08.494233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.842948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-01T14:32:08.494257Z","caller":"traceutil/trace.go:171","msg":"trace[1999911119] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:422; }","duration":"281.89999ms","start":"2026-02-01T14:32:08.212349Z","end":"2026-02-01T14:32:08.494249Z","steps":["trace[1999911119] 'agreement among raft nodes before linearized reading' (duration: 281.867189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:08.494465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.023128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-02-01T14:32:08.494489Z","caller":"traceutil/trace.go:171","msg":"trace[1806394818] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:422; }","duration":"254.072129ms","start":"2026-02-01T14:32:08.240412Z","end":"2026-02-01T14:32:08.494484Z","steps":["trace[1806394818] 'agreement among raft nodes before linearized reading' (duration: 254.035258ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:32:22.726531Z","caller":"traceutil/trace.go:171","msg":"trace[375750042] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"167.425906ms","start":"2026-02-01T14:32:22.55906Z","end":"2026-02-01T14:32:22.726486Z","steps":["trace[375750042] 'process raft request' (duration: 167.209349ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:32:23.089018Z","caller":"traceutil/trace.go:171","msg":"trace[1204713461] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:531; }","duration":"521.979637ms","start":"2026-02-01T14:32:22.567015Z","end":"2026-02-01T14:32:23.088995Z","steps":["trace[1204713461] 'read index received' (duration: 159.339115ms)","trace[1204713461] 'applied index is now lower than readState.Index' (duration: 362.639602ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T14:32:23.089234Z","caller":"traceutil/trace.go:171","msg":"trace[501460608] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"527.777525ms","start":"2026-02-01T14:32:22.561444Z","end":"2026-02-01T14:32:23.089222Z","steps":["trace[501460608] 'process raft request' (duration: 462.060539ms)","trace[501460608] 'compare' (duration: 65.072655ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T14:32:23.089325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:22.56142Z","time spent":"527.841437ms","remote":"127.0.0.1:60892","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-01T14:32:23.08947Z","caller":"traceutil/trace.go:171","msg":"trace[114618183] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"449.709949ms","start":"2026-02-01T14:32:22.639751Z","end":"2026-02-01T14:32:23.089461Z","steps":["trace[114618183] 'process raft request' (duration: 449.193973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:23.08952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:22.639733Z","time spent":"449.75785ms","remote":"127.0.0.1:60892","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-01T14:32:23.089823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.801914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-01T14:32:23.089876Z","caller":"traceutil/trace.go:171","msg":"trace[601881501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:521; }","duration":"522.849925ms","start":"2026-02-01T14:32:22.566996Z","end":"2026-02-01T14:32:23.089846Z","steps":["trace[601881501] 'agreement among raft nodes before linearized reading' (duration: 522.715501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:23.089907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:22.56699Z","time spent":"522.909778ms","remote":"127.0.0.1:60892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T14:32:23.090327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.17787ms","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-01T14:32:23.090399Z","caller":"traceutil/trace.go:171","msg":"trace[13171417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:521; }","duration":"444.256392ms","start":"2026-02-01T14:32:22.646124Z","end":"2026-02-01T14:32:23.090381Z","steps":["trace[13171417] 'agreement among raft nodes before linearized reading' (duration: 444.052396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:23.090443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:22.646099Z","time spent":"444.336155ms","remote":"127.0.0.1:60892","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-01T14:32:23.090717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.98967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T14:32:23.090749Z","caller":"traceutil/trace.go:171","msg":"trace[736785191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:521; }","duration":"302.053802ms","start":"2026-02-01T14:32:22.788685Z","end":"2026-02-01T14:32:23.090739Z","steps":["trace[736785191] 'agreement among raft nodes before linearized reading' (duration: 302.00894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:23.090775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:22.788667Z","time spent":"302.101604ms","remote":"127.0.0.1:60622","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-01T14:32:25.637693Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043359987380712812,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-01T14:32:25.895391Z","caller":"traceutil/trace.go:171","msg":"trace[1718186330] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"799.163885ms","start":"2026-02-01T14:32:25.096205Z","end":"2026-02-01T14:32:25.895369Z","steps":["trace[1718186330] 'process raft request' (duration: 799.023031ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:25.895572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:25.096197Z","time spent":"799.263618ms","remote":"127.0.0.1:60892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T14:32:25.89603Z","caller":"traceutil/trace.go:171","msg":"trace[1602621368] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:540; }","duration":"758.548471ms","start":"2026-02-01T14:32:25.137468Z","end":"2026-02-01T14:32:25.896016Z","steps":["trace[1602621368] 'read index received' (duration: 758.543641ms)","trace[1602621368] 'applied index is now lower than readState.Index' (duration: 3.91µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T14:32:25.89619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.720707ms","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-01T14:32:25.896225Z","caller":"traceutil/trace.go:171","msg":"trace[1599586502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:526; }","duration":"758.773758ms","start":"2026-02-01T14:32:25.137443Z","end":"2026-02-01T14:32:25.896217Z","steps":["trace[1599586502] 'agreement among raft nodes before linearized reading' (duration: 758.664624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:25.896261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:25.137434Z","time spent":"758.81881ms","remote":"127.0.0.1:60892","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-01T14:32:25.896427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.531989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-01T14:32:25.896462Z","caller":"traceutil/trace.go:171","msg":"trace[1072155664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:526; }","duration":"753.597161ms","start":"2026-02-01T14:32:25.142857Z","end":"2026-02-01T14:32:25.896454Z","steps":["trace[1072155664] 'agreement among raft nodes before linearized reading' (duration: 753.511449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:25.896487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:25.142831Z","time spent":"753.651232ms","remote":"127.0.0.1:60892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T14:32:25.896624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.110568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T14:32:25.896659Z","caller":"traceutil/trace.go:171","msg":"trace[461012264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:526; }","duration":"108.18058ms","start":"2026-02-01T14:32:25.788471Z","end":"2026-02-01T14:32:25.896651Z","steps":["trace[461012264] 'agreement among raft nodes before linearized reading' (duration: 108.134268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:26.612194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.894344ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T14:32:26.61229Z","caller":"traceutil/trace.go:171","msg":"trace[1446478710] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"709.321528ms","start":"2026-02-01T14:32:25.90295Z","end":"2026-02-01T14:32:26.612272Z","steps":["trace[1446478710] 'process raft request' (duration: 119.272268ms)","trace[1446478710] 'compare' (duration: 589.590325ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T14:32:26.612368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:25.902931Z","time spent":"709.39007ms","remote":"127.0.0.1:60892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T14:32:26.70415Z","caller":"traceutil/trace.go:171","msg":"trace[959116537] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"705.36244ms","start":"2026-02-01T14:32:25.998766Z","end":"2026-02-01T14:32:26.704129Z","steps":["trace[959116537] 'process raft request' (duration: 705.318728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:26.704294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:25.998752Z","time spent":"705.464924ms","remote":"127.0.0.1:60892","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-01T14:32:26.704599Z","caller":"traceutil/trace.go:171","msg":"trace[214022095] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"799.385462ms","start":"2026-02-01T14:32:25.905207Z","end":"2026-02-01T14:32:26.704592Z","steps":["trace[214022095] 'process raft request' (duration: 798.778692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:26.704636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:25.9052Z","time spent":"799.415763ms","remote":"127.0.0.1:60892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T14:32:31.126838Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043359987380712842,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T14:32:31.627873Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043359987380712842,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T14:32:32.128709Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043359987380712842,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T14:32:32.327968Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.371459796s","expected-duration":"1s"} {"level":"info","ts":"2026-02-01T14:32:32.328291Z","caller":"traceutil/trace.go:171","msg":"trace[1241792186] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:553; }","duration":"1.702212168s","start":"2026-02-01T14:32:30.626057Z","end":"2026-02-01T14:32:32.328269Z","steps":["trace[1241792186] 'read index received' (duration: 1.702077754s)","trace[1241792186] 'applied index is now lower than readState.Index' (duration: 133.464µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T14:32:32.32837Z","caller":"traceutil/trace.go:171","msg":"trace[545497732] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.810347745s","start":"2026-02-01T14:32:30.518014Z","end":"2026-02-01T14:32:32.328361Z","steps":["trace[545497732] 'process raft request' (duration: 1.81012868s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:32.328466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:30.517995Z","time spent":"1.810399896s","remote":"127.0.0.1:60802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T14:32:32.328676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.702623169s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-01T14:32:32.328708Z","caller":"traceutil/trace.go:171","msg":"trace[466116685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:539; }","duration":"1.70270832s","start":"2026-02-01T14:32:30.625987Z","end":"2026-02-01T14:32:32.328696Z","steps":["trace[466116685] 'agreement among raft nodes before linearized reading' (duration: 1.702582217s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:32.328735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:30.625963Z","time spent":"1.702766232s","remote":"127.0.0.1:60892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T14:32:32.329036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.540556345s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T14:32:32.329066Z","caller":"traceutil/trace.go:171","msg":"trace[1724847681] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"1.540624677s","start":"2026-02-01T14:32:30.788431Z","end":"2026-02-01T14:32:32.329056Z","steps":["trace[1724847681] 'agreement among raft nodes before linearized reading' (duration: 1.540581386s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:32.32909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:30.788414Z","time spent":"1.540671279s","remote":"127.0.0.1:60622","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-01T14:32:32.329091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.609641804s","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-01T14:32:32.329224Z","caller":"traceutil/trace.go:171","msg":"trace[1473077690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:539; }","duration":"1.609828299s","start":"2026-02-01T14:32:30.719378Z","end":"2026-02-01T14:32:32.329206Z","steps":["trace[1473077690] 'agreement among raft nodes before linearized reading' (duration: 1.609555952s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:32.329286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:30.719342Z","time spent":"1.609934772s","remote":"127.0.0.1:60892","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-01T14:32:32.329463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.652961ms","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-01T14:32:32.329505Z","caller":"traceutil/trace.go:171","msg":"trace[2032775050] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:539; }","duration":"409.751024ms","start":"2026-02-01T14:32:31.919743Z","end":"2026-02-01T14:32:32.329494Z","steps":["trace[2032775050] 'agreement among raft nodes before linearized reading' (duration: 409.610791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:32.329534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:31.919723Z","time spent":"409.804215ms","remote":"127.0.0.1:60892","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-01T14:32:32.329583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.583393006s","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-01T14:32:32.329639Z","caller":"traceutil/trace.go:171","msg":"trace[834848946] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:539; }","duration":"1.583475808s","start":"2026-02-01T14:32:30.746154Z","end":"2026-02-01T14:32:32.329629Z","steps":["trace[834848946] 'agreement among raft nodes before linearized reading' (duration: 1.583340534s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:32.329688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:30.746105Z","time spent":"1.58355634s","remote":"127.0.0.1:60892","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-01T14:32:32.653088Z","caller":"traceutil/trace.go:171","msg":"trace[2057660004] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"314.620992ms","start":"2026-02-01T14:32:32.338445Z","end":"2026-02-01T14:32:32.653066Z","steps":["trace[2057660004] 'process raft request' (duration: 314.460778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:32.65347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:32.338427Z","time spent":"314.747706ms","remote":"127.0.0.1:60892","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-01T14:32:32.66188Z","caller":"traceutil/trace.go:171","msg":"trace[1186426100] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:554; }","duration":"319.314968ms","start":"2026-02-01T14:32:32.342528Z","end":"2026-02-01T14:32:32.661843Z","steps":["trace[1186426100] 'read index received' (duration: 309.947918ms)","trace[1186426100] 'applied index is now lower than readState.Index' (duration: 9.36631ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T14:32:32.66207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.527323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T14:32:32.662121Z","caller":"traceutil/trace.go:171","msg":"trace[800205463] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:543; }","duration":"319.606215ms","start":"2026-02-01T14:32:32.342507Z","end":"2026-02-01T14:32:32.662114Z","steps":["trace[800205463] 'agreement among raft nodes before linearized reading' (duration: 319.465121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:32.662166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:32.342499Z","time spent":"319.657326ms","remote":"127.0.0.1:60622","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-01T14:32:32.663174Z","caller":"traceutil/trace.go:171","msg":"trace[1118662777] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"324.691991ms","start":"2026-02-01T14:32:32.338463Z","end":"2026-02-01T14:32:32.663155Z","steps":["trace[1118662777] 'process raft request' (duration: 323.262903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:32.663483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:32.338446Z","time spent":"324.990478ms","remote":"127.0.0.1:60892","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-01T14:32:32.663695Z","caller":"traceutil/trace.go:171","msg":"trace[1059643529] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"322.482712ms","start":"2026-02-01T14:32:32.341204Z","end":"2026-02-01T14:32:32.663687Z","steps":["trace[1059643529] 'process raft request' (duration: 320.594982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:32.664307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:32.341194Z","time spent":"323.084488ms","remote":"127.0.0.1:60892","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-01T14:32:32.664484Z","caller":"traceutil/trace.go:171","msg":"trace[719999374] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"322.192654ms","start":"2026-02-01T14:32:32.342284Z","end":"2026-02-01T14:32:32.664476Z","steps":["trace[719999374] 'process raft request' (duration: 319.534673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:32.664554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:32.342276Z","time spent":"322.255005ms","remote":"127.0.0.1:60892","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-01T14:32:34.858774Z","caller":"traceutil/trace.go:171","msg":"trace[749168349] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:571; }","duration":"182.166048ms","start":"2026-02-01T14:32:34.676562Z","end":"2026-02-01T14:32:34.858728Z","steps":["trace[749168349] 'read index received' (duration: 181.913551ms)","trace[749168349] 'applied index is now lower than readState.Index' (duration: 251.457µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T14:32:34.858888Z","caller":"traceutil/trace.go:171","msg":"trace[256801363] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"182.497287ms","start":"2026-02-01T14:32:34.67638Z","end":"2026-02-01T14:32:34.858878Z","steps":["trace[256801363] 'process raft request' (duration: 182.166658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:34.859079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.498957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-01T14:32:34.859145Z","caller":"traceutil/trace.go:171","msg":"trace[324799618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:556; }","duration":"182.59456ms","start":"2026-02-01T14:32:34.676535Z","end":"2026-02-01T14:32:34.859129Z","steps":["trace[324799618] 'agreement among raft nodes before linearized reading' (duration: 182.426165ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:32:34.968401Z","caller":"traceutil/trace.go:171","msg":"trace[461338021] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"104.227293ms","start":"2026-02-01T14:32:34.864159Z","end":"2026-02-01T14:32:34.968387Z","steps":["trace[461338021] 'process raft request' (duration: 104.201202ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:32:34.968679Z","caller":"traceutil/trace.go:171","msg":"trace[1973848135] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:572; }","duration":"109.816791ms","start":"2026-02-01T14:32:34.858853Z","end":"2026-02-01T14:32:34.96867Z","steps":["trace[1973848135] 'read index received' (duration: 106.232726ms)","trace[1973848135] 'applied index is now lower than readState.Index' (duration: 3.582825ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T14:32:34.968712Z","caller":"traceutil/trace.go:171","msg":"trace[1312568198] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"286.840052ms","start":"2026-02-01T14:32:34.681844Z","end":"2026-02-01T14:32:34.968684Z","steps":["trace[1312568198] 'process raft request' (duration: 286.482682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:34.96876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.229415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-01T14:32:34.968782Z","caller":"traceutil/trace.go:171","msg":"trace[209295812] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:559; }","duration":"230.292677ms","start":"2026-02-01T14:32:34.738483Z","end":"2026-02-01T14:32:34.968776Z","steps":["trace[209295812] 'agreement among raft nodes before linearized reading' (duration: 230.221145ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:32:34.969017Z","caller":"traceutil/trace.go:171","msg":"trace[1627107017] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"289.611786ms","start":"2026-02-01T14:32:34.679397Z","end":"2026-02-01T14:32:34.969009Z","steps":["trace[1627107017] 'process raft request' (duration: 285.679942ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:32:37.910256Z","caller":"traceutil/trace.go:171","msg":"trace[2041290917] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"108.634561ms","start":"2026-02-01T14:32:37.801599Z","end":"2026-02-01T14:32:37.910233Z","steps":["trace[2041290917] 'read index received' (duration: 106.791572ms)","trace[2041290917] 'applied index is now lower than readState.Index' (duration: 1.841648ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T14:32:37.910513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.896738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-01T14:32:37.910555Z","caller":"traceutil/trace.go:171","msg":"trace[753008984] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:610; }","duration":"108.998261ms","start":"2026-02-01T14:32:37.801547Z","end":"2026-02-01T14:32:37.910545Z","steps":["trace[753008984] 'agreement among raft nodes before linearized reading' (duration: 108.774965ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:32:37.910852Z","caller":"traceutil/trace.go:171","msg":"trace[924562094] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"181.4914ms","start":"2026-02-01T14:32:37.729349Z","end":"2026-02-01T14:32:37.91084Z","steps":["trace[924562094] 'process raft request' (duration: 179.098336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:45.522362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.884143ms","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-01T14:32:45.522444Z","caller":"traceutil/trace.go:171","msg":"trace[186383882] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:669; }","duration":"403.002596ms","start":"2026-02-01T14:32:45.119422Z","end":"2026-02-01T14:32:45.522425Z","steps":["trace[186383882] 'range keys from in-memory index tree' (duration: 402.716359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:45.522486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:45.119382Z","time spent":"403.094229ms","remote":"127.0.0.1:60892","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-01T14:32:45.522734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.172255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-01T14:32:45.522767Z","caller":"traceutil/trace.go:171","msg":"trace[1531427479] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:669; }","duration":"502.233957ms","start":"2026-02-01T14:32:45.020522Z","end":"2026-02-01T14:32:45.522756Z","steps":["trace[1531427479] 'range keys from in-memory index tree' (duration: 502.047912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:45.522794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:45.020505Z","time spent":"502.283568ms","remote":"127.0.0.1:60892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T14:32:45.522962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.608945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-01T14:32:45.522991Z","caller":"traceutil/trace.go:171","msg":"trace[1337647914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:669; }","duration":"476.669507ms","start":"2026-02-01T14:32:45.046311Z","end":"2026-02-01T14:32:45.522981Z","steps":["trace[1337647914] 'range keys from in-memory index tree' (duration: 476.520963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:45.523021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:45.046293Z","time spent":"476.721498ms","remote":"127.0.0.1:60892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T14:32:45.523797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.501295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-01T14:32:45.523834Z","caller":"traceutil/trace.go:171","msg":"trace[1415940522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:669; }","duration":"472.561687ms","start":"2026-02-01T14:32:45.051262Z","end":"2026-02-01T14:32:45.523823Z","steps":["trace[1415940522] 'range keys from in-memory index tree' (duration: 472.413123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:45.523867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:45.051248Z","time spent":"472.612288ms","remote":"127.0.0.1:60892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T14:32:45.75292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.094435ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T14:32:45.753273Z","caller":"traceutil/trace.go:171","msg":"trace[810659933] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"143.256151ms","start":"2026-02-01T14:32:45.609997Z","end":"2026-02-01T14:32:45.753253Z","steps":["trace[810659933] 'process raft request' (duration: 143.20966ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:32:45.753698Z","caller":"traceutil/trace.go:171","msg":"trace[2007070448] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"223.200769ms","start":"2026-02-01T14:32:45.530483Z","end":"2026-02-01T14:32:45.753683Z","steps":["trace[2007070448] 'process raft request' (duration: 93.264423ms)","trace[2007070448] 'compare' (duration: 128.991721ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T14:32:45.753826Z","caller":"traceutil/trace.go:171","msg":"trace[1033043836] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"223.213529ms","start":"2026-02-01T14:32:45.530604Z","end":"2026-02-01T14:32:45.753817Z","steps":["trace[1033043836] 'process raft request' (duration: 222.439258ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:32:45.753937Z","caller":"traceutil/trace.go:171","msg":"trace[988130177] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"223.276271ms","start":"2026-02-01T14:32:45.530652Z","end":"2026-02-01T14:32:45.753929Z","steps":["trace[988130177] 'process raft request' (duration: 222.48686ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:32:45.754056Z","caller":"traceutil/trace.go:171","msg":"trace[2110460893] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"217.440985ms","start":"2026-02-01T14:32:45.536607Z","end":"2026-02-01T14:32:45.754048Z","steps":["trace[2110460893] 'process raft request' (duration: 216.562362ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:32:45.754167Z","caller":"traceutil/trace.go:171","msg":"trace[1119325030] linearizableReadLoop","detail":"{readStateIndex:693; appliedIndex:689; }","duration":"183.303427ms","start":"2026-02-01T14:32:45.570853Z","end":"2026-02-01T14:32:45.754157Z","steps":["trace[1119325030] 'read index received' (duration: 52.962519ms)","trace[1119325030] 'applied index is now lower than readState.Index' (duration: 130.339998ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T14:32:45.754277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.41815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vtrms\" ","response":"range_response_count:1 size:18246"} {"level":"info","ts":"2026-02-01T14:32:45.754309Z","caller":"traceutil/trace.go:171","msg":"trace[97155820] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vtrms; range_end:; response_count:1; response_revision:675; }","duration":"183.479802ms","start":"2026-02-01T14:32:45.570821Z","end":"2026-02-01T14:32:45.754301Z","steps":["trace[97155820] 'agreement among raft nodes before linearized reading' (duration: 183.373949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:46.528483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.472789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T14:32:46.528593Z","caller":"traceutil/trace.go:171","msg":"trace[1469339298] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"762.306289ms","start":"2026-02-01T14:32:45.766267Z","end":"2026-02-01T14:32:46.528574Z","steps":["trace[1469339298] 'process raft request' (duration: 258.676355ms)","trace[1469339298] 'compare' (duration: 503.044858ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T14:32:46.528655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:45.766257Z","time spent":"762.36871ms","remote":"127.0.0.1:60802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18043,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T14:32:46.529339Z","caller":"traceutil/trace.go:171","msg":"trace[432854412] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"748.899891ms","start":"2026-02-01T14:32:45.780342Z","end":"2026-02-01T14:32:46.529242Z","steps":["trace[432854412] 'process raft request' (duration: 748.815039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:46.529419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:45.780325Z","time spent":"749.060866ms","remote":"127.0.0.1:60700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-01T14:32:47.099624Z","caller":"traceutil/trace.go:171","msg":"trace[395334565] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"559.047125ms","start":"2026-02-01T14:32:46.540556Z","end":"2026-02-01T14:32:47.099603Z","steps":["trace[395334565] 'process raft request' (duration: 558.512471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:47.100723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:46.540542Z","time spent":"560.089584ms","remote":"127.0.0.1:32886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14311,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T14:32:47.107067Z","caller":"traceutil/trace.go:171","msg":"trace[300401756] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:696; }","duration":"316.697057ms","start":"2026-02-01T14:32:46.790338Z","end":"2026-02-01T14:32:47.107035Z","steps":["trace[300401756] 'read index received' (duration: 308.757176ms)","trace[300401756] 'applied index is now lower than readState.Index' (duration: 7.939261ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T14:32:47.10722Z","caller":"traceutil/trace.go:171","msg":"trace[1816649122] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"563.957147ms","start":"2026-02-01T14:32:46.543255Z","end":"2026-02-01T14:32:47.107212Z","steps":["trace[1816649122] 'process raft request' (duration: 563.68474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:47.109713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.645413ms","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-01T14:32:47.109737Z","caller":"traceutil/trace.go:171","msg":"trace[1102082254] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:679; }","duration":"193.709565ms","start":"2026-02-01T14:32:46.916022Z","end":"2026-02-01T14:32:47.109731Z","steps":["trace[1102082254] 'agreement among raft nodes before linearized reading' (duration: 193.615982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:47.109839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:46.543247Z","time spent":"566.453703ms","remote":"127.0.0.1:60796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4085,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T14:32:47.107281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.942193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T14:32:47.110041Z","caller":"traceutil/trace.go:171","msg":"trace[2066306308] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:679; }","duration":"319.733198ms","start":"2026-02-01T14:32:46.790302Z","end":"2026-02-01T14:32:47.110035Z","steps":["trace[2066306308] 'agreement among raft nodes before linearized reading' (duration: 316.953814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:47.110062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:46.790261Z","time spent":"319.79582ms","remote":"127.0.0.1:60622","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-01T14:32:55.291953Z","caller":"traceutil/trace.go:171","msg":"trace[1754301420] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"137.464358ms","start":"2026-02-01T14:32:55.154474Z","end":"2026-02-01T14:32:55.291939Z","steps":["trace[1754301420] 'process raft request' (duration: 137.365975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:56.463265Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043359987380713233,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T14:32:56.963788Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043359987380713233,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T14:32:56.988339Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.072839609s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-01T14:32:57.350021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.466059ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T14:32:57.350092Z","caller":"traceutil/trace.go:171","msg":"trace[282366722] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"1.474653771s","start":"2026-02-01T14:32:55.875425Z","end":"2026-02-01T14:32:57.350079Z","steps":["trace[282366722] 'process raft request' (duration: 1.113080589s)","trace[282366722] 'compare' (duration: 361.190342ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T14:32:57.350129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:55.87542Z","time spent":"1.474692223s","remote":"127.0.0.1:60892","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-01T14:32:57.601755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.357266ms","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-01T14:32:57.601844Z","caller":"traceutil/trace.go:171","msg":"trace[1409640878] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:738; }","duration":"122.453569ms","start":"2026-02-01T14:32:57.479376Z","end":"2026-02-01T14:32:57.601829Z","steps":["trace[1409640878] 'range keys from in-memory index tree' (duration: 122.346366ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:32:57.792286Z","caller":"traceutil/trace.go:171","msg":"trace[532910579] linearizableReadLoop","detail":"{readStateIndex:760; appliedIndex:758; }","duration":"1.829730701s","start":"2026-02-01T14:32:55.96254Z","end":"2026-02-01T14:32:57.792271Z","steps":["trace[532910579] 'read index received' (duration: 1.02591391s)","trace[532910579] 'applied index is now lower than readState.Index' (duration: 803.816271ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T14:32:57.792505Z","caller":"traceutil/trace.go:171","msg":"trace[1945472068] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"994.245789ms","start":"2026-02-01T14:32:56.798252Z","end":"2026-02-01T14:32:57.792498Z","steps":["trace[1945472068] 'process raft request' (duration: 993.91274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:57.792577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:56.798239Z","time spent":"994.29277ms","remote":"127.0.0.1:60796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4725,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T14:32:57.792702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.830158952s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T14:32:57.792727Z","caller":"traceutil/trace.go:171","msg":"trace[840883925] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:739; }","duration":"1.830206904s","start":"2026-02-01T14:32:55.962513Z","end":"2026-02-01T14:32:57.79272Z","steps":["trace[840883925] 'agreement among raft nodes before linearized reading' (duration: 1.830169193s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:57.792742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:55.962502Z","time spent":"1.830236894s","remote":"127.0.0.1:32910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-02-01T14:32:58.159938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.469962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T14:32:58.160006Z","caller":"traceutil/trace.go:171","msg":"trace[706405589] linearizableReadLoop","detail":"{readStateIndex:761; appliedIndex:760; }","duration":"367.668885ms","start":"2026-02-01T14:32:57.792327Z","end":"2026-02-01T14:32:58.159996Z","steps":["trace[706405589] 'read index received' (duration: 60.046198ms)","trace[706405589] 'applied index is now lower than readState.Index' (duration: 307.621976ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T14:32:58.160059Z","caller":"traceutil/trace.go:171","msg":"trace[721466954] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"694.511512ms","start":"2026-02-01T14:32:57.465541Z","end":"2026-02-01T14:32:58.160052Z","steps":["trace[721466954] 'process raft request' (duration: 386.870135ms)","trace[721466954] 'compare' (duration: 307.3802ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T14:32:58.160096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:57.465524Z","time spent":"694.550254ms","remote":"127.0.0.1:60892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T14:32:58.160152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.369050372s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T14:32:58.160202Z","caller":"traceutil/trace.go:171","msg":"trace[329066528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:740; }","duration":"1.369163526s","start":"2026-02-01T14:32:56.791024Z","end":"2026-02-01T14:32:58.160188Z","steps":["trace[329066528] 'agreement among raft nodes before linearized reading' (duration: 1.369083333s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:58.160228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:56.791005Z","time spent":"1.369218808s","remote":"127.0.0.1:60622","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-01T14:32:58.160348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.307525ms","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-01T14:32:58.160366Z","caller":"traceutil/trace.go:171","msg":"trace[382270834] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:740; }","duration":"299.385147ms","start":"2026-02-01T14:32:57.860975Z","end":"2026-02-01T14:32:58.160361Z","steps":["trace[382270834] 'agreement among raft nodes before linearized reading' (duration: 299.314725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:58.16041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.332901ms","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-01T14:32:58.160432Z","caller":"traceutil/trace.go:171","msg":"trace[202005339] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:740; }","duration":"864.389953ms","start":"2026-02-01T14:32:57.296036Z","end":"2026-02-01T14:32:58.160426Z","steps":["trace[202005339] 'agreement among raft nodes before linearized reading' (duration: 864.307481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:58.160448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:57.296018Z","time spent":"864.426303ms","remote":"127.0.0.1:60892","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-01T14:32:58.160484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.338529ms","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-01T14:32:58.1605Z","caller":"traceutil/trace.go:171","msg":"trace[1296313144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:740; }","duration":"242.36847ms","start":"2026-02-01T14:32:57.918127Z","end":"2026-02-01T14:32:58.160496Z","steps":["trace[1296313144] 'agreement among raft nodes before linearized reading' (duration: 242.327539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:58.160585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.421116ms","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-01T14:32:58.160596Z","caller":"traceutil/trace.go:171","msg":"trace[2123030119] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:740; }","duration":"288.449376ms","start":"2026-02-01T14:32:57.872143Z","end":"2026-02-01T14:32:58.160593Z","steps":["trace[2123030119] 'agreement among raft nodes before linearized reading' (duration: 288.417086ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:58.736121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.794639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T14:32:58.736192Z","caller":"traceutil/trace.go:171","msg":"trace[1074084176] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"570.169994ms","start":"2026-02-01T14:32:58.166009Z","end":"2026-02-01T14:32:58.736179Z","steps":["trace[1074084176] 'process raft request' (duration: 191.257391ms)","trace[1074084176] 'compare' (duration: 378.614875ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T14:32:58.736229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:58.165998Z","time spent":"570.213445ms","remote":"127.0.0.1:60892","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-01T14:32:58.736703Z","caller":"traceutil/trace.go:171","msg":"trace[1221143195] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:761; }","duration":"566.413333ms","start":"2026-02-01T14:32:58.170282Z","end":"2026-02-01T14:32:58.736695Z","steps":["trace[1221143195] 'read index received' (duration: 186.995127ms)","trace[1221143195] 'applied index is now lower than readState.Index' (duration: 379.417696ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T14:32:58.736752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.466005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T14:32:58.736769Z","caller":"traceutil/trace.go:171","msg":"trace[581518940] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:744; }","duration":"566.496725ms","start":"2026-02-01T14:32:58.170266Z","end":"2026-02-01T14:32:58.736763Z","steps":["trace[581518940] 'agreement among raft nodes before linearized reading' (duration: 566.456184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:58.736787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:58.17026Z","time spent":"566.522837ms","remote":"127.0.0.1:60622","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-01T14:32:58.736952Z","caller":"traceutil/trace.go:171","msg":"trace[299225334] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"570.585255ms","start":"2026-02-01T14:32:58.166352Z","end":"2026-02-01T14:32:58.736937Z","steps":["trace[299225334] 'process raft request' (duration: 570.244816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:58.736992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:58.166335Z","time spent":"570.634616ms","remote":"127.0.0.1:60892","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-01T14:32:58.737067Z","caller":"traceutil/trace.go:171","msg":"trace[1147118857] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"570.649646ms","start":"2026-02-01T14:32:58.166411Z","end":"2026-02-01T14:32:58.737061Z","steps":["trace[1147118857] 'process raft request' (duration: 570.240855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:58.737095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:58.166406Z","time spent":"570.673157ms","remote":"127.0.0.1:60892","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-01T14:32:58.737174Z","caller":"traceutil/trace.go:171","msg":"trace[222593434] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"569.368602ms","start":"2026-02-01T14:32:58.1678Z","end":"2026-02-01T14:32:58.737168Z","steps":["trace[222593434] 'process raft request' (duration: 568.874089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:58.737208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:58.167791Z","time spent":"569.397483ms","remote":"127.0.0.1:60892","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-01T14:32:59.79439Z","caller":"traceutil/trace.go:171","msg":"trace[2096646571] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"436.048254ms","start":"2026-02-01T14:32:59.358327Z","end":"2026-02-01T14:32:59.794375Z","steps":["trace[2096646571] 'process raft request' (duration: 435.779987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:32:59.794482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:32:59.358307Z","time spent":"436.127386ms","remote":"127.0.0.1:60892","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-01T14:33:00.771895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.16716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/coredns-5dd5756b68-gr4vn.189025f0e2de2d6f\" ","response":"range_response_count:1 size:832"} {"level":"info","ts":"2026-02-01T14:33:00.771948Z","caller":"traceutil/trace.go:171","msg":"trace[1909996465] range","detail":"{range_begin:/registry/events/kube-system/coredns-5dd5756b68-gr4vn.189025f0e2de2d6f; range_end:; response_count:1; response_revision:745; }","duration":"686.266263ms","start":"2026-02-01T14:33:00.08567Z","end":"2026-02-01T14:33:00.771936Z","steps":["trace[1909996465] 'range keys from in-memory index tree' (duration: 686.047077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:00.771989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:00.085657Z","time spent":"686.326474ms","remote":"127.0.0.1:60700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":855,"request content":"key:\"/registry/events/kube-system/coredns-5dd5756b68-gr4vn.189025f0e2de2d6f\" "} {"level":"warn","ts":"2026-02-01T14:33:00.771977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.417008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T14:33:00.77203Z","caller":"traceutil/trace.go:171","msg":"trace[65774660] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:745; }","duration":"302.48711ms","start":"2026-02-01T14:33:00.469529Z","end":"2026-02-01T14:33:00.772016Z","steps":["trace[65774660] 'count revisions from in-memory index tree' (duration: 302.389257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:00.772055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:00.469527Z","time spent":"302.521191ms","remote":"127.0.0.1:57640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-02-01T14:33:00.772155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.399975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-01T14:33:00.772177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.594633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T14:33:00.77218Z","caller":"traceutil/trace.go:171","msg":"trace[655865148] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:745; }","duration":"663.435136ms","start":"2026-02-01T14:33:00.108737Z","end":"2026-02-01T14:33:00.772172Z","steps":["trace[655865148] 'range keys from in-memory index tree' (duration: 663.371274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:00.772218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.131341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-01T14:33:00.772221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:00.108734Z","time spent":"663.481207ms","remote":"127.0.0.1:57628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 "} {"level":"info","ts":"2026-02-01T14:33:00.772238Z","caller":"traceutil/trace.go:171","msg":"trace[1407073142] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:745; }","duration":"289.151092ms","start":"2026-02-01T14:33:00.483082Z","end":"2026-02-01T14:33:00.772233Z","steps":["trace[1407073142] 'range keys from in-memory index tree' (duration: 289.09328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:00.772335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.528954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T14:33:00.772352Z","caller":"traceutil/trace.go:171","msg":"trace[922060478] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:745; }","duration":"288.546655ms","start":"2026-02-01T14:33:00.4838Z","end":"2026-02-01T14:33:00.772347Z","steps":["trace[922060478] 'count revisions from in-memory index tree' (duration: 288.514154ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:00.772346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.646052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T14:33:00.772387Z","caller":"traceutil/trace.go:171","msg":"trace[1412230337] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:745; }","duration":"663.709894ms","start":"2026-02-01T14:33:00.10867Z","end":"2026-02-01T14:33:00.77238Z","steps":["trace[1412230337] 'range keys from in-memory index tree' (duration: 663.531588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:00.772405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:00.108656Z","time spent":"663.744265ms","remote":"127.0.0.1:57628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 "} {"level":"warn","ts":"2026-02-01T14:33:00.772428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.659808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T14:33:00.772446Z","caller":"traceutil/trace.go:171","msg":"trace[1175370407] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:745; }","duration":"288.688948ms","start":"2026-02-01T14:33:00.483751Z","end":"2026-02-01T14:33:00.77244Z","steps":["trace[1175370407] 'range keys from in-memory index tree' (duration: 288.600027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:00.772525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.624851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-01T14:33:00.772531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.717044ms","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-01T14:33:00.772541Z","caller":"traceutil/trace.go:171","msg":"trace[819498120] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:745; }","duration":"262.649431ms","start":"2026-02-01T14:33:00.509886Z","end":"2026-02-01T14:33:00.772536Z","steps":["trace[819498120] 'range keys from in-memory index tree' (duration: 262.60221ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:33:00.772552Z","caller":"traceutil/trace.go:171","msg":"trace[864273865] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:745; }","duration":"663.760935ms","start":"2026-02-01T14:33:00.108785Z","end":"2026-02-01T14:33:00.772546Z","steps":["trace[864273865] 'count revisions from in-memory index tree' (duration: 663.690373ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:00.772571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:00.108782Z","time spent":"663.783566ms","remote":"127.0.0.1:57628","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-01T14:33:00.772642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.770835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T14:33:00.772666Z","caller":"traceutil/trace.go:171","msg":"trace[1720212663] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:745; }","duration":"262.809346ms","start":"2026-02-01T14:33:00.509851Z","end":"2026-02-01T14:33:00.77266Z","steps":["trace[1720212663] 'range keys from in-memory index tree' (duration: 262.674662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:00.772753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.811926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T14:33:00.772771Z","caller":"traceutil/trace.go:171","msg":"trace[423253223] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:745; }","duration":"262.836147ms","start":"2026-02-01T14:33:00.509929Z","end":"2026-02-01T14:33:00.772765Z","steps":["trace[423253223] 'count revisions from in-memory index tree' (duration: 262.792805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:00.773062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.981072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T14:33:00.773082Z","caller":"traceutil/trace.go:171","msg":"trace[405115810] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:745; }","duration":"136.030064ms","start":"2026-02-01T14:33:00.637046Z","end":"2026-02-01T14:33:00.773076Z","steps":["trace[405115810] 'count revisions from in-memory index tree' (duration: 135.934881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:00.772159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.658285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T14:33:00.774225Z","caller":"traceutil/trace.go:171","msg":"trace[1663840568] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:745; }","duration":"304.744774ms","start":"2026-02-01T14:33:00.469471Z","end":"2026-02-01T14:33:00.774216Z","steps":["trace[1663840568] 'range keys from in-memory index tree' (duration: 302.556572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:00.774255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:00.469458Z","time spent":"304.787116ms","remote":"127.0.0.1:57640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"info","ts":"2026-02-01T14:33:00.772197Z","caller":"traceutil/trace.go:171","msg":"trace[950877563] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:745; }","duration":"302.618483ms","start":"2026-02-01T14:33:00.469572Z","end":"2026-02-01T14:33:00.772191Z","steps":["trace[950877563] 'range keys from in-memory index tree' (duration: 302.575713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:00.774399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:00.469569Z","time spent":"304.822706ms","remote":"127.0.0.1:57640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"info","ts":"2026-02-01T14:33:01.035184Z","caller":"traceutil/trace.go:171","msg":"trace[444747307] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"254.579882ms","start":"2026-02-01T14:33:00.780589Z","end":"2026-02-01T14:33:01.035169Z","steps":["trace[444747307] 'process raft request' (duration: 254.472469ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:33:01.036539Z","caller":"traceutil/trace.go:171","msg":"trace[1908242866] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"253.071769ms","start":"2026-02-01T14:33:00.783459Z","end":"2026-02-01T14:33:01.036531Z","steps":["trace[1908242866] 'process raft request' (duration: 252.520914ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:33:01.036629Z","caller":"traceutil/trace.go:171","msg":"trace[1965947688] linearizableReadLoop","detail":"{readStateIndex:772; appliedIndex:768; }","duration":"199.622736ms","start":"2026-02-01T14:33:00.836984Z","end":"2026-02-01T14:33:01.036607Z","steps":["trace[1965947688] 'read index received' (duration: 198.478613ms)","trace[1965947688] 'applied index is now lower than readState.Index' (duration: 1.143403ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T14:33:01.036711Z","caller":"traceutil/trace.go:171","msg":"trace[161367411] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"253.188363ms","start":"2026-02-01T14:33:00.783517Z","end":"2026-02-01T14:33:01.036706Z","steps":["trace[161367411] 'process raft request' (duration: 252.589536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:01.036743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.76697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T14:33:01.036762Z","caller":"traceutil/trace.go:171","msg":"trace[1784232096] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:749; }","duration":"199.822761ms","start":"2026-02-01T14:33:00.836933Z","end":"2026-02-01T14:33:01.036755Z","steps":["trace[1784232096] 'agreement among raft nodes before linearized reading' (duration: 199.733598ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:33:01.036844Z","caller":"traceutil/trace.go:171","msg":"trace[2102156285] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"251.020581ms","start":"2026-02-01T14:33:00.785818Z","end":"2026-02-01T14:33:01.036838Z","steps":["trace[2102156285] 'process raft request' (duration: 250.398133ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:33:11.360286Z","caller":"traceutil/trace.go:171","msg":"trace[1208851685] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"241.985316ms","start":"2026-02-01T14:33:11.118279Z","end":"2026-02-01T14:33:11.360265Z","steps":["trace[1208851685] 'process raft request' (duration: 241.866593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:12.142574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.498277ms","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-01T14:33:12.142648Z","caller":"traceutil/trace.go:171","msg":"trace[1321822122] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:783; }","duration":"658.59951ms","start":"2026-02-01T14:33:11.484031Z","end":"2026-02-01T14:33:12.142631Z","steps":["trace[1321822122] 'count revisions from in-memory index tree' (duration: 658.419574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:12.142687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:11.48397Z","time spent":"658.707483ms","remote":"127.0.0.1:32882","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-01T14:33:12.143476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.816249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T14:33:12.143508Z","caller":"traceutil/trace.go:171","msg":"trace[1912603303] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:783; }","duration":"386.885691ms","start":"2026-02-01T14:33:11.756613Z","end":"2026-02-01T14:33:12.143499Z","steps":["trace[1912603303] 'count revisions from in-memory index tree' (duration: 386.743047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:12.143535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:11.756594Z","time spent":"386.933852ms","remote":"127.0.0.1:60716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-02-01T14:33:12.145555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.249191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-01T14:33:12.145593Z","caller":"traceutil/trace.go:171","msg":"trace[245010269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:783; }","duration":"315.301913ms","start":"2026-02-01T14:33:11.83027Z","end":"2026-02-01T14:33:12.145572Z","steps":["trace[245010269] 'range keys from in-memory index tree' (duration: 315.128438ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:12.145615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:11.830257Z","time spent":"315.350385ms","remote":"127.0.0.1:60892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T14:33:13.117458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.500874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T14:33:13.117536Z","caller":"traceutil/trace.go:171","msg":"trace[425847509] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:784; }","duration":"630.628057ms","start":"2026-02-01T14:33:12.486888Z","end":"2026-02-01T14:33:13.117516Z","steps":["trace[425847509] 'count revisions from in-memory index tree' (duration: 630.429772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:13.117588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:12.486869Z","time spent":"630.708719ms","remote":"127.0.0.1:32918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-01T14:33:13.117782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.204719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T14:33:13.117902Z","caller":"traceutil/trace.go:171","msg":"trace[101215196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:784; }","duration":"328.617681ms","start":"2026-02-01T14:33:12.789266Z","end":"2026-02-01T14:33:13.117883Z","steps":["trace[101215196] 'range keys from in-memory index tree' (duration: 328.147127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:13.118148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:12.789245Z","time spent":"328.885218ms","remote":"127.0.0.1:60622","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-01T14:33:13.283514Z","caller":"traceutil/trace.go:171","msg":"trace[1000458977] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"158.076989ms","start":"2026-02-01T14:33:13.12542Z","end":"2026-02-01T14:33:13.283497Z","steps":["trace[1000458977] 'process raft request' (duration: 157.984057ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:33:13.571476Z","caller":"traceutil/trace.go:171","msg":"trace[1944364403] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:811; }","duration":"436.290821ms","start":"2026-02-01T14:33:13.13516Z","end":"2026-02-01T14:33:13.571451Z","steps":["trace[1944364403] 'read index received' (duration: 149.900787ms)","trace[1944364403] 'applied index is now lower than readState.Index' (duration: 286.387894ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T14:33:13.571988Z","caller":"traceutil/trace.go:171","msg":"trace[77164617] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"444.73918ms","start":"2026-02-01T14:33:13.127242Z","end":"2026-02-01T14:33:13.571981Z","steps":["trace[77164617] 'process raft request' (duration: 437.705051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:13.572142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:13.127234Z","time spent":"444.858883ms","remote":"127.0.0.1:60892","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-01T14:33:13.572793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.628329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-01T14:33:13.572912Z","caller":"traceutil/trace.go:171","msg":"trace[1070220252] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:786; }","duration":"437.759142ms","start":"2026-02-01T14:33:13.135146Z","end":"2026-02-01T14:33:13.572905Z","steps":["trace[1070220252] 'agreement among raft nodes before linearized reading' (duration: 437.591628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:13.572978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:13.13514Z","time spent":"437.832224ms","remote":"127.0.0.1:60892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T14:33:13.573122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.2575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-01T14:33:13.573246Z","caller":"traceutil/trace.go:171","msg":"trace[1305667186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:786; }","duration":"208.416355ms","start":"2026-02-01T14:33:13.364823Z","end":"2026-02-01T14:33:13.573239Z","steps":["trace[1305667186] 'agreement among raft nodes before linearized reading' (duration: 208.266021ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:33:28.774874Z","caller":"traceutil/trace.go:171","msg":"trace[736088164] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"466.056954ms","start":"2026-02-01T14:33:28.308798Z","end":"2026-02-01T14:33:28.774855Z","steps":["trace[736088164] 'process raft request' (duration: 465.956461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:28.774985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:28.30879Z","time spent":"466.137976ms","remote":"127.0.0.1:60892","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-01T14:33:28.850156Z","caller":"traceutil/trace.go:171","msg":"trace[793008700] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"131.6551ms","start":"2026-02-01T14:33:28.718487Z","end":"2026-02-01T14:33:28.850142Z","steps":["trace[793008700] 'process raft request' (duration: 131.32488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:29.521442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.637062ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-01T14:33:29.521539Z","caller":"traceutil/trace.go:171","msg":"trace[739355749] linearizableReadLoop","detail":"{readStateIndex:916; appliedIndex:915; }","duration":"311.449534ms","start":"2026-02-01T14:33:29.210052Z","end":"2026-02-01T14:33:29.521501Z","steps":["trace[739355749] 'read index received' (duration: 163.713418ms)","trace[739355749] 'applied index is now lower than readState.Index' (duration: 147.735306ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T14:33:29.521607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.531806ms","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":"warn","ts":"2026-02-01T14:33:29.521665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.043606ms","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-01T14:33:29.521662Z","caller":"traceutil/trace.go:171","msg":"trace[439746018] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:885; }","duration":"311.623129ms","start":"2026-02-01T14:33:29.210024Z","end":"2026-02-01T14:33:29.521647Z","steps":["trace[439746018] 'agreement among raft nodes before linearized reading' (duration: 311.511535ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:33:29.521683Z","caller":"traceutil/trace.go:171","msg":"trace[619681617] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:885; }","duration":"171.100447ms","start":"2026-02-01T14:33:29.350576Z","end":"2026-02-01T14:33:29.521676Z","steps":["trace[619681617] 'agreement among raft nodes before linearized reading' (duration: 171.010115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:29.521691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:29.210007Z","time spent":"311.679341ms","remote":"127.0.0.1:60804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-02-01T14:33:29.521891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.766518ms","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-01T14:33:29.521906Z","caller":"traceutil/trace.go:171","msg":"trace[1202771719] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:885; }","duration":"146.82541ms","start":"2026-02-01T14:33:29.375077Z","end":"2026-02-01T14:33:29.521902Z","steps":["trace[1202771719] 'agreement among raft nodes before linearized reading' (duration: 146.775599ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:33:30.934012Z","caller":"traceutil/trace.go:171","msg":"trace[263999678] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"150.634248ms","start":"2026-02-01T14:33:30.783371Z","end":"2026-02-01T14:33:30.934005Z","steps":["trace[263999678] 'process raft request' (duration: 150.316009ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:33:30.933793Z","caller":"traceutil/trace.go:171","msg":"trace[146764232] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:920; }","duration":"147.114508ms","start":"2026-02-01T14:33:30.786663Z","end":"2026-02-01T14:33:30.933777Z","steps":["trace[146764232] 'read index received' (duration: 146.986234ms)","trace[146764232] 'applied index is now lower than readState.Index' (duration: 127.834µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T14:33:30.934267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.593441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T14:33:30.934297Z","caller":"traceutil/trace.go:171","msg":"trace[550593914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:890; }","duration":"147.642863ms","start":"2026-02-01T14:33:30.786648Z","end":"2026-02-01T14:33:30.93429Z","steps":["trace[550593914] 'agreement among raft nodes before linearized reading' (duration: 147.593982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:36.350238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.453234ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T14:33:36.350368Z","caller":"traceutil/trace.go:171","msg":"trace[846944370] linearizableReadLoop","detail":"{readStateIndex:936; appliedIndex:935; }","duration":"674.832778ms","start":"2026-02-01T14:33:35.675501Z","end":"2026-02-01T14:33:36.350334Z","steps":["trace[846944370] 'read index received' (duration: 459.176858ms)","trace[846944370] 'applied index is now lower than readState.Index' (duration: 215.65475ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T14:33:36.35057Z","caller":"traceutil/trace.go:171","msg":"trace[487884614] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"683.782151ms","start":"2026-02-01T14:33:35.666776Z","end":"2026-02-01T14:33:36.350558Z","steps":["trace[487884614] 'process raft request' (duration: 467.928986ms)","trace[487884614] 'compare' (duration: 215.150195ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T14:33:36.350641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:35.666767Z","time spent":"683.839624ms","remote":"127.0.0.1:60892","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-01T14:33:36.350888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.380954ms","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-01T14:33:36.350932Z","caller":"traceutil/trace.go:171","msg":"trace[1255007682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:904; }","duration":"675.437426ms","start":"2026-02-01T14:33:35.675485Z","end":"2026-02-01T14:33:36.350923Z","steps":["trace[1255007682] 'agreement among raft nodes before linearized reading' (duration: 675.316612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:36.350962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:35.675479Z","time spent":"675.476337ms","remote":"127.0.0.1:60892","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-01T14:33:36.352429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.036907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T14:33:36.352526Z","caller":"traceutil/trace.go:171","msg":"trace[418831356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:904; }","duration":"566.170501ms","start":"2026-02-01T14:33:35.786338Z","end":"2026-02-01T14:33:36.352509Z","steps":["trace[418831356] 'agreement among raft nodes before linearized reading' (duration: 566.023726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:36.352567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:35.786325Z","time spent":"566.234143ms","remote":"127.0.0.1:60622","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-01T14:33:36.353116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.183289ms","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-01T14:33:36.353151Z","caller":"traceutil/trace.go:171","msg":"trace[788434615] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:904; }","duration":"663.22865ms","start":"2026-02-01T14:33:35.689913Z","end":"2026-02-01T14:33:36.353141Z","steps":["trace[788434615] 'agreement among raft nodes before linearized reading' (duration: 663.111206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:36.353179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:35.689907Z","time spent":"663.2654ms","remote":"127.0.0.1:60892","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-01T14:33:36.356347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.425623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T14:33:36.356411Z","caller":"traceutil/trace.go:171","msg":"trace[832634028] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:904; }","duration":"671.504974ms","start":"2026-02-01T14:33:35.684893Z","end":"2026-02-01T14:33:36.356398Z","steps":["trace[832634028] 'agreement among raft nodes before linearized reading' (duration: 666.182123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:36.356456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:35.684886Z","time spent":"671.555865ms","remote":"127.0.0.1:32950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":113,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-02-01T14:33:37.780169Z","caller":"traceutil/trace.go:171","msg":"trace[1691962909] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"179.069733ms","start":"2026-02-01T14:33:37.601078Z","end":"2026-02-01T14:33:37.780147Z","steps":["trace[1691962909] 'process raft request' (duration: 178.725213ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:33:38.80148Z","caller":"traceutil/trace.go:171","msg":"trace[218738369] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:940; }","duration":"379.106951ms","start":"2026-02-01T14:33:38.422359Z","end":"2026-02-01T14:33:38.801466Z","steps":["trace[218738369] 'read index received' (duration: 378.987328ms)","trace[218738369] 'applied index is now lower than readState.Index' (duration: 119.093µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T14:33:38.801648Z","caller":"traceutil/trace.go:171","msg":"trace[1841474793] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"433.43162ms","start":"2026-02-01T14:33:38.368209Z","end":"2026-02-01T14:33:38.801641Z","steps":["trace[1841474793] 'process raft request' (duration: 433.177903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:38.801707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:38.368198Z","time spent":"433.464191ms","remote":"127.0.0.1:60892","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-01T14:33:38.801843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.484752ms","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-01T14:33:38.801858Z","caller":"traceutil/trace.go:171","msg":"trace[814197253] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:909; }","duration":"379.536883ms","start":"2026-02-01T14:33:38.422317Z","end":"2026-02-01T14:33:38.801854Z","steps":["trace[814197253] 'agreement among raft nodes before linearized reading' (duration: 379.477672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:38.801871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:38.422297Z","time spent":"379.571295ms","remote":"127.0.0.1:60892","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-01T14:33:38.80195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.022332ms","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-01T14:33:38.801963Z","caller":"traceutil/trace.go:171","msg":"trace[366113694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:909; }","duration":"377.055003ms","start":"2026-02-01T14:33:38.424904Z","end":"2026-02-01T14:33:38.801959Z","steps":["trace[366113694] 'agreement among raft nodes before linearized reading' (duration: 377.020642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:38.801974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:38.424893Z","time spent":"377.078134ms","remote":"127.0.0.1:60892","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":"info","ts":"2026-02-01T14:33:39.012645Z","caller":"traceutil/trace.go:171","msg":"trace[1712172394] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"207.276153ms","start":"2026-02-01T14:33:38.805355Z","end":"2026-02-01T14:33:39.012631Z","steps":["trace[1712172394] 'process raft request' (duration: 207.198601ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:33:39.02713Z","caller":"traceutil/trace.go:171","msg":"trace[40136598] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"221.036872ms","start":"2026-02-01T14:33:38.806079Z","end":"2026-02-01T14:33:39.027116Z","steps":["trace[40136598] 'process raft request' (duration: 220.805046ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:33:39.918588Z","caller":"traceutil/trace.go:171","msg":"trace[2109920390] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:946; }","duration":"131.555348ms","start":"2026-02-01T14:33:39.787011Z","end":"2026-02-01T14:33:39.918567Z","steps":["trace[2109920390] 'read index received' (duration: 131.356952ms)","trace[2109920390] 'applied index is now lower than readState.Index' (duration: 197.446µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T14:33:39.918732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.698032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T14:33:39.918764Z","caller":"traceutil/trace.go:171","msg":"trace[1533071935] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:914; }","duration":"131.757763ms","start":"2026-02-01T14:33:39.786997Z","end":"2026-02-01T14:33:39.918754Z","steps":["trace[1533071935] 'agreement among raft nodes before linearized reading' (duration: 131.65202ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:33:39.91907Z","caller":"traceutil/trace.go:171","msg":"trace[1230738237] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"132.917996ms","start":"2026-02-01T14:33:39.78614Z","end":"2026-02-01T14:33:39.919057Z","steps":["trace[1230738237] 'process raft request' (duration: 132.303528ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:33:41.0158Z","caller":"traceutil/trace.go:171","msg":"trace[750172744] linearizableReadLoop","detail":"{readStateIndex:948; appliedIndex:947; }","duration":"202.867298ms","start":"2026-02-01T14:33:40.812914Z","end":"2026-02-01T14:33:41.015781Z","steps":["trace[750172744] 'read index received' (duration: 202.678702ms)","trace[750172744] 'applied index is now lower than readState.Index' (duration: 187.856µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T14:33:41.015896Z","caller":"traceutil/trace.go:171","msg":"trace[1499091002] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"206.81507ms","start":"2026-02-01T14:33:40.809072Z","end":"2026-02-01T14:33:41.015887Z","steps":["trace[1499091002] 'process raft request' (duration: 206.601054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:41.016134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.202117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T14:33:41.016175Z","caller":"traceutil/trace.go:171","msg":"trace[812770837] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:915; }","duration":"203.26518ms","start":"2026-02-01T14:33:40.812899Z","end":"2026-02-01T14:33:41.016164Z","steps":["trace[812770837] 'agreement among raft nodes before linearized reading' (duration: 203.180957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:41.530981Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043359987380713690,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-01T14:33:41.584664Z","caller":"traceutil/trace.go:171","msg":"trace[277808261] linearizableReadLoop","detail":"{readStateIndex:949; appliedIndex:948; }","duration":"554.703536ms","start":"2026-02-01T14:33:41.02994Z","end":"2026-02-01T14:33:41.584644Z","steps":["trace[277808261] 'read index received' (duration: 554.51999ms)","trace[277808261] 'applied index is now lower than readState.Index' (duration: 182.706µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T14:33:41.584888Z","caller":"traceutil/trace.go:171","msg":"trace[1269031159] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"564.903625ms","start":"2026-02-01T14:33:41.019976Z","end":"2026-02-01T14:33:41.584879Z","steps":["trace[1269031159] 'process raft request' (duration: 564.531405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:41.584964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:41.019963Z","time spent":"564.951676ms","remote":"127.0.0.1:60892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T14:33:41.585137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.191379ms","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-01T14:33:41.585164Z","caller":"traceutil/trace.go:171","msg":"trace[900237907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:916; }","duration":"555.229731ms","start":"2026-02-01T14:33:41.029928Z","end":"2026-02-01T14:33:41.585158Z","steps":["trace[900237907] 'agreement among raft nodes before linearized reading' (duration: 555.146879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:41.585254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:41.029923Z","time spent":"555.324314ms","remote":"127.0.0.1:60892","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-01T14:33:41.585394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.516571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-01T14:33:41.585419Z","caller":"traceutil/trace.go:171","msg":"trace[1938723737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:916; }","duration":"434.574482ms","start":"2026-02-01T14:33:41.150839Z","end":"2026-02-01T14:33:41.585413Z","steps":["trace[1938723737] 'agreement among raft nodes before linearized reading' (duration: 434.510841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:41.585469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:41.150822Z","time spent":"434.642455ms","remote":"127.0.0.1:60892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T14:33:41.585608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.682534ms","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":"info","ts":"2026-02-01T14:33:41.585632Z","caller":"traceutil/trace.go:171","msg":"trace[667343319] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:916; }","duration":"303.737925ms","start":"2026-02-01T14:33:41.281888Z","end":"2026-02-01T14:33:41.585626Z","steps":["trace[667343319] 'agreement among raft nodes before linearized reading' (duration: 303.694384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:41.585656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:41.281871Z","time spent":"303.779136ms","remote":"127.0.0.1:32852","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":"info","ts":"2026-02-01T14:33:42.092498Z","caller":"traceutil/trace.go:171","msg":"trace[1085377035] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"168.02348ms","start":"2026-02-01T14:33:41.924459Z","end":"2026-02-01T14:33:42.092482Z","steps":["trace[1085377035] 'process raft request' (duration: 167.937698ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:33:43.238843Z","caller":"traceutil/trace.go:171","msg":"trace[2104135160] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"216.397271ms","start":"2026-02-01T14:33:43.022415Z","end":"2026-02-01T14:33:43.238813Z","steps":["trace[2104135160] 'process raft request' (duration: 216.207346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:45.823564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.129823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-01T14:33:45.82366Z","caller":"traceutil/trace.go:171","msg":"trace[1690323765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:927; }","duration":"186.216435ms","start":"2026-02-01T14:33:45.6374Z","end":"2026-02-01T14:33:45.823617Z","steps":["trace[1690323765] 'range keys from in-memory index tree' (duration: 186.03564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:45.824097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.47427ms","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-01T14:33:45.824136Z","caller":"traceutil/trace.go:171","msg":"trace[656786374] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:927; }","duration":"200.569803ms","start":"2026-02-01T14:33:45.623557Z","end":"2026-02-01T14:33:45.824126Z","steps":["trace[656786374] 'range keys from in-memory index tree' (duration: 200.327815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:45.824702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.177363ms","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-01T14:33:45.824734Z","caller":"traceutil/trace.go:171","msg":"trace[145053447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:927; }","duration":"187.244685ms","start":"2026-02-01T14:33:45.63748Z","end":"2026-02-01T14:33:45.824725Z","steps":["trace[145053447] 'range keys from in-memory index tree' (duration: 187.06072ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:33:46.164653Z","caller":"traceutil/trace.go:171","msg":"trace[1629054378] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"335.352301ms","start":"2026-02-01T14:33:45.829285Z","end":"2026-02-01T14:33:46.164637Z","steps":["trace[1629054378] 'process raft request' (duration: 335.209807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:46.164778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:45.829269Z","time spent":"335.449904ms","remote":"127.0.0.1:60892","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-01T14:33:46.164974Z","caller":"traceutil/trace.go:171","msg":"trace[1115874588] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:962; }","duration":"335.390163ms","start":"2026-02-01T14:33:45.829553Z","end":"2026-02-01T14:33:46.164943Z","steps":["trace[1115874588] 'read index received' (duration: 335.382723ms)","trace[1115874588] 'applied index is now lower than readState.Index' (duration: 5.74µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T14:33:46.165102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.526497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T14:33:46.165124Z","caller":"traceutil/trace.go:171","msg":"trace[54512886] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:928; }","duration":"335.585158ms","start":"2026-02-01T14:33:45.829532Z","end":"2026-02-01T14:33:46.165117Z","steps":["trace[54512886] 'agreement among raft nodes before linearized reading' (duration: 335.508776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:46.165149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:45.829524Z","time spent":"335.619318ms","remote":"127.0.0.1:60630","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-01T14:33:46.165667Z","caller":"traceutil/trace.go:171","msg":"trace[1363692041] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"332.510801ms","start":"2026-02-01T14:33:45.833148Z","end":"2026-02-01T14:33:46.165658Z","steps":["trace[1363692041] 'process raft request' (duration: 331.896824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:46.165728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:45.833141Z","time spent":"332.544502ms","remote":"127.0.0.1:60892","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-01T14:33:46.165822Z","caller":"traceutil/trace.go:171","msg":"trace[669076250] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"332.633284ms","start":"2026-02-01T14:33:45.833183Z","end":"2026-02-01T14:33:46.165816Z","steps":["trace[669076250] 'process raft request' (duration: 332.189852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:33:46.165855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:33:45.83318Z","time spent":"332.656385ms","remote":"127.0.0.1:60892","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-01T14:34:02.152807Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043359987380713906,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T14:34:02.474543Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.108531217s","expected-duration":"1s"} {"level":"info","ts":"2026-02-01T14:34:02.475283Z","caller":"traceutil/trace.go:171","msg":"trace[1569639218] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1053; }","duration":"822.962157ms","start":"2026-02-01T14:34:01.652172Z","end":"2026-02-01T14:34:02.475134Z","steps":["trace[1569639218] 'read index received' (duration: 822.664188ms)","trace[1569639218] 'applied index is now lower than readState.Index' (duration: 296.739µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T14:34:02.475981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.78445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T14:34:02.476057Z","caller":"traceutil/trace.go:171","msg":"trace[1424369581] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1017; }","duration":"823.910993ms","start":"2026-02-01T14:34:01.652129Z","end":"2026-02-01T14:34:02.47604Z","steps":["trace[1424369581] 'agreement among raft nodes before linearized reading' (duration: 823.778309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:34:02.476099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:34:01.652093Z","time spent":"823.996236ms","remote":"127.0.0.1:60752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-02-01T14:34:02.476343Z","caller":"traceutil/trace.go:171","msg":"trace[2128825004] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"1.11043202s","start":"2026-02-01T14:34:01.365899Z","end":"2026-02-01T14:34:02.476331Z","steps":["trace[2128825004] 'process raft request' (duration: 1.10902018s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:34:02.476438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:34:01.365875Z","time spent":"1.110501463s","remote":"127.0.0.1:60892","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-01T14:34:02.476714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.215837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T14:34:02.476751Z","caller":"traceutil/trace.go:171","msg":"trace[1237980579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1017; }","duration":"689.278549ms","start":"2026-02-01T14:34:01.787464Z","end":"2026-02-01T14:34:02.476742Z","steps":["trace[1237980579] 'agreement among raft nodes before linearized reading' (duration: 689.225998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:34:02.476778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:34:01.787453Z","time spent":"689.31937ms","remote":"127.0.0.1:60622","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-01T14:34:02.477002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.490593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-01T14:34:02.477039Z","caller":"traceutil/trace.go:171","msg":"trace[1858032683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1017; }","duration":"138.537455ms","start":"2026-02-01T14:34:02.33849Z","end":"2026-02-01T14:34:02.477028Z","steps":["trace[1858032683] 'agreement among raft nodes before linearized reading' (duration: 138.409992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:34:02.477173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.918672ms","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-01T14:34:02.477203Z","caller":"traceutil/trace.go:171","msg":"trace[877444469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1017; }","duration":"140.968004ms","start":"2026-02-01T14:34:02.336228Z","end":"2026-02-01T14:34:02.477196Z","steps":["trace[877444469] 'agreement among raft nodes before linearized reading' (duration: 140.877241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:34:02.477298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.613685ms","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-01T14:34:02.477328Z","caller":"traceutil/trace.go:171","msg":"trace[1819618281] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1017; }","duration":"145.660816ms","start":"2026-02-01T14:34:02.331658Z","end":"2026-02-01T14:34:02.477319Z","steps":["trace[1819618281] 'agreement among raft nodes before linearized reading' (duration: 145.595875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:34:02.477434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.896818ms","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-01T14:34:02.477475Z","caller":"traceutil/trace.go:171","msg":"trace[987053887] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1017; }","duration":"148.95951ms","start":"2026-02-01T14:34:02.328496Z","end":"2026-02-01T14:34:02.477455Z","steps":["trace[987053887] 'agreement among raft nodes before linearized reading' (duration: 148.889778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:34:02.477572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.155345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T14:34:02.477602Z","caller":"traceutil/trace.go:171","msg":"trace[429366793] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1017; }","duration":"575.220547ms","start":"2026-02-01T14:34:01.902373Z","end":"2026-02-01T14:34:02.477593Z","steps":["trace[429366793] 'agreement among raft nodes before linearized reading' (duration: 575.155615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:34:02.477629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:34:01.902352Z","time spent":"575.269718ms","remote":"127.0.0.1:60976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-02-01T14:37:05.554763Z","caller":"traceutil/trace.go:171","msg":"trace[1525709397] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"100.555088ms","start":"2026-02-01T14:37:05.454182Z","end":"2026-02-01T14:37:05.554737Z","steps":["trace[1525709397] 'process raft request' (duration: 25.052977ms)","trace[1525709397] 'compare' (duration: 75.054609ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T14:37:11.91713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.548977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T14:37:11.917225Z","caller":"traceutil/trace.go:171","msg":"trace[1070492883] linearizableReadLoop","detail":"{readStateIndex:1914; appliedIndex:1913; }","duration":"309.601528ms","start":"2026-02-01T14:37:11.607608Z","end":"2026-02-01T14:37:11.91721Z","steps":["trace[1070492883] 'read index received' (duration: 73.861166ms)","trace[1070492883] 'applied index is now lower than readState.Index' (duration: 235.739332ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T14:37:11.917469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.838464ms","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-01T14:37:11.917556Z","caller":"traceutil/trace.go:171","msg":"trace[1261214790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1837; }","duration":"309.951907ms","start":"2026-02-01T14:37:11.607587Z","end":"2026-02-01T14:37:11.917539Z","steps":["trace[1261214790] 'agreement among raft nodes before linearized reading' (duration: 309.723301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:37:11.917596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:37:11.607578Z","time spent":"310.009219ms","remote":"127.0.0.1:60892","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-01T14:37:11.917723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.734886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T14:37:11.917755Z","caller":"traceutil/trace.go:171","msg":"trace[1065167025] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1837; }","duration":"126.794797ms","start":"2026-02-01T14:37:11.79095Z","end":"2026-02-01T14:37:11.917745Z","steps":["trace[1065167025] 'agreement among raft nodes before linearized reading' (duration: 126.749455ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T14:37:11.917809Z","caller":"traceutil/trace.go:171","msg":"trace[629964150] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"310.312147ms","start":"2026-02-01T14:37:11.60746Z","end":"2026-02-01T14:37:11.917772Z","steps":["trace[629964150] 'process raft request' (duration: 74.046541ms)","trace[629964150] 'compare' (duration: 235.447354ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T14:37:11.917895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:37:11.607446Z","time spent":"310.390539ms","remote":"127.0.0.1:60892","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-01T14:37:11.917931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.627686ms","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-01T14:37:11.917963Z","caller":"traceutil/trace.go:171","msg":"trace[968194102] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1837; }","duration":"266.672197ms","start":"2026-02-01T14:37:11.651283Z","end":"2026-02-01T14:37:11.917955Z","steps":["trace[968194102] 'agreement among raft nodes before linearized reading' (duration: 266.590865ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:37:11.917513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.09646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-01T14:37:11.91802Z","caller":"traceutil/trace.go:171","msg":"trace[246414301] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1837; }","duration":"307.627925ms","start":"2026-02-01T14:37:11.610383Z","end":"2026-02-01T14:37:11.91801Z","steps":["trace[246414301] 'agreement among raft nodes before linearized reading' (duration: 307.028679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:37:11.918051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:37:11.610372Z","time spent":"307.670326ms","remote":"127.0.0.1:60892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-01T14:37:11.918121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.674046ms","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-01T14:37:11.918146Z","caller":"traceutil/trace.go:171","msg":"trace[1891896804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1837; }","duration":"307.714157ms","start":"2026-02-01T14:37:11.610425Z","end":"2026-02-01T14:37:11.918139Z","steps":["trace[1891896804] 'agreement among raft nodes before linearized reading' (duration: 307.644615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T14:37:11.918172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T14:37:11.610419Z","time spent":"307.745608ms","remote":"127.0.0.1:60892","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\" "}